builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1463532099.44 results: success (0) buildid: 20160517143014 builduid: 78307bea84c140ec80590f2839ed3cb4 revision: f79314b1a23fa5fd403fbcf97b81a59a800d9614 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.440841) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.441415) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.441775) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.461922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.462267) ========= 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=33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 17:41:39-- 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% 217M=0s 2016-05-17 17:41:39 (217 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124469 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.601196) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.601773) ========= 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=33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018338 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.653806) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:39.654472) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f79314b1a23fa5fd403fbcf97b81a59a800d9614 --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 f79314b1a23fa5fd403fbcf97b81a59a800d9614 --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=33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 17:41:39,726 truncating revision to first 12 chars 2016-05-17 17:41:39,726 Setting DEBUG logging. 2016-05-17 17:41:39,726 attempt 1/10 2016-05-17 17:41:39,726 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f79314b1a23f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 17:41:39,985 unpacking tar archive at: fx-team-f79314b1a23f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.528507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:40.198049) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:40.198417) ========= script_repo_revision: f79314b1a23fa5fd403fbcf97b81a59a800d9614 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:40.198903) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:40.199226) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 17:41:40.212917) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-05-17 17:41:40.213252) ========= /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=33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:41:40 INFO - MultiFileLogger online at 20160517 17:41:40 in /builds/slave/test 17:41:40 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 17:41:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:41:40 INFO - {'append_to_log': False, 17:41:40 INFO - 'base_work_dir': '/builds/slave/test', 17:41:40 INFO - 'blob_upload_branch': 'Fx-Team', 17:41:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:41:40 INFO - 'branch': 'Fx-Team', 17:41:40 INFO - 'buildbot_json_path': 'buildprops.json', 17:41:40 INFO - 'config_files': ('talos/linux_config.py',), 17:41:40 INFO - 'default_actions': ('clobber', 17:41:40 INFO - 'read-buildbot-config', 17:41:40 INFO - 'download-and-extract', 17:41:40 INFO - 'populate-webroot', 17:41:40 INFO - 'create-virtualenv', 17:41:40 INFO - 'install', 17:41:40 INFO - 'run-tests'), 17:41:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:41:40 INFO - 'download_minidump_stackwalk': True, 17:41:40 INFO - 'download_symbols': 'ondemand', 17:41:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:41:40 INFO - 'tooltool.py': '/tools/tooltool.py', 17:41:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:41:40 INFO - '/tools/misc-python/virtualenv.py')}, 17:41:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:41:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:41:40 INFO - 'installer_path': 'installer.exe', 17:41:40 INFO - 'log_level': 'info', 17:41:40 INFO - 'log_name': 'talos', 17:41:40 INFO - 'log_to_console': True, 17:41:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:41:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:41:40 INFO - 'opt_config_files': (), 17:41:40 INFO - 'pip_index': False, 17:41:40 INFO - 'sps_profile': False, 17:41:40 INFO - 'sps_profile_interval': 0, 17:41:40 INFO - 'suite': 'dromaeojs', 17:41:40 INFO - 'system_bits': '32', 17:41:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:41:40 INFO - 'title': 'talos-linux64-ix-063', 17:41:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:41:40 INFO - 'use_talos_json': True, 17:41:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:41:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:41:40 INFO - 'work_dir': 'build'} 17:41:40 INFO - ##### 17:41:40 INFO - ##### Running clobber step. 17:41:40 INFO - ##### 17:41:40 INFO - Running pre-action listener: _resource_record_pre_action 17:41:40 INFO - Running main action method: clobber 17:41:40 INFO - rmtree: /builds/slave/test/build 17:41:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:41:40 INFO - Running post-action listener: _resource_record_post_action 17:41:40 INFO - ##### 17:41:40 INFO - ##### Finished clobber step (success) 17:41:40 INFO - ##### 17:41:40 INFO - ##### 17:41:40 INFO - ##### Running read-buildbot-config step. 17:41:40 INFO - ##### 17:41:40 INFO - Running pre-action listener: _resource_record_pre_action 17:41:40 INFO - Running main action method: read_buildbot_config 17:41:40 INFO - Using buildbot properties: 17:41:40 INFO - { 17:41:40 INFO - "project": "", 17:41:40 INFO - "product": "firefox", 17:41:40 INFO - "script_repo_revision": "production", 17:41:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:41:40 INFO - "repository": "", 17:41:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 17:41:40 INFO - "buildid": "20160517143014", 17:41:40 INFO - "pgo_build": "True", 17:41:40 INFO - "basedir": "/builds/slave/test", 17:41:40 INFO - "buildnumber": 99, 17:41:40 INFO - "slavename": "talos-linux64-ix-063", 17:41:40 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:41:40 INFO - "platform": "ubuntu64_hw", 17:41:40 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 17:41:40 INFO - "branch": "fx-team", 17:41:40 INFO - "repo_path": "integration/fx-team", 17:41:40 INFO - "revision": "f79314b1a23fa5fd403fbcf97b81a59a800d9614", 17:41:40 INFO - "stage_platform": "linux64-pgo", 17:41:40 INFO - "builduid": "78307bea84c140ec80590f2839ed3cb4", 17:41:40 INFO - "slavebuilddir": "test" 17:41:40 INFO - } 17:41:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:41:40 INFO - Running post-action listener: _resource_record_post_action 17:41:40 INFO - ##### 17:41:40 INFO - ##### Finished read-buildbot-config step (success) 17:41:40 INFO - ##### 17:41:40 INFO - ##### 17:41:40 INFO - ##### Running download-and-extract step. 17:41:40 INFO - ##### 17:41:40 INFO - Running pre-action listener: _resource_record_pre_action 17:41:40 INFO - Running main action method: download_and_extract 17:41:40 INFO - mkdir: /builds/slave/test/build/tests 17:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:41:40 INFO - https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:41:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:41:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:41:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:41 INFO - Downloaded 1361 bytes. 17:41:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:41:41 INFO - Using the following test package requirements: 17:41:41 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:41:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:41:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:41:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:41:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:41:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:41:41 INFO - u'jsshell-linux-x86_64.zip'], 17:41:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:41:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:41:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:41:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:41:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:41:41 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:41:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:41:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:41:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:41:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:41:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:41:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:41:41 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')]} 17:41:41 INFO - https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:41:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:41:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:41:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:42 INFO - Downloaded 24024983 bytes. 17:41:42 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'] 17:41:42 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 17:41:44 INFO - Return code: 0 17:41:44 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 17:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:41:44 INFO - https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:41:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:41:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:41:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:46 INFO - Downloaded 24024983 bytes. 17:41:46 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'] 17:41:46 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 17:41:48 INFO - Return code: 0 17:41:48 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')]} 17:41:48 INFO - https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:41:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:41:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:41:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:50 INFO - Downloaded 10840194 bytes. 17:41:50 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'] 17:41:50 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 17:41:50 INFO - Return code: 0 17:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:41:50 INFO - https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:41:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:41:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:41:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y68P-li6TR2ycCC4hmf81Q/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 17:41:51 INFO - Downloaded 58406849 bytes. 17:41:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:41:51 INFO - mkdir: /builds/slave/test/properties 17:41:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:41:51 INFO - Writing to file /builds/slave/test/properties/build_url 17:41:51 INFO - Contents: 17:41:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:41:52 INFO - Running post-action listener: _resource_record_post_action 17:41:52 INFO - Running post-action listener: set_extra_try_arguments 17:41:52 INFO - ##### 17:41:52 INFO - ##### Finished download-and-extract step (success) 17:41:52 INFO - ##### 17:41:52 INFO - ##### 17:41:52 INFO - ##### Running populate-webroot step. 17:41:52 INFO - ##### 17:41:52 INFO - Running pre-action listener: _resource_record_pre_action 17:41:52 INFO - Running main action method: populate_webroot 17:41:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:41:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:41:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:41:52 INFO - '--tppagecycles', 17:41:52 INFO - '7'], 17:41:52 INFO - 'tests': ['tsvgm']}}, 17:41:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:41:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:41:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:41:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:41:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:41:52 INFO - 'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:41:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:41:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:41:52 INFO - 'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['damp', 'tps']}, 17:41:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:41:52 INFO - 'tests': ['damp', 'tps']}, 17:41:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['dromaeo_dom']}, 17:41:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:41:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['a11yr', 17:41:52 INFO - 'ts_paint', 17:41:52 INFO - 'tpaint', 17:41:52 INFO - 'sessionrestore', 17:41:52 INFO - 'sessionrestore_no_auto_restore', 17:41:52 INFO - 'tabpaint']}, 17:41:52 INFO - 'other-e10s': {'tests': ['a11yr', 17:41:52 INFO - 'ts_paint', 17:41:52 INFO - 'tpaint', 17:41:52 INFO - 'sessionrestore', 17:41:52 INFO - 'sessionrestore_no_auto_restore', 17:41:52 INFO - 'tabpaint']}, 17:41:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:41:52 INFO - 'ts_paint', 17:41:52 INFO - 'tpaint', 17:41:52 INFO - 'sessionrestore', 17:41:52 INFO - 'sessionrestore_no_auto_restore', 17:41:52 INFO - 'tabpaint']}, 17:41:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:41:52 INFO - 'ts_paint', 17:41:52 INFO - 'tpaint', 17:41:52 INFO - 'sessionrestore', 17:41:52 INFO - 'sessionrestore_no_auto_restore', 17:41:52 INFO - 'tabpaint']}, 17:41:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['a11yr', 17:41:52 INFO - 'ts_paint', 17:41:52 INFO - 'tpaint', 17:41:52 INFO - 'sessionrestore', 17:41:52 INFO - 'sessionrestore_no_auto_restore', 17:41:52 INFO - 'tabpaint']}, 17:41:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['a11yr', 17:41:52 INFO - 'ts_paint', 17:41:52 INFO - 'tpaint', 17:41:52 INFO - 'sessionrestore', 17:41:52 INFO - 'sessionrestore_no_auto_restore', 17:41:52 INFO - 'tabpaint']}, 17:41:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['tsvgx', 17:41:52 INFO - 'tsvgr_opacity', 17:41:52 INFO - 'tart', 17:41:52 INFO - 'tscrollx', 17:41:52 INFO - 'cart']}, 17:41:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:41:52 INFO - 'tsvgr_opacity', 17:41:52 INFO - 'tart', 17:41:52 INFO - 'tscrollx', 17:41:52 INFO - 'cart']}, 17:41:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:41:52 INFO - 'talos_options': ['--disable-e10s'], 17:41:52 INFO - 'tests': ['tp5o']}, 17:41:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:41:52 INFO - 'tests': ['tp5o']}, 17:41:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:41:52 INFO - 'talos_options': ['--disable-e10s', 17:41:52 INFO - '--xperf_path', 17:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:41:52 INFO - 'tests': ['tp5n']}, 17:41:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:41:52 INFO - 'talos_options': ['--xperf_path', 17:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:41:52 INFO - 'tests': ['tp5n']}}, 17:41:52 INFO - 'talos.zip': {'path': '', 17:41:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:41:52 INFO - Running post-action listener: _resource_record_post_action 17:41:52 INFO - ##### 17:41:52 INFO - ##### Finished populate-webroot step (success) 17:41:52 INFO - ##### 17:41:52 INFO - ##### 17:41:52 INFO - ##### Running create-virtualenv step. 17:41:52 INFO - ##### 17:41:52 INFO - Running pre-action listener: _resource_record_pre_action 17:41:52 INFO - Running main action method: create_virtualenv 17:41:52 INFO - Creating virtualenv /builds/slave/test/build/venv 17:41:52 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 17:41:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:41:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:41:52 INFO - Using real prefix '/usr' 17:41:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:41:53 INFO - Installing distribute.............................................................................................................................................................................................done. 17:41:55 INFO - Installing pip.................done. 17:41:55 INFO - Return code: 0 17:41:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:41:55 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')]} 17:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:55 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')]} 17:41:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:55 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 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:41:55 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 17:41:55 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 17:41:55 INFO - Using env: {'DISPLAY': ':0', 17:41:55 INFO - 'HOME': '/home/cltbld', 17:41:55 INFO - 'LANG': 'en_US.UTF-8', 17:41:55 INFO - 'LANGUAGE': 'en_US:en', 17:41:55 INFO - 'LOGNAME': 'cltbld', 17:41:55 INFO - 'MAIL': '/var/mail/cltbld', 17:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:55 INFO - 'MOZ_NO_REMOTE': '1', 17:41:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:41:55 INFO - 'NO_EM_RESTART': '1', 17:41:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:55 INFO - 'PWD': '/builds/slave/test', 17:41:55 INFO - 'SHELL': '/bin/bash', 17:41:55 INFO - 'SHLVL': '1', 17:41:55 INFO - 'TERM': 'linux', 17:41:55 INFO - 'TMOUT': '86400', 17:41:55 INFO - 'USER': 'cltbld', 17:41:55 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:55 INFO - '_': '/tools/buildbot/bin/python'} 17:41:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:55 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 17:41:55 INFO - Cleaning up... 17:41:55 INFO - Return code: 0 17:41:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:41:55 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')]} 17:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:55 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')]} 17:41:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:55 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 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:41:55 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 17:41:55 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 17:41:55 INFO - Using env: {'DISPLAY': ':0', 17:41:55 INFO - 'HOME': '/home/cltbld', 17:41:55 INFO - 'LANG': 'en_US.UTF-8', 17:41:55 INFO - 'LANGUAGE': 'en_US:en', 17:41:55 INFO - 'LOGNAME': 'cltbld', 17:41:55 INFO - 'MAIL': '/var/mail/cltbld', 17:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:55 INFO - 'MOZ_NO_REMOTE': '1', 17:41:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:41:55 INFO - 'NO_EM_RESTART': '1', 17:41:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:55 INFO - 'PWD': '/builds/slave/test', 17:41:55 INFO - 'SHELL': '/bin/bash', 17:41:55 INFO - 'SHLVL': '1', 17:41:55 INFO - 'TERM': 'linux', 17:41:55 INFO - 'TMOUT': '86400', 17:41:55 INFO - 'USER': 'cltbld', 17:41:55 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:55 INFO - '_': '/tools/buildbot/bin/python'} 17:41:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:55 INFO - Downloading/unpacking psutil>=3.1.1 17:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:41:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:41:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:41:57 INFO - Installing collected packages: psutil 17:41:57 INFO - Running setup.py install for psutil 17:41:57 INFO - building 'psutil._psutil_linux' extension 17:41:57 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 17:41:58 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 17:41:58 INFO - building 'psutil._psutil_posix' extension 17:41:58 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 17:41:58 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 17:41:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:41:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:41:58 INFO - Successfully installed psutil 17:41:58 INFO - Cleaning up... 17:41:59 INFO - Return code: 0 17:41:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:41:59 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')]} 17:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:59 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')]} 17:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:59 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 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:41:59 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 17:41:59 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 17:41:59 INFO - Using env: {'DISPLAY': ':0', 17:41:59 INFO - 'HOME': '/home/cltbld', 17:41:59 INFO - 'LANG': 'en_US.UTF-8', 17:41:59 INFO - 'LANGUAGE': 'en_US:en', 17:41:59 INFO - 'LOGNAME': 'cltbld', 17:41:59 INFO - 'MAIL': '/var/mail/cltbld', 17:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:59 INFO - 'MOZ_NO_REMOTE': '1', 17:41:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:41:59 INFO - 'NO_EM_RESTART': '1', 17:41:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:59 INFO - 'PWD': '/builds/slave/test', 17:41:59 INFO - 'SHELL': '/bin/bash', 17:41:59 INFO - 'SHLVL': '1', 17:41:59 INFO - 'TERM': 'linux', 17:41:59 INFO - 'TMOUT': '86400', 17:41:59 INFO - 'USER': 'cltbld', 17:41:59 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:59 INFO - '_': '/tools/buildbot/bin/python'} 17:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:42:02 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 17:42:02 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 17:42:02 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 17:42:02 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 17:42:02 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 17:42:02 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 17:42:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:42:04 INFO - Installing collected packages: mozsystemmonitor 17:42:04 INFO - Running setup.py install for mozsystemmonitor 17:42:04 INFO - Successfully installed mozsystemmonitor 17:42:04 INFO - Cleaning up... 17:42:04 INFO - Return code: 0 17:42:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:42:04 INFO - Using env: {'DISPLAY': ':0', 17:42:04 INFO - 'HOME': '/home/cltbld', 17:42:04 INFO - 'LANG': 'en_US.UTF-8', 17:42:04 INFO - 'LANGUAGE': 'en_US:en', 17:42:04 INFO - 'LOGNAME': 'cltbld', 17:42:04 INFO - 'MAIL': '/var/mail/cltbld', 17:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:04 INFO - 'MOZ_NO_REMOTE': '1', 17:42:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:04 INFO - 'NO_EM_RESTART': '1', 17:42:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:04 INFO - 'PWD': '/builds/slave/test', 17:42:04 INFO - 'SHELL': '/bin/bash', 17:42:04 INFO - 'SHLVL': '1', 17:42:04 INFO - 'TERM': 'linux', 17:42:04 INFO - 'TMOUT': '86400', 17:42:04 INFO - 'USER': 'cltbld', 17:42:04 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:04 INFO - '_': '/tools/buildbot/bin/python'} 17:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:04 INFO - Downloading/unpacking blobuploader==1.2.4 17:42:04 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 17:42:04 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 17:42:04 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 17:42:04 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 17:42:04 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 17:42:04 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 17:42:06 INFO - Downloading blobuploader-1.2.4.tar.gz 17:42:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:42:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:42:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:42:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42:07 INFO - Downloading docopt-0.6.1.tar.gz 17:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:42:07 INFO - Installing collected packages: blobuploader, requests, docopt 17:42:07 INFO - Running setup.py install for blobuploader 17:42:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:42:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:42:07 INFO - Running setup.py install for requests 17:42:07 INFO - Running setup.py install for docopt 17:42:08 INFO - Successfully installed blobuploader requests docopt 17:42:08 INFO - Cleaning up... 17:42:08 INFO - Return code: 0 17:42:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:08 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 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:42:08 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 17:42:08 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 17:42:08 INFO - Using env: {'DISPLAY': ':0', 17:42:08 INFO - 'HOME': '/home/cltbld', 17:42:08 INFO - 'LANG': 'en_US.UTF-8', 17:42:08 INFO - 'LANGUAGE': 'en_US:en', 17:42:08 INFO - 'LOGNAME': 'cltbld', 17:42:08 INFO - 'MAIL': '/var/mail/cltbld', 17:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:08 INFO - 'MOZ_NO_REMOTE': '1', 17:42:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:08 INFO - 'NO_EM_RESTART': '1', 17:42:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:08 INFO - 'PWD': '/builds/slave/test', 17:42:08 INFO - 'SHELL': '/bin/bash', 17:42:08 INFO - 'SHLVL': '1', 17:42:08 INFO - 'TERM': 'linux', 17:42:08 INFO - 'TMOUT': '86400', 17:42:08 INFO - 'USER': 'cltbld', 17:42:08 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:08 INFO - '_': '/tools/buildbot/bin/python'} 17:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:42:08 INFO - Running setup.py (path:/tmp/pip-5adX3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:42:08 INFO - Running setup.py (path:/tmp/pip-hy6yxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:42:08 INFO - Running setup.py (path:/tmp/pip-UG8BRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:42:08 INFO - Running setup.py (path:/tmp/pip-oDT9_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:42:08 INFO - Running setup.py (path:/tmp/pip-65IK0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:42:08 INFO - Running setup.py (path:/tmp/pip-5iGSh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:42:08 INFO - Running setup.py (path:/tmp/pip-yUe83Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:42:08 INFO - Running setup.py (path:/tmp/pip-GDOygG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:42:08 INFO - Running setup.py (path:/tmp/pip-PNSwf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:42:08 INFO - Running setup.py (path:/tmp/pip-Xo_P2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:42:09 INFO - Running setup.py (path:/tmp/pip-oYcXdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:42:09 INFO - Running setup.py (path:/tmp/pip-YCbmZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:42:09 INFO - Running setup.py (path:/tmp/pip-y4LRvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:42:09 INFO - Running setup.py (path:/tmp/pip-v5J3TA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:09 INFO - Running setup.py (path:/tmp/pip-b1WWdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:42:09 INFO - Running setup.py (path:/tmp/pip-QSn6_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:42:09 INFO - Running setup.py (path:/tmp/pip-Lmm4U9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:42:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:42:09 INFO - Running setup.py install for manifestparser 17:42:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:42:09 INFO - Running setup.py install for mozcrash 17:42:09 INFO - Running setup.py install for mozdebug 17:42:09 INFO - Running setup.py install for mozdevice 17:42:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:42:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:42:09 INFO - Running setup.py install for mozfile 17:42:10 INFO - Running setup.py install for mozhttpd 17:42:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Running setup.py install for mozinfo 17:42:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Running setup.py install for mozInstall 17:42:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Running setup.py install for mozleak 17:42:10 INFO - Running setup.py install for mozlog 17:42:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Running setup.py install for moznetwork 17:42:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Running setup.py install for mozprocess 17:42:10 INFO - Running setup.py install for mozprofile 17:42:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:42:10 INFO - Running setup.py install for mozrunner 17:42:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:42:11 INFO - Running setup.py install for mozscreenshot 17:42:11 INFO - Running setup.py install for moztest 17:42:11 INFO - Running setup.py install for mozversion 17:42:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:42:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:42:11 INFO - Cleaning up... 17:42:11 INFO - Return code: 0 17:42:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:11 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 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:42:11 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 17:42:11 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 17:42:11 INFO - Using env: {'DISPLAY': ':0', 17:42:11 INFO - 'HOME': '/home/cltbld', 17:42:11 INFO - 'LANG': 'en_US.UTF-8', 17:42:11 INFO - 'LANGUAGE': 'en_US:en', 17:42:11 INFO - 'LOGNAME': 'cltbld', 17:42:11 INFO - 'MAIL': '/var/mail/cltbld', 17:42:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:11 INFO - 'MOZ_NO_REMOTE': '1', 17:42:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:11 INFO - 'NO_EM_RESTART': '1', 17:42:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:11 INFO - 'PWD': '/builds/slave/test', 17:42:11 INFO - 'SHELL': '/bin/bash', 17:42:11 INFO - 'SHLVL': '1', 17:42:11 INFO - 'TERM': 'linux', 17:42:11 INFO - 'TMOUT': '86400', 17:42:11 INFO - 'USER': 'cltbld', 17:42:11 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:42:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:11 INFO - '_': '/tools/buildbot/bin/python'} 17:42:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:42:11 INFO - Running setup.py (path:/tmp/pip-l32BYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:42:11 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)) 17:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:42:11 INFO - Running setup.py (path:/tmp/pip-mQw7iH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:42:11 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)) 17:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:42:11 INFO - Running setup.py (path:/tmp/pip-dg8Zhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:42:11 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)) 17:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:42:11 INFO - Running setup.py (path:/tmp/pip-ROXr9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:42:11 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)) 17:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:42:11 INFO - Running setup.py (path:/tmp/pip-Zc9ZnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:42:11 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)) 17:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:42:11 INFO - Running setup.py (path:/tmp/pip-hBaCa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:42:11 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)) 17:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:42:11 INFO - Running setup.py (path:/tmp/pip-2T5RbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:42:12 INFO - Running setup.py (path:/tmp/pip-XmKXZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:42:12 INFO - Running setup.py (path:/tmp/pip-S6_4jT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:42:12 INFO - Running setup.py (path:/tmp/pip-mfIkKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:42:12 INFO - Running setup.py (path:/tmp/pip-gii4QU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:42:12 INFO - Running setup.py (path:/tmp/pip-dYHHhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:42:12 INFO - Running setup.py (path:/tmp/pip-hUXqLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:42:12 INFO - Running setup.py (path:/tmp/pip-ZzUBQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:12 INFO - Running setup.py (path:/tmp/pip-se_jEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:42:12 INFO - Running setup.py (path:/tmp/pip-TopIBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:42:12 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)) 17:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:42:12 INFO - Running setup.py (path:/tmp/pip-L11KFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:42:12 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)) 17:42:12 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)) 17:42:12 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)) 17:42:12 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)) 17:42:12 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)) 17:42:12 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)) 17:42:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42:14 INFO - Downloading blessings-1.6.tar.gz 17:42:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:42:14 INFO - Installing collected packages: blessings 17:42:14 INFO - Running setup.py install for blessings 17:42:14 INFO - Successfully installed blessings 17:42:14 INFO - Cleaning up... 17:42:14 INFO - Return code: 0 17:42:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:42:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:42:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:42:15 INFO - Reading from file tmpfile_stdout 17:42:15 INFO - Current package versions: 17:42:15 INFO - argparse == 1.2.1 17:42:15 INFO - blessings == 1.6 17:42:15 INFO - blobuploader == 1.2.4 17:42:15 INFO - docopt == 0.6.1 17:42:15 INFO - manifestparser == 1.1 17:42:15 INFO - mozInstall == 1.12 17:42:15 INFO - mozcrash == 0.17 17:42:15 INFO - mozdebug == 0.1 17:42:15 INFO - mozdevice == 0.48 17:42:15 INFO - mozfile == 1.2 17:42:15 INFO - mozhttpd == 0.7 17:42:15 INFO - mozinfo == 0.9 17:42:15 INFO - mozleak == 0.1 17:42:15 INFO - mozlog == 3.2 17:42:15 INFO - moznetwork == 0.27 17:42:15 INFO - mozprocess == 0.22 17:42:15 INFO - mozprofile == 0.28 17:42:15 INFO - mozrunner == 6.11 17:42:15 INFO - mozscreenshot == 0.1 17:42:15 INFO - mozsystemmonitor == 0.3 17:42:15 INFO - moztest == 0.7 17:42:15 INFO - mozversion == 1.4 17:42:15 INFO - psutil == 3.1.1 17:42:15 INFO - requests == 1.2.3 17:42:15 INFO - wsgiref == 0.1.2 17:42:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42: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')]} 17:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42: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')]} 17:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:15 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 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:42:15 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 17:42:15 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 17:42:15 INFO - Using env: {'DISPLAY': ':0', 17:42:15 INFO - 'HOME': '/home/cltbld', 17:42:15 INFO - 'LANG': 'en_US.UTF-8', 17:42:15 INFO - 'LANGUAGE': 'en_US:en', 17:42:15 INFO - 'LOGNAME': 'cltbld', 17:42:15 INFO - 'MAIL': '/var/mail/cltbld', 17:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:15 INFO - 'MOZ_NO_REMOTE': '1', 17:42:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:15 INFO - 'NO_EM_RESTART': '1', 17:42:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:15 INFO - 'PWD': '/builds/slave/test', 17:42:15 INFO - 'SHELL': '/bin/bash', 17:42:15 INFO - 'SHLVL': '1', 17:42:15 INFO - 'TERM': 'linux', 17:42:15 INFO - 'TMOUT': '86400', 17:42:15 INFO - 'USER': 'cltbld', 17:42:15 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:15 INFO - '_': '/tools/buildbot/bin/python'} 17:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 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)) 17:42:15 INFO - Cleaning up... 17:42:15 INFO - Return code: 0 17:42:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:42: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')]} 17:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42: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')]} 17:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:15 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 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:42:15 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 17:42:15 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 17:42:15 INFO - Using env: {'DISPLAY': ':0', 17:42:15 INFO - 'HOME': '/home/cltbld', 17:42:15 INFO - 'LANG': 'en_US.UTF-8', 17:42:15 INFO - 'LANGUAGE': 'en_US:en', 17:42:15 INFO - 'LOGNAME': 'cltbld', 17:42:15 INFO - 'MAIL': '/var/mail/cltbld', 17:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:15 INFO - 'MOZ_NO_REMOTE': '1', 17:42:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:15 INFO - 'NO_EM_RESTART': '1', 17:42:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:15 INFO - 'PWD': '/builds/slave/test', 17:42:15 INFO - 'SHELL': '/bin/bash', 17:42:15 INFO - 'SHLVL': '1', 17:42:15 INFO - 'TERM': 'linux', 17:42:15 INFO - 'TMOUT': '86400', 17:42:15 INFO - 'USER': 'cltbld', 17:42:15 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:15 INFO - '_': '/tools/buildbot/bin/python'} 17:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:15 INFO - Downloading/unpacking jsonschema 17:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:42:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:42:17 INFO - Downloading/unpacking functools32 (from jsonschema) 17:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:42:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:17 INFO - Downloading functools32-3.2.3-2.tar.gz 17:42:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:42:17 INFO - warning: no files found matching '*.txt' 17:42:17 INFO - no previously-included directories found matching 'build' 17:42:17 INFO - no previously-included directories found matching 'dist' 17:42:17 INFO - no previously-included directories found matching '.git*' 17:42:17 INFO - Installing collected packages: jsonschema, functools32 17:42:17 INFO - Running setup.py install for functools32 17:42:17 INFO - warning: no files found matching '*.txt' 17:42:17 INFO - no previously-included directories found matching 'build' 17:42:17 INFO - no previously-included directories found matching 'dist' 17:42:17 INFO - no previously-included directories found matching '.git*' 17:42:17 INFO - Successfully installed jsonschema functools32 17:42:17 INFO - Cleaning up... 17:42:17 INFO - Return code: 0 17:42:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:42:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1b3bfff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, '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': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', '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 17:42:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:42:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:42:17 INFO - Using env: {'DISPLAY': ':0', 17:42:17 INFO - 'HOME': '/home/cltbld', 17:42:17 INFO - 'LANG': 'en_US.UTF-8', 17:42:17 INFO - 'LANGUAGE': 'en_US:en', 17:42:17 INFO - 'LOGNAME': 'cltbld', 17:42:17 INFO - 'MAIL': '/var/mail/cltbld', 17:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:17 INFO - 'MOZ_NO_REMOTE': '1', 17:42:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:17 INFO - 'NO_EM_RESTART': '1', 17:42:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:17 INFO - 'PWD': '/builds/slave/test', 17:42:17 INFO - 'SHELL': '/bin/bash', 17:42:17 INFO - 'SHLVL': '1', 17:42:17 INFO - 'TERM': 'linux', 17:42:17 INFO - 'TMOUT': '86400', 17:42:17 INFO - 'USER': 'cltbld', 17:42:17 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:17 INFO - '_': '/tools/buildbot/bin/python'} 17:42:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:18 INFO - Downloading/unpacking flake8 17:42:18 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 17:42:18 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 17:42:18 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 17:42:18 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 17:42:18 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 17:42:18 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 17:42:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:42:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:42:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42: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 17:42:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:42:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:42:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:42:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:42:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:42:20 INFO - no previously-included directories found matching 'docs/_build' 17:42:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:42:20 INFO - Running setup.py install for pep8 17:42:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:42:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:42:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:42:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:42:20 INFO - no previously-included directories found matching 'docs/_build' 17:42:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:42:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:42:20 INFO - Cleaning up... 17:42:21 INFO - Return code: 0 17:42:21 INFO - Running post-action listener: _resource_record_post_action 17:42:21 INFO - Running post-action listener: _start_resource_monitoring 17:42:21 INFO - Starting resource monitoring. 17:42:21 INFO - ##### 17:42:21 INFO - ##### Finished create-virtualenv step (success) 17:42:21 INFO - ##### 17:42:21 INFO - ##### 17:42:21 INFO - ##### Running install step. 17:42:21 INFO - ##### 17:42:21 INFO - Running pre-action listener: _resource_record_pre_action 17:42:21 INFO - Running main action method: install 17:42:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:42:21 INFO - Reading from file tmpfile_stdout 17:42:21 INFO - Detecting whether we're running mozinstall >=1.0... 17:42:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:42:21 INFO - Reading from file tmpfile_stdout 17:42:21 INFO - Output received: 17:42:21 INFO - Usage: mozinstall [options] installer 17:42:21 INFO - Options: 17:42:21 INFO - -h, --help show this help message and exit 17:42:21 INFO - -d DEST, --destination=DEST 17:42:21 INFO - Directory to install application into. [default: 17:42:21 INFO - "/builds/slave/test"] 17:42:21 INFO - --app=APP Application being installed. [default: firefox] 17:42:21 INFO - mkdir: /builds/slave/test/build/application 17:42:21 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'] 17:42:21 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 17:42:37 INFO - Reading from file tmpfile_stdout 17:42:37 INFO - Output received: 17:42:37 INFO - /builds/slave/test/build/application/firefox/firefox 17:42:37 INFO - Running post-action listener: _resource_record_post_action 17:42:37 INFO - ##### 17:42:37 INFO - ##### Finished install step (success) 17:42:37 INFO - ##### 17:42:37 INFO - ##### 17:42:37 INFO - ##### Running run-tests step. 17:42:37 INFO - ##### 17:42:37 INFO - Running pre-action listener: _resource_record_pre_action 17:42:37 INFO - Running main action method: run_tests 17:42:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:42:37 INFO - Python 2.7.3 17:42:37 INFO - Return code: 0 17:42:37 INFO - grabbing minidump binary from tooltool 17:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:37 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 0x14feb20>, '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 0x14ffa20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14ffeb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:42:37 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 17:42:37 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 17:42:37 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 17:42:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:42:37 INFO - Return code: 0 17:42:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:42:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:42:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:42:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:42:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:42:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:42:38 INFO - Return code: 0 17:42:38 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/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 17:42:38 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/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --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 17:42:38 INFO - Using env: {'DISPLAY': ':0', 17:42:38 INFO - 'HOME': '/home/cltbld', 17:42:38 INFO - 'LANG': 'en_US.UTF-8', 17:42:38 INFO - 'LANGUAGE': 'en_US:en', 17:42:38 INFO - 'LOGNAME': 'cltbld', 17:42:38 INFO - 'MAIL': '/var/mail/cltbld', 17:42:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:42:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:38 INFO - 'MOZ_NO_REMOTE': '1', 17:42:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:42:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:38 INFO - 'NO_EM_RESTART': '1', 17:42:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:38 INFO - 'PWD': '/builds/slave/test', 17:42:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:42:38 INFO - 'SHELL': '/bin/bash', 17:42:38 INFO - 'SHLVL': '1', 17:42:38 INFO - 'TERM': 'linux', 17:42:38 INFO - 'TMOUT': '86400', 17:42:38 INFO - 'USER': 'cltbld', 17:42:38 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957', 17:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:38 INFO - '_': '/tools/buildbot/bin/python'} 17:42:38 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/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 17:42:39 INFO - mozversion application_buildid: 20160517143014 17:42:39 INFO - mozversion application_changeset: f79314b1a23fa5fd403fbcf97b81a59a800d9614 17:42:39 INFO - mozversion application_display_name: Nightly 17:42:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:42:39 INFO - mozversion application_name: Firefox 17:42:39 INFO - mozversion application_remotingname: firefox 17:42:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:42:39 INFO - mozversion application_vendor: Mozilla 17:42:39 INFO - mozversion application_version: 49.0a1 17:42:39 INFO - mozversion platform_buildid: 20160517143014 17:42:39 INFO - mozversion platform_changeset: f79314b1a23fa5fd403fbcf97b81a59a800d9614 17:42:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:42:39 INFO - mozversion platform_version: 49.0a1 17:42:39 INFO - using testdate: 1463532159 17:42:39 INFO - actual date: 1463532159 17:42:39 INFO - starting webserver on 'localhost:55664' 17:42:39 INFO - SUITE-START | Running 2 tests 17:42:39 INFO - TEST-START | dromaeo_css 17:42:39 INFO - operating with platform_type : linux_ 17:42:39 INFO - Initialising browser for dromaeo_css test... 17:42:39 INFO - TEST-INFO | started process 5373 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxZyfmn/profile http://localhost:55664/getInfo.html) 17:42:43 INFO - PROCESS | 5373 | __metrics Screen width/height:1600/1200 17:42:43 INFO - PROCESS | 5373 | colorDepth:24 17:42:43 INFO - PROCESS | 5373 | Browser inner width/height: 1024/697 17:42:43 INFO - PROCESS | 5373 | __metrics 17:42:45 ERROR - PROCESS | 5373 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:42:45 INFO - TEST-INFO | 5373: exit 0 17:42:45 INFO - Browser initialized. 17:42:45 INFO - Running cycle 1/1 for dromaeo_css test... 17:42:45 INFO - TEST-INFO | started process 5454 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxZyfmn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:42:57 INFO - PROCESS | 5454 | RSS: Main: 152969216 17:42:57 INFO - PROCESS | 5454 | 17:43:49 INFO - PROCESS | 5454 | 1463532229053 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] 17:45:57 INFO - PROCESS | 5454 | Cycle 1(1): loaded http://localhost:55664/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55664/tests/dromaeo/cssquery-ext.html) 17:45:58 INFO - PROCESS | 5454 | RSS: Main: 251506688 17:45:58 INFO - PROCESS | 5454 | 17:49:00 INFO - PROCESS | 5454 | Cycle 1(1): loaded http://localhost:55664/tests/dromaeo/cssquery-ext.html (next: http://localhost:55664/tests/dromaeo/cssquery-jquery.html) 17:49:00 INFO - PROCESS | 5454 | RSS: Main: 230948864 17:49:00 INFO - PROCESS | 5454 | 17:52:02 INFO - PROCESS | 5454 | Cycle 1(1): loaded http://localhost:55664/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55664/tests/dromaeo/cssquery-mootools.html) 17:52:03 INFO - PROCESS | 5454 | RSS: Main: 243597312 17:52:03 INFO - PROCESS | 5454 | 17:55:05 INFO - PROCESS | 5454 | Cycle 1(1): loaded http://localhost:55664/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55664/tests/dromaeo/cssquery-prototype.html) 17:55:05 INFO - PROCESS | 5454 | RSS: Main: 231317504 17:55:05 INFO - PROCESS | 5454 | 17:58:08 INFO - PROCESS | 5454 | Cycle 1(1): loaded http://localhost:55664/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55664/tests/dromaeo/cssquery-yui.html) 17:58:09 INFO - PROCESS | 5454 | RSS: Main: 226750464 17:58:09 INFO - PROCESS | 5454 | 18:01:11 INFO - PROCESS | 5454 | Cycle 1(1): loaded http://localhost:55664/tests/dromaeo/cssquery-yui.html (next: http://localhost:55664/tests/dromaeo/cssquery-dojo.html) 18:01:12 INFO - PROCESS | 5454 | RSS: Main: 261632000 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - PROCESS | 5454 | __start_tp_report 18:01:12 INFO - PROCESS | 5454 | _x_x_mozilla_page_load 18:01:12 INFO - PROCESS | 5454 | _x_x_mozilla_page_load_details 18:01:12 INFO - PROCESS | 5454 | |i|pagename|runs| 18:01:12 INFO - PROCESS | 5454 | |0;dojo.html;3142.968570314297;3148.905532834016;3173.0956677346962;3180.125465496989;3218.291975765331;5315.7873685052555;5348.117560602501;5363.839084827452;5424.701641409721;5441.863953401162;5406.324209473816;5444.591655625824;5453.700046497451;5462.590305727076;5462.617616766828;4850.39370078741;4948.059868624968;4956.553910148086;4965.975170124144;5141.845744627667;5030.37120359955;5061.595072394218;5091.592672586203;5103.56619687327;5110.977804439127;4751.833685820997;4766.165920963852;4792.664513484057;4821.108095002401;4841.15279826029;4817.9036419271415;4843.66094373394;4922.507749225048;4923.630727695401;4965.82619608312;4870.853874383739;4881.951180488185;4886.291487734258;4894.3147959285725;4942.159832828428;5133.768980395891;5297.364316282033;5382.2464854920345;5424.538914192299;5465.125579907196;5892.616979896293;5912.763489460416;5925.229720136398;5931.851703707442;5965.791197308074;5690.630109042899;5975.43233392827;6012.939870601325;6024.126501657235;6024.277086749618;5386.407495175527;5502.9174562381595;5510.173473978895;5521.254630624894;5568.136938774496;6032.325960049212;6033.423201966596;6075.969620151871;6086.087086936995;6094.360092190344;5605.691686957257;5636.520895723827;5850.473457388855;5854.853628659318;5856.619319744203;5677.659340439587;5737.454941780524;5748.45389687979;5759.164921086419;5811.854703632358;5717.599768016282;5735.168400581976;5761.884762304814;5762.567807414461;5767.423257674199;5650.802221922295;5721.485066344049;5770.365259821417;5784.479396854304;5787.884242315215;4432.645388368923;4447.177272204653;4450.332450132506;4485.596296333346;4493.236149854532;3918.294706952776;3955.0903292242947;3964.345882929342;3984.6015398460504;3985.143194213258;5359.973200134053;5387.542058924957;5435.755391007414;5437.02133615953;5470.890582188334;5908.822735317947;5919.526437884959;5922.407759224129;5928.644281343133;5968.104784282393;5446.482584154499;5462;5510.834674959758;5536.114221724505;5540.667559946416;4876.804927802919;5054.317667114966;5099.107656160157;5160.200168973815;5163.8192663256605;6166;6179.876402471926;6183.3816618337805;6184.257889053342;6187.969060154671;5958.19564358806;5978.730957106854;5979;5983.970080149571;5988.730507127103;5940.198073260228;5954.7320370582565;5999.820005399845;6005.789797357072;6054.848628784315;5646.943530564642;5674.035413979551;5687.573431992534;5691.516221121251;5702.344230413555;5656.208130861765;5820.883582328414;5830.854228644318;5833.82498525045;5838.649681019152;5649.887002259849;5689.459501347365;5698.088305871041;5749.741261643319;5753.769849205984;5730.169125476866;5735.7418916148035;5737.053386191232;5737.971310143423;5741.25363702716;5428.077226871363;5598.272224610774;5623.409541998195;5623.943760562342;5624.268845050117;5827.825165245049;5883.676397798162;5884.529237660891;5892.852678683067;5893.086571581412;5658.377578466448;5686.658800471985;5693.4875861172695;5699.173619825185;5703.515201207943 18:01:12 INFO - PROCESS | 5454 | |1;ext.html;596.8657052163261;625.1872565664635;634.2547506679651;635.0886477904207;648.3386945315779;32858.17854553637;36402.45396319054;44109.77945110274;44842;45070.7746461268;46838;47449.28826067612;47520.76239618797;47588.28617570739;47616.76191619037;8932.106789321064;9081.273498120134;9088.818223635557;9089.318301127409;9091;8905.243054340388;8946.463212207256;8976;8978.640854365818;9009.684661036865;9036.322275829307;9074.591643376063;9077.36458447909;9084.545772711372;9105.408148470327;8965;9003.864942025875;9006.594703238305;9016.18854303116;9017.909820901772;8791.252743516809;8860.24687901529;8868.911310886937;8869.290456763509;8882.333824963089;43099.78450107761;43175.56824431779;43388;43615;43629.78185109086;3417.2311229973056;3570.4644303354444;3583.623719509466;3735.831887565066;4304.504981927087;33000.6699933;33707.32585348279;34341.82829085863;34509;34565;4380.934285985712;4390.582894625038;4393.207509586163;4512.278035514335;4537.9773101134615;358618;359980;360295.9999999974;360340;360789;35411.82294088513;36518.8174059128;60158.69920650369;60451;60874;37866.43200351997;37871.242575148346;37990.43014354787;38017;38023.42964855529;14793.186374749277;14857;14878.776818347733;14896.553103406915;14903.776443353358;12511.687207819696;12583.559575414816;12592.685182870318;12599.622011339674;12607.180533265308;8625.525596092275;8668.393212475192;8687.087855775053;8740.125987401336;8748.081451447633;3332.1169889979174;3370.76404651672;3373.9662603373654;3396.3037577296595;3429.8456569454434;1056.5509658395151;1065.4938904020528;1067.215596536545;1128.0355296221776;1130.141092769501;33362.66637333644;34129.31741365208;34135.65864341325;34410.48384274238;34919;1117.2514415341577;1142.1091548592112;1149.6148790155391;1198.0774803401332;1315.2371624457792;17294.308227670754;17935.192916318538;17996.910015449837;18054.819451805575;18149.27402903869;472884;474141;475762;475997;476179;58675.41324586785;59921.700391498634;60065;60147.594096236535;60309.095363569584;1282.9615111546668;1392.436063394327;1413.1026797983354;1421.5166843273337;1425.850285720005;33728.325433491686;35333.46999795057;35595.82202088973;35686.46470303;35758;53557.73221133869;53886.19170712364;54120.72939635276;54202.186967194735;54236.45763542472;411757;415975;416821;417517;418008;25682;29292;46548.767256163504;47074;47719.76140119277;48059.75970120127;52206.47793522168;53473.73263133659;53732.46267537431;54307;42239.788801055794;43481;44224.33663494986;44659.55340446684;45103.7744811287;43776.56223437852;44010.55989440065;44158.77920610505;44344;45125.097498049196;45328.77335613301;46019.76990115028;46258;46623.76688116538;46775.298370523786;37273.440898386005;39797.40303895502;39955.40066899057;40059.59940400675;40631.39052914149;29716.405671886874;30131;30510.694893050786;30605.387892242477;31128 18:01:12 INFO - PROCESS | 5454 | |2;jquery.html;410.2942938146388;414.8174803086642;416.860351782153;417.2885230681686;418.2972606021882;16790;18127;18733.250669973186;20001.799982000175;20039.1984320627;18583.628327433442;18757.718634220462;18865.62268754624;18967.24131034757;18987.52531186721;5792.942070579304;5907.793227237047;5966.79116230932;5974.731137098841;5975.820725378246;5672.262605861232;5806.042003069489;5812.273465816773;5813.09896965971;5816.272965879265;5476.808311709091;5773.942260577403;5776.191333213354;5870.530357571384;5875.970620146893;5799.9130013049835;5810.4189581041555;5837.6497410155525;5852.414758524156;6513.413792758674;5779.9422005779825;5794.159846822229;5814.389489103625;5822.039363505017;5832.620879642852;13055.477780888754;13253.73492530144;13332.800007999887;13400;13422;2311.5376924615143;2385.9642105368434;2469.592517234654;2668.6664166979126;2670.1588999465107;9869.11177993984;9935.801283974351;10002.349847259977;10083.344582602107;10196;1890.7258447525032;1981.5112139373998;1984.5237143085699;2036.1549956767924;2051.5281485258474;77028;78972;79804.20195798026;80422.59788701078;83354.58322708408;17029.40397086096;17452;17736.645267094846;18398.908005460155;18544.258229670933;32066.839665801523;32304.676953230635;32307.838460807547;32487.51268730971;32558.51162232521;9344.2524598032;9523.476208808443;9624.711258662252;10005.749856253651;10138.290319677551;8466.957665211634;8638.654453821902;8684.8697269541;8685.461099874845;8802.603882825288;8639.17927796871;9090.954540227958;9199.356045076915;9221.631134754669;9295.349325547282;3377.459606462954;3435.9656403436147;3460.602030766444;3611.4402267648557;3662.450632405107;924.3252425729121;996.3473924579412;1038.7922415516928;1063.2238465919768;1086.4459125845876;18457.16942737553;18881.339153129575;19013.904930475535;19075.04624768784;19633.410997670093;1026.2867307221559;1047.2145890582065;1058.4496062047692;1102.2284400919389;1131.4738646529404;10632.840507392395;11021.944890275658;11032;11126.332420054823;11179.83230251547;473274;481119;484487;487365;494520;83448.58275708582;93691;94159.52920235354;94339.52830235942;94980.52509737408;1097.5554900265406;1098.9285696429706;1107.4020029184312;1112.3770688414513;1113.8496302998992;17017.659646807242;17214.39749608758;17422.564435889206;17473.38843140484;17829.197686104417;41683.7915810419;42496.36255456232;42988.14023719571;43257;43269.350959735;59980;60837.69581152066;60893.39106608877;60898;62088.379116208256;8390.622421991147;8633.91366086331;8744.300455963381;8803.515806630698;8915.910840891507;12222;12941.158824676366;13298.667533311746;13567.932160339134;13654.317284135952;6395.712192951235;8907.910920890708;11346.886531134583;11703.23928944616;11722.94138529302;11246.325220486797;11366.090712743126;11368.204225704121;11372.54509819598;11548.133889958119;12018.639440816789;12147.81778273309;12175.63473095773;12190;12216.87783122193;8397;8547.914520854712;8596.613152408032;8667.653293868174;8838.823223535623;8597.699080532151;10975.560977560808;11402.429878506207;11539.134564907834;11794.705132371759 18:01:12 INFO - PROCESS | 5454 | |3;mootools.html;1417.560556227569;1532.9693406131873;1581.4939219449773;1778.3686791189127;1886.2549293029244;8801.42790718603;9119.133682300178;9135.314851386156;9479.289053321012;9579.80840383192;9301.441913485196;9511.524423778817;9519.857202141973;9527.952360238207;9528.523573821316;2112.4930016795934;2377.72656144543;2442.5237078769646;2455.5579995600783;2489.029278581355;2454.5459090068307;2465.568525508038;2477.913273035444;2483.3170878008514;2487.9751202488014;2418.0327868852423;2444.4866578018637;2447.510497900418;2450.644656524803;2451.2646206138174;2119.7562280337806;2347.319277409549;2417.601095819188;2421.031587365068;2428.5264373447185;2280.737715162761;2285.1202287119413;2287.3938406322336;2289.095807156181;2328.138588722167;9358.766030849216;9376.71869843899;9423;9430.151286384258;9436.339456238065;5470.17947307903;5565.66606003637;5569.10894256917;5579.9163012554845;5588.050031494656;3552.484889691006;3564.8752293669595;3611.837467313951;3646.4348026056005;3663.816809159558;1197.21582363552;1222.2177806204036;1225.828384026237;1233.2477188914754;1265.949362025518;12912;12912.870871291261;12919.483220671067;12934.935325323408;12986.870131298661;4858.902821943612;4969.552740253394;4977.776000080005;4985.850424487271;5059.62052846038;10813.459327033617;10870.782584348268;10885.564577416973;10924;10927.344359338465;5052.242163675478;5406.729663516809;5454.972725136349;5461.1808228766;5468.4531546845;4589.885252868705;4691.483936766953;4743.644226682944;5133.717645529457;5179.715115668599;4164.104717485755;4220.936685949713;4307.6769242306955;4314.870553883388;4345.9130817383475;1307.4312673986847;1331.4674130347744;1342.1142046249429;1342.90599658023;1351.8850897673808;932.9486878221763;1071.314358810362;1072.1744256922127;1081.9513121909533;1088.689723428835;6469.061986012002;6597.670116494156;6618.669066546653;6647.368499992493;6804.489663275274;449.2946074662748;454.6135784583144;458.7866642011481;471.15662963296;482.0768228841799;4537.2059889519205;4538.636909047268;4590.472095708969;4594.989102397467;4600.769961501912;11398.943005285086;11585.6524304271;11848.348340841165;11996.700082498008;12022.519099236108;11999.940000299943;12036.45835937385;12072.154949153475;12078.335691536875;12163.148579599343;8649.740507784776;8816.86774698393;8825.558722063999;8837.248833849195;8843.778905527413;6446.613203207822;6606.207255129415;6708.6310252936555;6732.663366831737;6827.419669328163;10810.135189185004;10854.45727713627;10989.175811813986;11165.776684466427;11194.552217911518;10875.891241087487;11119.332840029625;11168.720781980515;11236.887631123584;11383.943080284545;7388.335049845539;7913.208679132041;8030.357571394391;8132.146124656826;8183.140770219041;9347.719568412958;9376.859347109663;9437.716868493957;9444.0555944405;9452.669156579486;8117;8174.100848906496;8196.057453392936;8295.336373090258;8363.080061193386;8144.063432705315;8166.42835001568;8185.508869467851;8319.500829950222;8337.041240257447;7663.578503182379;7679.19368466303;7745.883811742716;7758.224177582197;7762.223777622193;5661.745221465127;5695.319880635139;5709.0865461525955;5873.177755114202;5874.265716785402;5695.345035320825;5704.600677952503;5742.425757424391;5840.649561026352;6035.1249068885645 18:01:12 INFO - PROCESS | 5454 | |4;prototype.html;339.86745169383937;348.3068693300331;350.98245087745613;351.5078889554625;356.8965000149955;5279.181726832342;5318.627696061278;5319.707416092114;5364.79292159264;5391.433899440561;5160.136124450928;5286.709230992294;5301.7084060376665;5317.3619165700175;5323.041852466555;4026.5973402659793;4121.876343709693;4156.732196680012;4184.937225941613;4253.213155566223;4091.754494730311;4108.465899433085;4119.608637179478;4126.442930204414;4159.3968874513175;4113.794310284488;4123.752574845519;4136.482939632546;4137.627613514783;4138.503379594438;3897.1815918657117;4084.611961863618;4106.178764247147;4122.999999999985;4139.275626765304;3612.674859262679;3623.58328792187;3628.5101511296166;3640.7269454791;3645.8359373828243;5111.488851114896;5128.410232823236;5145.305383773218;5150.922736158961;5154.742262886841;4593.609543188833;4749.786259618326;4760.000399916022;4767.261074533453;4769.880752981168;5474.042042642522;5519.8536834004635;5551.9722401387735;5569.832905012856;5583.6928968906695;3998.2203470323298;4012.8194231259663;4024.3561030235023;4041.7574945503366;4083.632473077437;5297.17893726473;5595.601099725069;5845.649261044351;5872.148538461899;5893.528517718572;4035.334169861999;4036.9394459083137;4050.10897602527;4051.6758659307184;4069.9389509157386;4111.075008123148;4114.958850411457;4119.629233369011;4127.112670775738;4130.504339479222;4519.79660915253;4593.908121837611;4698.9530104698515;4766.856994290177;4773.474917759044;5544.77820887168;5555.638883472561;5560.972195139079;5574.303212098488;5581.2186293918885;1422.4879043544315;1437.5328018394064;1438.0293302021303;1443.9061461005;1445.3423692220015;897.3001059173855;931.753085432361;933.9346245762731;941.3457646935451;950.9001554836781;4754.405699287589;4804.975975120102;4817.710937343771;4864.759486331034;4865.464798872121;5043.823466178666;5071.442141364447;5084.745762711849;5086.287919691246;5093.898122037613;4614.584687378152;4806.903861922742;4819.807207711652;4819.807207711722;4832.95167048332;2727.9045233416737;2746.585508463143;2759.903403380872;2760.751532362057;2770.3074231442138;5204.152525853267;5264.447233040552;5289.418164001957;5342.252084708144;5352.732363381815;4842.539958703917;5151.124308867502;5181.911798522277;5197.454267301954;5272.947270527246;5660.349059858087;5758.798442054508;5845.970770146122;5884.3520415813055;5901.822945311647;5690.459406356389;5697.430256974435;5710.373228314856;5712.171735098304;5712.400197979152;5464.453554644504;5578.63738856973;5608.4111168326735;5657.915131272952;5658.207850900795;5775.740091695968;5856.297244330707;5867.413258674099;5882.676452795137;5927.496162826208;4126.00975765808;4140.668746500333;4148.688848336326;4149.27387707144;4165.375193720966;5537;5707.574280930505;5734;5744.885102298014;5847.532197424281;5567.220589117445;5589.217509548585;5603.55171586264;5612.821307525465;5637.88724225505;5283.577313814809;5615.241942337732;5622.662640241599;5622.718864056699;5633.38032816398;5445.237666726741;5471.659443436383;5485.561155107662;5487.560995120461;5501.312335958005;5545.279113715191;5548.334199896039;5566.387697353207;5578.972105139448;5580.497755201889;5288.2860813791185;5461.2627295316215;5465;5474.507294343528;5486.862828429321 18:01:12 INFO - PROCESS | 5454 | |5;yui.html;811.8701007838744;815.2744057788567;821.0065820357368;828.9212524810143;847.8261956298959;14212.36044378003;14265.714685706293;14412.351444185011;14453.927730361333;14454.349554270057;13481;13511.594652160451;13534.120282181651;13562.321883905815;13574.389152488136;672.2705864137405;785.5365334452672;826.099551488877;826.8263664630434;830.2776584371575;816.828466022136;821.219841150909;821.3757544266341;824.2787560884226;824.7031068815222;704.6758491094103;884.0364003236471;885.8405487012335;886.4149661223594;887.1394747095308;823.6417158536026;859.2309882655052;861.6381119929645;861.8448679237734;862.2455351567379;637.1525870592102;637.8150336402438;637.9776707815204;638.0270088115615;638.581728967527;13365.264910429922;13401.46394144233;13411.396487158101;13412.329383530881;13418.060735748502;13660.316984150753;14100.929495352457;14233.28833558318;14344.64138396538;14453.855461445357;11164.218504704673;11182.217244792868;11374.488148033359;11374.829377559343;11518.88481115195;4458.197524445599;4472.932906006413;4528.569785870337;4625.468071171791;4653.7440440775745;56664.716676416356;56760.71619641917;56810;56850.43149568493;58035;11238.7190320241;11288.2662626929;11481;11501.482433290523;11533.308001519936;20031.198752049753;20124.698129528068;20139.39581812548;20153.992300385216;20175.192992280437;7662.846743065219;7825.060992680801;8028.317593004544;8047.436679432383;8062.596870156469;4580.541945805459;4735;4748.335233067373;4837.879053023703;4896.779644915988;3315.4529502631794;3416.8120753358307;3421.5551978243166;3421.9315613687586;3424.989628059728;624.0576729138942;624.5908929651085;634.5811764235675;639.9520035997319;643.4080645805869;688.6006116452446;713.5183750968076;715.4777012780595;744.4416687484387;748.2480107491936;9189.862152067724;9335.673251436167;9351.532423378803;9550.188234000188;9592.136707696342;615.7352338494401;640.8173670503977;658.3778329478597;707.0384277382756;761.3642608421919;4343.761093139908;4346.673999450054;4382.320740285261;4428.291473364311;4548.772561371984;66932.33067669359;70435.64782176056;71314;71357;72338.63830680918;61679;61996.07005894915;62726.37273627205;63078.369216308165;63239;55006.72496637491;55245;55305.1704224437;55394.44605553974;55655.443445565834;8612.009089244018;8777.648894044167;8806.779830504289;8813.91186088131;8945.642174312954;36987.81506092452;37270;37543.99999999891;37748;38046.61953380431;45439.77280113578;49537.25694114519;50398.49601503938;51108.23337649864;51158;12216.938915305722;12245;12377.814332784836;13419.194848308742;13528.93235533816;14678.63303417423;14925.552233433014;15107.924460377628;15505.922470387575;15539.766903496231;13917.443302267797;14118.2940852959;14350.928245358706;14557.344919478437;15153.696926061637;13631.182129072287;13860.653483662585;13987.720245595234;14456.349464273922;14545.200013998909;12549.435275412447;12691.682707932376;12760.106792524433;12817.423215955117;13352.198868067559;10519.73700657459;10834.729131771453;10936.234463587472;11411.372374519482;11733;10344.482775861328;11000.39418993943;11224.551017959517;11313.830292545454;11503.597374091867 18:01:12 INFO - PROCESS | 5454 | __end_tp_report 18:01:12 INFO - PROCESS | 5454 | __start_cc_report 18:01:12 INFO - PROCESS | 5454 | _x_x_mozilla_cycle_collect,836 18:01:12 INFO - PROCESS | 5454 | __end_cc_report 18:01:12 INFO - PROCESS | 5454 | __startTimestamp1463533272123__endTimestamp 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - PROCESS | 5454 | ------- Summary: start ------- 18:01:12 INFO - PROCESS | 5454 | Number of tests: 6 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - PROCESS | 5454 | [#0] dojo.html Cycles:165 Average:5423.13 Median:5646.94 stddev:633.30 (11.2%) stddev-sans-first:609.46 18:01:12 INFO - PROCESS | 5454 | Values: 3143.0 3148.9 3173.1 3180.1 3218.3 5315.8 5348.1 5363.8 5424.7 5441.9 5406.3 5444.6 5453.7 5462.6 5462.6 4850.4 4948.1 4956.6 4966.0 5141.8 5030.4 5061.6 5091.6 5103.6 5111.0 4751.8 4766.2 4792.7 4821.1 4841.2 4817.9 4843.7 4922.5 4923.6 4965.8 4870.9 4882.0 4886.3 4894.3 4942.2 5133.8 5297.4 5382.2 5424.5 5465.1 5892.6 5912.8 5925.2 5931.9 5965.8 5690.6 5975.4 6012.9 6024.1 6024.3 5386.4 5502.9 5510.2 5521.3 5568.1 6032.3 6033.4 6076.0 6086.1 6094.4 5605.7 5636.5 5850.5 5854.9 5856.6 5677.7 5737.5 5748.5 5759.2 5811.9 5717.6 5735.2 5761.9 5762.6 5767.4 5650.8 5721.5 5770.4 5784.5 5787.9 4432.6 4447.2 4450.3 4485.6 4493.2 3918.3 3955.1 3964.3 3984.6 3985.1 5360.0 5387.5 5435.8 5437.0 5470.9 5908.8 5919.5 5922.4 5928.6 5968.1 5446.5 5462.0 5510.8 5536.1 5540.7 4876.8 5054.3 5099.1 5160.2 5163.8 6166.0 6179.9 6183.4 6184.3 6188.0 5958.2 5978.7 5979.0 5984.0 5988.7 5940.2 5954.7 5999.8 6005.8 6054.8 5646.9 5674.0 5687.6 5691.5 5702.3 5656.2 5820.9 5830.9 5833.8 5838.6 5649.9 5689.5 5698.1 5749.7 5753.8 5730.2 5735.7 5737.1 5738.0 5741.3 5428.1 5598.3 5623.4 5623.9 5624.3 5827.8 5883.7 5884.5 5892.9 5893.1 5658.4 5686.7 5693.5 5699.2 5703.5 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - PROCESS | 5454 | [#1] ext.html Cycles:180 Average:58310.26 Median:43548.00 stddev:110868.68 (254.6%) stddev-sans-first:111095.02 18:01:12 INFO - PROCESS | 5454 | Values: 596.9 625.2 634.3 635.1 648.3 32858.2 36402.5 44109.8 44842.0 45070.8 46838.0 47449.3 47520.8 47588.3 47616.8 8932.1 9081.3 9088.8 9089.3 9091.0 8905.2 8946.5 8976.0 8978.6 9009.7 9036.3 9074.6 9077.4 9084.5 9105.4 8965.0 9003.9 9006.6 9016.2 9017.9 8791.3 8860.2 8868.9 8869.3 8882.3 43099.8 43175.6 43388.0 43615.0 43629.8 3417.2 3570.5 3583.6 3735.8 4304.5 33000.7 33707.3 34341.8 34509.0 34565.0 4380.9 4390.6 4393.2 4512.3 4538.0 358618.0 359980.0 360296.0 360340.0 360789.0 35411.8 36518.8 60158.7 60451.0 60874.0 37866.4 37871.2 37990.4 38017.0 38023.4 14793.2 14857.0 14878.8 14896.6 14903.8 12511.7 12583.6 12592.7 12599.6 12607.2 8625.5 8668.4 8687.1 8740.1 8748.1 3332.1 3370.8 3374.0 3396.3 3429.8 1056.6 1065.5 1067.2 1128.0 1130.1 33362.7 34129.3 34135.7 34410.5 34919.0 1117.3 1142.1 1149.6 1198.1 1315.2 17294.3 17935.2 17996.9 18054.8 18149.3 472884.0 474141.0 475762.0 475997.0 476179.0 58675.4 59921.7 60065.0 60147.6 60309.1 1283.0 1392.4 1413.1 1421.5 1425.9 33728.3 35333.5 35595.8 35686.5 35758.0 53557.7 53886.2 54120.7 54202.2 54236.5 411757.0 415975.0 416821.0 417517.0 418008.0 25682.0 29292.0 46548.8 47074.0 47719.8 48059.8 52206.5 53473.7 53732.5 54307.0 42239.8 43481.0 44224.3 44659.6 45103.8 43776.6 44010.6 44158.8 44344.0 45125.1 45328.8 46019.8 46258.0 46623.8 46775.3 37273.4 39797.4 39955.4 40059.6 40631.4 29716.4 30131.0 30510.7 30605.4 31128.0 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - PROCESS | 5454 | [#2] jquery.html Cycles:180 Average:29486.26 Median:3406.71 stddev:79803.97 (2342.6%) stddev-sans-first:79997.98 18:01:12 INFO - PROCESS | 5454 | Values: 410.3 414.8 416.9 417.3 418.3 16790.0 18127.0 18733.3 20001.8 20039.2 18583.6 18757.7 18865.6 18967.2 18987.5 5792.9 5907.8 5966.8 5974.7 5975.8 5672.3 5806.0 5812.3 5813.1 5816.3 5476.8 5773.9 5776.2 5870.5 5876.0 5799.9 5810.4 5837.6 5852.4 6513.4 5779.9 5794.2 5814.4 5822.0 5832.6 13055.5 13253.7 13332.8 13400.0 13422.0 2311.5 2386.0 2469.6 2668.7 2670.2 9869.1 9935.8 10002.3 10083.3 10196.0 1890.7 1981.5 1984.5 2036.2 2051.5 77028.0 78972.0 79804.2 80422.6 83354.6 17029.4 17452.0 17736.6 18398.9 18544.3 32066.8 32304.7 32307.8 32487.5 32558.5 9344.3 9523.5 9624.7 10005.7 10138.3 8467.0 8638.7 8684.9 8685.5 8802.6 8639.2 9091.0 9199.4 9221.6 9295.3 3377.5 3436.0 3460.6 3611.4 3662.5 924.3 996.3 1038.8 1063.2 1086.4 18457.2 18881.3 19013.9 19075.0 19633.4 1026.3 1047.2 1058.4 1102.2 1131.5 10632.8 11021.9 11032.0 11126.3 11179.8 473274.0 481119.0 484487.0 487365.0 494520.0 83448.6 93691.0 94159.5 94339.5 94980.5 1097.6 1098.9 1107.4 1112.4 1113.8 17017.7 17214.4 17422.6 17473.4 17829.2 41683.8 42496.4 42988.1 43257.0 43269.4 59980.0 60837.7 60893.4 60898.0 62088.4 8390.6 8633.9 8744.3 8803.5 8915.9 12222.0 12941.2 13298.7 13567.9 13654.3 6395.7 8907.9 11346.9 11703.2 11722.9 11246.3 11366.1 11368.2 11372.5 11548.1 12018.6 12147.8 12175.6 12190.0 12216.9 8397.0 8547.9 8596.6 8667.7 8838.8 8597.7 10975.6 11402.4 11539.1 11794.7 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - PROCESS | 5454 | [#3] mootools.html Cycles:180 Average:6221.11 Median:4592.73 stddev:3589.42 (78.2%) stddev-sans-first:3581.33 18:01:12 INFO - PROCESS | 5454 | Values: 1417.6 1533.0 1581.5 1778.4 1886.3 8801.4 9119.1 9135.3 9479.3 9579.8 9301.4 9511.5 9519.9 9528.0 9528.5 2112.5 2377.7 2442.5 2455.6 2489.0 2454.5 2465.6 2477.9 2483.3 2488.0 2418.0 2444.5 2447.5 2450.6 2451.3 2119.8 2347.3 2417.6 2421.0 2428.5 2280.7 2285.1 2287.4 2289.1 2328.1 9358.8 9376.7 9423.0 9430.2 9436.3 5470.2 5565.7 5569.1 5579.9 5588.1 3552.5 3564.9 3611.8 3646.4 3663.8 1197.2 1222.2 1225.8 1233.2 1265.9 12912.0 12912.9 12919.5 12934.9 12986.9 4858.9 4969.6 4977.8 4985.9 5059.6 10813.5 10870.8 10885.6 10924.0 10927.3 5052.2 5406.7 5455.0 5461.2 5468.5 4589.9 4691.5 4743.6 5133.7 5179.7 4164.1 4220.9 4307.7 4314.9 4345.9 1307.4 1331.5 1342.1 1342.9 1351.9 932.9 1071.3 1072.2 1082.0 1088.7 6469.1 6597.7 6618.7 6647.4 6804.5 449.3 454.6 458.8 471.2 482.1 4537.2 4538.6 4590.5 4595.0 4600.8 11398.9 11585.7 11848.3 11996.7 12022.5 11999.9 12036.5 12072.2 12078.3 12163.1 8649.7 8816.9 8825.6 8837.2 8843.8 6446.6 6606.2 6708.6 6732.7 6827.4 10810.1 10854.5 10989.2 11165.8 11194.6 10875.9 11119.3 11168.7 11236.9 11383.9 7388.3 7913.2 8030.4 8132.1 8183.1 9347.7 9376.9 9437.7 9444.1 9452.7 8117.0 8174.1 8196.1 8295.3 8363.1 8144.1 8166.4 8185.5 8319.5 8337.0 7663.6 7679.2 7745.9 7758.2 7762.2 5661.7 5695.3 5709.1 5873.2 5874.3 5695.3 5704.6 5742.4 5840.6 6035.1 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - PROCESS | 5454 | [#4] prototype.html Cycles:180 Average:4591.33 Median:5152.93 stddev:1339.86 (26.0%) stddev-sans-first:1305.07 18:01:12 INFO - PROCESS | 5454 | Values: 339.9 348.3 351.0 351.5 356.9 5279.2 5318.6 5319.7 5364.8 5391.4 5160.1 5286.7 5301.7 5317.4 5323.0 4026.6 4121.9 4156.7 4184.9 4253.2 4091.8 4108.5 4119.6 4126.4 4159.4 4113.8 4123.8 4136.5 4137.6 4138.5 3897.2 4084.6 4106.2 4123.0 4139.3 3612.7 3623.6 3628.5 3640.7 3645.8 5111.5 5128.4 5145.3 5150.9 5154.7 4593.6 4749.8 4760.0 4767.3 4769.9 5474.0 5519.9 5552.0 5569.8 5583.7 3998.2 4012.8 4024.4 4041.8 4083.6 5297.2 5595.6 5845.6 5872.1 5893.5 4035.3 4036.9 4050.1 4051.7 4069.9 4111.1 4115.0 4119.6 4127.1 4130.5 4519.8 4593.9 4699.0 4766.9 4773.5 5544.8 5555.6 5561.0 5574.3 5581.2 1422.5 1437.5 1438.0 1443.9 1445.3 897.3 931.8 933.9 941.3 950.9 4754.4 4805.0 4817.7 4864.8 4865.5 5043.8 5071.4 5084.7 5086.3 5093.9 4614.6 4806.9 4819.8 4819.8 4833.0 2727.9 2746.6 2759.9 2760.8 2770.3 5204.2 5264.4 5289.4 5342.3 5352.7 4842.5 5151.1 5181.9 5197.5 5272.9 5660.3 5758.8 5846.0 5884.4 5901.8 5690.5 5697.4 5710.4 5712.2 5712.4 5464.5 5578.6 5608.4 5657.9 5658.2 5775.7 5856.3 5867.4 5882.7 5927.5 4126.0 4140.7 4148.7 4149.3 4165.4 5537.0 5707.6 5734.0 5744.9 5847.5 5567.2 5589.2 5603.6 5612.8 5637.9 5283.6 5615.2 5622.7 5622.7 5633.4 5445.2 5471.7 5485.6 5487.6 5501.3 5545.3 5548.3 5566.4 5579.0 5580.5 5288.3 5461.3 5465.0 5474.5 5486.9 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - PROCESS | 5454 | [#5] yui.html Cycles:180 Average:15894.20 Median:4639.61 stddev:18995.27 (409.4%) stddev-sans-first:19014.80 18:01:12 INFO - PROCESS | 5454 | Values: 811.9 815.3 821.0 828.9 847.8 14212.4 14265.7 14412.4 14453.9 14454.3 13481.0 13511.6 13534.1 13562.3 13574.4 672.3 785.5 826.1 826.8 830.3 816.8 821.2 821.4 824.3 824.7 704.7 884.0 885.8 886.4 887.1 823.6 859.2 861.6 861.8 862.2 637.2 637.8 638.0 638.0 638.6 13365.3 13401.5 13411.4 13412.3 13418.1 13660.3 14100.9 14233.3 14344.6 14453.9 11164.2 11182.2 11374.5 11374.8 11518.9 4458.2 4472.9 4528.6 4625.5 4653.7 56664.7 56760.7 56810.0 56850.4 58035.0 11238.7 11288.3 11481.0 11501.5 11533.3 20031.2 20124.7 20139.4 20154.0 20175.2 7662.8 7825.1 8028.3 8047.4 8062.6 4580.5 4735.0 4748.3 4837.9 4896.8 3315.5 3416.8 3421.6 3421.9 3425.0 624.1 624.6 634.6 640.0 643.4 688.6 713.5 715.5 744.4 748.2 9189.9 9335.7 9351.5 9550.2 9592.1 615.7 640.8 658.4 707.0 761.4 4343.8 4346.7 4382.3 4428.3 4548.8 66932.3 70435.6 71314.0 71357.0 72338.6 61679.0 61996.1 62726.4 63078.4 63239.0 55006.7 55245.0 55305.2 55394.4 55655.4 8612.0 8777.6 8806.8 8813.9 8945.6 36987.8 37270.0 37544.0 37748.0 38046.6 45439.8 49537.3 50398.5 51108.2 51158.0 12216.9 12245.0 12377.8 13419.2 13528.9 14678.6 14925.6 15107.9 15505.9 15539.8 13917.4 14118.3 14350.9 14557.3 15153.7 13631.2 13860.7 13987.7 14456.3 14545.2 12549.4 12691.7 12760.1 12817.4 13352.2 10519.7 10834.7 10936.2 11411.4 11733.0 10344.5 11000.4 11224.6 11313.8 11503.6 18:01:12 INFO - PROCESS | 5454 | -------- Summary: end -------- 18:01:12 INFO - PROCESS | 5454 | 18:01:12 INFO - TEST-INFO | 5454: exit 0 18:01:12 INFO - TEST-OK | dromaeo_css | took 1113705ms 18:01:12 INFO - TEST-START | kraken 18:01:12 INFO - operating with platform_type : linux_ 18:01:12 INFO - Initialising browser for kraken test... 18:01:12 INFO - TEST-INFO | started process 5637 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjBrHJN/profile http://localhost:55664/getInfo.html) 18:01:17 INFO - PROCESS | 5637 | __metrics Screen width/height:1600/1200 18:01:17 INFO - PROCESS | 5637 | colorDepth:24 18:01:17 INFO - PROCESS | 5637 | Browser inner width/height: 1024/697 18:01:17 INFO - PROCESS | 5637 | __metrics 18:01:18 ERROR - PROCESS | 5637 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:01:19 INFO - TEST-INFO | 5637: exit 0 18:01:19 INFO - Browser initialized. 18:01:19 INFO - Running cycle 1/1 for kraken test... 18:01:19 INFO - TEST-INFO | started process 5721 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjBrHJN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:01:31 INFO - PROCESS | 5721 | RSS: Main: 152854528 18:01:31 INFO - PROCESS | 5721 | 18:01:33 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55664/tests/kraken/driver.html?testName=audio-beat-detection) 18:01:34 INFO - PROCESS | 5721 | RSS: Main: 209145856 18:01:34 INFO - PROCESS | 5721 | 18:01:37 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55664/tests/kraken/driver.html?testName=audio-dft) 18:01:37 INFO - PROCESS | 5721 | RSS: Main: 198574080 18:01:37 INFO - PROCESS | 5721 | 18:01:41 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55664/tests/kraken/driver.html?testName=audio-fft) 18:01:41 INFO - PROCESS | 5721 | RSS: Main: 265613312 18:01:41 INFO - PROCESS | 5721 | 18:01:43 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55664/tests/kraken/driver.html?testName=audio-oscillator) 18:01:44 INFO - PROCESS | 5721 | RSS: Main: 173912064 18:01:44 INFO - PROCESS | 5721 | 18:01:46 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55664/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:01:47 INFO - PROCESS | 5721 | RSS: Main: 178761728 18:01:47 INFO - PROCESS | 5721 | 18:01:50 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55664/tests/kraken/driver.html?testName=imaging-darkroom) 18:01:51 INFO - PROCESS | 5721 | RSS: Main: 215351296 18:01:51 INFO - PROCESS | 5721 | 18:01:54 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55664/tests/kraken/driver.html?testName=imaging-desaturate) 18:01:55 INFO - PROCESS | 5721 | RSS: Main: 212787200 18:01:55 INFO - PROCESS | 5721 | 18:01:58 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55664/tests/kraken/driver.html?testName=json-parse-financial) 18:01:58 INFO - PROCESS | 5721 | RSS: Main: 214495232 18:01:58 INFO - PROCESS | 5721 | 18:02:00 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55664/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:02:00 INFO - PROCESS | 5721 | RSS: Main: 201904128 18:02:00 INFO - PROCESS | 5721 | 18:02:02 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55664/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:02:03 INFO - PROCESS | 5721 | RSS: Main: 204165120 18:02:03 INFO - PROCESS | 5721 | 18:02:05 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55664/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:02:06 INFO - PROCESS | 5721 | RSS: Main: 216576000 18:02:06 INFO - PROCESS | 5721 | 18:02:08 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55664/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:02:09 INFO - PROCESS | 5721 | RSS: Main: 224464896 18:02:09 INFO - PROCESS | 5721 | 18:02:12 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55664/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:02:12 INFO - PROCESS | 5721 | RSS: Main: 222158848 18:02:12 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | Cycle 1(1): loaded http://localhost:55664/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55664/tests/kraken/driver.html?testName=ai-astar) 18:02:14 INFO - PROCESS | 5721 | RSS: Main: 268283904 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | __start_tp_report 18:02:14 INFO - PROCESS | 5721 | _x_x_mozilla_page_load 18:02:14 INFO - PROCESS | 5721 | _x_x_mozilla_page_load_details 18:02:14 INFO - PROCESS | 5721 | |i|pagename|runs| 18:02:14 INFO - PROCESS | 5721 | |0;ai-astar;110;105;110;109;111;109;109;108;109;109 18:02:14 INFO - PROCESS | 5721 | |1;audio-beat-detection;156;126;155;211;126;155;156;125;178;127 18:02:14 INFO - PROCESS | 5721 | |2;audio-dft;161;162;160;161;160;160;162;161;160;160 18:02:14 INFO - PROCESS | 5721 | |3;audio-fft;74;78;75;151;75;75;76;74;137;75 18:02:14 INFO - PROCESS | 5721 | |4;audio-oscillator;91;90;144;91;90;91;125;91;91;128 18:02:14 INFO - PROCESS | 5721 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;117;117;116 18:02:14 INFO - PROCESS | 5721 | |6;imaging-darkroom;146;145;146;146;146;146;146;145;147;146 18:02:14 INFO - PROCESS | 5721 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;96;95 18:02:14 INFO - PROCESS | 5721 | |8;json-parse-financial;56;53;54;53;55;56;54;108;53;52 18:02:14 INFO - PROCESS | 5721 | |9;json-stringify-tinderbox;58;58;58;58;59;57;57;58;57;60 18:02:14 INFO - PROCESS | 5721 | |10;stanford-crypto-aes;78;83;81;78;78;79;84;80;84;85 18:02:14 INFO - PROCESS | 5721 | |11;stanford-crypto-ccm;119;126;116;118;116;118;121;123;193;130 18:02:14 INFO - PROCESS | 5721 | |12;stanford-crypto-pbkdf2;164;161;159;159;159;164;164;162;165;158 18:02:14 INFO - PROCESS | 5721 | |13;stanford-crypto-sha256-iterative;62;63;62;62;62;62;62;60;61;70 18:02:14 INFO - PROCESS | 5721 | __end_tp_report 18:02:14 INFO - PROCESS | 5721 | __start_cc_report 18:02:14 INFO - PROCESS | 5721 | _x_x_mozilla_cycle_collect,1855 18:02:14 INFO - PROCESS | 5721 | __end_cc_report 18:02:14 INFO - PROCESS | 5721 | __startTimestamp1463533334774__endTimestamp 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | ------- Summary: start ------- 18:02:14 INFO - PROCESS | 5721 | Number of tests: 14 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#0] ai-astar Cycles:10 Average:108.90 Median:109.00 stddev:1.60 (1.5%) stddev-sans-first:1.64 18:02:14 INFO - PROCESS | 5721 | Values: 110.0 105.0 110.0 109.0 111.0 109.0 109.0 108.0 109.0 109.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#1] audio-beat-detection Cycles:10 Average:151.50 Median:155.50 stddev:27.71 (17.8%) stddev-sans-first:29.34 18:02:14 INFO - PROCESS | 5721 | Values: 156.0 126.0 155.0 211.0 126.0 155.0 156.0 125.0 178.0 127.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 18:02:14 INFO - PROCESS | 5721 | Values: 161.0 162.0 160.0 161.0 160.0 160.0 162.0 161.0 160.0 160.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#3] audio-fft Cycles:10 Average:89.00 Median:75.00 stddev:29.20 (38.9%) stddev-sans-first:30.46 18:02:14 INFO - PROCESS | 5721 | Values: 74.0 78.0 75.0 151.0 75.0 75.0 76.0 74.0 137.0 75.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:91.00 stddev:20.68 (22.7%) stddev-sans-first:21.45 18:02:14 INFO - PROCESS | 5721 | Values: 91.0 90.0 144.0 91.0 90.0 91.0 125.0 91.0 91.0 128.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 18:02:14 INFO - PROCESS | 5721 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 18:02:14 INFO - PROCESS | 5721 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 147.0 146.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:02:14 INFO - PROCESS | 5721 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:54.00 stddev:17.13 (31.7%) stddev-sans-first:18.12 18:02:14 INFO - PROCESS | 5721 | Values: 56.0 53.0 54.0 53.0 55.0 56.0 54.0 108.0 53.0 52.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#9] json-stringify-tinderbox Cycles:10 Average:58.00 Median:58.00 stddev:0.94 (1.6%) stddev-sans-first:1.00 18:02:14 INFO - PROCESS | 5721 | Values: 58.0 58.0 58.0 58.0 59.0 57.0 57.0 58.0 57.0 60.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:82.00 stddev:2.79 (3.4%) stddev-sans-first:2.74 18:02:14 INFO - PROCESS | 5721 | Values: 78.0 83.0 81.0 78.0 78.0 79.0 84.0 80.0 84.0 85.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:122.00 stddev:23.28 (19.1%) stddev-sans-first:24.46 18:02:14 INFO - PROCESS | 5721 | Values: 119.0 126.0 116.0 118.0 116.0 118.0 121.0 123.0 193.0 130.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:163.00 stddev:2.64 (1.6%) stddev-sans-first:2.64 18:02:14 INFO - PROCESS | 5721 | Values: 164.0 161.0 159.0 159.0 159.0 164.0 164.0 162.0 165.0 158.0 18:02:14 INFO - PROCESS | 5721 | 18:02:14 INFO - PROCESS | 5721 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.00 stddev:2.72 (4.4%) stddev-sans-first:2.87 18:02:14 INFO - PROCESS | 5721 | Values: 62.0 63.0 62.0 62.0 62.0 62.0 62.0 60.0 61.0 70.0 18:02:14 INFO - PROCESS | 5721 | -------- Summary: end -------- 18:02:14 INFO - PROCESS | 5721 | 18:02:15 INFO - TEST-INFO | 5721: exit 0 18:02:15 INFO - TEST-OK | kraken | took 62996ms 18:02:15 INFO - SUITE-END | took 1176s 18:02:15 INFO - Completed test suite (00:19:36) 18:02:15 INFO - javascript benchmark 18:02:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3142.968570314297, 3148.905532834016, 3173.0956677346962, 3180.125465496989, 3218.291975765331, 5315.7873685052555, 5348.117560602501, 5363.839084827452, 5424.701641409721, 5441.863953401162, 5406.324209473816, 5444.591655625824, 5453.700046497451, 5462.590305727076, 5462.617616766828, 4850.39370078741, 4948.059868624968, 4956.553910148086, 4965.975170124144, 5141.845744627667, 5030.37120359955, 5061.595072394218, 5091.592672586203, 5103.56619687327, 5110.977804439127, 4751.833685820997, 4766.165920963852, 4792.664513484057, 4821.108095002401, 4841.15279826029, 4817.9036419271415, 4843.66094373394, 4922.507749225048, 4923.630727695401, 4965.82619608312, 4870.853874383739, 4881.951180488185, 4886.291487734258, 4894.3147959285725, 4942.159832828428, 5133.768980395891, 5297.364316282033, 5382.2464854920345, 5424.538914192299, 5465.125579907196, 5892.616979896293, 5912.763489460416, 5925.229720136398, 5931.851703707442, 5965.791197308074, 5690.630109042899, 5975.43233392827, 6012.939870601325, 6024.126501657235, 6024.277086749618, 5386.407495175527, 5502.9174562381595, 5510.173473978895, 5521.254630624894, 5568.136938774496, 6032.325960049212, 6033.423201966596, 6075.969620151871, 6086.087086936995, 6094.360092190344, 5605.691686957257, 5636.520895723827, 5850.473457388855, 5854.853628659318, 5856.619319744203, 5677.659340439587, 5737.454941780524, 5748.45389687979, 5759.164921086419, 5811.854703632358, 5717.599768016282, 5735.168400581976, 5761.884762304814, 5762.567807414461, 5767.423257674199, 5650.802221922295, 5721.485066344049, 5770.365259821417, 5784.479396854304, 5787.884242315215, 4432.645388368923, 4447.177272204653, 4450.332450132506, 4485.596296333346, 4493.236149854532, 3918.294706952776, 3955.0903292242947, 3964.345882929342, 3984.6015398460504, 3985.143194213258, 5359.973200134053, 5387.542058924957, 5435.755391007414, 5437.02133615953, 5470.890582188334, 5908.822735317947, 5919.526437884959, 5922.407759224129, 5928.644281343133, 5968.104784282393, 5446.482584154499, 5462.0, 5510.834674959758, 5536.114221724505, 5540.667559946416, 4876.804927802919, 5054.317667114966, 5099.107656160157, 5160.200168973815, 5163.8192663256605, 6166.0, 6179.876402471926, 6183.3816618337805, 6184.257889053342, 6187.969060154671, 5958.19564358806, 5978.730957106854, 5979.0, 5983.970080149571, 5988.730507127103, 5940.198073260228, 5954.7320370582565, 5999.820005399845, 6005.789797357072, 6054.848628784315, 5646.943530564642, 5674.035413979551, 5687.573431992534, 5691.516221121251, 5702.344230413555, 5656.208130861765, 5820.883582328414, 5830.854228644318, 5833.82498525045, 5838.649681019152, 5649.887002259849, 5689.459501347365, 5698.088305871041, 5749.741261643319, 5753.769849205984, 5730.169125476866, 5735.7418916148035, 5737.053386191232, 5737.971310143423, 5741.25363702716, 5428.077226871363, 5598.272224610774, 5623.409541998195, 5623.943760562342, 5624.268845050117, 5827.825165245049, 5883.676397798162, 5884.529237660891, 5892.852678683067, 5893.086571581412, 5658.377578466448, 5686.658800471985, 5693.4875861172695, 5699.173619825185, 5703.515201207943], "value": 5379.70770382036, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.8657052163261, 625.1872565664635, 634.2547506679651, 635.0886477904207, 648.3386945315779, 32858.17854553637, 36402.45396319054, 44109.77945110274, 44842.0, 45070.7746461268, 46838.0, 47449.28826067612, 47520.76239618797, 47588.28617570739, 47616.76191619037, 8932.106789321064, 9081.273498120134, 9088.818223635557, 9089.318301127409, 9091.0, 8905.243054340388, 8946.463212207256, 8976.0, 8978.640854365818, 9009.684661036865, 9036.322275829307, 9074.591643376063, 9077.36458447909, 9084.545772711372, 9105.408148470327, 8965.0, 9003.864942025875, 9006.594703238305, 9016.18854303116, 9017.909820901772, 8791.252743516809, 8860.24687901529, 8868.911310886937, 8869.290456763509, 8882.333824963089, 43099.78450107761, 43175.56824431779, 43388.0, 43615.0, 43629.78185109086, 3417.2311229973056, 3570.4644303354444, 3583.623719509466, 3735.831887565066, 4304.504981927087, 33000.6699933, 33707.32585348279, 34341.82829085863, 34509.0, 34565.0, 4380.934285985712, 4390.582894625038, 4393.207509586163, 4512.278035514335, 4537.9773101134615, 358618.0, 359980.0, 360295.9999999974, 360340.0, 360789.0, 35411.82294088513, 36518.8174059128, 60158.69920650369, 60451.0, 60874.0, 37866.43200351997, 37871.242575148346, 37990.43014354787, 38017.0, 38023.42964855529, 14793.186374749277, 14857.0, 14878.776818347733, 14896.553103406915, 14903.776443353358, 12511.687207819696, 12583.559575414816, 12592.685182870318, 12599.622011339674, 12607.180533265308, 8625.525596092275, 8668.393212475192, 8687.087855775053, 8740.125987401336, 8748.081451447633, 3332.1169889979174, 3370.76404651672, 3373.9662603373654, 3396.3037577296595, 3429.8456569454434, 1056.5509658395151, 1065.4938904020528, 1067.215596536545, 1128.0355296221776, 1130.141092769501, 33362.66637333644, 34129.31741365208, 34135.65864341325, 34410.48384274238, 34919.0, 1117.2514415341577, 1142.1091548592112, 1149.6148790155391, 1198.0774803401332, 1315.2371624457792, 17294.308227670754, 17935.192916318538, 17996.910015449837, 18054.819451805575, 18149.27402903869, 472884.0, 474141.0, 475762.0, 475997.0, 476179.0, 58675.41324586785, 59921.700391498634, 60065.0, 60147.594096236535, 60309.095363569584, 1282.9615111546668, 1392.436063394327, 1413.1026797983354, 1421.5166843273337, 1425.850285720005, 33728.325433491686, 35333.46999795057, 35595.82202088973, 35686.46470303, 35758.0, 53557.73221133869, 53886.19170712364, 54120.72939635276, 54202.186967194735, 54236.45763542472, 411757.0, 415975.0, 416821.0, 417517.0, 418008.0, 25682.0, 29292.0, 46548.767256163504, 47074.0, 47719.76140119277, 48059.75970120127, 52206.47793522168, 53473.73263133659, 53732.46267537431, 54307.0, 42239.788801055794, 43481.0, 44224.33663494986, 44659.55340446684, 45103.7744811287, 43776.56223437852, 44010.55989440065, 44158.77920610505, 44344.0, 45125.097498049196, 45328.77335613301, 46019.76990115028, 46258.0, 46623.76688116538, 46775.298370523786, 37273.440898386005, 39797.40303895502, 39955.40066899057, 40059.59940400675, 40631.39052914149, 29716.405671886874, 30131.0, 30510.694893050786, 30605.387892242477, 31128.0], "value": 19648.276355522965, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.2942938146388, 414.8174803086642, 416.860351782153, 417.2885230681686, 418.2972606021882, 16790.0, 18127.0, 18733.250669973186, 20001.799982000175, 20039.1984320627, 18583.628327433442, 18757.718634220462, 18865.62268754624, 18967.24131034757, 18987.52531186721, 5792.942070579304, 5907.793227237047, 5966.79116230932, 5974.731137098841, 5975.820725378246, 5672.262605861232, 5806.042003069489, 5812.273465816773, 5813.09896965971, 5816.272965879265, 5476.808311709091, 5773.942260577403, 5776.191333213354, 5870.530357571384, 5875.970620146893, 5799.9130013049835, 5810.4189581041555, 5837.6497410155525, 5852.414758524156, 6513.413792758674, 5779.9422005779825, 5794.159846822229, 5814.389489103625, 5822.039363505017, 5832.620879642852, 13055.477780888754, 13253.73492530144, 13332.800007999887, 13400.0, 13422.0, 2311.5376924615143, 2385.9642105368434, 2469.592517234654, 2668.6664166979126, 2670.1588999465107, 9869.11177993984, 9935.801283974351, 10002.349847259977, 10083.344582602107, 10196.0, 1890.7258447525032, 1981.5112139373998, 1984.5237143085699, 2036.1549956767924, 2051.5281485258474, 77028.0, 78972.0, 79804.20195798026, 80422.59788701078, 83354.58322708408, 17029.40397086096, 17452.0, 17736.645267094846, 18398.908005460155, 18544.258229670933, 32066.839665801523, 32304.676953230635, 32307.838460807547, 32487.51268730971, 32558.51162232521, 9344.2524598032, 9523.476208808443, 9624.711258662252, 10005.749856253651, 10138.290319677551, 8466.957665211634, 8638.654453821902, 8684.8697269541, 8685.461099874845, 8802.603882825288, 8639.17927796871, 9090.954540227958, 9199.356045076915, 9221.631134754669, 9295.349325547282, 3377.459606462954, 3435.9656403436147, 3460.602030766444, 3611.4402267648557, 3662.450632405107, 924.3252425729121, 996.3473924579412, 1038.7922415516928, 1063.2238465919768, 1086.4459125845876, 18457.16942737553, 18881.339153129575, 19013.904930475535, 19075.04624768784, 19633.410997670093, 1026.2867307221559, 1047.2145890582065, 1058.4496062047692, 1102.2284400919389, 1131.4738646529404, 10632.840507392395, 11021.944890275658, 11032.0, 11126.332420054823, 11179.83230251547, 473274.0, 481119.0, 484487.0, 487365.0, 494520.0, 83448.58275708582, 93691.0, 94159.52920235354, 94339.52830235942, 94980.52509737408, 1097.5554900265406, 1098.9285696429706, 1107.4020029184312, 1112.3770688414513, 1113.8496302998992, 17017.659646807242, 17214.39749608758, 17422.564435889206, 17473.38843140484, 17829.197686104417, 41683.7915810419, 42496.36255456232, 42988.14023719571, 43257.0, 43269.350959735, 59980.0, 60837.69581152066, 60893.39106608877, 60898.0, 62088.379116208256, 8390.622421991147, 8633.91366086331, 8744.300455963381, 8803.515806630698, 8915.910840891507, 12222.0, 12941.158824676366, 13298.667533311746, 13567.932160339134, 13654.317284135952, 6395.712192951235, 8907.910920890708, 11346.886531134583, 11703.23928944616, 11722.94138529302, 11246.325220486797, 11366.090712743126, 11368.204225704121, 11372.54509819598, 11548.133889958119, 12018.639440816789, 12147.81778273309, 12175.63473095773, 12190.0, 12216.87783122193, 8397.0, 8547.914520854712, 8596.613152408032, 8667.653293868174, 8838.823223535623, 8597.699080532151, 10975.560977560808, 11402.429878506207, 11539.134564907834, 11794.705132371759], "value": 9870.651522650483, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.560556227569, 1532.9693406131873, 1581.4939219449773, 1778.3686791189127, 1886.2549293029244, 8801.42790718603, 9119.133682300178, 9135.314851386156, 9479.289053321012, 9579.80840383192, 9301.441913485196, 9511.524423778817, 9519.857202141973, 9527.952360238207, 9528.523573821316, 2112.4930016795934, 2377.72656144543, 2442.5237078769646, 2455.5579995600783, 2489.029278581355, 2454.5459090068307, 2465.568525508038, 2477.913273035444, 2483.3170878008514, 2487.9751202488014, 2418.0327868852423, 2444.4866578018637, 2447.510497900418, 2450.644656524803, 2451.2646206138174, 2119.7562280337806, 2347.319277409549, 2417.601095819188, 2421.031587365068, 2428.5264373447185, 2280.737715162761, 2285.1202287119413, 2287.3938406322336, 2289.095807156181, 2328.138588722167, 9358.766030849216, 9376.71869843899, 9423.0, 9430.151286384258, 9436.339456238065, 5470.17947307903, 5565.66606003637, 5569.10894256917, 5579.9163012554845, 5588.050031494656, 3552.484889691006, 3564.8752293669595, 3611.837467313951, 3646.4348026056005, 3663.816809159558, 1197.21582363552, 1222.2177806204036, 1225.828384026237, 1233.2477188914754, 1265.949362025518, 12912.0, 12912.870871291261, 12919.483220671067, 12934.935325323408, 12986.870131298661, 4858.902821943612, 4969.552740253394, 4977.776000080005, 4985.850424487271, 5059.62052846038, 10813.459327033617, 10870.782584348268, 10885.564577416973, 10924.0, 10927.344359338465, 5052.242163675478, 5406.729663516809, 5454.972725136349, 5461.1808228766, 5468.4531546845, 4589.885252868705, 4691.483936766953, 4743.644226682944, 5133.717645529457, 5179.715115668599, 4164.104717485755, 4220.936685949713, 4307.6769242306955, 4314.870553883388, 4345.9130817383475, 1307.4312673986847, 1331.4674130347744, 1342.1142046249429, 1342.90599658023, 1351.8850897673808, 932.9486878221763, 1071.314358810362, 1072.1744256922127, 1081.9513121909533, 1088.689723428835, 6469.061986012002, 6597.670116494156, 6618.669066546653, 6647.368499992493, 6804.489663275274, 449.2946074662748, 454.6135784583144, 458.7866642011481, 471.15662963296, 482.0768228841799, 4537.2059889519205, 4538.636909047268, 4590.472095708969, 4594.989102397467, 4600.769961501912, 11398.943005285086, 11585.6524304271, 11848.348340841165, 11996.700082498008, 12022.519099236108, 11999.940000299943, 12036.45835937385, 12072.154949153475, 12078.335691536875, 12163.148579599343, 8649.740507784776, 8816.86774698393, 8825.558722063999, 8837.248833849195, 8843.778905527413, 6446.613203207822, 6606.207255129415, 6708.6310252936555, 6732.663366831737, 6827.419669328163, 10810.135189185004, 10854.45727713627, 10989.175811813986, 11165.776684466427, 11194.552217911518, 10875.891241087487, 11119.332840029625, 11168.720781980515, 11236.887631123584, 11383.943080284545, 7388.335049845539, 7913.208679132041, 8030.357571394391, 8132.146124656826, 8183.140770219041, 9347.719568412958, 9376.859347109663, 9437.716868493957, 9444.0555944405, 9452.669156579486, 8117.0, 8174.100848906496, 8196.057453392936, 8295.336373090258, 8363.080061193386, 8144.063432705315, 8166.42835001568, 8185.508869467851, 8319.500829950222, 8337.041240257447, 7663.578503182379, 7679.19368466303, 7745.883811742716, 7758.224177582197, 7762.223777622193, 5661.745221465127, 5695.319880635139, 5709.0865461525955, 5873.177755114202, 5874.265716785402, 5695.345035320825, 5704.600677952503, 5742.425757424391, 5840.649561026352, 6035.1249068885645], "value": 4861.035012440794, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.86745169383937, 348.3068693300331, 350.98245087745613, 351.5078889554625, 356.8965000149955, 5279.181726832342, 5318.627696061278, 5319.707416092114, 5364.79292159264, 5391.433899440561, 5160.136124450928, 5286.709230992294, 5301.7084060376665, 5317.3619165700175, 5323.041852466555, 4026.5973402659793, 4121.876343709693, 4156.732196680012, 4184.937225941613, 4253.213155566223, 4091.754494730311, 4108.465899433085, 4119.608637179478, 4126.442930204414, 4159.3968874513175, 4113.794310284488, 4123.752574845519, 4136.482939632546, 4137.627613514783, 4138.503379594438, 3897.1815918657117, 4084.611961863618, 4106.178764247147, 4122.999999999985, 4139.275626765304, 3612.674859262679, 3623.58328792187, 3628.5101511296166, 3640.7269454791, 3645.8359373828243, 5111.488851114896, 5128.410232823236, 5145.305383773218, 5150.922736158961, 5154.742262886841, 4593.609543188833, 4749.786259618326, 4760.000399916022, 4767.261074533453, 4769.880752981168, 5474.042042642522, 5519.8536834004635, 5551.9722401387735, 5569.832905012856, 5583.6928968906695, 3998.2203470323298, 4012.8194231259663, 4024.3561030235023, 4041.7574945503366, 4083.632473077437, 5297.17893726473, 5595.601099725069, 5845.649261044351, 5872.148538461899, 5893.528517718572, 4035.334169861999, 4036.9394459083137, 4050.10897602527, 4051.6758659307184, 4069.9389509157386, 4111.075008123148, 4114.958850411457, 4119.629233369011, 4127.112670775738, 4130.504339479222, 4519.79660915253, 4593.908121837611, 4698.9530104698515, 4766.856994290177, 4773.474917759044, 5544.77820887168, 5555.638883472561, 5560.972195139079, 5574.303212098488, 5581.2186293918885, 1422.4879043544315, 1437.5328018394064, 1438.0293302021303, 1443.9061461005, 1445.3423692220015, 897.3001059173855, 931.753085432361, 933.9346245762731, 941.3457646935451, 950.9001554836781, 4754.405699287589, 4804.975975120102, 4817.710937343771, 4864.759486331034, 4865.464798872121, 5043.823466178666, 5071.442141364447, 5084.745762711849, 5086.287919691246, 5093.898122037613, 4614.584687378152, 4806.903861922742, 4819.807207711652, 4819.807207711722, 4832.95167048332, 2727.9045233416737, 2746.585508463143, 2759.903403380872, 2760.751532362057, 2770.3074231442138, 5204.152525853267, 5264.447233040552, 5289.418164001957, 5342.252084708144, 5352.732363381815, 4842.539958703917, 5151.124308867502, 5181.911798522277, 5197.454267301954, 5272.947270527246, 5660.349059858087, 5758.798442054508, 5845.970770146122, 5884.3520415813055, 5901.822945311647, 5690.459406356389, 5697.430256974435, 5710.373228314856, 5712.171735098304, 5712.400197979152, 5464.453554644504, 5578.63738856973, 5608.4111168326735, 5657.915131272952, 5658.207850900795, 5775.740091695968, 5856.297244330707, 5867.413258674099, 5882.676452795137, 5927.496162826208, 4126.00975765808, 4140.668746500333, 4148.688848336326, 4149.27387707144, 4165.375193720966, 5537.0, 5707.574280930505, 5734.0, 5744.885102298014, 5847.532197424281, 5567.220589117445, 5589.217509548585, 5603.55171586264, 5612.821307525465, 5637.88724225505, 5283.577313814809, 5615.241942337732, 5622.662640241599, 5622.718864056699, 5633.38032816398, 5445.237666726741, 5471.659443436383, 5485.561155107662, 5487.560995120461, 5501.312335958005, 5545.279113715191, 5548.334199896039, 5566.387697353207, 5578.972105139448, 5580.497755201889, 5288.2860813791185, 5461.2627295316215, 5465.0, 5474.507294343528, 5486.862828429321], "value": 4171.677613447669, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [811.8701007838744, 815.2744057788567, 821.0065820357368, 828.9212524810143, 847.8261956298959, 14212.36044378003, 14265.714685706293, 14412.351444185011, 14453.927730361333, 14454.349554270057, 13481.0, 13511.594652160451, 13534.120282181651, 13562.321883905815, 13574.389152488136, 672.2705864137405, 785.5365334452672, 826.099551488877, 826.8263664630434, 830.2776584371575, 816.828466022136, 821.219841150909, 821.3757544266341, 824.2787560884226, 824.7031068815222, 704.6758491094103, 884.0364003236471, 885.8405487012335, 886.4149661223594, 887.1394747095308, 823.6417158536026, 859.2309882655052, 861.6381119929645, 861.8448679237734, 862.2455351567379, 637.1525870592102, 637.8150336402438, 637.9776707815204, 638.0270088115615, 638.581728967527, 13365.264910429922, 13401.46394144233, 13411.396487158101, 13412.329383530881, 13418.060735748502, 13660.316984150753, 14100.929495352457, 14233.28833558318, 14344.64138396538, 14453.855461445357, 11164.218504704673, 11182.217244792868, 11374.488148033359, 11374.829377559343, 11518.88481115195, 4458.197524445599, 4472.932906006413, 4528.569785870337, 4625.468071171791, 4653.7440440775745, 56664.716676416356, 56760.71619641917, 56810.0, 56850.43149568493, 58035.0, 11238.7190320241, 11288.2662626929, 11481.0, 11501.482433290523, 11533.308001519936, 20031.198752049753, 20124.698129528068, 20139.39581812548, 20153.992300385216, 20175.192992280437, 7662.846743065219, 7825.060992680801, 8028.317593004544, 8047.436679432383, 8062.596870156469, 4580.541945805459, 4735.0, 4748.335233067373, 4837.879053023703, 4896.779644915988, 3315.4529502631794, 3416.8120753358307, 3421.5551978243166, 3421.9315613687586, 3424.989628059728, 624.0576729138942, 624.5908929651085, 634.5811764235675, 639.9520035997319, 643.4080645805869, 688.6006116452446, 713.5183750968076, 715.4777012780595, 744.4416687484387, 748.2480107491936, 9189.862152067724, 9335.673251436167, 9351.532423378803, 9550.188234000188, 9592.136707696342, 615.7352338494401, 640.8173670503977, 658.3778329478597, 707.0384277382756, 761.3642608421919, 4343.761093139908, 4346.673999450054, 4382.320740285261, 4428.291473364311, 4548.772561371984, 66932.33067669359, 70435.64782176056, 71314.0, 71357.0, 72338.63830680918, 61679.0, 61996.07005894915, 62726.37273627205, 63078.369216308165, 63239.0, 55006.72496637491, 55245.0, 55305.1704224437, 55394.44605553974, 55655.443445565834, 8612.009089244018, 8777.648894044167, 8806.779830504289, 8813.91186088131, 8945.642174312954, 36987.81506092452, 37270.0, 37543.99999999891, 37748.0, 38046.61953380431, 45439.77280113578, 49537.25694114519, 50398.49601503938, 51108.23337649864, 51158.0, 12216.938915305722, 12245.0, 12377.814332784836, 13419.194848308742, 13528.93235533816, 14678.63303417423, 14925.552233433014, 15107.924460377628, 15505.922470387575, 15539.766903496231, 13917.443302267797, 14118.2940852959, 14350.928245358706, 14557.344919478437, 15153.696926061637, 13631.182129072287, 13860.653483662585, 13987.720245595234, 14456.349464273922, 14545.200013998909, 12549.435275412447, 12691.682707932376, 12760.106792524433, 12817.423215955117, 13352.198868067559, 10519.73700657459, 10834.729131771453, 10936.234463587472, 11411.372374519482, 11733.0, 10344.482775861328, 11000.39418993943, 11224.551017959517, 11313.830292545454, 11503.597374091867], "value": 6990.017226024135, "unit": "score"}], "name": "dromaeo_css", "value": 7272.188508678319, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 110.0, 109.0, 111.0, 109.0, 109.0, 108.0, 109.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 126.0, 155.0, 211.0, 126.0, 155.0, 156.0, 125.0, 178.0, 127.0], "value": 151.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 160.0, 160.0, 162.0, 161.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 75.0, 151.0, 75.0, 75.0, 76.0, 74.0, 137.0, 75.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 144.0, 91.0, 90.0, 91.0, 125.0, 91.0, 91.0, 128.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 53.0, 55.0, 56.0, 54.0, 108.0, 53.0, 52.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 59.0, 57.0, 57.0, 58.0, 57.0, 60.0], "value": 58.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 81.0, 78.0, 78.0, 79.0, 84.0, 80.0, 84.0, 85.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 126.0, 116.0, 118.0, 116.0, 118.0, 121.0, 123.0, 193.0, 130.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 159.0, 159.0, 159.0, 164.0, 164.0, 162.0, 165.0, 158.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 60.0, 61.0, 70.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1521.6, "alertThreshold": 2.0}]} 18:02:15 INFO - Return code: 0 18:02:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:02:16 ERROR - # TBPL SUCCESS # 18:02:16 INFO - Running post-action listener: _resource_record_post_action 18:02:16 INFO - ##### 18:02:16 INFO - ##### Finished run-tests step (success) 18:02:16 INFO - ##### 18:02:16 INFO - Running post-run listener: _resource_record_post_run 18:02:16 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 499712; Write bytes: 292495360; Read time: 80; Write time: 390732 18:02:16 INFO - TinderboxPrint: CPU usage
13.2% 18:02:16 INFO - TinderboxPrint: I/O read bytes / time
499,712 / 80 18:02:16 INFO - TinderboxPrint: I/O write bytes / time
292,495,360 / 390,732 18:02:16 INFO - TinderboxPrint: CPU idle
8,274.4 (86.8%) 18:02:16 INFO - TinderboxPrint: CPU user
1,206.7 (12.7%) 18:02:16 INFO - TinderboxPrint: Swap in / out
0 / 0 18:02:16 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 8253440; Read time: 0; Write time: 944 18:02:16 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 499712; Write bytes: 284209152; Read time: 80; Write time: 389128 18:02:16 INFO - Running post-run listener: _upload_blobber_files 18:02:16 INFO - Blob upload gear active. 18:02:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:02:16 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 18:02:16 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'] 18:02:16 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 18:02:17 INFO - (blobuploader) - INFO - Open directory for files ... 18:02:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:02:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:02:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:02:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:02:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:02:17 INFO - (blobuploader) - INFO - Done attempting. 18:02:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:02:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:02:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:02:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:02:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:02:21 INFO - (blobuploader) - INFO - Done attempting. 18:02:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:02:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:02:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:02:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:02:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:02:22 INFO - (blobuploader) - INFO - Done attempting. 18:02:22 INFO - (blobuploader) - INFO - Iteration through files over. 18:02:22 INFO - Return code: 0 18:02:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:02:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:02:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83ce2ab43b70ab4032a4f28569034ebacdf57f362b5167c0d48e6db4edb8231c88ed06000554fb8427a5a8da55e8c613f77417001841c46d8fa7722da641de8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b8e8149a96c30f195de0d3871d5101cd2509dfdb1f3fad8225ad4029b8acbad777ec78ead87f7d64d501318e1685406a78996035068fe3a95e07e85bb06b395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bde380c36e89ff525534e6922c699a01d3c9c4687d513c2001b430d061656950e09efeae8dc2b421e6ea585eecbff63471d202f7e2cf2faaa3a52743a703987a"} 18:02:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:02:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:02:22 INFO - Contents: 18:02:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83ce2ab43b70ab4032a4f28569034ebacdf57f362b5167c0d48e6db4edb8231c88ed06000554fb8427a5a8da55e8c613f77417001841c46d8fa7722da641de8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b8e8149a96c30f195de0d3871d5101cd2509dfdb1f3fad8225ad4029b8acbad777ec78ead87f7d64d501318e1685406a78996035068fe3a95e07e85bb06b395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bde380c36e89ff525534e6922c699a01d3c9c4687d513c2001b430d061656950e09efeae8dc2b421e6ea585eecbff63471d202f7e2cf2faaa3a52743a703987a"} 18:02:22 INFO - Running post-run listener: copy_logs_to_upload_dir 18:02:22 INFO - Copying logs to upload dir... 18:02:22 INFO - mkdir: /builds/slave/test/build/upload/logs 18:02:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.850658 ========= master_lag: 13.95 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-05-17 18:02:36.012347) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 18:02:36.015941) ========= 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=33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957 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/83ce2ab43b70ab4032a4f28569034ebacdf57f362b5167c0d48e6db4edb8231c88ed06000554fb8427a5a8da55e8c613f77417001841c46d8fa7722da641de8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b8e8149a96c30f195de0d3871d5101cd2509dfdb1f3fad8225ad4029b8acbad777ec78ead87f7d64d501318e1685406a78996035068fe3a95e07e85bb06b395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bde380c36e89ff525534e6922c699a01d3c9c4687d513c2001b430d061656950e09efeae8dc2b421e6ea585eecbff63471d202f7e2cf2faaa3a52743a703987a"} build_url:https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010059 build_url: 'https://queue.taskcluster.net/v1/task/Y68P-li6TR2ycCC4hmf81Q/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/83ce2ab43b70ab4032a4f28569034ebacdf57f362b5167c0d48e6db4edb8231c88ed06000554fb8427a5a8da55e8c613f77417001841c46d8fa7722da641de8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b8e8149a96c30f195de0d3871d5101cd2509dfdb1f3fad8225ad4029b8acbad777ec78ead87f7d64d501318e1685406a78996035068fe3a95e07e85bb06b395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bde380c36e89ff525534e6922c699a01d3c9c4687d513c2001b430d061656950e09efeae8dc2b421e6ea585eecbff63471d202f7e2cf2faaa3a52743a703987a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 18:02:36.047929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 18:02:36.048644) ========= 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=33d5923976266a1c518c4bf7000001ab-1463532098.57161-85023957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 18:02:36.098464) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 18:02:36.098834) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 18:02:36.099157) ========= ========= Total master_lag: 14.08 =========