builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1464243468.84 results: success (0) buildid: 20160525203033 builduid: 94abec3d545b40a39d51e94f4cf96f6f revision: 8dfd7c9d86397ed898d84933a3c39834f1db886f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:48.841713) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:48.842573) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:48.842886) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:48.874341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:48.874673) ========= 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=1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-25 23:17: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% 40.7M=0s 2016-05-25 23:17:49 (40.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.175255 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:49.064079) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:49.064433) ========= 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=1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021976 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:49.153195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 23:17:49.153507) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8dfd7c9d86397ed898d84933a3c39834f1db886f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8dfd7c9d86397ed898d84933a3c39834f1db886f --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=1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-25 23:17:49,575 truncating revision to first 12 chars 2016-05-25 23:17:49,575 Setting DEBUG logging. 2016-05-25 23:17:49,575 attempt 1/10 2016-05-25 23:17:49,575 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8dfd7c9d8639?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 23:17:50,652 unpacking tar archive at: fx-team-8dfd7c9d8639/testing/mozharness/ program finished with exit code 0 elapsedTime=1.396895 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 23:17:50.563990) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:50.564316) ========= script_repo_revision: 8dfd7c9d86397ed898d84933a3c39834f1db886f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:50.564776) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:50.565075) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 23:17:50.575622) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-25 23:17:50.575978) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:17:51 INFO - MultiFileLogger online at 20160525 23:17:51 in /builds/slave/test 23:17:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:17:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:17:51 INFO - {'append_to_log': False, 23:17:51 INFO - 'base_work_dir': '/builds/slave/test', 23:17:51 INFO - 'blob_upload_branch': 'Fx-Team', 23:17:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:17:51 INFO - 'branch': 'Fx-Team', 23:17:51 INFO - 'buildbot_json_path': 'buildprops.json', 23:17:51 INFO - 'config_files': ('talos/linux_config.py',), 23:17:51 INFO - 'default_actions': ('clobber', 23:17:51 INFO - 'read-buildbot-config', 23:17:51 INFO - 'download-and-extract', 23:17:51 INFO - 'populate-webroot', 23:17:51 INFO - 'create-virtualenv', 23:17:51 INFO - 'install', 23:17:51 INFO - 'run-tests'), 23:17:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:17:51 INFO - 'download_minidump_stackwalk': True, 23:17:51 INFO - 'download_symbols': 'ondemand', 23:17:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:17:51 INFO - 'tooltool.py': '/tools/tooltool.py', 23:17:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:17:51 INFO - '/tools/misc-python/virtualenv.py')}, 23:17:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:17:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:17:51 INFO - 'installer_path': 'installer.exe', 23:17:51 INFO - 'log_level': 'info', 23:17:51 INFO - 'log_name': 'talos', 23:17:51 INFO - 'log_to_console': True, 23:17:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:17:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:17:51 INFO - 'opt_config_files': (), 23:17:51 INFO - 'pip_index': False, 23:17:51 INFO - 'sps_profile': False, 23:17:51 INFO - 'sps_profile_interval': 0, 23:17:51 INFO - 'suite': 'dromaeojs', 23:17:51 INFO - 'system_bits': '32', 23:17:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:17:51 INFO - 'title': 'talos-linux64-ix-027', 23:17:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:17:51 INFO - 'use_talos_json': True, 23:17:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:17:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:17:51 INFO - 'work_dir': 'build'} 23:17:51 INFO - ##### 23:17:51 INFO - ##### Running clobber step. 23:17:51 INFO - ##### 23:17:51 INFO - Running pre-action listener: _resource_record_pre_action 23:17:51 INFO - Running main action method: clobber 23:17:51 INFO - rmtree: /builds/slave/test/build 23:17:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:17:52 INFO - Running post-action listener: _resource_record_post_action 23:17:52 INFO - ##### 23:17:52 INFO - ##### Finished clobber step (success) 23:17:52 INFO - ##### 23:17:52 INFO - ##### 23:17:52 INFO - ##### Running read-buildbot-config step. 23:17:52 INFO - ##### 23:17:52 INFO - Running pre-action listener: _resource_record_pre_action 23:17:52 INFO - Running main action method: read_buildbot_config 23:17:52 INFO - Using buildbot properties: 23:17:52 INFO - { 23:17:52 INFO - "project": "", 23:17:52 INFO - "product": "firefox", 23:17:52 INFO - "script_repo_revision": "production", 23:17:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:17:52 INFO - "repository": "", 23:17:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:17:52 INFO - "buildid": "20160525203033", 23:17:52 INFO - "pgo_build": "True", 23:17:52 INFO - "basedir": "/builds/slave/test", 23:17:52 INFO - "buildnumber": 1187, 23:17:52 INFO - "slavename": "talos-linux64-ix-027", 23:17:52 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:17:52 INFO - "platform": "ubuntu64_hw", 23:17:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:17:52 INFO - "branch": "fx-team", 23:17:52 INFO - "repo_path": "integration/fx-team", 23:17:52 INFO - "revision": "8dfd7c9d86397ed898d84933a3c39834f1db886f", 23:17:52 INFO - "stage_platform": "linux64-pgo", 23:17:52 INFO - "builduid": "94abec3d545b40a39d51e94f4cf96f6f", 23:17:52 INFO - "slavebuilddir": "test" 23:17:52 INFO - } 23:17:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:17:52 INFO - Running post-action listener: _resource_record_post_action 23:17:52 INFO - ##### 23:17:52 INFO - ##### Finished read-buildbot-config step (success) 23:17:52 INFO - ##### 23:17:52 INFO - ##### 23:17:52 INFO - ##### Running download-and-extract step. 23:17:52 INFO - ##### 23:17:52 INFO - Running pre-action listener: _resource_record_pre_action 23:17:52 INFO - Running main action method: download_and_extract 23:17:52 INFO - mkdir: /builds/slave/test/build/tests 23:17:52 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')]} 23:17:52 INFO - https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:17:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:17:53 INFO - Downloaded 1361 bytes. 23:17:53 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:53 INFO - Using the following test package requirements: 23:17:53 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:53 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:17:53 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:17:53 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:53 INFO - u'jsshell-linux-x86_64.zip'], 23:17:53 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:17:53 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:53 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:17:53 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:17:53 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:17:53 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:17:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:17: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')]} 23:17:53 INFO - https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:17:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:17:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:17:54 INFO - Downloaded 24408799 bytes. 23:17:54 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'] 23:17:54 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 23:17:56 INFO - Return code: 0 23:17: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 23:17: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')]} 23:17:56 INFO - https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:17:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:17:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:17:59 INFO - Downloaded 24408799 bytes. 23:17:59 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'] 23:17:59 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 23:18:00 INFO - Return code: 0 23:18: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')]} 23:18:00 INFO - https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:18:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:18:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:18:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:18:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:18:02 INFO - Downloaded 10834034 bytes. 23:18:02 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'] 23:18:02 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 23:18:02 INFO - Return code: 0 23:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:02 INFO - https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:18:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:18:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TNgXiOGaQMifhBsCHTBhyA/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 23:18:03 INFO - Downloaded 58658390 bytes. 23:18:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:03 INFO - mkdir: /builds/slave/test/properties 23:18:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:18:03 INFO - Writing to file /builds/slave/test/properties/build_url 23:18:03 INFO - Contents: 23:18:03 INFO - build_url:https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:04 INFO - Running post-action listener: _resource_record_post_action 23:18:04 INFO - Running post-action listener: set_extra_try_arguments 23:18:04 INFO - ##### 23:18:04 INFO - ##### Finished download-and-extract step (success) 23:18:04 INFO - ##### 23:18:04 INFO - ##### 23:18:04 INFO - ##### Running populate-webroot step. 23:18:04 INFO - ##### 23:18:04 INFO - Running pre-action listener: _resource_record_pre_action 23:18:04 INFO - Running main action method: populate_webroot 23:18:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:18:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:18:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:18:04 INFO - '--tppagecycles', 23:18:04 INFO - '7'], 23:18:04 INFO - 'tests': ['tsvgm']}}, 23:18:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:18:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:18:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:18:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:18:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:04 INFO - 'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:18:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:18:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:04 INFO - 'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['damp', 'tps']}, 23:18:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:04 INFO - 'tests': ['damp', 'tps']}, 23:18:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['dromaeo_dom']}, 23:18:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:18:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['a11yr', 23:18:04 INFO - 'ts_paint', 23:18:04 INFO - 'tpaint', 23:18:04 INFO - 'sessionrestore', 23:18:04 INFO - 'sessionrestore_no_auto_restore', 23:18:04 INFO - 'tabpaint']}, 23:18:04 INFO - 'other-e10s': {'tests': ['a11yr', 23:18:04 INFO - 'ts_paint', 23:18:04 INFO - 'tpaint', 23:18:04 INFO - 'sessionrestore', 23:18:04 INFO - 'sessionrestore_no_auto_restore', 23:18:04 INFO - 'tabpaint']}, 23:18:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:18:04 INFO - 'ts_paint', 23:18:04 INFO - 'tpaint', 23:18:04 INFO - 'sessionrestore', 23:18:04 INFO - 'sessionrestore_no_auto_restore', 23:18:04 INFO - 'tabpaint']}, 23:18:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:18:04 INFO - 'ts_paint', 23:18:04 INFO - 'tpaint', 23:18:04 INFO - 'sessionrestore', 23:18:04 INFO - 'sessionrestore_no_auto_restore', 23:18:04 INFO - 'tabpaint']}, 23:18:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['a11yr', 23:18:04 INFO - 'ts_paint', 23:18:04 INFO - 'tpaint', 23:18:04 INFO - 'sessionrestore', 23:18:04 INFO - 'sessionrestore_no_auto_restore', 23:18:04 INFO - 'tabpaint']}, 23:18:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['a11yr', 23:18:04 INFO - 'ts_paint', 23:18:04 INFO - 'tpaint', 23:18:04 INFO - 'sessionrestore', 23:18:04 INFO - 'sessionrestore_no_auto_restore', 23:18:04 INFO - 'tabpaint']}, 23:18:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['tsvgx', 23:18:04 INFO - 'tsvgr_opacity', 23:18:04 INFO - 'tart', 23:18:04 INFO - 'tscrollx', 23:18:04 INFO - 'cart']}, 23:18:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:18:04 INFO - 'tsvgr_opacity', 23:18:04 INFO - 'tart', 23:18:04 INFO - 'tscrollx', 23:18:04 INFO - 'cart']}, 23:18:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:04 INFO - 'talos_options': ['--disable-e10s'], 23:18:04 INFO - 'tests': ['tp5o']}, 23:18:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:04 INFO - 'tests': ['tp5o']}, 23:18:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:04 INFO - 'talos_options': ['--disable-e10s', 23:18:04 INFO - '--xperf_path', 23:18:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:18:04 INFO - 'tests': ['tp5n']}, 23:18:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:04 INFO - 'talos_options': ['--xperf_path', 23:18:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:18:04 INFO - 'tests': ['tp5n']}}, 23:18:04 INFO - 'talos.zip': {'path': '', 23:18:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:18:04 INFO - Running post-action listener: _resource_record_post_action 23:18:04 INFO - ##### 23:18:04 INFO - ##### Finished populate-webroot step (success) 23:18:04 INFO - ##### 23:18:04 INFO - ##### 23:18:04 INFO - ##### Running create-virtualenv step. 23:18:04 INFO - ##### 23:18:04 INFO - Running pre-action listener: _resource_record_pre_action 23:18:04 INFO - Running main action method: create_virtualenv 23:18:04 INFO - Creating virtualenv /builds/slave/test/build/venv 23:18:04 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 23:18:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:18:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:18:04 INFO - Using real prefix '/usr' 23:18:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:18:05 INFO - Installing distribute.............................................................................................................................................................................................done. 23:18:07 INFO - Installing pip.................done. 23:18:07 INFO - Return code: 0 23:18:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:18:07 INFO - Using env: {'DISPLAY': ':0', 23:18:07 INFO - 'HOME': '/home/cltbld', 23:18:07 INFO - 'LANG': 'en_US.UTF-8', 23:18:07 INFO - 'LANGUAGE': 'en_US:en', 23:18:07 INFO - 'LOGNAME': 'cltbld', 23:18:07 INFO - 'MAIL': '/var/mail/cltbld', 23:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:07 INFO - 'MOZ_NO_REMOTE': '1', 23:18:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:07 INFO - 'NO_EM_RESTART': '1', 23:18:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:07 INFO - 'PWD': '/builds/slave/test', 23:18:07 INFO - 'SHELL': '/bin/bash', 23:18:07 INFO - 'SHLVL': '1', 23:18:07 INFO - 'TERM': 'linux', 23:18:07 INFO - 'TMOUT': '86400', 23:18:07 INFO - 'USER': 'cltbld', 23:18:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:07 INFO - '_': '/tools/buildbot/bin/python'} 23:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:07 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 23:18:07 INFO - Cleaning up... 23:18:07 INFO - Return code: 0 23:18:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:18:07 INFO - Using env: {'DISPLAY': ':0', 23:18:07 INFO - 'HOME': '/home/cltbld', 23:18:07 INFO - 'LANG': 'en_US.UTF-8', 23:18:07 INFO - 'LANGUAGE': 'en_US:en', 23:18:07 INFO - 'LOGNAME': 'cltbld', 23:18:07 INFO - 'MAIL': '/var/mail/cltbld', 23:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:07 INFO - 'MOZ_NO_REMOTE': '1', 23:18:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:07 INFO - 'NO_EM_RESTART': '1', 23:18:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:07 INFO - 'PWD': '/builds/slave/test', 23:18:07 INFO - 'SHELL': '/bin/bash', 23:18:07 INFO - 'SHLVL': '1', 23:18:07 INFO - 'TERM': 'linux', 23:18:07 INFO - 'TMOUT': '86400', 23:18:07 INFO - 'USER': 'cltbld', 23:18:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:07 INFO - '_': '/tools/buildbot/bin/python'} 23:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:07 INFO - Downloading/unpacking psutil>=3.1.1 23:18:07 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 23:18:07 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 23:18:07 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 23:18:07 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 23:18:07 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 23:18:07 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 23:18:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:18:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:18:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:18:09 INFO - Installing collected packages: psutil 23:18:09 INFO - Running setup.py install for psutil 23:18:09 INFO - building 'psutil._psutil_linux' extension 23:18:09 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 23:18:09 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 23:18:09 INFO - building 'psutil._psutil_posix' extension 23:18:09 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 23:18:09 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 23:18:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:18:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:18:10 INFO - Successfully installed psutil 23:18:10 INFO - Cleaning up... 23:18:10 INFO - Return code: 0 23:18:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:18:10 INFO - Using env: {'DISPLAY': ':0', 23:18:10 INFO - 'HOME': '/home/cltbld', 23:18:10 INFO - 'LANG': 'en_US.UTF-8', 23:18:10 INFO - 'LANGUAGE': 'en_US:en', 23:18:10 INFO - 'LOGNAME': 'cltbld', 23:18:10 INFO - 'MAIL': '/var/mail/cltbld', 23:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:10 INFO - 'MOZ_NO_REMOTE': '1', 23:18:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:10 INFO - 'NO_EM_RESTART': '1', 23:18:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:10 INFO - 'PWD': '/builds/slave/test', 23:18:10 INFO - 'SHELL': '/bin/bash', 23:18:10 INFO - 'SHLVL': '1', 23:18:10 INFO - 'TERM': 'linux', 23:18:10 INFO - 'TMOUT': '86400', 23:18:10 INFO - 'USER': 'cltbld', 23:18:10 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:10 INFO - '_': '/tools/buildbot/bin/python'} 23:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:18:10 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 23:18:10 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 23:18:10 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 23:18:10 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 23:18:10 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 23:18:10 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 23:18:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:18:12 INFO - Installing collected packages: mozsystemmonitor 23:18:12 INFO - Running setup.py install for mozsystemmonitor 23:18:12 INFO - Successfully installed mozsystemmonitor 23:18:12 INFO - Cleaning up... 23:18:12 INFO - Return code: 0 23:18:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:18:12 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')]} 23:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:12 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')]} 23:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:12 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 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:12 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 23:18:12 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 23:18:12 INFO - Using env: {'DISPLAY': ':0', 23:18:12 INFO - 'HOME': '/home/cltbld', 23:18:12 INFO - 'LANG': 'en_US.UTF-8', 23:18:12 INFO - 'LANGUAGE': 'en_US:en', 23:18:12 INFO - 'LOGNAME': 'cltbld', 23:18:12 INFO - 'MAIL': '/var/mail/cltbld', 23:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:12 INFO - 'MOZ_NO_REMOTE': '1', 23:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:12 INFO - 'NO_EM_RESTART': '1', 23:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:12 INFO - 'PWD': '/builds/slave/test', 23:18:12 INFO - 'SHELL': '/bin/bash', 23:18:12 INFO - 'SHLVL': '1', 23:18:12 INFO - 'TERM': 'linux', 23:18:12 INFO - 'TMOUT': '86400', 23:18:12 INFO - 'USER': 'cltbld', 23:18:12 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:12 INFO - '_': '/tools/buildbot/bin/python'} 23:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:12 INFO - Downloading/unpacking blobuploader==1.2.4 23:18:12 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 23:18:12 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 23:18:12 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 23:18:12 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 23:18:12 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 23:18:12 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 23:18:14 INFO - Downloading blobuploader-1.2.4.tar.gz 23:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:18:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:18:14 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 23:18:14 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 23:18:14 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 23:18:14 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 23:18:14 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 23:18:14 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 23:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:18:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:18:14 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 23:18:14 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 23:18:14 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 23:18:14 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 23:18:14 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 23:18:14 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 23:18:15 INFO - Downloading docopt-0.6.1.tar.gz 23:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:18:15 INFO - Installing collected packages: blobuploader, requests, docopt 23:18:15 INFO - Running setup.py install for blobuploader 23:18:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:18:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:18:15 INFO - Running setup.py install for requests 23:18:15 INFO - Running setup.py install for docopt 23:18:15 INFO - Successfully installed blobuploader requests docopt 23:18:15 INFO - Cleaning up... 23:18:15 INFO - Return code: 0 23:18:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:15 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 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:15 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 23:18:15 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 23:18:15 INFO - Using env: {'DISPLAY': ':0', 23:18:15 INFO - 'HOME': '/home/cltbld', 23:18:15 INFO - 'LANG': 'en_US.UTF-8', 23:18:15 INFO - 'LANGUAGE': 'en_US:en', 23:18:15 INFO - 'LOGNAME': 'cltbld', 23:18:15 INFO - 'MAIL': '/var/mail/cltbld', 23:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:15 INFO - 'MOZ_NO_REMOTE': '1', 23:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:15 INFO - 'NO_EM_RESTART': '1', 23:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:15 INFO - 'PWD': '/builds/slave/test', 23:18:15 INFO - 'SHELL': '/bin/bash', 23:18:15 INFO - 'SHLVL': '1', 23:18:15 INFO - 'TERM': 'linux', 23:18:15 INFO - 'TMOUT': '86400', 23:18:15 INFO - 'USER': 'cltbld', 23:18:15 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:15 INFO - '_': '/tools/buildbot/bin/python'} 23:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:18:16 INFO - Running setup.py (path:/tmp/pip-uOFcHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:18:16 INFO - Running setup.py (path:/tmp/pip-gK_hFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:18:16 INFO - Running setup.py (path:/tmp/pip-blI2oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:18:16 INFO - Running setup.py (path:/tmp/pip-U1mszr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:18:16 INFO - Running setup.py (path:/tmp/pip-q5OgJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:18:16 INFO - Running setup.py (path:/tmp/pip-dMgIQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:18:16 INFO - Running setup.py (path:/tmp/pip-heOim4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:18:16 INFO - Running setup.py (path:/tmp/pip-ULhnVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:18:16 INFO - Running setup.py (path:/tmp/pip-etBuW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:18:16 INFO - Running setup.py (path:/tmp/pip-XvNmoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:18:16 INFO - Running setup.py (path:/tmp/pip-7K2LOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:18:16 INFO - Running setup.py (path:/tmp/pip-6eCeLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:18:16 INFO - Running setup.py (path:/tmp/pip-Jc4qIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:18:17 INFO - Running setup.py (path:/tmp/pip-AZhbuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:17 INFO - Running setup.py (path:/tmp/pip-MoTBM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:18:17 INFO - Running setup.py (path:/tmp/pip-_HOTpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:18:17 INFO - Running setup.py (path:/tmp/pip-Mexuf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:18:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:18:17 INFO - Running setup.py install for manifestparser 23:18:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:18:17 INFO - Running setup.py install for mozcrash 23:18:17 INFO - Running setup.py install for mozdebug 23:18:17 INFO - Running setup.py install for mozdevice 23:18:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:18:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:18:17 INFO - Running setup.py install for mozfile 23:18:17 INFO - Running setup.py install for mozhttpd 23:18:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:18:17 INFO - Running setup.py install for mozinfo 23:18:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Running setup.py install for mozInstall 23:18:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Running setup.py install for mozleak 23:18:18 INFO - Running setup.py install for mozlog 23:18:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Running setup.py install for moznetwork 23:18:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Running setup.py install for mozprocess 23:18:18 INFO - Running setup.py install for mozprofile 23:18:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Running setup.py install for mozrunner 23:18:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:18:18 INFO - Running setup.py install for mozscreenshot 23:18:18 INFO - Running setup.py install for moztest 23:18:18 INFO - Running setup.py install for mozversion 23:18:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:18:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:18:19 INFO - Cleaning up... 23:18:19 INFO - Return code: 0 23:18:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:19 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 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:19 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 23:18:19 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 23:18:19 INFO - Using env: {'DISPLAY': ':0', 23:18:19 INFO - 'HOME': '/home/cltbld', 23:18:19 INFO - 'LANG': 'en_US.UTF-8', 23:18:19 INFO - 'LANGUAGE': 'en_US:en', 23:18:19 INFO - 'LOGNAME': 'cltbld', 23:18:19 INFO - 'MAIL': '/var/mail/cltbld', 23:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:19 INFO - 'MOZ_NO_REMOTE': '1', 23:18:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:19 INFO - 'NO_EM_RESTART': '1', 23:18:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:19 INFO - 'PWD': '/builds/slave/test', 23:18:19 INFO - 'SHELL': '/bin/bash', 23:18:19 INFO - 'SHLVL': '1', 23:18:19 INFO - 'TERM': 'linux', 23:18:19 INFO - 'TMOUT': '86400', 23:18:19 INFO - 'USER': 'cltbld', 23:18:19 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:19 INFO - '_': '/tools/buildbot/bin/python'} 23:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:18:19 INFO - Running setup.py (path:/tmp/pip-YSS0Ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:18:19 INFO - Running setup.py (path:/tmp/pip-MlYBW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:18:19 INFO - Running setup.py (path:/tmp/pip-Ppupk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:18:19 INFO - Running setup.py (path:/tmp/pip-97pA6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:18:19 INFO - Running setup.py (path:/tmp/pip-yH8VCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:18:19 INFO - Running setup.py (path:/tmp/pip-o8YKy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:18:19 INFO - Running setup.py (path:/tmp/pip-jJEPSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:18:19 INFO - Running setup.py (path:/tmp/pip-8x8EwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:18:19 INFO - Running setup.py (path:/tmp/pip-EXl6Ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:18:19 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)) 23:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:18:19 INFO - Running setup.py (path:/tmp/pip-0XcoOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:18:20 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)) 23:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:18:20 INFO - Running setup.py (path:/tmp/pip-_LTsdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:18:20 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)) 23:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:18:20 INFO - Running setup.py (path:/tmp/pip-zOOGkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:18:20 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)) 23:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:18:20 INFO - Running setup.py (path:/tmp/pip-YbOluE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:18:20 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)) 23:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:18:20 INFO - Running setup.py (path:/tmp/pip-ZyV6kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:18:20 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)) 23:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:20 INFO - Running setup.py (path:/tmp/pip-rz7rM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:20 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)) 23:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:18:20 INFO - Running setup.py (path:/tmp/pip-xiSTOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:18:20 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)) 23:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:18:20 INFO - Running setup.py (path:/tmp/pip-9Ow64u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:18:20 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)) 23:18:20 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)) 23:18:20 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)) 23:18:20 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)) 23:18:20 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)) 23:18:20 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)) 23:18:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:22 INFO - Downloading blessings-1.6.tar.gz 23:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:18:22 INFO - Installing collected packages: blessings 23:18:22 INFO - Running setup.py install for blessings 23:18:22 INFO - Successfully installed blessings 23:18:22 INFO - Cleaning up... 23:18:22 INFO - Return code: 0 23:18:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:18:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:22 INFO - Reading from file tmpfile_stdout 23:18:22 INFO - Current package versions: 23:18:22 INFO - argparse == 1.2.1 23:18:22 INFO - blessings == 1.6 23:18:22 INFO - blobuploader == 1.2.4 23:18:22 INFO - docopt == 0.6.1 23:18:22 INFO - manifestparser == 1.1 23:18:22 INFO - mozInstall == 1.12 23:18:22 INFO - mozcrash == 0.17 23:18:22 INFO - mozdebug == 0.1 23:18:22 INFO - mozdevice == 0.48 23:18:22 INFO - mozfile == 1.2 23:18:22 INFO - mozhttpd == 0.7 23:18:22 INFO - mozinfo == 0.9 23:18:22 INFO - mozleak == 0.1 23:18:22 INFO - mozlog == 3.2 23:18:22 INFO - moznetwork == 0.27 23:18:22 INFO - mozprocess == 0.22 23:18:22 INFO - mozprofile == 0.28 23:18:22 INFO - mozrunner == 6.11 23:18:22 INFO - mozscreenshot == 0.1 23:18:22 INFO - mozsystemmonitor == 0.3 23:18:22 INFO - moztest == 0.7 23:18:22 INFO - mozversion == 1.4 23:18:22 INFO - psutil == 3.1.1 23:18:22 INFO - requests == 1.2.3 23:18:22 INFO - wsgiref == 0.1.2 23:18:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:22 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 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:22 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 23:18:22 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 23:18:22 INFO - Using env: {'DISPLAY': ':0', 23:18:22 INFO - 'HOME': '/home/cltbld', 23:18:22 INFO - 'LANG': 'en_US.UTF-8', 23:18:22 INFO - 'LANGUAGE': 'en_US:en', 23:18:22 INFO - 'LOGNAME': 'cltbld', 23:18:22 INFO - 'MAIL': '/var/mail/cltbld', 23:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:22 INFO - 'MOZ_NO_REMOTE': '1', 23:18:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:22 INFO - 'NO_EM_RESTART': '1', 23:18:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:22 INFO - 'PWD': '/builds/slave/test', 23:18:22 INFO - 'SHELL': '/bin/bash', 23:18:22 INFO - 'SHLVL': '1', 23:18:22 INFO - 'TERM': 'linux', 23:18:22 INFO - 'TMOUT': '86400', 23:18:22 INFO - 'USER': 'cltbld', 23:18:22 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:22 INFO - '_': '/tools/buildbot/bin/python'} 23:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 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)) 23:18:23 INFO - Cleaning up... 23:18:23 INFO - Return code: 0 23:18:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:23 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 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:23 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 23:18:23 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 23:18:23 INFO - Using env: {'DISPLAY': ':0', 23:18:23 INFO - 'HOME': '/home/cltbld', 23:18:23 INFO - 'LANG': 'en_US.UTF-8', 23:18:23 INFO - 'LANGUAGE': 'en_US:en', 23:18:23 INFO - 'LOGNAME': 'cltbld', 23:18:23 INFO - 'MAIL': '/var/mail/cltbld', 23:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:23 INFO - 'MOZ_NO_REMOTE': '1', 23:18:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:23 INFO - 'NO_EM_RESTART': '1', 23:18:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:23 INFO - 'PWD': '/builds/slave/test', 23:18:23 INFO - 'SHELL': '/bin/bash', 23:18:23 INFO - 'SHLVL': '1', 23:18:23 INFO - 'TERM': 'linux', 23:18:23 INFO - 'TMOUT': '86400', 23:18:23 INFO - 'USER': 'cltbld', 23:18:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:23 INFO - '_': '/tools/buildbot/bin/python'} 23:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:23 INFO - Downloading/unpacking jsonschema 23:18: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 23:18: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 23:18: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 23:18: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 23:18: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 23:18: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 23:18:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:18:25 INFO - Downloading/unpacking functools32 (from jsonschema) 23:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:25 INFO - Downloading functools32-3.2.3-2.tar.gz 23:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:18:25 INFO - warning: no files found matching '*.txt' 23:18:25 INFO - no previously-included directories found matching 'build' 23:18:25 INFO - no previously-included directories found matching 'dist' 23:18:25 INFO - no previously-included directories found matching '.git*' 23:18:25 INFO - Installing collected packages: jsonschema, functools32 23:18:25 INFO - Running setup.py install for functools32 23:18:25 INFO - warning: no files found matching '*.txt' 23:18:25 INFO - no previously-included directories found matching 'build' 23:18:25 INFO - no previously-included directories found matching 'dist' 23:18:25 INFO - no previously-included directories found matching '.git*' 23:18:25 INFO - Successfully installed jsonschema functools32 23:18:25 INFO - Cleaning up... 23:18:25 INFO - Return code: 0 23:18:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:25 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 0x7fbf441cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, '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': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', '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 23:18:25 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 23:18:25 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 23:18:25 INFO - Using env: {'DISPLAY': ':0', 23:18:25 INFO - 'HOME': '/home/cltbld', 23:18:25 INFO - 'LANG': 'en_US.UTF-8', 23:18:25 INFO - 'LANGUAGE': 'en_US:en', 23:18:25 INFO - 'LOGNAME': 'cltbld', 23:18:25 INFO - 'MAIL': '/var/mail/cltbld', 23:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:25 INFO - 'MOZ_NO_REMOTE': '1', 23:18:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:25 INFO - 'NO_EM_RESTART': '1', 23:18:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:25 INFO - 'PWD': '/builds/slave/test', 23:18:25 INFO - 'SHELL': '/bin/bash', 23:18:25 INFO - 'SHLVL': '1', 23:18:25 INFO - 'TERM': 'linux', 23:18:25 INFO - 'TMOUT': '86400', 23:18:25 INFO - 'USER': 'cltbld', 23:18:25 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:25 INFO - '_': '/tools/buildbot/bin/python'} 23:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:25 INFO - Downloading/unpacking flake8 23:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:18:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:18:27 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 23:18:27 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 23:18:27 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 23:18:27 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 23:18:27 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 23:18:27 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 23:18:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:18:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:18:28 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 23:18:28 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 23:18:28 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 23:18:28 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 23:18:28 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 23:18:28 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 23:18:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:18:28 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 23:18:28 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 23:18:28 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 23:18:28 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 23:18:28 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 23:18:28 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 23:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:18:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:28 INFO - no previously-included directories found matching 'docs/_build' 23:18:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:18:28 INFO - Running setup.py install for pep8 23:18:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:28 INFO - no previously-included directories found matching 'docs/_build' 23:18:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:18:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:18:28 INFO - Cleaning up... 23:18:28 INFO - Return code: 0 23:18:28 INFO - Running post-action listener: _resource_record_post_action 23:18:28 INFO - Running post-action listener: _start_resource_monitoring 23:18:28 INFO - Starting resource monitoring. 23:18:28 INFO - ##### 23:18:28 INFO - ##### Finished create-virtualenv step (success) 23:18:28 INFO - ##### 23:18:28 INFO - ##### 23:18:28 INFO - ##### Running install step. 23:18:28 INFO - ##### 23:18:28 INFO - Running pre-action listener: _resource_record_pre_action 23:18:28 INFO - Running main action method: install 23:18:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:29 INFO - Reading from file tmpfile_stdout 23:18:29 INFO - Detecting whether we're running mozinstall >=1.0... 23:18:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:18:29 INFO - Reading from file tmpfile_stdout 23:18:29 INFO - Output received: 23:18:29 INFO - Usage: mozinstall [options] installer 23:18:29 INFO - Options: 23:18:29 INFO - -h, --help show this help message and exit 23:18:29 INFO - -d DEST, --destination=DEST 23:18:29 INFO - Directory to install application into. [default: 23:18:29 INFO - "/builds/slave/test"] 23:18:29 INFO - --app=APP Application being installed. [default: firefox] 23:18:29 INFO - mkdir: /builds/slave/test/build/application 23:18:29 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'] 23:18:29 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 23:18:45 INFO - Reading from file tmpfile_stdout 23:18:45 INFO - Output received: 23:18:45 INFO - /builds/slave/test/build/application/firefox/firefox 23:18:45 INFO - Running post-action listener: _resource_record_post_action 23:18:45 INFO - ##### 23:18:45 INFO - ##### Finished install step (success) 23:18:45 INFO - ##### 23:18:45 INFO - ##### 23:18:45 INFO - ##### Running run-tests step. 23:18:45 INFO - ##### 23:18:45 INFO - Running pre-action listener: _resource_record_pre_action 23:18:45 INFO - Running main action method: run_tests 23:18:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:18:45 INFO - Python 2.7.3 23:18:45 INFO - Return code: 0 23:18:45 INFO - grabbing minidump binary from tooltool 23:18:45 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')]} 23:18:45 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 0x121a9c0>, '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 0x121b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121bd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:18:45 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 23:18:45 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 23:18:45 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 23:18:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:18:45 INFO - Return code: 0 23:18:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:18:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:18:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:18:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:18:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:18:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:18:46 INFO - Return code: 0 23:18:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 23:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 23:18:46 INFO - Using env: {'DISPLAY': ':0', 23:18:46 INFO - 'HOME': '/home/cltbld', 23:18:46 INFO - 'LANG': 'en_US.UTF-8', 23:18:46 INFO - 'LANGUAGE': 'en_US:en', 23:18:46 INFO - 'LOGNAME': 'cltbld', 23:18:46 INFO - 'MAIL': '/var/mail/cltbld', 23:18:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:18:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:46 INFO - 'MOZ_NO_REMOTE': '1', 23:18:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:18:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:46 INFO - 'NO_EM_RESTART': '1', 23:18:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:46 INFO - 'PWD': '/builds/slave/test', 23:18:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:18:46 INFO - 'SHELL': '/bin/bash', 23:18:46 INFO - 'SHLVL': '1', 23:18:46 INFO - 'TERM': 'linux', 23:18:46 INFO - 'TMOUT': '86400', 23:18:46 INFO - 'USER': 'cltbld', 23:18:46 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776', 23:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:46 INFO - '_': '/tools/buildbot/bin/python'} 23:18:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 23:18:46 INFO - mozversion application_buildid: 20160525203033 23:18:46 INFO - mozversion application_changeset: 8dfd7c9d86397ed898d84933a3c39834f1db886f 23:18:46 INFO - mozversion application_display_name: Nightly 23:18:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:18:46 INFO - mozversion application_name: Firefox 23:18:46 INFO - mozversion application_remotingname: firefox 23:18:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:18:46 INFO - mozversion application_vendor: Mozilla 23:18:46 INFO - mozversion application_version: 49.0a1 23:18:46 INFO - mozversion platform_buildid: 20160525203033 23:18:46 INFO - mozversion platform_changeset: 8dfd7c9d86397ed898d84933a3c39834f1db886f 23:18:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:18:46 INFO - mozversion platform_version: 49.0a1 23:18:46 INFO - using testdate: 1464243526 23:18:46 INFO - actual date: 1464243526 23:18:46 INFO - starting webserver on 'localhost:45344' 23:18:46 INFO - SUITE-START | Running 2 tests 23:18:46 INFO - TEST-START | dromaeo_css 23:18:46 INFO - operating with platform_type : linux_ 23:18:46 INFO - Initialising browser for dromaeo_css test... 23:18:46 INFO - TEST-INFO | started process 5111 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJfrAyG/profile http://localhost:45344/getInfo.html) 23:18:50 INFO - PROCESS | 5111 | __metrics Screen width/height:1600/1200 23:18:50 INFO - PROCESS | 5111 | colorDepth:24 23:18:50 INFO - PROCESS | 5111 | Browser inner width/height: 1024/697 23:18:50 INFO - PROCESS | 5111 | __metrics 23:18:51 ERROR - PROCESS | 5111 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:18:52 INFO - TEST-INFO | 5111: exit 0 23:18:52 INFO - Browser initialized. 23:18:52 INFO - Running cycle 1/1 for dromaeo_css test... 23:18:52 INFO - TEST-INFO | started process 5200 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJfrAyG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:19:04 INFO - PROCESS | 5200 | RSS: Main: 155123712 23:19:04 INFO - PROCESS | 5200 | 23:19:55 INFO - PROCESS | 5200 | 1464243595304 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] 23:22:02 INFO - PROCESS | 5200 | Cycle 1(1): loaded http://localhost:45344/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45344/tests/dromaeo/cssquery-ext.html) 23:22:04 INFO - PROCESS | 5200 | RSS: Main: 296079360 23:22:04 INFO - PROCESS | 5200 | 23:25:06 INFO - PROCESS | 5200 | Cycle 1(1): loaded http://localhost:45344/tests/dromaeo/cssquery-ext.html (next: http://localhost:45344/tests/dromaeo/cssquery-jquery.html) 23:25:07 INFO - PROCESS | 5200 | RSS: Main: 237985792 23:25:07 INFO - PROCESS | 5200 | 23:28:09 INFO - PROCESS | 5200 | Cycle 1(1): loaded http://localhost:45344/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45344/tests/dromaeo/cssquery-mootools.html) 23:28:09 INFO - PROCESS | 5200 | RSS: Main: 235708416 23:28:09 INFO - PROCESS | 5200 | 23:31:11 INFO - PROCESS | 5200 | Cycle 1(1): loaded http://localhost:45344/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45344/tests/dromaeo/cssquery-prototype.html) 23:31:12 INFO - PROCESS | 5200 | RSS: Main: 235016192 23:31:12 INFO - PROCESS | 5200 | 23:34:15 INFO - PROCESS | 5200 | Cycle 1(1): loaded http://localhost:45344/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45344/tests/dromaeo/cssquery-yui.html) 23:34:15 INFO - PROCESS | 5200 | RSS: Main: 212180992 23:34:15 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | Cycle 1(1): loaded http://localhost:45344/tests/dromaeo/cssquery-yui.html (next: http://localhost:45344/tests/dromaeo/cssquery-dojo.html) 23:37:18 INFO - PROCESS | 5200 | RSS: Main: 275603456 23:37:18 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | __start_tp_report 23:37:18 INFO - PROCESS | 5200 | _x_x_mozilla_page_load 23:37:18 INFO - PROCESS | 5200 | _x_x_mozilla_page_load_details 23:37:18 INFO - PROCESS | 5200 | |i|pagename|runs| 23:37:18 INFO - PROCESS | 5200 | |0;dojo.html;3093.5514350419185;3105.7825952183343;3134.3731253749256;3145.4809956357203;3173.30187358781;5268.9473105268935;5334.199870019499;5393.406725260228;5436.075867102592;5472.179173124034;5328.17413300939;5337.1994200869885;5345.812896548621;5370.3287089113865;5448.727563621823;4757.381540399743;4858.319835223054;4866.853994380156;4874.122657921591;5058.595312375001;4949.8020079196785;4974.179260422026;4997.225430058346;5019.573336266421;5030.320906677606;4785.569298763111;4799.808007679689;4817.3737414136285;4874.171390863562;4885.682430642032;4833.685810422347;4844.224924012176;4848.0788650156355;4856.611471082305;4969.900601987976;4846.878828029292;4872.878178045542;4890.486498917598;4898.8775280618265;4944.579710724593;5224.294720212761;5263.842084737464;5351.17056856184;5351.759170837283;5371.892562148774;5838.2118414013985;5886.676232807194;5900.734466948998;5909;5909.084091965752;5885.176075349455;5910.054391297372;5920.496757775594;5946.762129514815;5966.880662386771;5294.6029047821185;5385.380681221632;5443.5100840924115;5466.34403871538;5556.361018482846;5989.311229208697;6050.54620903434;6053.697315134314;6055.878882422415;6056.121862329937;5790.36306006339;5798.333191682926;5807.767689292381;5810.360860305363;5810.389909059572;5599.1321345190745;5715.457031581993;5772.797952071742;5776.913346299809;5806.332271788799;5777.335606405233;5782.132680097935;5792.275965504312;5798.768049278066;5807.303123625192;5592.356878958972;5744.897755212222;5747.453991870849;5808.94191058084;5816.796412125555;4309.806058727365;4366.912661746747;4375.912481750347;4417.315316126005;4437.179121862466;3939.881803545898;3946.4474973503156;3960.881173564798;3976.900577485528;3991.201759648024;5290.6296559241255;5485.149801780731;5566.888662226733;5573.804916827891;5576.525995290364;5831.154482599999;5885.470307672244;5908.14331921869;5921.644701317935;5964.850878728066;5559.8888022239325;5567.638103523258;5570.053090974544;5578.721063946787;5582;4843.612510999182;4981.32752078472;4999.550040496373;5027.145385284511;5032.043911656773;6088.543359248074;6091.8477038073515;6095.512359011268;6098.603590766586;6141.7543298268465;5893.292804863444;5893.823185304448;5895.970520147372;5904.498117659961;5912.586118971631;5868;5895.852603684943;5972.970135149297;6007.849803754766;6071.908921366271;5756.913646295226;5758.222639943721;5758.424157584208;5764.452377024176;5770.163326317576;5790.478856902899;5799;5854.648721076749;5854.853628659318;5863.091220860773;5534.806281780118;5621.80323688669;5649.067903795828;5681.375048744718;5744.942550574441;5674.546036317002;5720.485156335783;5726.885462290647;5727.971360143172;5738.397468265772;5459.2902922619805;5600.635958662674;5614.185943038155;5615.803446879503;5616.157576363578;5533.280673512418;5596.4123767003875;5620.775168993358;5648.209250704823;5681.602287839812;5554.472325129106;5562.443755624405;5577.051901176891;5577.27495425592;5579.6652200868075 23:37:18 INFO - PROCESS | 5200 | |1;ext.html;570.5606682854202;629.2166253014997;633.9461145802608;635.5138319185824;648.9026646003102;43749;44094.33858492117;44155.77922110393;44165.55834441659;44214.557854421444;45701.08597828041;46349;46434.07131857361;46455;46504.3024354635;8121.593920303991;8728.127187281285;8943.910560894405;10499.212559058102;10768.838467422995;10282.434466104361;10750.193735469871;10754.19343549233;10759;10764.300320479182;10742.624008159715;10835.945820270888;10841.512131954041;10844.132469402468;10844.240903136744;10621.78756424871;10751.462426878625;10766.138708903347;10766.676999689982;10775.35347879122;10553.208509361828;10608.787824243473;10620.044196022318;10620.097291730135;10625.628103016435;43427;43697;43729.344059839124;43915.56084439147;43915.78042109801;3666.981665091684;3809.4857194278693;3950;4072.7759973201464;4381.38660587518;33405.33189336224;33752.15619609505;34293.82853085719;34563.82718086419;35271.64728352735;4362.541933097011;4368.410264614268;4394.428724265825;4525.592696657317;4536.478304994935;381650.9999999972;388355;389469;389677;392163;64863.35136648668;65394;65585.67207163933;65636;65689;66754.3324566748;66794;67109;67303.3269667297;67354.32645673481;17707.645847082986;17891.105444727713;17906.641867162583;17930.731039034425;17944.461666150037;12664;12678;12695.04787140968;12698.174618649758;12703.110782245154;17659.74707706991;20236.595268094556;20241.16623672687;20274.594508109756;20324.69512957307;7185.6047917364;7355.521891077063;7429.182789893108;7502.6623801929045;7508.856433343178;984.3257368702461;1097.4841824342545;1117.804384232756;1147.5180424221846;1182.3024415594841;33173;33380.66619333824;33605.83197083999;33685.15787105293;33824.32351352959;1054.6361505280665;1118.9328640281446;1142.5943789954613;1176.5587904535798;1201.141184053406;16575.83424165767;17191.540243629075;17380.65238695219;17399.56501087483;17497.375393691047;579342;582549;585809;586975;588953;59443.70278148668;59992;60057;60157;60161.39838601558;1302.7524770293614;1373.8351397832323;1375.4773186189384;1387.9097858638954;1390.1936876611542;31910.84044579762;32513.67486325201;32789.83605081959;32829.50755738618;32966.505502417;52243.47756522538;52251;52391.47608523866;52521.21218181654;52863.471365285855;494398;497590;497839;497853.660975421;497872;43941;44297;44731;45117.774411127735;45311.54688453073;49029.50970490249;49592;50185;51222;51258;41678;41935;42446.1510769789;43180.352294714976;43609.56390436182;41899;42018.57981420147;42633.36049959315;43202.567974319856;43493;36604.63395366012;41100.1779964405;41649.58350416457;42841.57158428376;43200.35199471947;35814.4627830588;37748.62251377451;37908.1904780897;37960.240795184494;38015.80992095022;28746.712532874404;29187.124386268446;29316.120516384544;29420.558691619215;29567.852160739058 23:37:18 INFO - PROCESS | 5200 | |2;jquery.html;387.32605266835714;389.5909295239998;393.22927062956603;401.9778912159831;419;14157.150570965749;16465;17843.73234401485;19304;19338.80661193386;14263.215523146222;15313.310901009454;15661.530154095395;17592.20835062422;17694.91152544239;5462.535684466825;5655.208270842086;5657.9717101414435;6590.50571207159;6940.548864323828;6777.7966661;6789.490788190881;6994.125734283215;7002.439804815603;7286.927130728678;7235.0594422725235;7249.166345870213;7291.270872912719;7294.234105418935;7302.1237451505895;7053.576785392867;7150.141982962025;7224.783256502314;7227.566346019255;7236.0593122894215;6708.329167083252;6873.415759660435;6890.552114112566;6935.72257109715;6936.132983377078;23836;24315.63526547103;24537.754622453725;25391;25681.35796605081;2201.4056204824606;2276.237460450751;2405.7714517120844;2454.2637208837277;2636.5781474963915;15454.072755634697;15582.376704931788;15777.211139442981;15867.285972131282;16129.032258064437;2056.8378865940695;2110.3457928042203;2160.34109596573;2206.4483879030245;2231.141010710872;80447.59776201082;92133;93215;95134.52432737792;95361.5231923836;8630;9244.676436324697;9355.391899526508;9397.624095036259;9415.905840941503;12341.50633974649;12341.69145771345;12375.75248495025;12390.938045309716;12412.441440135215;7383.372413344926;7629.923700762922;7836.490628109154;8042.919570804334;8139.267465928095;7570.356519695777;7948.761537153895;7997.960010199912;8155.5514446706;8223.342132629376;4570.634349252052;4613.423322084739;4629.768511574408;4631.791569379386;4670.95329046712;1900.6578815813286;1920.3662791278844;1939.8739081959627;1955.8633490168477;1984.682450807864;904.1636486250193;959.1703176752059;961.3174646001278;1056.8520407143005;1078.0351585331084;17110.401135960183;17308.394206202724;17553.824461755474;17912.731309030376;18322.358717444826;980.460746589373;1014.8579198912158;1068.540527573151;1068.615298492542;1082.2478377527543;10230.23273254509;10681.145508359468;10691.83962240567;10751.354918704903;10788.78422431547;501155;515477;519882;520080;534659;95063;99253.50373248088;99591;100045.49977250212;100385.49807250917;1012.5645972231864;1044.529961517305;1061.341967979842;1075.295681328731;1085.2023762534532;16784.16079196011;16913.66172676564;17218.569535761708;17325.30698772035;17558.29766809313;42362;42536;42597.5740242606;42947.30982933563;43148.56851431446;57351.42648573628;58049.70975145097;59227;59971.400285996584;60165.39834601773;8168.264856162974;8497.107803680525;8573.571321434029;8600.956995214983;8645.481271123752;12492.562760303346;12654.493820247088;12881.227126372447;12956.35218239103;13010;9639.373440726331;10106.444145572064;11155;11222.270552414067;11270.211085223955;10588.417637030037;10854.457277135954;11005.229633925548;11537.307761534335;11751.059915206934;11994.220375675553;12037.578684746342;12106.242733609964;12110.333931633844;12228.75542489163;7326.7069317226715;8485.787855303666;8690.565471726262;8737;8752.474851508676;11536.365499897585;11777.587784427504;12160.574379896661;12185.878141218474;12191.146619736533 23:37:18 INFO - PROCESS | 5200 | |3;mootools.html;1343.476044342706;1468.4199741102261;1519.7340465418552;1637.9836201637988;1784.9286028558859;8549.487030778158;8933.419327743692;8960.596773145217;9138.223251023672;9145.314101442402;8772.912270877305;8950.239229665487;8956.283497320215;8966.86549701755;8967.10328967103;1967;2023.7470316210474;2028.9695654565194;2300.309907027889;2327.7788610082016;2308.3998160478227;2391.055533064447;2394.413368724665;2402.4954759500524;2407.7471865454136;2293.3693234360517;2327.72067351918;2340.7776261255153;2359.6696462495265;2362.3031205794246;2015.5263513074563;2076.252549082329;2259.4464356232666;2288.874111923852;2293.621552443845;2043.0601923115385;2196.3740334004733;2240.271911628727;2244.5062086340977;2258.5708715344194;8777.7805554861;8878;8886;8892;8892.021877593459;5088.7964481420695;5222.608304377187;5225.033368826779;5226.05930932432;5229.058769421503;3274.1323549259464;3333.783304085251;3336.3627785475433;3351.514030465569;3353.513740507637;1061.8725752909622;1062.6493257225097;1073.0396295315727;1132.779108073926;1150.7295785490403;12506.124571280014;12519.93740031294;12537.686557836036;12539.937300313532;12543.874561254363;4386.780660966939;4511.8646440606835;4522.8643140705835;4620.445546534437;4628.907421851544;10539;10554.208434367452;10558.208134389952;10567.577296908192;10578.047975682226;5196.974015129976;5199.038177937094;5203.765830537635;5203.765830537635;5208.557272631869;4002.779847108379;4658.790354433991;4731.361266229083;4762.3808904842845;4828.975855120702;3822.961770382316;3914.8434062637743;3937.822797974098;3955.5055618047745;4051.534073581517;1210.3887536793864;1217.2148963918223;1220.7680540697238;1221.2245224282644;1222.5965431407615;926.2543652359915;930.1721467893581;934.56075644447;941.430434587065;953.1802649721234;5495.642783219078;5512.476314750093;5567.498925096761;5639.351474580394;5653.321601407857;422.14726252969444;427.4827458774855;430.262100497648;434.9195398851223;446.6605379911291;3724.9627503724614;3733.0294123527533;3794.2221844521805;3795.7342985991268;3815.751976121543;10863.51114199863;11238.606648767254;11368.431578421047;11376.033037191766;11409.714757130972;11114.388708621103;11166;11184.049355804686;11186.608468703718;11199.096153653898;8212.671493140208;8326.209010144026;8524.147585241426;8536.231739143506;8540.871886921701;5555.416681248573;5619.185218143428;5622.7750889963945;5679.460451257124;5778.797742079175;10027.949860250652;10041.196704263788;10306.433146177033;10317.690469285933;10357.533910974174;10334.224933129895;10426.843597345895;10517.526711297856;10527.420991845522;10529.842052369373;7214.494985350975;7401.1488678802625;7401.59291238987;7739.18738532446;7960.4825686330205;8193.959030204811;8400.327973762207;8401.411901166859;8655.17775811297;8687.60905759255;7674.117476490052;7690.884636730565;7748.070231572247;7915;7949.562774047483;7331.266873312626;7438.702451901863;7487.6630551626395;7689.384849211938;7811.843763124592;6713.395794378529;6774.491913106635;6830.8633827324165;6833.41915937131;6833.453323734188;4728.929066064081;4852.344933433941;4948.480409556944;4949.950500494949;4951.628627852998;4978.377702787152;4979.701217926791;5066.265391518135;5121.8463446096675;5168.431472538093 23:37:18 INFO - PROCESS | 5200 | |4;prototype.html;312.1883103929782;315.0941044497071;315.68431568431566;316.86849957267736;319.17174931053904;5319.893602127955;5358.732063396834;5366.221897824823;5370.43610420906;5387.676739395639;5251.186066159751;5291.1798671206025;5300.310959575259;5317.2821669074665;5319.414864364917;3928.744631598937;3961.841526338943;4015.738976966488;4044.413560033793;4057.8579749708765;4183.142455796555;4183.330667093266;4192.895177620569;4200.306949353353;4217.536071032184;4109.903948986023;4136.379543068534;4141.917161656765;4141.9585804142025;4160.687948403866;4054.371572406267;4120.629143377096;4150.024744185114;4166.812493437772;4175.645070169039;3656.6708996190205;3659.335002573849;3668.523091998023;3671.5961244263376;3690.3910854708943;5047.722375269359;5068.41713202979;5088.1350170471105;5102.749826292545;5103.851633382496;4656.075255284605;4671.602913752336;4721.669483136182;4740.715557066553;4760.690555113906;5515.4760297772045;5526.585506087019;5527.170924361338;5528.640638358494;5544.417836127189;4004.159126583421;4005.399190121476;4025.3961905714086;4043.716939814214;4070.2266569351727;5581.748821303051;5594.216809646653;5596.916046259309;5709.658230315872;5770.9422905770825;4062.2484840304046;4119.588041195856;4139.2135494256;4140.109876376593;4143.606357396103;4180.811863466151;4209.116085621992;4214.936775948363;4236.110416812504;4248.87253382399;4545.204644165536;4714.727023703649;4797.832075877328;4813.315339631171;4815.15734746418;5657.886842263131;5673.205751194836;5684.573656975744;5689.317281926113;5723.656580605177;1406.8100806391005;1423.1603354021177;1424.166862385516;1425.1164278147407;1429.3639330497829;899.3299991506238;912.6212621762027;913.456493386434;918.93108016899;939.7227817793768;4979.701217926936;5117.565006974449;5250.632455728062;5377.946220537823;5379.354477462652;5669.43305669438;5672.631278966854;5686.800961966312;5699.487046165865;5717.571182161355;5340.946590534045;5365.409804921455;5375.489328513785;5378.042076857712;5392.083345831218;2943.45546073977;3081.4145312390574;3082.6300843898425;3085.3983473222506;3100.1784527100335;5943.257092863392;5958.731857066442;5974.641521508724;5994.250718660168;6003.519718422515;5788.999999999915;5822.214001109881;5845.678487683133;5863.648181109147;5921.851953701192;5930.2883653960935;5947.762089516371;5961.880762384728;5997.7001149942325;6019.638821670714;5452.972735136457;5522.917156242579;5672.404397538199;5687.1753595729215;5692.857678558069;5545.306836645419;5653.632513886584;5686;5688.805350876362;5734.455226753452;5849.766009359578;5865.178874957424;5969.52243820502;5986.55100867428;5987.401259873977;4033.4353190553934;4054.533728621246;4073.431728784499;4097.590240975878;4115.5678653742125;5813.854653633693;5822.679752613647;5848.795292164754;5855.677937713296;5857.414258574108;5517.69652669107;5610.074337734228;5623.971880140573;5698.6010979232715;5723.771049157987;5591.6365436246515;5593.328800543961;5596.776128954796;5596.832095037155;5644.266245388073;5583.357913839798;5620.437956204347;5627.324721033502;5627.831165065055;5629.101012978402;5439.292891924025;5710.314762228559;5712.942870571241;5720.141978703228;5742.942570574408;5559.944400555943;5585.413531579288;5588.748506317144;5592.664440133605;5613.691246981455 23:37:18 INFO - PROCESS | 5200 | |5;yui.html;730.784418596514;768.8923550702906;797.7367468735318;802.8193656427304;815.6207363575936;13878;15074.170920599363;15120.54638360851;15264.236788160602;15327.69344613107;13254.138480998728;13299.800502992439;13348.0656354055;13360.799588006188;13362.599122026331;677.1096008748505;810.8094597769534;823.0082750285891;823.180934969706;824.72371755462;787.4999375396636;788.7002938883217;790.1348023913833;811.1766556944706;812.2689579378577;849.4987957105307;850.26452118917;852.2457625001887;852.3947996922193;852.9872051919226;708.2810946888912;839.5718183726343;847.2501835875233;848.4909054567241;849.3417601358965;613.3467856732566;613.4846728747873;613.8250598579428;614.253681776639;621.3941407128059;14556.053856499293;14587;14607.634809129857;14617.196054217015;14646.487372942003;13499.932500337436;13882.583522494342;14091.647708807259;14207.502737404142;14286.571402857931;12084.27494350333;12152.392380381034;12250.326232057234;12346.135770496068;12399.504019839196;4448.466184057901;4487.080148569536;4574.862754117382;4579.702319349231;4693.061387722435;62593.37406625921;63370;63446;64175.679121604095;66119.3388066118;10508.106810921005;10576.312539684895;10652.733681658021;10741.462926853626;11113.83329250062;18689.532761680795;20949.790502094784;20950.790492094882;20950.89524552398;20980.370588882357;8043.436959412902;8090.352771778244;8333.208345207195;8588;8681.696140635173;4676.298555216745;4688.0858232644305;4700.55344742249;4882.462929077798;4888.804447822047;3354.765166438326;3421.5551978243166;3423.383790917609;3434.862605495802;3445.896623101311;554.464941331617;568.9829305120853;577.2755192233719;578.5805291163872;583.76941108262;564.8361975027196;721.4841388407314;770.5761830993043;776.7320274505287;790.4071946040469;9391.483468409166;9722.611095556098;10001.24990625706;10016.899831001743;10044.748881278027;638.8147437243148;709.3013381818904;749.5502698380927;762.3900879296541;765.7434759355567;4641.721496710208;4732.479427263067;4893.388326459192;4896.559309662076;4903.436104847988;62676;64565.67717161384;64857.35142648607;73644;74923.62538187383;62113.68943155346;63220;63238.68380658159;64682.35317646763;64777;58502.414975850545;59505.70247148736;59659;60204.698976504835;60935.39064609386;9890.625311857597;10039.397636141855;10358.844617330897;10389.324693894872;10440.530176142243;39748.820094392955;39791.60208397879;40020.399694005195;40113.799431002655;40194;47267.52732472631;53498.46501535091;53791.731041344545;54045;54542;11692.298462092302;12257.570985015482;12622.495100196256;12661.303628300164;12754.808677869654;13326.33368331599;13418;13771.724565508834;14117.08238964464;14361.64095897611;13676.93161534186;13879;13904.096233744775;13977.650558735706;14344.713105738036;11863.76272474563;11902.285862848257;12710;13695.931520342732;14104.435822566982;12580.685482863002;12902.419391127234;13541.729165416833;13733;13750.449982000608;10667.079615956924;10709.732256693645;11198.272112312674;11233.71915702114;11394;11176.608818691308;11435.428228588704;11447.771044578894;11542;11719.589814356457 23:37:18 INFO - PROCESS | 5200 | __end_tp_report 23:37:18 INFO - PROCESS | 5200 | __start_cc_report 23:37:18 INFO - PROCESS | 5200 | _x_x_mozilla_cycle_collect,1211 23:37:18 INFO - PROCESS | 5200 | __end_cc_report 23:37:18 INFO - PROCESS | 5200 | __startTimestamp1464244638891__endTimestamp 23:37:18 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | ------- Summary: start ------- 23:37:18 INFO - PROCESS | 5200 | Number of tests: 6 23:37:18 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | [#0] dojo.html Cycles:165 Average:5394.04 Median:5582.00 stddev:635.23 (11.4%) stddev-sans-first:611.00 23:37:18 INFO - PROCESS | 5200 | Values: 3093.6 3105.8 3134.4 3145.5 3173.3 5268.9 5334.2 5393.4 5436.1 5472.2 5328.2 5337.2 5345.8 5370.3 5448.7 4757.4 4858.3 4866.9 4874.1 5058.6 4949.8 4974.2 4997.2 5019.6 5030.3 4785.6 4799.8 4817.4 4874.2 4885.7 4833.7 4844.2 4848.1 4856.6 4969.9 4846.9 4872.9 4890.5 4898.9 4944.6 5224.3 5263.8 5351.2 5351.8 5371.9 5838.2 5886.7 5900.7 5909.0 5909.1 5885.2 5910.1 5920.5 5946.8 5966.9 5294.6 5385.4 5443.5 5466.3 5556.4 5989.3 6050.5 6053.7 6055.9 6056.1 5790.4 5798.3 5807.8 5810.4 5810.4 5599.1 5715.5 5772.8 5776.9 5806.3 5777.3 5782.1 5792.3 5798.8 5807.3 5592.4 5744.9 5747.5 5808.9 5816.8 4309.8 4366.9 4375.9 4417.3 4437.2 3939.9 3946.4 3960.9 3976.9 3991.2 5290.6 5485.1 5566.9 5573.8 5576.5 5831.2 5885.5 5908.1 5921.6 5964.9 5559.9 5567.6 5570.1 5578.7 5582.0 4843.6 4981.3 4999.6 5027.1 5032.0 6088.5 6091.8 6095.5 6098.6 6141.8 5893.3 5893.8 5896.0 5904.5 5912.6 5868.0 5895.9 5973.0 6007.8 6071.9 5756.9 5758.2 5758.4 5764.5 5770.2 5790.5 5799.0 5854.6 5854.9 5863.1 5534.8 5621.8 5649.1 5681.4 5744.9 5674.5 5720.5 5726.9 5728.0 5738.4 5459.3 5600.6 5614.2 5615.8 5616.2 5533.3 5596.4 5620.8 5648.2 5681.6 5554.5 5562.4 5577.1 5577.3 5579.7 23:37:18 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | [#1] ext.html Cycles:180 Average:66035.60 Median:22586.48 stddev:131728.84 (583.2%) stddev-sans-first:132006.67 23:37:18 INFO - PROCESS | 5200 | Values: 570.6 629.2 633.9 635.5 648.9 43749.0 44094.3 44155.8 44165.6 44214.6 45701.1 46349.0 46434.1 46455.0 46504.3 8121.6 8728.1 8943.9 10499.2 10768.8 10282.4 10750.2 10754.2 10759.0 10764.3 10742.6 10835.9 10841.5 10844.1 10844.2 10621.8 10751.5 10766.1 10766.7 10775.4 10553.2 10608.8 10620.0 10620.1 10625.6 43427.0 43697.0 43729.3 43915.6 43915.8 3667.0 3809.5 3950.0 4072.8 4381.4 33405.3 33752.2 34293.8 34563.8 35271.6 4362.5 4368.4 4394.4 4525.6 4536.5 381651.0 388355.0 389469.0 389677.0 392163.0 64863.4 65394.0 65585.7 65636.0 65689.0 66754.3 66794.0 67109.0 67303.3 67354.3 17707.6 17891.1 17906.6 17930.7 17944.5 12664.0 12678.0 12695.0 12698.2 12703.1 17659.7 20236.6 20241.2 20274.6 20324.7 7185.6 7355.5 7429.2 7502.7 7508.9 984.3 1097.5 1117.8 1147.5 1182.3 33173.0 33380.7 33605.8 33685.2 33824.3 1054.6 1118.9 1142.6 1176.6 1201.1 16575.8 17191.5 17380.7 17399.6 17497.4 579342.0 582549.0 585809.0 586975.0 588953.0 59443.7 59992.0 60057.0 60157.0 60161.4 1302.8 1373.8 1375.5 1387.9 1390.2 31910.8 32513.7 32789.8 32829.5 32966.5 52243.5 52251.0 52391.5 52521.2 52863.5 494398.0 497590.0 497839.0 497853.7 497872.0 43941.0 44297.0 44731.0 45117.8 45311.5 49029.5 49592.0 50185.0 51222.0 51258.0 41678.0 41935.0 42446.2 43180.4 43609.6 41899.0 42018.6 42633.4 43202.6 43493.0 36604.6 41100.2 41649.6 42841.6 43200.4 35814.5 37748.6 37908.2 37960.2 38015.8 28746.7 29187.1 29316.1 29420.6 29567.9 23:37:18 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | [#2] jquery.html Cycles:180 Average:30317.38 Median:14158.97 stddev:85657.50 (605.0%) stddev-sans-first:85868.31 23:37:18 INFO - PROCESS | 5200 | Values: 387.3 389.6 393.2 402.0 419.0 14157.2 16465.0 17843.7 19304.0 19338.8 14263.2 15313.3 15661.5 17592.2 17694.9 5462.5 5655.2 5658.0 6590.5 6940.5 6777.8 6789.5 6994.1 7002.4 7286.9 7235.1 7249.2 7291.3 7294.2 7302.1 7053.6 7150.1 7224.8 7227.6 7236.1 6708.3 6873.4 6890.6 6935.7 6936.1 23836.0 24315.6 24537.8 25391.0 25681.4 2201.4 2276.2 2405.8 2454.3 2636.6 15454.1 15582.4 15777.2 15867.3 16129.0 2056.8 2110.3 2160.3 2206.4 2231.1 80447.6 92133.0 93215.0 95134.5 95361.5 8630.0 9244.7 9355.4 9397.6 9415.9 12341.5 12341.7 12375.8 12390.9 12412.4 7383.4 7629.9 7836.5 8042.9 8139.3 7570.4 7948.8 7998.0 8155.6 8223.3 4570.6 4613.4 4629.8 4631.8 4671.0 1900.7 1920.4 1939.9 1955.9 1984.7 904.2 959.2 961.3 1056.9 1078.0 17110.4 17308.4 17553.8 17912.7 18322.4 980.5 1014.9 1068.5 1068.6 1082.2 10230.2 10681.1 10691.8 10751.4 10788.8 501155.0 515477.0 519882.0 520080.0 534659.0 95063.0 99253.5 99591.0 100045.5 100385.5 1012.6 1044.5 1061.3 1075.3 1085.2 16784.2 16913.7 17218.6 17325.3 17558.3 42362.0 42536.0 42597.6 42947.3 43148.6 57351.4 58049.7 59227.0 59971.4 60165.4 8168.3 8497.1 8573.6 8601.0 8645.5 12492.6 12654.5 12881.2 12956.4 13010.0 9639.4 10106.4 11155.0 11222.3 11270.2 10588.4 10854.5 11005.2 11537.3 11751.1 11994.2 12037.6 12106.2 12110.3 12228.8 7326.7 8485.8 8690.6 8737.0 8752.5 11536.4 11777.6 12160.6 12185.9 12191.1 23:37:18 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | [#3] mootools.html Cycles:180 Average:5769.85 Median:2416.72 stddev:3417.28 (141.4%) stddev-sans-first:3410.68 23:37:18 INFO - PROCESS | 5200 | Values: 1343.5 1468.4 1519.7 1638.0 1784.9 8549.5 8933.4 8960.6 9138.2 9145.3 8772.9 8950.2 8956.3 8966.9 8967.1 1967.0 2023.7 2029.0 2300.3 2327.8 2308.4 2391.1 2394.4 2402.5 2407.7 2293.4 2327.7 2340.8 2359.7 2362.3 2015.5 2076.3 2259.4 2288.9 2293.6 2043.1 2196.4 2240.3 2244.5 2258.6 8777.8 8878.0 8886.0 8892.0 8892.0 5088.8 5222.6 5225.0 5226.1 5229.1 3274.1 3333.8 3336.4 3351.5 3353.5 1061.9 1062.6 1073.0 1132.8 1150.7 12506.1 12519.9 12537.7 12539.9 12543.9 4386.8 4511.9 4522.9 4620.4 4628.9 10539.0 10554.2 10558.2 10567.6 10578.0 5197.0 5199.0 5203.8 5203.8 5208.6 4002.8 4658.8 4731.4 4762.4 4829.0 3823.0 3914.8 3937.8 3955.5 4051.5 1210.4 1217.2 1220.8 1221.2 1222.6 926.3 930.2 934.6 941.4 953.2 5495.6 5512.5 5567.5 5639.4 5653.3 422.1 427.5 430.3 434.9 446.7 3725.0 3733.0 3794.2 3795.7 3815.8 10863.5 11238.6 11368.4 11376.0 11409.7 11114.4 11166.0 11184.0 11186.6 11199.1 8212.7 8326.2 8524.1 8536.2 8540.9 5555.4 5619.2 5622.8 5679.5 5778.8 10027.9 10041.2 10306.4 10317.7 10357.5 10334.2 10426.8 10517.5 10527.4 10529.8 7214.5 7401.1 7401.6 7739.2 7960.5 8194.0 8400.3 8401.4 8655.2 8687.6 7674.1 7690.9 7748.1 7915.0 7949.6 7331.3 7438.7 7487.7 7689.4 7811.8 6713.4 6774.5 6830.9 6833.4 6833.5 4728.9 4852.3 4948.5 4950.0 4951.6 4978.4 4979.7 5066.3 5121.8 5168.4 23:37:18 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | [#4] prototype.html Cycles:180 Average:4710.28 Median:5377.99 stddev:1393.25 (25.9%) stddev-sans-first:1357.49 23:37:18 INFO - PROCESS | 5200 | Values: 312.2 315.1 315.7 316.9 319.2 5319.9 5358.7 5366.2 5370.4 5387.7 5251.2 5291.2 5300.3 5317.3 5319.4 3928.7 3961.8 4015.7 4044.4 4057.9 4183.1 4183.3 4192.9 4200.3 4217.5 4109.9 4136.4 4141.9 4142.0 4160.7 4054.4 4120.6 4150.0 4166.8 4175.6 3656.7 3659.3 3668.5 3671.6 3690.4 5047.7 5068.4 5088.1 5102.7 5103.9 4656.1 4671.6 4721.7 4740.7 4760.7 5515.5 5526.6 5527.2 5528.6 5544.4 4004.2 4005.4 4025.4 4043.7 4070.2 5581.7 5594.2 5596.9 5709.7 5770.9 4062.2 4119.6 4139.2 4140.1 4143.6 4180.8 4209.1 4214.9 4236.1 4248.9 4545.2 4714.7 4797.8 4813.3 4815.2 5657.9 5673.2 5684.6 5689.3 5723.7 1406.8 1423.2 1424.2 1425.1 1429.4 899.3 912.6 913.5 918.9 939.7 4979.7 5117.6 5250.6 5377.9 5379.4 5669.4 5672.6 5686.8 5699.5 5717.6 5340.9 5365.4 5375.5 5378.0 5392.1 2943.5 3081.4 3082.6 3085.4 3100.2 5943.3 5958.7 5974.6 5994.3 6003.5 5789.0 5822.2 5845.7 5863.6 5921.9 5930.3 5947.8 5961.9 5997.7 6019.6 5453.0 5522.9 5672.4 5687.2 5692.9 5545.3 5653.6 5686.0 5688.8 5734.5 5849.8 5865.2 5969.5 5986.6 5987.4 4033.4 4054.5 4073.4 4097.6 4115.6 5813.9 5822.7 5848.8 5855.7 5857.4 5517.7 5610.1 5624.0 5698.6 5723.8 5591.6 5593.3 5596.8 5596.8 5644.3 5583.4 5620.4 5627.3 5627.8 5629.1 5439.3 5710.3 5712.9 5720.1 5742.9 5559.9 5585.4 5588.7 5592.7 5613.7 23:37:18 INFO - PROCESS | 5200 | 23:37:18 INFO - PROCESS | 5200 | [#5] yui.html Cycles:180 Average:16384.00 Median:4610.71 stddev:19828.03 (430.0%) stddev-sans-first:19848.81 23:37:18 INFO - PROCESS | 5200 | Values: 730.8 768.9 797.7 802.8 815.6 13878.0 15074.2 15120.5 15264.2 15327.7 13254.1 13299.8 13348.1 13360.8 13362.6 677.1 810.8 823.0 823.2 824.7 787.5 788.7 790.1 811.2 812.3 849.5 850.3 852.2 852.4 853.0 708.3 839.6 847.3 848.5 849.3 613.3 613.5 613.8 614.3 621.4 14556.1 14587.0 14607.6 14617.2 14646.5 13499.9 13882.6 14091.6 14207.5 14286.6 12084.3 12152.4 12250.3 12346.1 12399.5 4448.5 4487.1 4574.9 4579.7 4693.1 62593.4 63370.0 63446.0 64175.7 66119.3 10508.1 10576.3 10652.7 10741.5 11113.8 18689.5 20949.8 20950.8 20950.9 20980.4 8043.4 8090.4 8333.2 8588.0 8681.7 4676.3 4688.1 4700.6 4882.5 4888.8 3354.8 3421.6 3423.4 3434.9 3445.9 554.5 569.0 577.3 578.6 583.8 564.8 721.5 770.6 776.7 790.4 9391.5 9722.6 10001.2 10016.9 10044.7 638.8 709.3 749.6 762.4 765.7 4641.7 4732.5 4893.4 4896.6 4903.4 62676.0 64565.7 64857.4 73644.0 74923.6 62113.7 63220.0 63238.7 64682.4 64777.0 58502.4 59505.7 59659.0 60204.7 60935.4 9890.6 10039.4 10358.8 10389.3 10440.5 39748.8 39791.6 40020.4 40113.8 40194.0 47267.5 53498.5 53791.7 54045.0 54542.0 11692.3 12257.6 12622.5 12661.3 12754.8 13326.3 13418.0 13771.7 14117.1 14361.6 13676.9 13879.0 13904.1 13977.7 14344.7 11863.8 11902.3 12710.0 13695.9 14104.4 12580.7 12902.4 13541.7 13733.0 13750.4 10667.1 10709.7 11198.3 11233.7 11394.0 11176.6 11435.4 11447.8 11542.0 11719.6 23:37:18 INFO - PROCESS | 5200 | -------- Summary: end -------- 23:37:18 INFO - PROCESS | 5200 | 23:37:19 INFO - TEST-INFO | 5200: exit 0 23:37:19 INFO - TEST-OK | dromaeo_css | took 1112619ms 23:37:19 INFO - TEST-START | kraken 23:37:19 INFO - operating with platform_type : linux_ 23:37:19 INFO - Initialising browser for kraken test... 23:37:19 INFO - TEST-INFO | started process 5384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0aPEnj/profile http://localhost:45344/getInfo.html) 23:37:23 INFO - PROCESS | 5384 | __metrics Screen width/height:1600/1200 23:37:23 INFO - PROCESS | 5384 | colorDepth:24 23:37:23 INFO - PROCESS | 5384 | Browser inner width/height: 1024/697 23:37:23 INFO - PROCESS | 5384 | __metrics 23:37:24 ERROR - PROCESS | 5384 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:37:25 INFO - TEST-INFO | 5384: exit 0 23:37:25 INFO - Browser initialized. 23:37:25 INFO - Running cycle 1/1 for kraken test... 23:37:25 INFO - TEST-INFO | started process 5463 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0aPEnj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:37:36 INFO - PROCESS | 5463 | RSS: Main: 155602944 23:37:36 INFO - PROCESS | 5463 | 23:37:39 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45344/tests/kraken/driver.html?testName=audio-beat-detection) 23:37:40 INFO - PROCESS | 5463 | RSS: Main: 212086784 23:37:40 INFO - PROCESS | 5463 | 23:37:42 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45344/tests/kraken/driver.html?testName=audio-dft) 23:37:43 INFO - PROCESS | 5463 | RSS: Main: 200740864 23:37:43 INFO - PROCESS | 5463 | 23:37:46 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45344/tests/kraken/driver.html?testName=audio-fft) 23:37:47 INFO - PROCESS | 5463 | RSS: Main: 268644352 23:37:47 INFO - PROCESS | 5463 | 23:37:49 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45344/tests/kraken/driver.html?testName=audio-oscillator) 23:37:49 INFO - PROCESS | 5463 | RSS: Main: 177487872 23:37:49 INFO - PROCESS | 5463 | 23:37:52 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45344/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:37:52 INFO - PROCESS | 5463 | RSS: Main: 181743616 23:37:52 INFO - PROCESS | 5463 | 23:37:55 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45344/tests/kraken/driver.html?testName=imaging-darkroom) 23:37:56 INFO - PROCESS | 5463 | RSS: Main: 218116096 23:37:56 INFO - PROCESS | 5463 | 23:37:59 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45344/tests/kraken/driver.html?testName=imaging-desaturate) 23:38:00 INFO - PROCESS | 5463 | RSS: Main: 218767360 23:38:00 INFO - PROCESS | 5463 | 23:38:03 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45344/tests/kraken/driver.html?testName=json-parse-financial) 23:38:03 INFO - PROCESS | 5463 | RSS: Main: 218988544 23:38:03 INFO - PROCESS | 5463 | 23:38:05 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45344/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:38:05 INFO - PROCESS | 5463 | RSS: Main: 208531456 23:38:05 INFO - PROCESS | 5463 | 23:38:07 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45344/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:38:08 INFO - PROCESS | 5463 | RSS: Main: 208826368 23:38:08 INFO - PROCESS | 5463 | 23:38:10 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45344/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:38:10 INFO - PROCESS | 5463 | RSS: Main: 218636288 23:38:10 INFO - PROCESS | 5463 | 23:38:13 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45344/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:38:14 INFO - PROCESS | 5463 | RSS: Main: 227360768 23:38:14 INFO - PROCESS | 5463 | 23:38:16 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45344/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:38:17 INFO - PROCESS | 5463 | RSS: Main: 223109120 23:38:17 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | Cycle 1(1): loaded http://localhost:45344/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45344/tests/kraken/driver.html?testName=ai-astar) 23:38:19 INFO - PROCESS | 5463 | RSS: Main: 269258752 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | __start_tp_report 23:38:19 INFO - PROCESS | 5463 | _x_x_mozilla_page_load 23:38:19 INFO - PROCESS | 5463 | _x_x_mozilla_page_load_details 23:38:19 INFO - PROCESS | 5463 | |i|pagename|runs| 23:38:19 INFO - PROCESS | 5463 | |0;ai-astar;110;104;111;111;111;110;109;109;110;110 23:38:19 INFO - PROCESS | 5463 | |1;audio-beat-detection;162;125;128;187;132;132;152;126;169;124 23:38:19 INFO - PROCESS | 5463 | |2;audio-dft;161;160;161;161;161;159;160;161;160;161 23:38:19 INFO - PROCESS | 5463 | |3;audio-fft;74;75;73;118;73;75;76;74;107;75 23:38:19 INFO - PROCESS | 5463 | |4;audio-oscillator;82;84;119;83;84;82;120;83;83;121 23:38:19 INFO - PROCESS | 5463 | |5;imaging-gaussian-blur;118;118;118;118;118;116;117;116;117;117 23:38:19 INFO - PROCESS | 5463 | |6;imaging-darkroom;145;146;146;146;146;146;146;145;145;145 23:38:19 INFO - PROCESS | 5463 | |7;imaging-desaturate;95;95;95;95;94;94;95;97;96;95 23:38:19 INFO - PROCESS | 5463 | |8;json-parse-financial;56;53;54;53;53;53;53;72;52;52 23:38:19 INFO - PROCESS | 5463 | |9;json-stringify-tinderbox;61;62;61;62;62;61;61;61;62;62 23:38:19 INFO - PROCESS | 5463 | |10;stanford-crypto-aes;80;80;78;78;81;79;81;81;85;79 23:38:19 INFO - PROCESS | 5463 | |11;stanford-crypto-ccm;122;127;121;120;130;120;128;120;165;125 23:38:19 INFO - PROCESS | 5463 | |12;stanford-crypto-pbkdf2;159;158;160;163;161;161;160;160;164;164 23:38:19 INFO - PROCESS | 5463 | |13;stanford-crypto-sha256-iterative;65;62;63;61;61;60;62;63;60;61 23:38:19 INFO - PROCESS | 5463 | __end_tp_report 23:38:19 INFO - PROCESS | 5463 | __start_cc_report 23:38:19 INFO - PROCESS | 5463 | _x_x_mozilla_cycle_collect,1635 23:38:19 INFO - PROCESS | 5463 | __end_cc_report 23:38:19 INFO - PROCESS | 5463 | __startTimestamp1464244699319__endTimestamp 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | ------- Summary: start ------- 23:38:19 INFO - PROCESS | 5463 | Number of tests: 14 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:2.07 (1.9%) stddev-sans-first:2.19 23:38:19 INFO - PROCESS | 5463 | Values: 110.0 104.0 111.0 111.0 111.0 110.0 109.0 109.0 110.0 110.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:142.00 stddev:22.34 (15.7%) stddev-sans-first:22.69 23:38:19 INFO - PROCESS | 5463 | Values: 162.0 125.0 128.0 187.0 132.0 132.0 152.0 126.0 169.0 124.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.71 (0.4%) stddev-sans-first:0.73 23:38:19 INFO - PROCESS | 5463 | Values: 161.0 160.0 161.0 161.0 161.0 159.0 160.0 161.0 160.0 161.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#3] audio-fft Cycles:10 Average:82.00 Median:74.50 stddev:16.31 (21.9%) stddev-sans-first:17.04 23:38:19 INFO - PROCESS | 5463 | Values: 74.0 75.0 73.0 118.0 73.0 75.0 76.0 74.0 107.0 75.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:83.00 stddev:17.89 (21.6%) stddev-sans-first:18.43 23:38:19 INFO - PROCESS | 5463 | Values: 82.0 84.0 119.0 83.0 84.0 82.0 120.0 83.0 83.0 121.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:118.00 stddev:0.82 (0.7%) stddev-sans-first:0.83 23:38:19 INFO - PROCESS | 5463 | Values: 118.0 118.0 118.0 118.0 118.0 116.0 117.0 116.0 117.0 117.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 23:38:19 INFO - PROCESS | 5463 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.88 (0.9%) stddev-sans-first:0.93 23:38:19 INFO - PROCESS | 5463 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 95.0 97.0 96.0 95.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:53.00 stddev:6.05 (11.4%) stddev-sans-first:6.40 23:38:19 INFO - PROCESS | 5463 | Values: 56.0 53.0 54.0 53.0 53.0 53.0 53.0 72.0 52.0 52.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:62.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 23:38:19 INFO - PROCESS | 5463 | Values: 61.0 62.0 61.0 62.0 62.0 61.0 61.0 61.0 62.0 62.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.50 stddev:2.04 (2.5%) stddev-sans-first:2.17 23:38:19 INFO - PROCESS | 5463 | Values: 80.0 80.0 78.0 78.0 81.0 79.0 81.0 81.0 85.0 79.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:126.00 stddev:13.58 (10.8%) stddev-sans-first:14.24 23:38:19 INFO - PROCESS | 5463 | Values: 122.0 127.0 121.0 120.0 130.0 120.0 128.0 120.0 165.0 125.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.00 Median:161.00 stddev:2.05 (1.3%) stddev-sans-first:2.05 23:38:19 INFO - PROCESS | 5463 | Values: 159.0 158.0 160.0 163.0 161.0 161.0 160.0 160.0 164.0 164.0 23:38:19 INFO - PROCESS | 5463 | 23:38:19 INFO - PROCESS | 5463 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.55 (2.5%) stddev-sans-first:1.13 23:38:19 INFO - PROCESS | 5463 | Values: 65.0 62.0 63.0 61.0 61.0 60.0 62.0 63.0 60.0 61.0 23:38:19 INFO - PROCESS | 5463 | -------- Summary: end -------- 23:38:19 INFO - PROCESS | 5463 | 23:38:20 INFO - TEST-INFO | 5463: exit 0 23:38:20 INFO - TEST-OK | kraken | took 60682ms 23:38:20 INFO - SUITE-END | took 1173s 23:38:20 INFO - Completed test suite (00:19:33) 23:38:20 INFO - javascript benchmark 23:38:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3093.5514350419185, 3105.7825952183343, 3134.3731253749256, 3145.4809956357203, 3173.30187358781, 5268.9473105268935, 5334.199870019499, 5393.406725260228, 5436.075867102592, 5472.179173124034, 5328.17413300939, 5337.1994200869885, 5345.812896548621, 5370.3287089113865, 5448.727563621823, 4757.381540399743, 4858.319835223054, 4866.853994380156, 4874.122657921591, 5058.595312375001, 4949.8020079196785, 4974.179260422026, 4997.225430058346, 5019.573336266421, 5030.320906677606, 4785.569298763111, 4799.808007679689, 4817.3737414136285, 4874.171390863562, 4885.682430642032, 4833.685810422347, 4844.224924012176, 4848.0788650156355, 4856.611471082305, 4969.900601987976, 4846.878828029292, 4872.878178045542, 4890.486498917598, 4898.8775280618265, 4944.579710724593, 5224.294720212761, 5263.842084737464, 5351.17056856184, 5351.759170837283, 5371.892562148774, 5838.2118414013985, 5886.676232807194, 5900.734466948998, 5909.0, 5909.084091965752, 5885.176075349455, 5910.054391297372, 5920.496757775594, 5946.762129514815, 5966.880662386771, 5294.6029047821185, 5385.380681221632, 5443.5100840924115, 5466.34403871538, 5556.361018482846, 5989.311229208697, 6050.54620903434, 6053.697315134314, 6055.878882422415, 6056.121862329937, 5790.36306006339, 5798.333191682926, 5807.767689292381, 5810.360860305363, 5810.389909059572, 5599.1321345190745, 5715.457031581993, 5772.797952071742, 5776.913346299809, 5806.332271788799, 5777.335606405233, 5782.132680097935, 5792.275965504312, 5798.768049278066, 5807.303123625192, 5592.356878958972, 5744.897755212222, 5747.453991870849, 5808.94191058084, 5816.796412125555, 4309.806058727365, 4366.912661746747, 4375.912481750347, 4417.315316126005, 4437.179121862466, 3939.881803545898, 3946.4474973503156, 3960.881173564798, 3976.900577485528, 3991.201759648024, 5290.6296559241255, 5485.149801780731, 5566.888662226733, 5573.804916827891, 5576.525995290364, 5831.154482599999, 5885.470307672244, 5908.14331921869, 5921.644701317935, 5964.850878728066, 5559.8888022239325, 5567.638103523258, 5570.053090974544, 5578.721063946787, 5582.0, 4843.612510999182, 4981.32752078472, 4999.550040496373, 5027.145385284511, 5032.043911656773, 6088.543359248074, 6091.8477038073515, 6095.512359011268, 6098.603590766586, 6141.7543298268465, 5893.292804863444, 5893.823185304448, 5895.970520147372, 5904.498117659961, 5912.586118971631, 5868.0, 5895.852603684943, 5972.970135149297, 6007.849803754766, 6071.908921366271, 5756.913646295226, 5758.222639943721, 5758.424157584208, 5764.452377024176, 5770.163326317576, 5790.478856902899, 5799.0, 5854.648721076749, 5854.853628659318, 5863.091220860773, 5534.806281780118, 5621.80323688669, 5649.067903795828, 5681.375048744718, 5744.942550574441, 5674.546036317002, 5720.485156335783, 5726.885462290647, 5727.971360143172, 5738.397468265772, 5459.2902922619805, 5600.635958662674, 5614.185943038155, 5615.803446879503, 5616.157576363578, 5533.280673512418, 5596.4123767003875, 5620.775168993358, 5648.209250704823, 5681.602287839812, 5554.472325129106, 5562.443755624405, 5577.051901176891, 5577.27495425592, 5579.6652200868075], "value": 5349.9550941320085, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.5606682854202, 629.2166253014997, 633.9461145802608, 635.5138319185824, 648.9026646003102, 43749.0, 44094.33858492117, 44155.77922110393, 44165.55834441659, 44214.557854421444, 45701.08597828041, 46349.0, 46434.07131857361, 46455.0, 46504.3024354635, 8121.593920303991, 8728.127187281285, 8943.910560894405, 10499.212559058102, 10768.838467422995, 10282.434466104361, 10750.193735469871, 10754.19343549233, 10759.0, 10764.300320479182, 10742.624008159715, 10835.945820270888, 10841.512131954041, 10844.132469402468, 10844.240903136744, 10621.78756424871, 10751.462426878625, 10766.138708903347, 10766.676999689982, 10775.35347879122, 10553.208509361828, 10608.787824243473, 10620.044196022318, 10620.097291730135, 10625.628103016435, 43427.0, 43697.0, 43729.344059839124, 43915.56084439147, 43915.78042109801, 3666.981665091684, 3809.4857194278693, 3950.0, 4072.7759973201464, 4381.38660587518, 33405.33189336224, 33752.15619609505, 34293.82853085719, 34563.82718086419, 35271.64728352735, 4362.541933097011, 4368.410264614268, 4394.428724265825, 4525.592696657317, 4536.478304994935, 381650.9999999972, 388355.0, 389469.0, 389677.0, 392163.0, 64863.35136648668, 65394.0, 65585.67207163933, 65636.0, 65689.0, 66754.3324566748, 66794.0, 67109.0, 67303.3269667297, 67354.32645673481, 17707.645847082986, 17891.105444727713, 17906.641867162583, 17930.731039034425, 17944.461666150037, 12664.0, 12678.0, 12695.04787140968, 12698.174618649758, 12703.110782245154, 17659.74707706991, 20236.595268094556, 20241.16623672687, 20274.594508109756, 20324.69512957307, 7185.6047917364, 7355.521891077063, 7429.182789893108, 7502.6623801929045, 7508.856433343178, 984.3257368702461, 1097.4841824342545, 1117.804384232756, 1147.5180424221846, 1182.3024415594841, 33173.0, 33380.66619333824, 33605.83197083999, 33685.15787105293, 33824.32351352959, 1054.6361505280665, 1118.9328640281446, 1142.5943789954613, 1176.5587904535798, 1201.141184053406, 16575.83424165767, 17191.540243629075, 17380.65238695219, 17399.56501087483, 17497.375393691047, 579342.0, 582549.0, 585809.0, 586975.0, 588953.0, 59443.70278148668, 59992.0, 60057.0, 60157.0, 60161.39838601558, 1302.7524770293614, 1373.8351397832323, 1375.4773186189384, 1387.9097858638954, 1390.1936876611542, 31910.84044579762, 32513.67486325201, 32789.83605081959, 32829.50755738618, 32966.505502417, 52243.47756522538, 52251.0, 52391.47608523866, 52521.21218181654, 52863.471365285855, 494398.0, 497590.0, 497839.0, 497853.660975421, 497872.0, 43941.0, 44297.0, 44731.0, 45117.774411127735, 45311.54688453073, 49029.50970490249, 49592.0, 50185.0, 51222.0, 51258.0, 41678.0, 41935.0, 42446.1510769789, 43180.352294714976, 43609.56390436182, 41899.0, 42018.57981420147, 42633.36049959315, 43202.567974319856, 43493.0, 36604.63395366012, 41100.1779964405, 41649.58350416457, 42841.57158428376, 43200.35199471947, 35814.4627830588, 37748.62251377451, 37908.1904780897, 37960.240795184494, 38015.80992095022, 28746.712532874404, 29187.124386268446, 29316.120516384544, 29420.558691619215, 29567.852160739058], "value": 21693.0407313637, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.32605266835714, 389.5909295239998, 393.22927062956603, 401.9778912159831, 419.0, 14157.150570965749, 16465.0, 17843.73234401485, 19304.0, 19338.80661193386, 14263.215523146222, 15313.310901009454, 15661.530154095395, 17592.20835062422, 17694.91152544239, 5462.535684466825, 5655.208270842086, 5657.9717101414435, 6590.50571207159, 6940.548864323828, 6777.7966661, 6789.490788190881, 6994.125734283215, 7002.439804815603, 7286.927130728678, 7235.0594422725235, 7249.166345870213, 7291.270872912719, 7294.234105418935, 7302.1237451505895, 7053.576785392867, 7150.141982962025, 7224.783256502314, 7227.566346019255, 7236.0593122894215, 6708.329167083252, 6873.415759660435, 6890.552114112566, 6935.72257109715, 6936.132983377078, 23836.0, 24315.63526547103, 24537.754622453725, 25391.0, 25681.35796605081, 2201.4056204824606, 2276.237460450751, 2405.7714517120844, 2454.2637208837277, 2636.5781474963915, 15454.072755634697, 15582.376704931788, 15777.211139442981, 15867.285972131282, 16129.032258064437, 2056.8378865940695, 2110.3457928042203, 2160.34109596573, 2206.4483879030245, 2231.141010710872, 80447.59776201082, 92133.0, 93215.0, 95134.52432737792, 95361.5231923836, 8630.0, 9244.676436324697, 9355.391899526508, 9397.624095036259, 9415.905840941503, 12341.50633974649, 12341.69145771345, 12375.75248495025, 12390.938045309716, 12412.441440135215, 7383.372413344926, 7629.923700762922, 7836.490628109154, 8042.919570804334, 8139.267465928095, 7570.356519695777, 7948.761537153895, 7997.960010199912, 8155.5514446706, 8223.342132629376, 4570.634349252052, 4613.423322084739, 4629.768511574408, 4631.791569379386, 4670.95329046712, 1900.6578815813286, 1920.3662791278844, 1939.8739081959627, 1955.8633490168477, 1984.682450807864, 904.1636486250193, 959.1703176752059, 961.3174646001278, 1056.8520407143005, 1078.0351585331084, 17110.401135960183, 17308.394206202724, 17553.824461755474, 17912.731309030376, 18322.358717444826, 980.460746589373, 1014.8579198912158, 1068.540527573151, 1068.615298492542, 1082.2478377527543, 10230.23273254509, 10681.145508359468, 10691.83962240567, 10751.354918704903, 10788.78422431547, 501155.0, 515477.0, 519882.0, 520080.0, 534659.0, 95063.0, 99253.50373248088, 99591.0, 100045.49977250212, 100385.49807250917, 1012.5645972231864, 1044.529961517305, 1061.341967979842, 1075.295681328731, 1085.2023762534532, 16784.16079196011, 16913.66172676564, 17218.569535761708, 17325.30698772035, 17558.29766809313, 42362.0, 42536.0, 42597.5740242606, 42947.30982933563, 43148.56851431446, 57351.42648573628, 58049.70975145097, 59227.0, 59971.400285996584, 60165.39834601773, 8168.264856162974, 8497.107803680525, 8573.571321434029, 8600.956995214983, 8645.481271123752, 12492.562760303346, 12654.493820247088, 12881.227126372447, 12956.35218239103, 13010.0, 9639.373440726331, 10106.444145572064, 11155.0, 11222.270552414067, 11270.211085223955, 10588.417637030037, 10854.457277135954, 11005.229633925548, 11537.307761534335, 11751.059915206934, 11994.220375675553, 12037.578684746342, 12106.242733609964, 12110.333931633844, 12228.75542489163, 7326.7069317226715, 8485.787855303666, 8690.565471726262, 8737.0, 8752.474851508676, 11536.365499897585, 11777.587784427504, 12160.574379896661, 12185.878141218474, 12191.146619736533], "value": 9472.329473806281, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.476044342706, 1468.4199741102261, 1519.7340465418552, 1637.9836201637988, 1784.9286028558859, 8549.487030778158, 8933.419327743692, 8960.596773145217, 9138.223251023672, 9145.314101442402, 8772.912270877305, 8950.239229665487, 8956.283497320215, 8966.86549701755, 8967.10328967103, 1967.0, 2023.7470316210474, 2028.9695654565194, 2300.309907027889, 2327.7788610082016, 2308.3998160478227, 2391.055533064447, 2394.413368724665, 2402.4954759500524, 2407.7471865454136, 2293.3693234360517, 2327.72067351918, 2340.7776261255153, 2359.6696462495265, 2362.3031205794246, 2015.5263513074563, 2076.252549082329, 2259.4464356232666, 2288.874111923852, 2293.621552443845, 2043.0601923115385, 2196.3740334004733, 2240.271911628727, 2244.5062086340977, 2258.5708715344194, 8777.7805554861, 8878.0, 8886.0, 8892.0, 8892.021877593459, 5088.7964481420695, 5222.608304377187, 5225.033368826779, 5226.05930932432, 5229.058769421503, 3274.1323549259464, 3333.783304085251, 3336.3627785475433, 3351.514030465569, 3353.513740507637, 1061.8725752909622, 1062.6493257225097, 1073.0396295315727, 1132.779108073926, 1150.7295785490403, 12506.124571280014, 12519.93740031294, 12537.686557836036, 12539.937300313532, 12543.874561254363, 4386.780660966939, 4511.8646440606835, 4522.8643140705835, 4620.445546534437, 4628.907421851544, 10539.0, 10554.208434367452, 10558.208134389952, 10567.577296908192, 10578.047975682226, 5196.974015129976, 5199.038177937094, 5203.765830537635, 5203.765830537635, 5208.557272631869, 4002.779847108379, 4658.790354433991, 4731.361266229083, 4762.3808904842845, 4828.975855120702, 3822.961770382316, 3914.8434062637743, 3937.822797974098, 3955.5055618047745, 4051.534073581517, 1210.3887536793864, 1217.2148963918223, 1220.7680540697238, 1221.2245224282644, 1222.5965431407615, 926.2543652359915, 930.1721467893581, 934.56075644447, 941.430434587065, 953.1802649721234, 5495.642783219078, 5512.476314750093, 5567.498925096761, 5639.351474580394, 5653.321601407857, 422.14726252969444, 427.4827458774855, 430.262100497648, 434.9195398851223, 446.6605379911291, 3724.9627503724614, 3733.0294123527533, 3794.2221844521805, 3795.7342985991268, 3815.751976121543, 10863.51114199863, 11238.606648767254, 11368.431578421047, 11376.033037191766, 11409.714757130972, 11114.388708621103, 11166.0, 11184.049355804686, 11186.608468703718, 11199.096153653898, 8212.671493140208, 8326.209010144026, 8524.147585241426, 8536.231739143506, 8540.871886921701, 5555.416681248573, 5619.185218143428, 5622.7750889963945, 5679.460451257124, 5778.797742079175, 10027.949860250652, 10041.196704263788, 10306.433146177033, 10317.690469285933, 10357.533910974174, 10334.224933129895, 10426.843597345895, 10517.526711297856, 10527.420991845522, 10529.842052369373, 7214.494985350975, 7401.1488678802625, 7401.59291238987, 7739.18738532446, 7960.4825686330205, 8193.959030204811, 8400.327973762207, 8401.411901166859, 8655.17775811297, 8687.60905759255, 7674.117476490052, 7690.884636730565, 7748.070231572247, 7915.0, 7949.562774047483, 7331.266873312626, 7438.702451901863, 7487.6630551626395, 7689.384849211938, 7811.843763124592, 6713.395794378529, 6774.491913106635, 6830.8633827324165, 6833.41915937131, 6833.453323734188, 4728.929066064081, 4852.344933433941, 4948.480409556944, 4949.950500494949, 4951.628627852998, 4978.377702787152, 4979.701217926791, 5066.265391518135, 5121.8463446096675, 5168.431472538093], "value": 4481.259363025773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.1883103929782, 315.0941044497071, 315.68431568431566, 316.86849957267736, 319.17174931053904, 5319.893602127955, 5358.732063396834, 5366.221897824823, 5370.43610420906, 5387.676739395639, 5251.186066159751, 5291.1798671206025, 5300.310959575259, 5317.2821669074665, 5319.414864364917, 3928.744631598937, 3961.841526338943, 4015.738976966488, 4044.413560033793, 4057.8579749708765, 4183.142455796555, 4183.330667093266, 4192.895177620569, 4200.306949353353, 4217.536071032184, 4109.903948986023, 4136.379543068534, 4141.917161656765, 4141.9585804142025, 4160.687948403866, 4054.371572406267, 4120.629143377096, 4150.024744185114, 4166.812493437772, 4175.645070169039, 3656.6708996190205, 3659.335002573849, 3668.523091998023, 3671.5961244263376, 3690.3910854708943, 5047.722375269359, 5068.41713202979, 5088.1350170471105, 5102.749826292545, 5103.851633382496, 4656.075255284605, 4671.602913752336, 4721.669483136182, 4740.715557066553, 4760.690555113906, 5515.4760297772045, 5526.585506087019, 5527.170924361338, 5528.640638358494, 5544.417836127189, 4004.159126583421, 4005.399190121476, 4025.3961905714086, 4043.716939814214, 4070.2266569351727, 5581.748821303051, 5594.216809646653, 5596.916046259309, 5709.658230315872, 5770.9422905770825, 4062.2484840304046, 4119.588041195856, 4139.2135494256, 4140.109876376593, 4143.606357396103, 4180.811863466151, 4209.116085621992, 4214.936775948363, 4236.110416812504, 4248.87253382399, 4545.204644165536, 4714.727023703649, 4797.832075877328, 4813.315339631171, 4815.15734746418, 5657.886842263131, 5673.205751194836, 5684.573656975744, 5689.317281926113, 5723.656580605177, 1406.8100806391005, 1423.1603354021177, 1424.166862385516, 1425.1164278147407, 1429.3639330497829, 899.3299991506238, 912.6212621762027, 913.456493386434, 918.93108016899, 939.7227817793768, 4979.701217926936, 5117.565006974449, 5250.632455728062, 5377.946220537823, 5379.354477462652, 5669.43305669438, 5672.631278966854, 5686.800961966312, 5699.487046165865, 5717.571182161355, 5340.946590534045, 5365.409804921455, 5375.489328513785, 5378.042076857712, 5392.083345831218, 2943.45546073977, 3081.4145312390574, 3082.6300843898425, 3085.3983473222506, 3100.1784527100335, 5943.257092863392, 5958.731857066442, 5974.641521508724, 5994.250718660168, 6003.519718422515, 5788.999999999915, 5822.214001109881, 5845.678487683133, 5863.648181109147, 5921.851953701192, 5930.2883653960935, 5947.762089516371, 5961.880762384728, 5997.7001149942325, 6019.638821670714, 5452.972735136457, 5522.917156242579, 5672.404397538199, 5687.1753595729215, 5692.857678558069, 5545.306836645419, 5653.632513886584, 5686.0, 5688.805350876362, 5734.455226753452, 5849.766009359578, 5865.178874957424, 5969.52243820502, 5986.55100867428, 5987.401259873977, 4033.4353190553934, 4054.533728621246, 4073.431728784499, 4097.590240975878, 4115.5678653742125, 5813.854653633693, 5822.679752613647, 5848.795292164754, 5855.677937713296, 5857.414258574108, 5517.69652669107, 5610.074337734228, 5623.971880140573, 5698.6010979232715, 5723.771049157987, 5591.6365436246515, 5593.328800543961, 5596.776128954796, 5596.832095037155, 5644.266245388073, 5583.357913839798, 5620.437956204347, 5627.324721033502, 5627.831165065055, 5629.101012978402, 5439.292891924025, 5710.314762228559, 5712.942870571241, 5720.141978703228, 5742.942570574408, 5559.944400555943, 5585.413531579288, 5588.748506317144, 5592.664440133605, 5613.691246981455], "value": 4257.554728867692, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.784418596514, 768.8923550702906, 797.7367468735318, 802.8193656427304, 815.6207363575936, 13878.0, 15074.170920599363, 15120.54638360851, 15264.236788160602, 15327.69344613107, 13254.138480998728, 13299.800502992439, 13348.0656354055, 13360.799588006188, 13362.599122026331, 677.1096008748505, 810.8094597769534, 823.0082750285891, 823.180934969706, 824.72371755462, 787.4999375396636, 788.7002938883217, 790.1348023913833, 811.1766556944706, 812.2689579378577, 849.4987957105307, 850.26452118917, 852.2457625001887, 852.3947996922193, 852.9872051919226, 708.2810946888912, 839.5718183726343, 847.2501835875233, 848.4909054567241, 849.3417601358965, 613.3467856732566, 613.4846728747873, 613.8250598579428, 614.253681776639, 621.3941407128059, 14556.053856499293, 14587.0, 14607.634809129857, 14617.196054217015, 14646.487372942003, 13499.932500337436, 13882.583522494342, 14091.647708807259, 14207.502737404142, 14286.571402857931, 12084.27494350333, 12152.392380381034, 12250.326232057234, 12346.135770496068, 12399.504019839196, 4448.466184057901, 4487.080148569536, 4574.862754117382, 4579.702319349231, 4693.061387722435, 62593.37406625921, 63370.0, 63446.0, 64175.679121604095, 66119.3388066118, 10508.106810921005, 10576.312539684895, 10652.733681658021, 10741.462926853626, 11113.83329250062, 18689.532761680795, 20949.790502094784, 20950.790492094882, 20950.89524552398, 20980.370588882357, 8043.436959412902, 8090.352771778244, 8333.208345207195, 8588.0, 8681.696140635173, 4676.298555216745, 4688.0858232644305, 4700.55344742249, 4882.462929077798, 4888.804447822047, 3354.765166438326, 3421.5551978243166, 3423.383790917609, 3434.862605495802, 3445.896623101311, 554.464941331617, 568.9829305120853, 577.2755192233719, 578.5805291163872, 583.76941108262, 564.8361975027196, 721.4841388407314, 770.5761830993043, 776.7320274505287, 790.4071946040469, 9391.483468409166, 9722.611095556098, 10001.24990625706, 10016.899831001743, 10044.748881278027, 638.8147437243148, 709.3013381818904, 749.5502698380927, 762.3900879296541, 765.7434759355567, 4641.721496710208, 4732.479427263067, 4893.388326459192, 4896.559309662076, 4903.436104847988, 62676.0, 64565.67717161384, 64857.35142648607, 73644.0, 74923.62538187383, 62113.68943155346, 63220.0, 63238.68380658159, 64682.35317646763, 64777.0, 58502.414975850545, 59505.70247148736, 59659.0, 60204.698976504835, 60935.39064609386, 9890.625311857597, 10039.397636141855, 10358.844617330897, 10389.324693894872, 10440.530176142243, 39748.820094392955, 39791.60208397879, 40020.399694005195, 40113.799431002655, 40194.0, 47267.52732472631, 53498.46501535091, 53791.731041344545, 54045.0, 54542.0, 11692.298462092302, 12257.570985015482, 12622.495100196256, 12661.303628300164, 12754.808677869654, 13326.33368331599, 13418.0, 13771.724565508834, 14117.08238964464, 14361.64095897611, 13676.93161534186, 13879.0, 13904.096233744775, 13977.650558735706, 14344.713105738036, 11863.76272474563, 11902.285862848257, 12710.0, 13695.931520342732, 14104.435822566982, 12580.685482863002, 12902.419391127234, 13541.729165416833, 13733.0, 13750.449982000608, 10667.079615956924, 10709.732256693645, 11198.272112312674, 11233.71915702114, 11394.0, 11176.608818691308, 11435.428228588704, 11447.771044578894, 11542.0, 11719.589814356457], "value": 7070.773865720519, "unit": "score"}], "name": "dromaeo_css", "value": 7275.574777558137, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 111.0, 111.0, 111.0, 110.0, 109.0, 109.0, 110.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 125.0, 128.0, 187.0, 132.0, 132.0, 152.0, 126.0, 169.0, 124.0], "value": 143.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 161.0, 159.0, 160.0, 161.0, 160.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 118.0, 73.0, 75.0, 76.0, 74.0, 107.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 119.0, 83.0, 84.0, 82.0, 120.0, 83.0, 83.0, 121.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 118.0, 118.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 97.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0, 72.0, 52.0, 52.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 62.0, 61.0, 61.0, 61.0, 62.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 78.0, 78.0, 81.0, 79.0, 81.0, 81.0, 85.0, 79.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 127.0, 121.0, 120.0, 130.0, 120.0, 128.0, 120.0, 165.0, 125.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 160.0, 163.0, 161.0, 161.0, 160.0, 160.0, 164.0, 164.0], "value": 161.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 63.0, 61.0, 61.0, 60.0, 62.0, 63.0, 60.0, 61.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1495.2, "alertThreshold": 2.0}]} 23:38:20 INFO - Return code: 0 23:38:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:38:20 ERROR - # TBPL SUCCESS # 23:38:20 INFO - Running post-action listener: _resource_record_post_action 23:38:20 INFO - ##### 23:38:20 INFO - ##### Finished run-tests step (success) 23:38:20 INFO - ##### 23:38:20 INFO - Running post-run listener: _resource_record_post_run 23:38:20 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 556859392; Read time: 12; Write time: 449500 23:38:20 INFO - TinderboxPrint: CPU usage
13.1% 23:38:20 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:38:20 INFO - TinderboxPrint: I/O write bytes / time
556,859,392 / 449,500 23:38:20 INFO - TinderboxPrint: CPU idle
8,256.8 (86.9%) 23:38:20 INFO - TinderboxPrint: CPU user
1,206.1 (12.7%) 23:38:20 INFO - TinderboxPrint: Swap in / out
0 / 0 23:38:20 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247103488; Read time: 0; Write time: 250184 23:38:20 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290299904; Read time: 12; Write time: 189964 23:38:21 INFO - Running post-run listener: _upload_blobber_files 23:38:21 INFO - Blob upload gear active. 23:38:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:38:21 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 23:38:21 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:38:21 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:38:21 INFO - (blobuploader) - INFO - Open directory for files ... 23:38:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:38:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:25 INFO - (blobuploader) - INFO - Done attempting. 23:38:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:38:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:38:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:26 INFO - (blobuploader) - INFO - Done attempting. 23:38:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:38:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:38:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:27 INFO - (blobuploader) - INFO - Done attempting. 23:38:27 INFO - (blobuploader) - INFO - Iteration through files over. 23:38:27 INFO - Return code: 0 23:38:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:38:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:38:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c48180804f97ba12995d4e927086a8f398947692a04a018d2fd7780f48cb50d7235be03a824198e451bc6c45c4e461bd30471aa2098e6beaf762d78f5492e742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11b436ced56db562dba7fee2551145bb4b53d50eacee28f6673dc879aaf72226a42cc0cfda98014e0fc8a13236f75e6fa3dafad24c7a418c8aaf93a802b4682a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a22389f61eca9b2bbf9408d9294fe8ba63de054fc60187bf37d352f2990c8a677b7916cfa12ebfb383800957b0bc3398999d15d55d0c248c6714cf81541a20c"} 23:38:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:38:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:38:27 INFO - Contents: 23:38:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c48180804f97ba12995d4e927086a8f398947692a04a018d2fd7780f48cb50d7235be03a824198e451bc6c45c4e461bd30471aa2098e6beaf762d78f5492e742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11b436ced56db562dba7fee2551145bb4b53d50eacee28f6673dc879aaf72226a42cc0cfda98014e0fc8a13236f75e6fa3dafad24c7a418c8aaf93a802b4682a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a22389f61eca9b2bbf9408d9294fe8ba63de054fc60187bf37d352f2990c8a677b7916cfa12ebfb383800957b0bc3398999d15d55d0c248c6714cf81541a20c"} 23:38:27 INFO - Running post-run listener: copy_logs_to_upload_dir 23:38:27 INFO - Copying logs to upload dir... 23:38:27 INFO - mkdir: /builds/slave/test/build/upload/logs 23:38:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.771874 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-25 23:38:27.369200) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 23:38:27.373244) ========= 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=1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c48180804f97ba12995d4e927086a8f398947692a04a018d2fd7780f48cb50d7235be03a824198e451bc6c45c4e461bd30471aa2098e6beaf762d78f5492e742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11b436ced56db562dba7fee2551145bb4b53d50eacee28f6673dc879aaf72226a42cc0cfda98014e0fc8a13236f75e6fa3dafad24c7a418c8aaf93a802b4682a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a22389f61eca9b2bbf9408d9294fe8ba63de054fc60187bf37d352f2990c8a677b7916cfa12ebfb383800957b0bc3398999d15d55d0c248c6714cf81541a20c"} build_url:https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011394 build_url: 'https://queue.taskcluster.net/v1/task/TNgXiOGaQMifhBsCHTBhyA/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/Fx-Team/sha512/c48180804f97ba12995d4e927086a8f398947692a04a018d2fd7780f48cb50d7235be03a824198e451bc6c45c4e461bd30471aa2098e6beaf762d78f5492e742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11b436ced56db562dba7fee2551145bb4b53d50eacee28f6673dc879aaf72226a42cc0cfda98014e0fc8a13236f75e6fa3dafad24c7a418c8aaf93a802b4682a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a22389f61eca9b2bbf9408d9294fe8ba63de054fc60187bf37d352f2990c8a677b7916cfa12ebfb383800957b0bc3398999d15d55d0c248c6714cf81541a20c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 23:38:27.402658) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 23:38:27.403080) ========= 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=1f5564757af27664ca02d71f000001b0-1464241526.378565-1981967776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 23:38:27.487760) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 23:38:27.488472) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 23:38:27.489191) ========= ========= Total master_lag: 0.21 =========