builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1463176059.33 results: success (0) buildid: 20160513113013 builduid: 9928dc09ea3249ab98cd4dacec97cb7f revision: c4a209a8e2a8483f71182ecfa6044b830fd85362 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.327826) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.328906) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.329574) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.352146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.352871) ========= 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=52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-13 14:47: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% 31.9M=0s 2016-05-13 14:47:39 (31.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108908 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.480274) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.480975) ========= 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=52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019793 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.532802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:39.533580) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c4a209a8e2a8483f71182ecfa6044b830fd85362 --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 c4a209a8e2a8483f71182ecfa6044b830fd85362 --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=52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-13 14:47:39,612 truncating revision to first 12 chars 2016-05-13 14:47:39,612 Setting DEBUG logging. 2016-05-13 14:47:39,612 attempt 1/10 2016-05-13 14:47:39,612 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c4a209a8e2a8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 14:47:39,894 unpacking tar archive at: fx-team-c4a209a8e2a8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.581278 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:40.130223) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:40.130665) ========= script_repo_revision: c4a209a8e2a8483f71182ecfa6044b830fd85362 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:40.131174) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:40.131554) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 14:47:40.140786) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-13 14:47:40.141106) ========= /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=52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:47:40 INFO - MultiFileLogger online at 20160513 14:47:40 in /builds/slave/test 14:47: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 14:47:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:47:40 INFO - {'append_to_log': False, 14:47:40 INFO - 'base_work_dir': '/builds/slave/test', 14:47:40 INFO - 'blob_upload_branch': 'Fx-Team', 14:47:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:47:40 INFO - 'branch': 'Fx-Team', 14:47:40 INFO - 'buildbot_json_path': 'buildprops.json', 14:47:40 INFO - 'config_files': ('talos/linux_config.py',), 14:47:40 INFO - 'default_actions': ('clobber', 14:47:40 INFO - 'read-buildbot-config', 14:47:40 INFO - 'download-and-extract', 14:47:40 INFO - 'populate-webroot', 14:47:40 INFO - 'create-virtualenv', 14:47:40 INFO - 'install', 14:47:40 INFO - 'run-tests'), 14:47:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:47:40 INFO - 'download_minidump_stackwalk': True, 14:47:40 INFO - 'download_symbols': 'ondemand', 14:47:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:47:40 INFO - 'tooltool.py': '/tools/tooltool.py', 14:47:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:47:40 INFO - '/tools/misc-python/virtualenv.py')}, 14:47:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:47:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:47:40 INFO - 'installer_path': 'installer.exe', 14:47:40 INFO - 'log_level': 'info', 14:47:40 INFO - 'log_name': 'talos', 14:47:40 INFO - 'log_to_console': True, 14:47:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:47:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:47:40 INFO - 'opt_config_files': (), 14:47:40 INFO - 'pip_index': False, 14:47:40 INFO - 'sps_profile': False, 14:47:40 INFO - 'sps_profile_interval': 0, 14:47:40 INFO - 'suite': 'dromaeojs', 14:47:40 INFO - 'system_bits': '32', 14:47:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:47:40 INFO - 'title': 'talos-linux64-ix-056', 14:47:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:47:40 INFO - 'use_talos_json': True, 14:47:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:47:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:47:40 INFO - 'work_dir': 'build'} 14:47:40 INFO - ##### 14:47:40 INFO - ##### Running clobber step. 14:47:40 INFO - ##### 14:47:40 INFO - Running pre-action listener: _resource_record_pre_action 14:47:40 INFO - Running main action method: clobber 14:47:40 INFO - rmtree: /builds/slave/test/build 14:47:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:47:40 INFO - Running post-action listener: _resource_record_post_action 14:47:40 INFO - ##### 14:47:40 INFO - ##### Finished clobber step (success) 14:47:40 INFO - ##### 14:47:40 INFO - ##### 14:47:40 INFO - ##### Running read-buildbot-config step. 14:47:40 INFO - ##### 14:47:40 INFO - Running pre-action listener: _resource_record_pre_action 14:47:40 INFO - Running main action method: read_buildbot_config 14:47:40 INFO - Using buildbot properties: 14:47:40 INFO - { 14:47:40 INFO - "project": "", 14:47:40 INFO - "product": "firefox", 14:47:40 INFO - "script_repo_revision": "production", 14:47:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 14:47:40 INFO - "repository": "", 14:47:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 14:47:40 INFO - "buildid": "20160513113013", 14:47:40 INFO - "pgo_build": "True", 14:47:40 INFO - "basedir": "/builds/slave/test", 14:47:40 INFO - "buildnumber": 83, 14:47:40 INFO - "slavename": "talos-linux64-ix-056", 14:47:40 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:47:40 INFO - "platform": "ubuntu64_hw", 14:47:40 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 14:47:40 INFO - "branch": "fx-team", 14:47:40 INFO - "repo_path": "integration/fx-team", 14:47:40 INFO - "revision": "c4a209a8e2a8483f71182ecfa6044b830fd85362", 14:47:40 INFO - "stage_platform": "linux64-pgo", 14:47:40 INFO - "builduid": "9928dc09ea3249ab98cd4dacec97cb7f", 14:47:40 INFO - "slavebuilddir": "test" 14:47:40 INFO - } 14:47:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:47:40 INFO - Running post-action listener: _resource_record_post_action 14:47:40 INFO - ##### 14:47:40 INFO - ##### Finished read-buildbot-config step (success) 14:47:40 INFO - ##### 14:47:40 INFO - ##### 14:47:40 INFO - ##### Running download-and-extract step. 14:47:40 INFO - ##### 14:47:40 INFO - Running pre-action listener: _resource_record_pre_action 14:47:40 INFO - Running main action method: download_and_extract 14:47:40 INFO - mkdir: /builds/slave/test/build/tests 14:47: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')]} 14:47:40 INFO - https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:47:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:47:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:47:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:41 INFO - Downloaded 1361 bytes. 14:47:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:47:41 INFO - Using the following test package requirements: 14:47:41 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:47:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:47:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:47:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:47:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:47:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:47:41 INFO - u'jsshell-linux-x86_64.zip'], 14:47:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:47:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:47:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:47:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:47:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:47:41 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:47:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:47:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:47:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:47:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:47:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:47:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:47: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')]} 14:47:41 INFO - https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:47:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:47:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:47:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:42 INFO - Downloaded 23909523 bytes. 14:47: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'] 14:47: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 14:47:43 INFO - Return code: 0 14:47:43 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 14:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:47:43 INFO - https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:47:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:47:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:47:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:45 INFO - Downloaded 23909523 bytes. 14:47:45 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'] 14:47:45 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 14:47:46 INFO - Return code: 0 14:47:46 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')]} 14:47:46 INFO - https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:47:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:47:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:47:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:47 INFO - Downloaded 10840239 bytes. 14:47:47 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'] 14:47:47 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 14:47:47 INFO - Return code: 0 14:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:47:47 INFO - https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:47:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:47:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:47:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TgOAhaGlRhSGofhl78advA/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 14:47:49 INFO - Downloaded 58399498 bytes. 14:47:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:47:49 INFO - mkdir: /builds/slave/test/properties 14:47:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:47:49 INFO - Writing to file /builds/slave/test/properties/build_url 14:47:49 INFO - Contents: 14:47:49 INFO - build_url:https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:47:49 INFO - Running post-action listener: _resource_record_post_action 14:47:49 INFO - Running post-action listener: set_extra_try_arguments 14:47:49 INFO - ##### 14:47:49 INFO - ##### Finished download-and-extract step (success) 14:47:49 INFO - ##### 14:47:49 INFO - ##### 14:47:49 INFO - ##### Running populate-webroot step. 14:47:49 INFO - ##### 14:47:49 INFO - Running pre-action listener: _resource_record_pre_action 14:47:49 INFO - Running main action method: populate_webroot 14:47:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:47:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:47:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:47:49 INFO - '--tppagecycles', 14:47:49 INFO - '7'], 14:47:49 INFO - 'tests': ['tsvgm']}}, 14:47:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:47:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:47:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:47:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:47:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:49 INFO - 'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:47:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:47:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:49 INFO - 'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['damp', 'tps']}, 14:47:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:49 INFO - 'tests': ['damp', 'tps']}, 14:47:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['dromaeo_dom']}, 14:47:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:47:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['a11yr', 14:47:49 INFO - 'ts_paint', 14:47:49 INFO - 'tpaint', 14:47:49 INFO - 'sessionrestore', 14:47:49 INFO - 'sessionrestore_no_auto_restore', 14:47:49 INFO - 'tabpaint']}, 14:47:49 INFO - 'other-e10s': {'tests': ['a11yr', 14:47:49 INFO - 'ts_paint', 14:47:49 INFO - 'tpaint', 14:47:49 INFO - 'sessionrestore', 14:47:49 INFO - 'sessionrestore_no_auto_restore', 14:47:49 INFO - 'tabpaint']}, 14:47:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:47:49 INFO - 'ts_paint', 14:47:49 INFO - 'tpaint', 14:47:49 INFO - 'sessionrestore', 14:47:49 INFO - 'sessionrestore_no_auto_restore', 14:47:49 INFO - 'tabpaint']}, 14:47:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:47:49 INFO - 'ts_paint', 14:47:49 INFO - 'tpaint', 14:47:49 INFO - 'sessionrestore', 14:47:49 INFO - 'sessionrestore_no_auto_restore', 14:47:49 INFO - 'tabpaint']}, 14:47:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['a11yr', 14:47:49 INFO - 'ts_paint', 14:47:49 INFO - 'tpaint', 14:47:49 INFO - 'sessionrestore', 14:47:49 INFO - 'sessionrestore_no_auto_restore', 14:47:49 INFO - 'tabpaint']}, 14:47:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['a11yr', 14:47:49 INFO - 'ts_paint', 14:47:49 INFO - 'tpaint', 14:47:49 INFO - 'sessionrestore', 14:47:49 INFO - 'sessionrestore_no_auto_restore', 14:47:49 INFO - 'tabpaint']}, 14:47:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['tsvgx', 14:47:49 INFO - 'tsvgr_opacity', 14:47:49 INFO - 'tart', 14:47:49 INFO - 'tscrollx', 14:47:49 INFO - 'cart']}, 14:47:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:47:49 INFO - 'tsvgr_opacity', 14:47:49 INFO - 'tart', 14:47:49 INFO - 'tscrollx', 14:47:49 INFO - 'cart']}, 14:47:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:49 INFO - 'talos_options': ['--disable-e10s'], 14:47:49 INFO - 'tests': ['tp5o']}, 14:47:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:49 INFO - 'tests': ['tp5o']}, 14:47:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:49 INFO - 'talos_options': ['--disable-e10s', 14:47:49 INFO - '--xperf_path', 14:47:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:47:49 INFO - 'tests': ['tp5n']}, 14:47:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:49 INFO - 'talos_options': ['--xperf_path', 14:47:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:47:49 INFO - 'tests': ['tp5n']}}, 14:47:49 INFO - 'talos.zip': {'path': '', 14:47:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:47:49 INFO - Running post-action listener: _resource_record_post_action 14:47:49 INFO - ##### 14:47:49 INFO - ##### Finished populate-webroot step (success) 14:47:49 INFO - ##### 14:47:49 INFO - ##### 14:47:49 INFO - ##### Running create-virtualenv step. 14:47:49 INFO - ##### 14:47:49 INFO - Running pre-action listener: _resource_record_pre_action 14:47:49 INFO - Running main action method: create_virtualenv 14:47:49 INFO - Creating virtualenv /builds/slave/test/build/venv 14:47:49 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 14:47:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:47:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:47:49 INFO - Using real prefix '/usr' 14:47:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:47:50 INFO - Installing distribute.............................................................................................................................................................................................done. 14:47:52 INFO - Installing pip.................done. 14:47:52 INFO - Return code: 0 14:47:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47:52 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 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:47:52 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 14:47:52 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 14:47:52 INFO - Using env: {'DISPLAY': ':0', 14:47:52 INFO - 'HOME': '/home/cltbld', 14:47:52 INFO - 'LANG': 'en_US.UTF-8', 14:47:52 INFO - 'LANGUAGE': 'en_US:en', 14:47:52 INFO - 'LOGNAME': 'cltbld', 14:47:52 INFO - 'MAIL': '/var/mail/cltbld', 14:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:52 INFO - 'MOZ_NO_REMOTE': '1', 14:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:47:52 INFO - 'NO_EM_RESTART': '1', 14:47:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:52 INFO - 'PWD': '/builds/slave/test', 14:47:52 INFO - 'SHELL': '/bin/bash', 14:47:52 INFO - 'SHLVL': '1', 14:47:52 INFO - 'TERM': 'linux', 14:47:52 INFO - 'TMOUT': '86400', 14:47:52 INFO - 'USER': 'cltbld', 14:47:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:52 INFO - '_': '/tools/buildbot/bin/python'} 14:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:52 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 14:47:52 INFO - Cleaning up... 14:47:52 INFO - Return code: 0 14:47:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47:52 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 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:47:52 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 14:47:52 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 14:47:52 INFO - Using env: {'DISPLAY': ':0', 14:47:52 INFO - 'HOME': '/home/cltbld', 14:47:52 INFO - 'LANG': 'en_US.UTF-8', 14:47:52 INFO - 'LANGUAGE': 'en_US:en', 14:47:52 INFO - 'LOGNAME': 'cltbld', 14:47:52 INFO - 'MAIL': '/var/mail/cltbld', 14:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:52 INFO - 'MOZ_NO_REMOTE': '1', 14:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:47:52 INFO - 'NO_EM_RESTART': '1', 14:47:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:52 INFO - 'PWD': '/builds/slave/test', 14:47:52 INFO - 'SHELL': '/bin/bash', 14:47:52 INFO - 'SHLVL': '1', 14:47:52 INFO - 'TERM': 'linux', 14:47:52 INFO - 'TMOUT': '86400', 14:47:52 INFO - 'USER': 'cltbld', 14:47:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:52 INFO - '_': '/tools/buildbot/bin/python'} 14:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:52 INFO - Downloading/unpacking psutil>=3.1.1 14:47:52 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 14:47:52 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 14:47:52 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 14:47:52 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 14:47:52 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 14:47:52 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 14:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:47:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:47:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:47:55 INFO - Installing collected packages: psutil 14:47:55 INFO - Running setup.py install for psutil 14:47:55 INFO - building 'psutil._psutil_linux' extension 14:47:55 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 14:47:55 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 14:47:55 INFO - building 'psutil._psutil_posix' extension 14:47:55 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 14:47:55 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 14:47:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:47:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:47:56 INFO - Successfully installed psutil 14:47:56 INFO - Cleaning up... 14:47:57 INFO - Return code: 0 14:47:57 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 14:47:57 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')]} 14:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47:57 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')]} 14:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47:57 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.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:47:57 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.1'] in /builds/slave/test/build 14:47:57 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.1 14:47:57 INFO - Using env: {'DISPLAY': ':0', 14:47:57 INFO - 'HOME': '/home/cltbld', 14:47:57 INFO - 'LANG': 'en_US.UTF-8', 14:47:57 INFO - 'LANGUAGE': 'en_US:en', 14:47:57 INFO - 'LOGNAME': 'cltbld', 14:47:57 INFO - 'MAIL': '/var/mail/cltbld', 14:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:57 INFO - 'MOZ_NO_REMOTE': '1', 14:47:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:47:57 INFO - 'NO_EM_RESTART': '1', 14:47:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:57 INFO - 'PWD': '/builds/slave/test', 14:47:57 INFO - 'SHELL': '/bin/bash', 14:47:57 INFO - 'SHLVL': '1', 14:47:57 INFO - 'TERM': 'linux', 14:47:57 INFO - 'TMOUT': '86400', 14:47:57 INFO - 'USER': 'cltbld', 14:47:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:57 INFO - '_': '/tools/buildbot/bin/python'} 14:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:57 INFO - Downloading/unpacking mozsystemmonitor==0.1 14:47:57 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 14:47:57 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 14:47:57 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 14:47:57 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 14:47:57 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 14:47:57 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 14:47:59 INFO - Downloading mozsystemmonitor-0.1.tar.gz 14:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 14:47:59 INFO - Installing collected packages: mozsystemmonitor 14:47:59 INFO - Running setup.py install for mozsystemmonitor 14:47:59 INFO - Successfully installed mozsystemmonitor 14:47:59 INFO - Cleaning up... 14:47:59 INFO - Return code: 0 14:47:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:47: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')]} 14:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47: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')]} 14:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47: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', '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 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:47: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:47: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 blobuploader==1.2.4 14:47:59 INFO - Using env: {'DISPLAY': ':0', 14:47:59 INFO - 'HOME': '/home/cltbld', 14:47:59 INFO - 'LANG': 'en_US.UTF-8', 14:47:59 INFO - 'LANGUAGE': 'en_US:en', 14:47:59 INFO - 'LOGNAME': 'cltbld', 14:47:59 INFO - 'MAIL': '/var/mail/cltbld', 14:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:59 INFO - 'MOZ_NO_REMOTE': '1', 14:47:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:47:59 INFO - 'NO_EM_RESTART': '1', 14:47:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:59 INFO - 'PWD': '/builds/slave/test', 14:47:59 INFO - 'SHELL': '/bin/bash', 14:47:59 INFO - 'SHLVL': '1', 14:47:59 INFO - 'TERM': 'linux', 14:47:59 INFO - 'TMOUT': '86400', 14:47:59 INFO - 'USER': 'cltbld', 14:47:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:59 INFO - '_': '/tools/buildbot/bin/python'} 14:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:59 INFO - Downloading/unpacking blobuploader==1.2.4 14:47:59 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 14:47:59 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 14:47:59 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 14:47:59 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 14:47:59 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 14:47:59 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 14:48:01 INFO - Downloading blobuploader-1.2.4.tar.gz 14:48:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:48:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:48:01 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 14:48:01 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 14:48:01 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 14:48:01 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 14:48:01 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 14:48:01 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 14:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:48:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48:02 INFO - Downloading docopt-0.6.1.tar.gz 14:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:48:02 INFO - Installing collected packages: blobuploader, requests, docopt 14:48:02 INFO - Running setup.py install for blobuploader 14:48:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:48:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:48:02 INFO - Running setup.py install for requests 14:48:02 INFO - Running setup.py install for docopt 14:48:03 INFO - Successfully installed blobuploader requests docopt 14:48:03 INFO - Cleaning up... 14:48:03 INFO - Return code: 0 14:48:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:03 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')]} 14:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:03 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')]} 14:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:03 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 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:48:03 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 14:48:03 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 14:48:03 INFO - Using env: {'DISPLAY': ':0', 14:48:03 INFO - 'HOME': '/home/cltbld', 14:48:03 INFO - 'LANG': 'en_US.UTF-8', 14:48:03 INFO - 'LANGUAGE': 'en_US:en', 14:48:03 INFO - 'LOGNAME': 'cltbld', 14:48:03 INFO - 'MAIL': '/var/mail/cltbld', 14:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:03 INFO - 'MOZ_NO_REMOTE': '1', 14:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:03 INFO - 'NO_EM_RESTART': '1', 14:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:03 INFO - 'PWD': '/builds/slave/test', 14:48:03 INFO - 'SHELL': '/bin/bash', 14:48:03 INFO - 'SHLVL': '1', 14:48:03 INFO - 'TERM': 'linux', 14:48:03 INFO - 'TMOUT': '86400', 14:48:03 INFO - 'USER': 'cltbld', 14:48:03 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:03 INFO - '_': '/tools/buildbot/bin/python'} 14:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:48:03 INFO - Running setup.py (path:/tmp/pip-VY3Q2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:48:03 INFO - Running setup.py (path:/tmp/pip-PPdu2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:48:03 INFO - Running setup.py (path:/tmp/pip-NvzN8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:48:03 INFO - Running setup.py (path:/tmp/pip-jHtKr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:48:03 INFO - Running setup.py (path:/tmp/pip-uqYhoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:48:03 INFO - Running setup.py (path:/tmp/pip-gtUn9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:48:03 INFO - Running setup.py (path:/tmp/pip-prKjRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:48:03 INFO - Running setup.py (path:/tmp/pip-GoSTZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:48:03 INFO - Running setup.py (path:/tmp/pip-1MLVAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:48:04 INFO - Running setup.py (path:/tmp/pip-Kotlcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:48:04 INFO - Running setup.py (path:/tmp/pip-21klRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:48:04 INFO - Running setup.py (path:/tmp/pip-nXlyHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:48:04 INFO - Running setup.py (path:/tmp/pip-TtMa0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:48:04 INFO - Running setup.py (path:/tmp/pip-IsNcX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:04 INFO - Running setup.py (path:/tmp/pip-Y0wmPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:48:04 INFO - Running setup.py (path:/tmp/pip-tQI0j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:48:04 INFO - Running setup.py (path:/tmp/pip-FfIL8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:48:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:48:04 INFO - Running setup.py install for manifestparser 14:48:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:48:04 INFO - Running setup.py install for mozcrash 14:48:04 INFO - Running setup.py install for mozdebug 14:48:04 INFO - Running setup.py install for mozdevice 14:48:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:48:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:48:04 INFO - Running setup.py install for mozfile 14:48:05 INFO - Running setup.py install for mozhttpd 14:48:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Running setup.py install for mozinfo 14:48:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Running setup.py install for mozInstall 14:48:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Running setup.py install for mozleak 14:48:05 INFO - Running setup.py install for mozlog 14:48:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Running setup.py install for moznetwork 14:48:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Running setup.py install for mozprocess 14:48:05 INFO - Running setup.py install for mozprofile 14:48:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:48:05 INFO - Running setup.py install for mozrunner 14:48:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:48:06 INFO - Running setup.py install for mozscreenshot 14:48:06 INFO - Running setup.py install for moztest 14:48:06 INFO - Running setup.py install for mozversion 14:48:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:48:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:48:06 INFO - Cleaning up... 14:48:06 INFO - Return code: 0 14:48:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:06 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 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:48:06 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 14:48:06 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 14:48:06 INFO - Using env: {'DISPLAY': ':0', 14:48:06 INFO - 'HOME': '/home/cltbld', 14:48:06 INFO - 'LANG': 'en_US.UTF-8', 14:48:06 INFO - 'LANGUAGE': 'en_US:en', 14:48:06 INFO - 'LOGNAME': 'cltbld', 14:48:06 INFO - 'MAIL': '/var/mail/cltbld', 14:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:06 INFO - 'MOZ_NO_REMOTE': '1', 14:48:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:06 INFO - 'NO_EM_RESTART': '1', 14:48:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:06 INFO - 'PWD': '/builds/slave/test', 14:48:06 INFO - 'SHELL': '/bin/bash', 14:48:06 INFO - 'SHLVL': '1', 14:48:06 INFO - 'TERM': 'linux', 14:48:06 INFO - 'TMOUT': '86400', 14:48:06 INFO - 'USER': 'cltbld', 14:48:06 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:06 INFO - '_': '/tools/buildbot/bin/python'} 14:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:48:06 INFO - Running setup.py (path:/tmp/pip-tYWaC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:48:06 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)) 14:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:48:06 INFO - Running setup.py (path:/tmp/pip-e95zPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:48:06 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)) 14:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:48:06 INFO - Running setup.py (path:/tmp/pip-AvuGGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:48:06 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)) 14:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:48:06 INFO - Running setup.py (path:/tmp/pip-lymN1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:48:06 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)) 14:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:48:06 INFO - Running setup.py (path:/tmp/pip-Ba4gpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:48:06 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)) 14:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:48:06 INFO - Running setup.py (path:/tmp/pip-aStei4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:48:07 INFO - Running setup.py (path:/tmp/pip-cmcBgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:48:07 INFO - Running setup.py (path:/tmp/pip-PWlGgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:48:07 INFO - Running setup.py (path:/tmp/pip-q0Fzg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:48:07 INFO - Running setup.py (path:/tmp/pip-9FEnPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:48:07 INFO - Running setup.py (path:/tmp/pip-S4MGWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:48:07 INFO - Running setup.py (path:/tmp/pip-aHBF06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:48:07 INFO - Running setup.py (path:/tmp/pip-yJntSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:48:07 INFO - Running setup.py (path:/tmp/pip-XrjWYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:07 INFO - Running setup.py (path:/tmp/pip-BBQrij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:48:07 INFO - Running setup.py (path:/tmp/pip-sCDU5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:48:07 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)) 14:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:48:07 INFO - Running setup.py (path:/tmp/pip-K22FNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:48:07 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)) 14:48:07 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)) 14:48:07 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)) 14:48:07 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)) 14:48:07 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)) 14:48:07 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)) 14:48:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48:09 INFO - Downloading blessings-1.6.tar.gz 14:48:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:48:09 INFO - Installing collected packages: blessings 14:48:09 INFO - Running setup.py install for blessings 14:48:09 INFO - Successfully installed blessings 14:48:09 INFO - Cleaning up... 14:48:09 INFO - Return code: 0 14:48:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:48:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:48:10 INFO - Reading from file tmpfile_stdout 14:48:10 INFO - Current package versions: 14:48:10 INFO - argparse == 1.2.1 14:48:10 INFO - blessings == 1.6 14:48:10 INFO - blobuploader == 1.2.4 14:48:10 INFO - docopt == 0.6.1 14:48:10 INFO - manifestparser == 1.1 14:48:10 INFO - mozInstall == 1.12 14:48:10 INFO - mozcrash == 0.17 14:48:10 INFO - mozdebug == 0.1 14:48:10 INFO - mozdevice == 0.48 14:48:10 INFO - mozfile == 1.2 14:48:10 INFO - mozhttpd == 0.7 14:48:10 INFO - mozinfo == 0.9 14:48:10 INFO - mozleak == 0.1 14:48:10 INFO - mozlog == 3.2 14:48:10 INFO - moznetwork == 0.27 14:48:10 INFO - mozprocess == 0.22 14:48:10 INFO - mozprofile == 0.28 14:48:10 INFO - mozrunner == 6.11 14:48:10 INFO - mozscreenshot == 0.1 14:48:10 INFO - mozsystemmonitor == 0.1 14:48:10 INFO - moztest == 0.7 14:48:10 INFO - mozversion == 1.4 14:48:10 INFO - psutil == 3.1.1 14:48:10 INFO - requests == 1.2.3 14:48:10 INFO - wsgiref == 0.1.2 14:48:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:10 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 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:48:10 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 14:48:10 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 14:48:10 INFO - Using env: {'DISPLAY': ':0', 14:48:10 INFO - 'HOME': '/home/cltbld', 14:48:10 INFO - 'LANG': 'en_US.UTF-8', 14:48:10 INFO - 'LANGUAGE': 'en_US:en', 14:48:10 INFO - 'LOGNAME': 'cltbld', 14:48:10 INFO - 'MAIL': '/var/mail/cltbld', 14:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:10 INFO - 'MOZ_NO_REMOTE': '1', 14:48:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:10 INFO - 'NO_EM_RESTART': '1', 14:48:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:10 INFO - 'PWD': '/builds/slave/test', 14:48:10 INFO - 'SHELL': '/bin/bash', 14:48:10 INFO - 'SHLVL': '1', 14:48:10 INFO - 'TERM': 'linux', 14:48:10 INFO - 'TMOUT': '86400', 14:48:10 INFO - 'USER': 'cltbld', 14:48:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:10 INFO - '_': '/tools/buildbot/bin/python'} 14:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 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)) 14:48:10 INFO - Cleaning up... 14:48:10 INFO - Return code: 0 14:48:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:48:10 INFO - Using env: {'DISPLAY': ':0', 14:48:10 INFO - 'HOME': '/home/cltbld', 14:48:10 INFO - 'LANG': 'en_US.UTF-8', 14:48:10 INFO - 'LANGUAGE': 'en_US:en', 14:48:10 INFO - 'LOGNAME': 'cltbld', 14:48:10 INFO - 'MAIL': '/var/mail/cltbld', 14:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:10 INFO - 'MOZ_NO_REMOTE': '1', 14:48:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:10 INFO - 'NO_EM_RESTART': '1', 14:48:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:10 INFO - 'PWD': '/builds/slave/test', 14:48:10 INFO - 'SHELL': '/bin/bash', 14:48:10 INFO - 'SHLVL': '1', 14:48:10 INFO - 'TERM': 'linux', 14:48:10 INFO - 'TMOUT': '86400', 14:48:10 INFO - 'USER': 'cltbld', 14:48:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:10 INFO - '_': '/tools/buildbot/bin/python'} 14:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:10 INFO - Downloading/unpacking jsonschema 14:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:48:12 INFO - Downloading/unpacking functools32 (from jsonschema) 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48:12 INFO - Downloading functools32-3.2.3-2.tar.gz 14:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:48:12 INFO - warning: no files found matching '*.txt' 14:48:12 INFO - no previously-included directories found matching 'build' 14:48:12 INFO - no previously-included directories found matching 'dist' 14:48:12 INFO - no previously-included directories found matching '.git*' 14:48:12 INFO - Installing collected packages: jsonschema, functools32 14:48:12 INFO - Running setup.py install for functools32 14:48:12 INFO - warning: no files found matching '*.txt' 14:48:12 INFO - no previously-included directories found matching 'build' 14:48:12 INFO - no previously-included directories found matching 'dist' 14:48:12 INFO - no previously-included directories found matching '.git*' 14:48:12 INFO - Successfully installed jsonschema functools32 14:48:12 INFO - Cleaning up... 14:48:12 INFO - Return code: 0 14:48:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5b6e1a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, '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': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', '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 14:48:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:48:12 INFO - Using env: {'DISPLAY': ':0', 14:48:12 INFO - 'HOME': '/home/cltbld', 14:48:12 INFO - 'LANG': 'en_US.UTF-8', 14:48:12 INFO - 'LANGUAGE': 'en_US:en', 14:48:12 INFO - 'LOGNAME': 'cltbld', 14:48:12 INFO - 'MAIL': '/var/mail/cltbld', 14:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:12 INFO - 'MOZ_NO_REMOTE': '1', 14:48:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:12 INFO - 'NO_EM_RESTART': '1', 14:48:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:12 INFO - 'PWD': '/builds/slave/test', 14:48:12 INFO - 'SHELL': '/bin/bash', 14:48:12 INFO - 'SHLVL': '1', 14:48:12 INFO - 'TERM': 'linux', 14:48:12 INFO - 'TMOUT': '86400', 14:48:12 INFO - 'USER': 'cltbld', 14:48:12 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:12 INFO - '_': '/tools/buildbot/bin/python'} 14:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:13 INFO - Downloading/unpacking flake8 14:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:48:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:48:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48: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 14:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:48:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:48:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:48:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:48:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:48:15 INFO - no previously-included directories found matching 'docs/_build' 14:48:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:48:15 INFO - Running setup.py install for pep8 14:48:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:48:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:48:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:48:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:48:15 INFO - no previously-included directories found matching 'docs/_build' 14:48:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:48:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:48:15 INFO - Cleaning up... 14:48:15 INFO - Return code: 0 14:48:15 INFO - Running post-action listener: _resource_record_post_action 14:48:15 INFO - Running post-action listener: _start_resource_monitoring 14:48:16 INFO - Starting resource monitoring. 14:48:16 INFO - ##### 14:48:16 INFO - ##### Finished create-virtualenv step (success) 14:48:16 INFO - ##### 14:48:16 INFO - ##### 14:48:16 INFO - ##### Running install step. 14:48:16 INFO - ##### 14:48:16 INFO - Running pre-action listener: _resource_record_pre_action 14:48:16 INFO - Running main action method: install 14:48:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:48:16 INFO - Reading from file tmpfile_stdout 14:48:16 INFO - Detecting whether we're running mozinstall >=1.0... 14:48:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:48:16 INFO - Reading from file tmpfile_stdout 14:48:16 INFO - Output received: 14:48:16 INFO - Usage: mozinstall [options] installer 14:48:16 INFO - Options: 14:48:16 INFO - -h, --help show this help message and exit 14:48:16 INFO - -d DEST, --destination=DEST 14:48:16 INFO - Directory to install application into. [default: 14:48:16 INFO - "/builds/slave/test"] 14:48:16 INFO - --app=APP Application being installed. [default: firefox] 14:48:16 INFO - mkdir: /builds/slave/test/build/application 14:48:16 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'] 14:48:16 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 14:48:32 INFO - Reading from file tmpfile_stdout 14:48:32 INFO - Output received: 14:48:32 INFO - /builds/slave/test/build/application/firefox/firefox 14:48:32 INFO - Running post-action listener: _resource_record_post_action 14:48:32 INFO - ##### 14:48:32 INFO - ##### Finished install step (success) 14:48:32 INFO - ##### 14:48:32 INFO - ##### 14:48:32 INFO - ##### Running run-tests step. 14:48:32 INFO - ##### 14:48:32 INFO - Running pre-action listener: _resource_record_pre_action 14:48:32 INFO - Running main action method: run_tests 14:48:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:48:32 INFO - Python 2.7.3 14:48:32 INFO - Return code: 0 14:48:32 INFO - grabbing minidump binary from tooltool 14:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:32 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 0x10ff9c0>, '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 0x11008c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:48:32 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 14:48:32 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 14:48:32 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 14:48:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:48:32 INFO - Return code: 0 14:48:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:48:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:48:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:48:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:48:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:48:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:48:33 INFO - Return code: 0 14:48:33 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/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 14:48:33 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/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 14:48:33 INFO - Using env: {'DISPLAY': ':0', 14:48:33 INFO - 'HOME': '/home/cltbld', 14:48:33 INFO - 'LANG': 'en_US.UTF-8', 14:48:33 INFO - 'LANGUAGE': 'en_US:en', 14:48:33 INFO - 'LOGNAME': 'cltbld', 14:48:33 INFO - 'MAIL': '/var/mail/cltbld', 14:48:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:48:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:33 INFO - 'MOZ_NO_REMOTE': '1', 14:48:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:48:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:33 INFO - 'NO_EM_RESTART': '1', 14:48:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:33 INFO - 'PWD': '/builds/slave/test', 14:48:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:48:33 INFO - 'SHELL': '/bin/bash', 14:48:33 INFO - 'SHLVL': '1', 14:48:33 INFO - 'TERM': 'linux', 14:48:33 INFO - 'TMOUT': '86400', 14:48:33 INFO - 'USER': 'cltbld', 14:48:33 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457', 14:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:33 INFO - '_': '/tools/buildbot/bin/python'} 14:48:33 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/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 14:48:34 INFO - mozversion application_buildid: 20160513113013 14:48:34 INFO - mozversion application_changeset: c4a209a8e2a8483f71182ecfa6044b830fd85362 14:48:34 INFO - mozversion application_display_name: Nightly 14:48:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:48:34 INFO - mozversion application_name: Firefox 14:48:34 INFO - mozversion application_remotingname: firefox 14:48:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:48:34 INFO - mozversion application_vendor: Mozilla 14:48:34 INFO - mozversion application_version: 49.0a1 14:48:34 INFO - mozversion platform_buildid: 20160513113013 14:48:34 INFO - mozversion platform_changeset: c4a209a8e2a8483f71182ecfa6044b830fd85362 14:48:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:48:34 INFO - mozversion platform_version: 49.0a1 14:48:34 INFO - using testdate: 1463176114 14:48:34 INFO - actual date: 1463176114 14:48:34 INFO - starting webserver on 'localhost:38234' 14:48:34 INFO - SUITE-START | Running 2 tests 14:48:34 INFO - TEST-START | dromaeo_css 14:48:34 INFO - operating with platform_type : linux_ 14:48:34 INFO - Initialising browser for dromaeo_css test... 14:48:34 INFO - TEST-INFO | started process 7122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgtuDoy/profile http://localhost:38234/getInfo.html) 14:48:38 INFO - PROCESS | 7122 | __metrics Screen width/height:1600/1200 14:48:38 INFO - PROCESS | 7122 | colorDepth:24 14:48:38 INFO - PROCESS | 7122 | Browser inner width/height: 1024/697 14:48:38 INFO - PROCESS | 7122 | __metrics 14:48:39 ERROR - PROCESS | 7122 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:48:40 INFO - TEST-INFO | 7122: exit 0 14:48:40 INFO - Browser initialized. 14:48:40 INFO - Running cycle 1/1 for dromaeo_css test... 14:48:40 INFO - TEST-INFO | started process 7206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgtuDoy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:48:52 INFO - PROCESS | 7206 | RSS: Main: 152260608 14:48:52 INFO - PROCESS | 7206 | 14:49:43 INFO - PROCESS | 7206 | 1463176183409 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] 14:51:51 INFO - PROCESS | 7206 | Cycle 1(1): loaded http://localhost:38234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38234/tests/dromaeo/cssquery-ext.html) 14:51:52 INFO - PROCESS | 7206 | RSS: Main: 255152128 14:51:52 INFO - PROCESS | 7206 | 14:54:54 INFO - PROCESS | 7206 | Cycle 1(1): loaded http://localhost:38234/tests/dromaeo/cssquery-ext.html (next: http://localhost:38234/tests/dromaeo/cssquery-jquery.html) 14:54:55 INFO - PROCESS | 7206 | RSS: Main: 237989888 14:54:55 INFO - PROCESS | 7206 | 14:57:57 INFO - PROCESS | 7206 | Cycle 1(1): loaded http://localhost:38234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38234/tests/dromaeo/cssquery-mootools.html) 14:57:57 INFO - PROCESS | 7206 | RSS: Main: 239542272 14:57:57 INFO - PROCESS | 7206 | 15:00:59 INFO - PROCESS | 7206 | Cycle 1(1): loaded http://localhost:38234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38234/tests/dromaeo/cssquery-prototype.html) 15:01:00 INFO - PROCESS | 7206 | RSS: Main: 225411072 15:01:00 INFO - PROCESS | 7206 | 15:04:03 INFO - PROCESS | 7206 | Cycle 1(1): loaded http://localhost:38234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38234/tests/dromaeo/cssquery-yui.html) 15:04:03 INFO - PROCESS | 7206 | RSS: Main: 224665600 15:04:03 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | Cycle 1(1): loaded http://localhost:38234/tests/dromaeo/cssquery-yui.html (next: http://localhost:38234/tests/dromaeo/cssquery-dojo.html) 15:07:06 INFO - PROCESS | 7206 | RSS: Main: 260354048 15:07:06 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | __start_tp_report 15:07:06 INFO - PROCESS | 7206 | _x_x_mozilla_page_load 15:07:06 INFO - PROCESS | 7206 | _x_x_mozilla_page_load_details 15:07:06 INFO - PROCESS | 7206 | |i|pagename|runs| 15:07:06 INFO - PROCESS | 7206 | |0;dojo.html;3162.9367412651745;3197.880741740391;3215.0997720638215;3240.3195328980914;3264.0371090528306;5395.325584301962;5464.9180262295995;5483.040467918113;5560.165975103731;5572.916406253904;5486.945130548694;5523.337199536051;5526.696031718254;5595.552355811536;5622.987862184805;4942.431620363659;4949.331840201563;5082.822101226458;5114.43741188469;5128.076946149691;5065.873353166164;5067.366579177603;5093.84718458445;5137.820176293831;5151.76817043232;4942.87642808929;4948.628852836034;4977.228529577921;5034.219695947134;5063.645544811865;5039.143345631215;5052.191649336125;5053.519915608012;5058.848234552969;5090.720010399426;4830.178869592178;4943.752812359367;4950.084234416644;5012.373453318335;5013.974930125362;5452.427495113036;5577.163425486169;5584.134459158837;5584.5811564132855;5605.32736071674;5972.4027597240365;5994.700264986734;5997.640141591475;5999.820005399845;6021.09683547467;5760.567957403169;5819.388964158787;5828.6502809831545;5941.732622032019;5979.22270104888;5557.749901254453;5559.305086864142;5560.2493663355335;5560.555155587544;5567.582431317667;6071.180390647294;6073.605215660968;6077.969610151921;6083.39166083397;6084.787032453843;5702.0591602385975;5784.1034639630925;5848.035074212792;5861.912071318934;5872.765089396462;5971.790987315423;5972.701364931823;5982.132590774313;5988.760449582055;5991.460768530853;5908.763649454059;5922.585419020714;5963.79126730571;5965.55258355625;5973.313068997034;5747.741351639186;5831.183634291203;5867.20792692981;5869.090291004901;5950.494207992369;4463.843765468193;4477.865664030084;4480.843170489017;4482.641388688897;4504;3940.349842275992;3983.3626619740703;3988.80055997199;4022.35642297231;4046.8988275293354;5488.862778430492;5508.03609368367;5511.917321240185;5520.365158006881;5533.806316778974;5864;5918.319393269743;5928.525717942554;5933.851653708605;5946.405359464019;5427.674339539641;5562.054450743383;5582.27430434041;5586.190002449622;5597.776088956396;5025.84922452327;5069.239614057921;5086.847394578169;5096.643234973591;5101.897962040738;6121.816345509642;6134.294556126013;6136.140940268366;6137.60105593135;6151.477124444473;5757.107648314518;5934.287885453859;5946.791862284799;5952.940470595412;5955.315138759098;6016;6064.727087280995;6073.574849760651;6121.081837724377;6128.601640893327;5793.652380857162;5826.883462330815;5839.678817665069;5840.357560668408;5843.474087332161;5842.298924129132;5858.384869588632;5933.436323549256;5940.584159108994;5952.434518720715;5730.312362516524;5758.049921762862;5795.623284486495;5804.361520232856;5860.267466566679;5701.201831743642;5718.542516598745;5732.082866741302;5739.713014349182;5744.4829965303325;5517.36550296721;5568;5609.074502707171;5626.5780066496;5634.380218175916;5784.2191304175085;5824;5826.592138550261;5830.271216097988;5847.210626565529;5656.943430565806;5672.14917762339;5672.2342483765815;5675.347335056521;5692.51613612831 15:07:06 INFO - PROCESS | 7206 | |1;ext.html;604.3654163128714;638.5306799502365;644.6100109433789;650.0152269311992;652.2173391929686;32601.51097733533;35775.64224357755;35854.820725896396;35866;35867.64132358676;44486;45584;45608.3158752619;49275;49367.25949110766;8661.69684061058;8790.64837406503;8792.604332805007;8802.603882825257;8803.119688031178;8724;8802.03177650458;8803.73588792334;8808.03111657717;8809.515476648781;8850.380473366868;8898.199162075412;8926.464412135292;8933.15135062168;8938.64245430182;8799.824003519925;8830.69092581763;8830.86753698695;8855.601497932608;8857.468551886876;8756.562171891444;8761.56192190388;8793.164649358301;8847.911520884772;8867.980182279056;41905.79047104775;42389.57610423918;42416.787916060224;42520.3621945668;42534.78732606348;3644.744867859251;3818.236352729465;3847.3844184930276;3877.7479463835043;4414.227510185737;32200;32478.83760581182;32649.836750816095;32869.50695739566;33788.6621133788;4281.207976524322;4397.626201772853;4399.428074350314;4402.295632698785;4408.14041261954;360588;361767;362075.9999999974;362389;363046;35369.64630353715;36168.81915590458;59547.70226148841;59890.70054649786;60043.699781500814;38144.237115258096;38214.80892595519;38346.233075338336;38346.42480362797;38420.61579384226;15849.445269415746;15975.920120399556;15977.201139942956;15999.440019599258;16027.759583606256;13477.191368517724;13569.1858488491;13570.728585428236;13580.185188888698;13583.52457663977;8693.869591956125;8708.608112634947;8712.69505567302;8777.824443511094;8782.165694259034;3322.2026713588566;3343.823367800599;3363.360961417323;3376.139084533428;3450.4824276358745;1069.6202847989007;1084.7017070305571;1144.8053830848776;1196.4855112301793;1227.3679055286534;33049;33117.834410828276;33681.49477757836;33922.32155356929;34208.74212331526;1166.7666466706692;1194.6416075177412;1227.5089964014467;1231.6428235811693;1258.5972488803677;17303.52920001789;17585.648287034186;17994;18010.729839052423;18077.7288340675;582790;585659;587090;588304;591280;60304.39695603075;60689.393106069256;60777.08834367488;60827.695861520406;61036;1400.355836315286;1413.9568323772255;1416.767412351261;1418.2979425184394;1429.0925262458413;32426.027219183463;33300.66699332976;33889.83055084808;33911.3217735639;34515.654843451244;50659;51158.488415115375;51253.48746512487;51336;51386.48613513967;430883;435154;436162;437059;437383;28457.43085138327;29684.703152969058;29693.10920672383;29699.703002969694;29709.405811883207;47115.76442117767;48438.757806212154;48834.755826220644;49328;49739.50260497349;40163.93035443753;41053.589464106175;41737.79131104325;41916.580834192486;42308.36537451879;40244.59755402408;40544.1891162181;40550.797246014765;41404.58595414007;41563.7921810389;41687.374689379074;41774;41929.79035104805;42746.78626606847;42863.571364285955;34451.82774086113;35545;36259.637403625624;36278;36809;28270.57594136131;28318.85840570784;28703.282417939718;28750.856245718638;28877 15:07:06 INFO - PROCESS | 7206 | |2;jquery.html;370.11727031030983;388.3320688415924;396.98213580388887;408.2264109512474;412.1057305646746;16591.66816663666;19285.035748212587;19288.710669339973;19313.324033658824;20183.69724454131;19301.22795088195;19481.90259048703;20259.59480810383;20344.287949921756;20453.181872725112;5632.098864181732;5727.140928860663;5827.213326200972;5841;5852.882942341151;5700.600957932947;5774.191613174159;5836.2996440427205;5845.56158288128;5846.707664616773;5627.296587926509;5756.568257380693;5791.131330300467;5809.738561764731;5811.6803575803315;5695.914561281584;5750.1949727038245;5754.712264386785;5757.222774925373;5763.971180144073;5055.115354812893;5202.193659982709;5210.505002024802;5227.738613069331;5232.947670523284;23349.299521014396;23556.882215588983;24014.75985240143;24106.276811695676;24163.033478660833;2228.465168359582;2364.148906393697;2417.2144053182788;2514.7531521490278;3051.420230156263;10079.69760907174;10283.640072597424;10286.691399258034;10306.278560500768;12527.56153534622;1984.7320611717523;2030.979690203094;2040.6020825938947;2045.2841505472966;2093.204582258732;53594;57741.711291443695;71267;73972.26027739707;84494;10733.731656708645;10992.560297588007;11206.327620342643;11259.155563332783;11370.09039276856;13456.327183640778;13847.584572462642;14296.213708246147;15860.682786344209;15862.60343491422;8414.200650938152;8439.95780021096;8540.957295213484;8631.223189912938;8681.782955426164;8261.917380826235;8265.297449716842;8337.666493340319;8440.198181172902;8446.61990210442;4684.367610372624;4731.266653668687;4741.383620129361;4834.444038935497;4853.102176097434;1923.9711404328666;2003.4891102768702;2026.2097781865084;2027.6212175720652;2040.6632905570414;985.8619793228954;1040.5369610523244;1110.5613282753268;1111.5553778488509;1114.7213196700825;11612.419379031015;11938.164328496921;12138.817917731241;12397.194182378296;19696.113674884666;495.212611947006;503.16474652077375;509.30479894943875;510.91059064663534;517.6816258001302;6394.776182833579;6464.676766161673;6607.834804129839;6625.503087268377;6634.701438435282;493909;505171;506193;507538;508946;85827;91913.08086919045;103992;104157.39606509898;105493.47253263685;478.7773685236312;480.0950208856261;485.934398856157;486.86367817011296;488.6530563300097;9343.672971445967;11440.199186057229;11731.472083756422;11747.530098795953;11849.466773995018;41245.38131927964;42588.14823703447;42712.145757084065;43006.569934300256;43605.78197108994;54834.72582637061;55587;57702;57917.710411449356;58291.4170858286;8919.598618062333;8933.19601235892;9050.27597792162;9156.954215228881;9228.95385523068;9393.29550283745;11971.221870578385;12392.442340094898;13062.673433164247;13211.66970825737;10976;11126.833097503706;11230.606928757454;11497.885021149681;11582.305061696325;10799.460026998462;10936.234463587472;10951.780964380509;11065.225434219527;11086.61196858106;11441.283807428952;11553.480093395985;11553.595624153451;11648.184627076362;11725.061995040547;8956.417832840845;9082.091790820865;9235.630574776933;9255.76860578491;9301.441913485212;8283.332501372783;10808;10939;11115.055220306365;11286.322820630789 15:07:06 INFO - PROCESS | 7206 | |3;mootools.html;1455.1778245291403;1513.281191434069;1585.8493443122902;1755.6664233795577;1877.6338613970272;8997.505137217458;9372.625094996201;9417.48203848788;9705.36915100518;9722.56248468819;9364.62541498341;9596.808063838736;9599.184069354096;9605.471699056567;9619.230461563076;2081.510844951435;2425.1875621666763;2454.0429232599254;2479.987600061997;2490.8915532588007;2518.0305582350743;2519.546481633305;2530.8354956927833;2532.6580911576884;2532.8353657012326;2402.615581506959;2468.9876550617223;2470.542949554338;2471.888765005579;2475.4801491686767;2074.232533962436;2351.8941647625898;2398.37642213025;2399.292208798391;2410.590199666061;2412.903483860661;2421.092090466075;2441.023590563771;2441.9755802441928;2445.193086281523;9542.52287385635;9584.95207523965;9591.040895910353;9597.856032159523;9601.711948641552;5640.2667653205235;5777.220075289824;5785.739641716133;5797.275340582427;5798.507126894219;3569.428891377393;3633.1280492681567;3645.4167333226555;3686.981565092184;3693.132113953219;1144.1133121830649;1187.7861984842725;1198.161287099034;1209.6552482542434;1220.3288191494642;13573.11774734639;13723.176609403368;13736.313184340843;13760;13771.931140344333;4919.434265059564;4986.775595098229;5031.547160755476;5055.823046193365;5110.28456016158;11348.035416989484;11407.11782555869;11434.542618295342;11435.828462573068;11448.542058317575;5694.572907031989;5699.7435115419075;5706;5709.314882214161;5709.9714501427225;4578.702384345007;5103.336566246364;5247.055530004636;5247.291615631841;5274.314339135888;4330.7401555906745;4341.630961368256;4395.692301538926;4429.579189976947;4432.711873728197;1355.7288542291383;1385.8336999560117;1395.4487977249132;1398.685295808435;1403.1160368967485;1031.9071283584512;1034.5499707627207;1036.896310368957;1052.7473406382412;1059.094474224527;6243.968780156161;6393.840153996187;6410.134631824644;6447.484201263887;6478.967605161944;472.16898259064294;476.25703901913124;483.2220125597785;494.22900275570225;495.89338292267337;4228.344606585984;4380.627646650007;4431.82272709088;4491.550844915548;4509.616682582017;11696.70758231034;12087.395630218454;12108.334041627619;12117.575884844164;12188.268703877795;11456.770864582662;11916.46375913086;11991.940040299743;12014.75970480603;12034.157608967464;8398.832023359499;8756.693515727047;8765.474071555727;8798.86801697975;8825.02924678285;6530;6695.296993815774;6752.257251702407;6799.966000169968;6802.3537763912345;10795.460226988776;11091.833622495507;11118.3329000257;11126.443677815914;11159.665210043711;11184.776304474028;11185.161112916401;11186.720331991766;11323.830142548033;11328;7423.146338170964;7633.389328853567;7634.351080158249;8388.874166887623;8759.12408759119;8611.741647750578;9098.272138229058;9274.350795444525;9314.25485961135;9616.855747163938;8299.336053115858;8372.832543349221;8411.957940210259;8451.577421128795;8534.487930724177;8210.835783284181;8324.916750832657;8335.583220839055;8407.2433480987;8460.323174146177;7253.782386528413;7374.926250737424;7383.261673832574;7390.224026477357;7391.815204619712;5493.835184944458;5507.208986177458;5558.055130627722;5666.603337766482;5673.943260567342;5436.401995780382;5481.780728770805;5485.698286594116;5597.916031259453;5647.350554686263 15:07:06 INFO - PROCESS | 7206 | |4;prototype.html;333.70133730311375;341.56450525579885;348.65134865134866;349.29616822103463;349.6520961643162;5314.937012597481;5410.0802863513245;5418.431064738204;5421.322334708157;5440;5067.543921047105;5197.012567612151;5207.192885102815;5243;5336.6531175473565;4089.3865920111925;4115.135821477494;4168.8332466701295;4241.448611680493;4242.066745316026;4164.501299220474;4219.556946520617;4225.535191128974;4229.830806767741;4230.513490948534;4028.5295867008476;4178.268802959486;4182.97908510457;4191.287481128215;4215.346621273708;4150.335946248586;4164.167166566668;4168.62482376586;4188.81150348235;4199.706020578561;3714;3726.720495962814;3730.9813450932843;3731.5522137343423;3734.1038150843874;4942.9258561121615;5088.898222035538;5139.871503212412;5143.305653736735;5152.639315247934;4712.680449474153;4717.127331443694;4750.833720819789;4759.809607615691;4778.259369797687;5531.751071201806;5540.252065971083;5545.694986775726;5555.611107222496;5567.582431317667;3953.1303113315025;4052.7770972596495;4060.533038700558;4072.2466343726505;4080.918556582508;5743.540516758691;5763.6253643513455;5768.942310576925;5780.739866705966;5784.508316793078;4168.895777605523;4175.663787587942;4181.393697913785;4201;4211.515675697273;4044;4173.537612808457;4216.915661686749;4233.936490952638;4259.552746961586;4722.268048452426;4730.763461826895;4750.767139444456;4761.262004389325;4762.571368576775;5625.496653989017;5631.971840140773;5639.295088113986;5639.689817060019;5640.210370548127;1338.1569611144917;1364.4133022800092;1447.1534152520683;1474.6387135151956;1475.151787722064;950.6007476859736;968.3463662028244;971.32007594684;971.9271054670928;977.0473788056588;4818.975905120522;4863.756812159378;4873.293372461044;4882.194437917775;4886.847983050912;5022.7990880365105;5064.012517559041;5101.9744901276;5117.309163262987;5122.410922743858;4504.954950450519;4833.879153021203;4839.274108883626;4849.927251091236;4860.95139048605;2721.7278272172625;2728.6356821589206;2742.90283886443;2772.2930652683435;2774.5283301838735;5213.973930130325;5238.214267859776;5248.711320877388;5249.265102885599;5276.657017293864;4771.618270538349;5098.796048158032;5153.768080436389;5193.766280517386;5196.401799100449;5181.35233095863;5216.765245563883;5218.191180367049;5235;5261.710605916712;4876.366072410564;5050.77271522775;5075.923861142012;5088.6946783193125;5119.769610367468;5043.167877300204;5043.739065233692;5069.31564238823;5083.949160508496;5102.72431892027;5204.34945631796;5212.817551385835;5232.712200828991;5241.475852414728;5281.762320695655;3734.3278209922473;3812.1613245086037;3829.0427393151713;3860.4788353571907;3891.5094339622096;5184.818531351535;5188.533032027136;5584.860378490408;5629.577781666309;5679.9148012779015;5589.357223919139;5598.916016259678;5618.747156377891;5663.32040155184;5683.175939488834;5495.587830912617;5564.1051264116095;5621.297337832771;5639.154126880837;5660.54715622741;5559.694216818114;5561.860953476195;5567.888642227052;5574.442555744393;5580.804671836466;5644.943550564606;5657.179708942263;5686.289213848269;5696;5720.141978703228;5261.868453288698;5510.889782204414;5517.44825517445;5559.8888022240135;5562.666240025612 15:07:06 INFO - PROCESS | 7206 | |5;yui.html;748.3938010211725;757.170897866836;852.5609311204729;879.6129702930708;885.4510203673723;14369.497067602637;14580.708385832277;14586.92706536467;14654.560363189095;15384.230788460574;13453.932730336335;13460.46158153675;13463.124896881696;13522.661933451669;13614.455421783117;688.3426327856885;836.3518273338151;844.5988155626089;848.3128665780706;850.1710831938872;836.3058661311128;840.9873851892227;846.5005646668465;847.9364047696416;848.5248260973874;901.4681338010572;904.3624244907325;904.4030939579878;904.674317245791;905.5924833824772;735.2096496266508;878.3193025405299;892.901780804111;893.0221407558778;905.9909400906039;661.5237029338869;661.7022339947043;661.758458162772;663.2505269045947;664.3555750921647;14232.146071235758;14248.145111293252;14273.429062837475;14284.142951422844;14312;14081.436742530286;14380.56858294253;14418.639534011629;14469.638259043502;14609.123452592878;12258.632241032783;12379.690507737288;12402.75194496105;12535.749285014248;12680.302583358003;4396.450443694538;4423.51341352451;4447.799849006802;4449.087936972913;4449.176902273058;60514;61052.694736526035;62013;62040.7265382443;62146.378536214965;11490.195686302046;11683.357415342069;11690.590829320932;11866.466009029615;11999.940000300117;21067.789322106582;21095.68356474654;21103.261385851423;21106.9446527673;21150.894245528674;8237.423380363436;8305.501669899826;8333.291670207978;8603;8637.222649961534;4705.976470117696;4754.215554433549;4763.1426343258545;4779.976100119547;4930.6795058321095;3477.078574177845;3563.7149028077692;3577.7495575309476;3578.3201191774;3579.463080537888;629.3926361061598;637.2830565613684;643.8744444833212;647.2007071266981;648.3289795062088;725.1189804387694;782.6986610155018;823.8887750153652;824.4229039672133;834.6911642692243;9622.807543849223;10062.69811905644;10101.595936162617;10232.693019209286;10236.641717539851;761;790.3282210121466;804.8511025460191;822.8971378577678;823.8228780812034;4598.448186217675;4732.148213321635;4843.588294994895;4964.8758781030765;4967.7019378837385;68890;70371.2962870375;71306;72310.55378892392;72330.27669723236;62361.68819155875;63228.367716322246;63286.08266630853;64044.0338352631;65328.67335663291;55903.72048139733;55947;56545.649065621204;57374.7131264341;58605;8819.955900220459;9020.954895225483;9038.593263303299;9081.409708368936;9134.588943497425;36264.63735362612;36649.45025824668;36767.63232367749;37268.627313726516;37618.43572346362;47026.52973470358;50181.74909125577;50416;50734.746326268134;50867.491325086274;12116.333601651993;12167.574134905235;12289.754204915673;12310;12632.2420654761;12701;13142.474301028235;13996.16023038621;14025.438982440995;14351;12352.691182720504;12782.74434511286;12793.168444051107;12824.102312838011;12972.545960891324;11359.886401135884;11495.770084598094;12800;12833.807492887427;13391.129576577492;11609.825852612048;12317.384130793245;12376.938115309726;12513.687157820763;12751.872481275439;10733.892661073289;10785.838212426977;10977.615783447542;10983.286086404358;11150.219484636322;10149.898501014895;10394.896051039394;10402.27184097106;10629.840552391566;10813.189010824062 15:07:06 INFO - PROCESS | 7206 | __end_tp_report 15:07:06 INFO - PROCESS | 7206 | __start_cc_report 15:07:06 INFO - PROCESS | 7206 | _x_x_mozilla_cycle_collect,835 15:07:06 INFO - PROCESS | 7206 | __end_cc_report 15:07:06 INFO - PROCESS | 7206 | __startTimestamp1463177226517__endTimestamp 15:07:06 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | ------- Summary: start ------- 15:07:06 INFO - PROCESS | 7206 | Number of tests: 6 15:07:06 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | [#0] dojo.html Cycles:165 Average:5486.99 Median:5672.23 stddev:626.87 (11.1%) stddev-sans-first:601.70 15:07:06 INFO - PROCESS | 7206 | Values: 3162.9 3197.9 3215.1 3240.3 3264.0 5395.3 5464.9 5483.0 5560.2 5572.9 5486.9 5523.3 5526.7 5595.6 5623.0 4942.4 4949.3 5082.8 5114.4 5128.1 5065.9 5067.4 5093.8 5137.8 5151.8 4942.9 4948.6 4977.2 5034.2 5063.6 5039.1 5052.2 5053.5 5058.8 5090.7 4830.2 4943.8 4950.1 5012.4 5014.0 5452.4 5577.2 5584.1 5584.6 5605.3 5972.4 5994.7 5997.6 5999.8 6021.1 5760.6 5819.4 5828.7 5941.7 5979.2 5557.7 5559.3 5560.2 5560.6 5567.6 6071.2 6073.6 6078.0 6083.4 6084.8 5702.1 5784.1 5848.0 5861.9 5872.8 5971.8 5972.7 5982.1 5988.8 5991.5 5908.8 5922.6 5963.8 5965.6 5973.3 5747.7 5831.2 5867.2 5869.1 5950.5 4463.8 4477.9 4480.8 4482.6 4504.0 3940.3 3983.4 3988.8 4022.4 4046.9 5488.9 5508.0 5511.9 5520.4 5533.8 5864.0 5918.3 5928.5 5933.9 5946.4 5427.7 5562.1 5582.3 5586.2 5597.8 5025.8 5069.2 5086.8 5096.6 5101.9 6121.8 6134.3 6136.1 6137.6 6151.5 5757.1 5934.3 5946.8 5952.9 5955.3 6016.0 6064.7 6073.6 6121.1 6128.6 5793.7 5826.9 5839.7 5840.4 5843.5 5842.3 5858.4 5933.4 5940.6 5952.4 5730.3 5758.0 5795.6 5804.4 5860.3 5701.2 5718.5 5732.1 5739.7 5744.5 5517.4 5568.0 5609.1 5626.6 5634.4 5784.2 5824.0 5826.6 5830.3 5847.2 5656.9 5672.1 5672.2 5675.3 5692.5 15:07:06 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | [#1] ext.html Cycles:180 Average:60901.61 Median:41229.09 stddev:125338.37 (304.0%) stddev-sans-first:125608.22 15:07:06 INFO - PROCESS | 7206 | Values: 604.4 638.5 644.6 650.0 652.2 32601.5 35775.6 35854.8 35866.0 35867.6 44486.0 45584.0 45608.3 49275.0 49367.3 8661.7 8790.6 8792.6 8802.6 8803.1 8724.0 8802.0 8803.7 8808.0 8809.5 8850.4 8898.2 8926.5 8933.2 8938.6 8799.8 8830.7 8830.9 8855.6 8857.5 8756.6 8761.6 8793.2 8847.9 8868.0 41905.8 42389.6 42416.8 42520.4 42534.8 3644.7 3818.2 3847.4 3877.7 4414.2 32200.0 32478.8 32649.8 32869.5 33788.7 4281.2 4397.6 4399.4 4402.3 4408.1 360588.0 361767.0 362076.0 362389.0 363046.0 35369.6 36168.8 59547.7 59890.7 60043.7 38144.2 38214.8 38346.2 38346.4 38420.6 15849.4 15975.9 15977.2 15999.4 16027.8 13477.2 13569.2 13570.7 13580.2 13583.5 8693.9 8708.6 8712.7 8777.8 8782.2 3322.2 3343.8 3363.4 3376.1 3450.5 1069.6 1084.7 1144.8 1196.5 1227.4 33049.0 33117.8 33681.5 33922.3 34208.7 1166.8 1194.6 1227.5 1231.6 1258.6 17303.5 17585.6 17994.0 18010.7 18077.7 582790.0 585659.0 587090.0 588304.0 591280.0 60304.4 60689.4 60777.1 60827.7 61036.0 1400.4 1414.0 1416.8 1418.3 1429.1 32426.0 33300.7 33889.8 33911.3 34515.7 50659.0 51158.5 51253.5 51336.0 51386.5 430883.0 435154.0 436162.0 437059.0 437383.0 28457.4 29684.7 29693.1 29699.7 29709.4 47115.8 48438.8 48834.8 49328.0 49739.5 40163.9 41053.6 41737.8 41916.6 42308.4 40244.6 40544.2 40550.8 41404.6 41563.8 41687.4 41774.0 41929.8 42746.8 42863.6 34451.8 35545.0 36259.6 36278.0 36809.0 28270.6 28318.9 28703.3 28750.9 28877.0 15:07:06 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | [#2] jquery.html Cycles:180 Average:28732.59 Median:42650.15 stddev:83151.20 (195.0%) stddev-sans-first:83357.19 15:07:06 INFO - PROCESS | 7206 | Values: 370.1 388.3 397.0 408.2 412.1 16591.7 19285.0 19288.7 19313.3 20183.7 19301.2 19481.9 20259.6 20344.3 20453.2 5632.1 5727.1 5827.2 5841.0 5852.9 5700.6 5774.2 5836.3 5845.6 5846.7 5627.3 5756.6 5791.1 5809.7 5811.7 5695.9 5750.2 5754.7 5757.2 5764.0 5055.1 5202.2 5210.5 5227.7 5232.9 23349.3 23556.9 24014.8 24106.3 24163.0 2228.5 2364.1 2417.2 2514.8 3051.4 10079.7 10283.6 10286.7 10306.3 12527.6 1984.7 2031.0 2040.6 2045.3 2093.2 53594.0 57741.7 71267.0 73972.3 84494.0 10733.7 10992.6 11206.3 11259.2 11370.1 13456.3 13847.6 14296.2 15860.7 15862.6 8414.2 8440.0 8541.0 8631.2 8681.8 8261.9 8265.3 8337.7 8440.2 8446.6 4684.4 4731.3 4741.4 4834.4 4853.1 1924.0 2003.5 2026.2 2027.6 2040.7 985.9 1040.5 1110.6 1111.6 1114.7 11612.4 11938.2 12138.8 12397.2 19696.1 495.2 503.2 509.3 510.9 517.7 6394.8 6464.7 6607.8 6625.5 6634.7 493909.0 505171.0 506193.0 507538.0 508946.0 85827.0 91913.1 103992.0 104157.4 105493.5 478.8 480.1 485.9 486.9 488.7 9343.7 11440.2 11731.5 11747.5 11849.5 41245.4 42588.1 42712.1 43006.6 43605.8 54834.7 55587.0 57702.0 57917.7 58291.4 8919.6 8933.2 9050.3 9157.0 9229.0 9393.3 11971.2 12392.4 13062.7 13211.7 10976.0 11126.8 11230.6 11497.9 11582.3 10799.5 10936.2 10951.8 11065.2 11086.6 11441.3 11553.5 11553.6 11648.2 11725.1 8956.4 9082.1 9235.6 9255.8 9301.4 8283.3 10808.0 10939.0 11115.1 11286.3 15:07:06 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | [#3] mootools.html Cycles:180 Average:6278.47 Median:2525.44 stddev:3657.14 (144.8%) stddev-sans-first:3649.44 15:07:06 INFO - PROCESS | 7206 | Values: 1455.2 1513.3 1585.8 1755.7 1877.6 8997.5 9372.6 9417.5 9705.4 9722.6 9364.6 9596.8 9599.2 9605.5 9619.2 2081.5 2425.2 2454.0 2480.0 2490.9 2518.0 2519.5 2530.8 2532.7 2532.8 2402.6 2469.0 2470.5 2471.9 2475.5 2074.2 2351.9 2398.4 2399.3 2410.6 2412.9 2421.1 2441.0 2442.0 2445.2 9542.5 9585.0 9591.0 9597.9 9601.7 5640.3 5777.2 5785.7 5797.3 5798.5 3569.4 3633.1 3645.4 3687.0 3693.1 1144.1 1187.8 1198.2 1209.7 1220.3 13573.1 13723.2 13736.3 13760.0 13771.9 4919.4 4986.8 5031.5 5055.8 5110.3 11348.0 11407.1 11434.5 11435.8 11448.5 5694.6 5699.7 5706.0 5709.3 5710.0 4578.7 5103.3 5247.1 5247.3 5274.3 4330.7 4341.6 4395.7 4429.6 4432.7 1355.7 1385.8 1395.4 1398.7 1403.1 1031.9 1034.5 1036.9 1052.7 1059.1 6244.0 6393.8 6410.1 6447.5 6479.0 472.2 476.3 483.2 494.2 495.9 4228.3 4380.6 4431.8 4491.6 4509.6 11696.7 12087.4 12108.3 12117.6 12188.3 11456.8 11916.5 11991.9 12014.8 12034.2 8398.8 8756.7 8765.5 8798.9 8825.0 6530.0 6695.3 6752.3 6800.0 6802.4 10795.5 11091.8 11118.3 11126.4 11159.7 11184.8 11185.2 11186.7 11323.8 11328.0 7423.1 7633.4 7634.4 8388.9 8759.1 8611.7 9098.3 9274.4 9314.3 9616.9 8299.3 8372.8 8412.0 8451.6 8534.5 8210.8 8324.9 8335.6 8407.2 8460.3 7253.8 7374.9 7383.3 7390.2 7391.8 5493.8 5507.2 5558.1 5666.6 5673.9 5436.4 5481.8 5485.7 5597.9 5647.4 15:07:06 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | [#4] prototype.html Cycles:180 Average:4537.58 Median:5079.94 stddev:1295.52 (25.5%) stddev-sans-first:1260.14 15:07:06 INFO - PROCESS | 7206 | Values: 333.7 341.6 348.7 349.3 349.7 5314.9 5410.1 5418.4 5421.3 5440.0 5067.5 5197.0 5207.2 5243.0 5336.7 4089.4 4115.1 4168.8 4241.4 4242.1 4164.5 4219.6 4225.5 4229.8 4230.5 4028.5 4178.3 4183.0 4191.3 4215.3 4150.3 4164.2 4168.6 4188.8 4199.7 3714.0 3726.7 3731.0 3731.6 3734.1 4942.9 5088.9 5139.9 5143.3 5152.6 4712.7 4717.1 4750.8 4759.8 4778.3 5531.8 5540.3 5545.7 5555.6 5567.6 3953.1 4052.8 4060.5 4072.2 4080.9 5743.5 5763.6 5768.9 5780.7 5784.5 4168.9 4175.7 4181.4 4201.0 4211.5 4044.0 4173.5 4216.9 4233.9 4259.6 4722.3 4730.8 4750.8 4761.3 4762.6 5625.5 5632.0 5639.3 5639.7 5640.2 1338.2 1364.4 1447.2 1474.6 1475.2 950.6 968.3 971.3 971.9 977.0 4819.0 4863.8 4873.3 4882.2 4886.8 5022.8 5064.0 5102.0 5117.3 5122.4 4505.0 4833.9 4839.3 4849.9 4861.0 2721.7 2728.6 2742.9 2772.3 2774.5 5214.0 5238.2 5248.7 5249.3 5276.7 4771.6 5098.8 5153.8 5193.8 5196.4 5181.4 5216.8 5218.2 5235.0 5261.7 4876.4 5050.8 5075.9 5088.7 5119.8 5043.2 5043.7 5069.3 5083.9 5102.7 5204.3 5212.8 5232.7 5241.5 5281.8 3734.3 3812.2 3829.0 3860.5 3891.5 5184.8 5188.5 5584.9 5629.6 5679.9 5589.4 5598.9 5618.7 5663.3 5683.2 5495.6 5564.1 5621.3 5639.2 5660.5 5559.7 5561.9 5567.9 5574.4 5580.8 5644.9 5657.2 5686.3 5696.0 5720.1 5261.9 5510.9 5517.4 5559.9 5562.7 15:07:06 INFO - PROCESS | 7206 | 15:07:06 INFO - PROCESS | 7206 | [#5] yui.html Cycles:180 Average:16085.76 Median:25866.25 stddev:19477.44 (75.3%) stddev-sans-first:19498.03 15:07:06 INFO - PROCESS | 7206 | Values: 748.4 757.2 852.6 879.6 885.5 14369.5 14580.7 14586.9 14654.6 15384.2 13453.9 13460.5 13463.1 13522.7 13614.5 688.3 836.4 844.6 848.3 850.2 836.3 841.0 846.5 847.9 848.5 901.5 904.4 904.4 904.7 905.6 735.2 878.3 892.9 893.0 906.0 661.5 661.7 661.8 663.3 664.4 14232.1 14248.1 14273.4 14284.1 14312.0 14081.4 14380.6 14418.6 14469.6 14609.1 12258.6 12379.7 12402.8 12535.7 12680.3 4396.5 4423.5 4447.8 4449.1 4449.2 60514.0 61052.7 62013.0 62040.7 62146.4 11490.2 11683.4 11690.6 11866.5 11999.9 21067.8 21095.7 21103.3 21106.9 21150.9 8237.4 8305.5 8333.3 8603.0 8637.2 4706.0 4754.2 4763.1 4780.0 4930.7 3477.1 3563.7 3577.7 3578.3 3579.5 629.4 637.3 643.9 647.2 648.3 725.1 782.7 823.9 824.4 834.7 9622.8 10062.7 10101.6 10232.7 10236.6 761.0 790.3 804.9 822.9 823.8 4598.4 4732.1 4843.6 4964.9 4967.7 68890.0 70371.3 71306.0 72310.6 72330.3 62361.7 63228.4 63286.1 64044.0 65328.7 55903.7 55947.0 56545.6 57374.7 58605.0 8820.0 9021.0 9038.6 9081.4 9134.6 36264.6 36649.5 36767.6 37268.6 37618.4 47026.5 50181.7 50416.0 50734.7 50867.5 12116.3 12167.6 12289.8 12310.0 12632.2 12701.0 13142.5 13996.2 14025.4 14351.0 12352.7 12782.7 12793.2 12824.1 12972.5 11359.9 11495.8 12800.0 12833.8 13391.1 11609.8 12317.4 12376.9 12513.7 12751.9 10733.9 10785.8 10977.6 10983.3 11150.2 10149.9 10394.9 10402.3 10629.8 10813.2 15:07:06 INFO - PROCESS | 7206 | -------- Summary: end -------- 15:07:06 INFO - PROCESS | 7206 | 15:07:07 INFO - TEST-INFO | 7206: exit 0 15:07:07 INFO - TEST-OK | dromaeo_css | took 1113124ms 15:07:07 INFO - TEST-START | kraken 15:07:07 INFO - operating with platform_type : linux_ 15:07:07 INFO - Initialising browser for kraken test... 15:07:07 INFO - TEST-INFO | started process 7388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIzp0MQ/profile http://localhost:38234/getInfo.html) 15:07:11 INFO - PROCESS | 7388 | __metrics Screen width/height:1600/1200 15:07:11 INFO - PROCESS | 7388 | colorDepth:24 15:07:11 INFO - PROCESS | 7388 | Browser inner width/height: 1024/697 15:07:11 INFO - PROCESS | 7388 | __metrics 15:07:12 ERROR - PROCESS | 7388 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:07:13 INFO - TEST-INFO | 7388: exit 0 15:07:13 INFO - Browser initialized. 15:07:13 INFO - Running cycle 1/1 for kraken test... 15:07:13 INFO - TEST-INFO | started process 7472 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIzp0MQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:07:25 INFO - PROCESS | 7472 | RSS: Main: 152817664 15:07:25 INFO - PROCESS | 7472 | 15:07:27 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38234/tests/kraken/driver.html?testName=audio-beat-detection) 15:07:28 INFO - PROCESS | 7472 | RSS: Main: 209223680 15:07:28 INFO - PROCESS | 7472 | 15:07:31 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38234/tests/kraken/driver.html?testName=audio-dft) 15:07:31 INFO - PROCESS | 7472 | RSS: Main: 197836800 15:07:31 INFO - PROCESS | 7472 | 15:07:35 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38234/tests/kraken/driver.html?testName=audio-fft) 15:07:35 INFO - PROCESS | 7472 | RSS: Main: 265334784 15:07:35 INFO - PROCESS | 7472 | 15:07:37 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38234/tests/kraken/driver.html?testName=audio-oscillator) 15:07:38 INFO - PROCESS | 7472 | RSS: Main: 174714880 15:07:38 INFO - PROCESS | 7472 | 15:07:40 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:07:41 INFO - PROCESS | 7472 | RSS: Main: 178163712 15:07:41 INFO - PROCESS | 7472 | 15:07:44 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38234/tests/kraken/driver.html?testName=imaging-darkroom) 15:07:45 INFO - PROCESS | 7472 | RSS: Main: 214536192 15:07:45 INFO - PROCESS | 7472 | 15:07:48 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38234/tests/kraken/driver.html?testName=imaging-desaturate) 15:07:49 INFO - PROCESS | 7472 | RSS: Main: 212893696 15:07:49 INFO - PROCESS | 7472 | 15:07:52 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38234/tests/kraken/driver.html?testName=json-parse-financial) 15:07:53 INFO - PROCESS | 7472 | RSS: Main: 213725184 15:07:53 INFO - PROCESS | 7472 | 15:07:54 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:07:55 INFO - PROCESS | 7472 | RSS: Main: 202760192 15:07:55 INFO - PROCESS | 7472 | 15:07:57 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38234/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:07:57 INFO - PROCESS | 7472 | RSS: Main: 207945728 15:07:57 INFO - PROCESS | 7472 | 15:07:59 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:08:00 INFO - PROCESS | 7472 | RSS: Main: 216952832 15:08:00 INFO - PROCESS | 7472 | 15:08:03 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:08:03 INFO - PROCESS | 7472 | RSS: Main: 221302784 15:08:03 INFO - PROCESS | 7472 | 15:08:06 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:08:07 INFO - PROCESS | 7472 | RSS: Main: 218341376 15:08:07 INFO - PROCESS | 7472 | 15:08:08 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:38234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38234/tests/kraken/driver.html?testName=ai-astar) 15:08:09 INFO - PROCESS | 7472 | RSS: Main: 264388608 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | __start_tp_report 15:08:09 INFO - PROCESS | 7472 | _x_x_mozilla_page_load 15:08:09 INFO - PROCESS | 7472 | _x_x_mozilla_page_load_details 15:08:09 INFO - PROCESS | 7472 | |i|pagename|runs| 15:08:09 INFO - PROCESS | 7472 | |0;ai-astar;110;106;110;108;109;110;109;109;110;109 15:08:09 INFO - PROCESS | 7472 | |1;audio-beat-detection;148;127;149;203;124;148;149;125;184;125 15:08:09 INFO - PROCESS | 7472 | |2;audio-dft;161;161;163;160;161;162;162;163;162;163 15:08:09 INFO - PROCESS | 7472 | |3;audio-fft;75;75;76;152;76;75;76;75;132;75 15:08:09 INFO - PROCESS | 7472 | |4;audio-oscillator;92;91;148;104;92;92;125;91;91;121 15:08:09 INFO - PROCESS | 7472 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;116;117 15:08:09 INFO - PROCESS | 7472 | |6;imaging-darkroom;145;146;145;145;146;146;145;145;145;146 15:08:09 INFO - PROCESS | 7472 | |7;imaging-desaturate;95;95;95;96;94;95;94;95;106;95 15:08:09 INFO - PROCESS | 7472 | |8;json-parse-financial;54;55;56;55;55;56;55;107;53;53 15:08:09 INFO - PROCESS | 7472 | |9;json-stringify-tinderbox;60;59;58;58;58;59;59;58;58;59 15:08:09 INFO - PROCESS | 7472 | |10;stanford-crypto-aes;89;77;79;78;76;85;77;78;80;79 15:08:09 INFO - PROCESS | 7472 | |11;stanford-crypto-ccm;123;128;118;119;131;116;127;125;196;125 15:08:09 INFO - PROCESS | 7472 | |12;stanford-crypto-pbkdf2;162;159;159;160;157;163;161;165;159;161 15:08:09 INFO - PROCESS | 7472 | |13;stanford-crypto-sha256-iterative;63;64;63;62;62;63;64;62;64;61 15:08:09 INFO - PROCESS | 7472 | __end_tp_report 15:08:09 INFO - PROCESS | 7472 | __start_cc_report 15:08:09 INFO - PROCESS | 7472 | _x_x_mozilla_cycle_collect,1875 15:08:09 INFO - PROCESS | 7472 | __end_cc_report 15:08:09 INFO - PROCESS | 7472 | __startTimestamp1463177289043__endTimestamp 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | ------- Summary: start ------- 15:08:09 INFO - PROCESS | 7472 | Number of tests: 14 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#0] ai-astar Cycles:10 Average:109.00 Median:109.50 stddev:1.25 (1.1%) stddev-sans-first:1.27 15:08:09 INFO - PROCESS | 7472 | Values: 110.0 106.0 110.0 108.0 109.0 110.0 109.0 109.0 110.0 109.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:148.50 stddev:26.66 (18.0%) stddev-sans-first:28.28 15:08:09 INFO - PROCESS | 7472 | Values: 148.0 127.0 149.0 203.0 124.0 148.0 149.0 125.0 184.0 125.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 15:08:09 INFO - PROCESS | 7472 | Values: 161.0 161.0 163.0 160.0 161.0 162.0 162.0 163.0 162.0 163.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#3] audio-fft Cycles:10 Average:88.70 Median:75.00 stddev:28.49 (38.0%) stddev-sans-first:29.78 15:08:09 INFO - PROCESS | 7472 | Values: 75.0 75.0 76.0 152.0 76.0 75.0 76.0 75.0 132.0 75.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#4] audio-oscillator Cycles:10 Average:104.70 Median:91.00 stddev:20.00 (22.0%) stddev-sans-first:20.68 15:08:09 INFO - PROCESS | 7472 | Values: 92.0 91.0 148.0 104.0 92.0 92.0 125.0 91.0 91.0 121.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:08:09 INFO - PROCESS | 7472 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 15:08:09 INFO - PROCESS | 7472 | Values: 145.0 146.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 146.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:95.00 stddev:3.56 (3.7%) stddev-sans-first:3.76 15:08:09 INFO - PROCESS | 7472 | Values: 95.0 95.0 95.0 96.0 94.0 95.0 94.0 95.0 106.0 95.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:55.00 stddev:16.58 (30.2%) stddev-sans-first:17.45 15:08:09 INFO - PROCESS | 7472 | Values: 54.0 55.0 56.0 55.0 55.0 56.0 55.0 107.0 53.0 53.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.70 (1.2%) stddev-sans-first:0.53 15:08:09 INFO - PROCESS | 7472 | Values: 60.0 59.0 58.0 58.0 58.0 59.0 59.0 58.0 58.0 59.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.00 stddev:4.08 (5.2%) stddev-sans-first:2.64 15:08:09 INFO - PROCESS | 7472 | Values: 89.0 77.0 79.0 78.0 76.0 85.0 77.0 78.0 80.0 79.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#11] stanford-crypto-ccm Cycles:10 Average:130.80 Median:126.00 stddev:23.39 (18.6%) stddev-sans-first:24.64 15:08:09 INFO - PROCESS | 7472 | Values: 123.0 128.0 118.0 119.0 131.0 116.0 127.0 125.0 196.0 125.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.60 Median:161.00 stddev:2.32 (1.4%) stddev-sans-first:2.40 15:08:09 INFO - PROCESS | 7472 | Values: 162.0 159.0 159.0 160.0 157.0 163.0 161.0 165.0 159.0 161.0 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - PROCESS | 7472 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 15:08:09 INFO - PROCESS | 7472 | Values: 63.0 64.0 63.0 62.0 62.0 63.0 64.0 62.0 64.0 61.0 15:08:09 INFO - PROCESS | 7472 | -------- Summary: end -------- 15:08:09 INFO - PROCESS | 7472 | 15:08:09 INFO - TEST-INFO | 7472: exit 0 15:08:10 INFO - TEST-OK | kraken | took 62815ms 15:08:10 INFO - SUITE-END | took 1175s 15:08:10 INFO - Completed test suite (00:19:36) 15:08:10 INFO - javascript benchmark 15:08:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3162.9367412651745, 3197.880741740391, 3215.0997720638215, 3240.3195328980914, 3264.0371090528306, 5395.325584301962, 5464.9180262295995, 5483.040467918113, 5560.165975103731, 5572.916406253904, 5486.945130548694, 5523.337199536051, 5526.696031718254, 5595.552355811536, 5622.987862184805, 4942.431620363659, 4949.331840201563, 5082.822101226458, 5114.43741188469, 5128.076946149691, 5065.873353166164, 5067.366579177603, 5093.84718458445, 5137.820176293831, 5151.76817043232, 4942.87642808929, 4948.628852836034, 4977.228529577921, 5034.219695947134, 5063.645544811865, 5039.143345631215, 5052.191649336125, 5053.519915608012, 5058.848234552969, 5090.720010399426, 4830.178869592178, 4943.752812359367, 4950.084234416644, 5012.373453318335, 5013.974930125362, 5452.427495113036, 5577.163425486169, 5584.134459158837, 5584.5811564132855, 5605.32736071674, 5972.4027597240365, 5994.700264986734, 5997.640141591475, 5999.820005399845, 6021.09683547467, 5760.567957403169, 5819.388964158787, 5828.6502809831545, 5941.732622032019, 5979.22270104888, 5557.749901254453, 5559.305086864142, 5560.2493663355335, 5560.555155587544, 5567.582431317667, 6071.180390647294, 6073.605215660968, 6077.969610151921, 6083.39166083397, 6084.787032453843, 5702.0591602385975, 5784.1034639630925, 5848.035074212792, 5861.912071318934, 5872.765089396462, 5971.790987315423, 5972.701364931823, 5982.132590774313, 5988.760449582055, 5991.460768530853, 5908.763649454059, 5922.585419020714, 5963.79126730571, 5965.55258355625, 5973.313068997034, 5747.741351639186, 5831.183634291203, 5867.20792692981, 5869.090291004901, 5950.494207992369, 4463.843765468193, 4477.865664030084, 4480.843170489017, 4482.641388688897, 4504.0, 3940.349842275992, 3983.3626619740703, 3988.80055997199, 4022.35642297231, 4046.8988275293354, 5488.862778430492, 5508.03609368367, 5511.917321240185, 5520.365158006881, 5533.806316778974, 5864.0, 5918.319393269743, 5928.525717942554, 5933.851653708605, 5946.405359464019, 5427.674339539641, 5562.054450743383, 5582.27430434041, 5586.190002449622, 5597.776088956396, 5025.84922452327, 5069.239614057921, 5086.847394578169, 5096.643234973591, 5101.897962040738, 6121.816345509642, 6134.294556126013, 6136.140940268366, 6137.60105593135, 6151.477124444473, 5757.107648314518, 5934.287885453859, 5946.791862284799, 5952.940470595412, 5955.315138759098, 6016.0, 6064.727087280995, 6073.574849760651, 6121.081837724377, 6128.601640893327, 5793.652380857162, 5826.883462330815, 5839.678817665069, 5840.357560668408, 5843.474087332161, 5842.298924129132, 5858.384869588632, 5933.436323549256, 5940.584159108994, 5952.434518720715, 5730.312362516524, 5758.049921762862, 5795.623284486495, 5804.361520232856, 5860.267466566679, 5701.201831743642, 5718.542516598745, 5732.082866741302, 5739.713014349182, 5744.4829965303325, 5517.36550296721, 5568.0, 5609.074502707171, 5626.5780066496, 5634.380218175916, 5784.2191304175085, 5824.0, 5826.592138550261, 5830.271216097988, 5847.210626565529, 5656.943430565806, 5672.14917762339, 5672.2342483765815, 5675.347335056521, 5692.51613612831], "value": 5444.4652793106925, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.3654163128714, 638.5306799502365, 644.6100109433789, 650.0152269311992, 652.2173391929686, 32601.51097733533, 35775.64224357755, 35854.820725896396, 35866.0, 35867.64132358676, 44486.0, 45584.0, 45608.3158752619, 49275.0, 49367.25949110766, 8661.69684061058, 8790.64837406503, 8792.604332805007, 8802.603882825257, 8803.119688031178, 8724.0, 8802.03177650458, 8803.73588792334, 8808.03111657717, 8809.515476648781, 8850.380473366868, 8898.199162075412, 8926.464412135292, 8933.15135062168, 8938.64245430182, 8799.824003519925, 8830.69092581763, 8830.86753698695, 8855.601497932608, 8857.468551886876, 8756.562171891444, 8761.56192190388, 8793.164649358301, 8847.911520884772, 8867.980182279056, 41905.79047104775, 42389.57610423918, 42416.787916060224, 42520.3621945668, 42534.78732606348, 3644.744867859251, 3818.236352729465, 3847.3844184930276, 3877.7479463835043, 4414.227510185737, 32200.0, 32478.83760581182, 32649.836750816095, 32869.50695739566, 33788.6621133788, 4281.207976524322, 4397.626201772853, 4399.428074350314, 4402.295632698785, 4408.14041261954, 360588.0, 361767.0, 362075.9999999974, 362389.0, 363046.0, 35369.64630353715, 36168.81915590458, 59547.70226148841, 59890.70054649786, 60043.699781500814, 38144.237115258096, 38214.80892595519, 38346.233075338336, 38346.42480362797, 38420.61579384226, 15849.445269415746, 15975.920120399556, 15977.201139942956, 15999.440019599258, 16027.759583606256, 13477.191368517724, 13569.1858488491, 13570.728585428236, 13580.185188888698, 13583.52457663977, 8693.869591956125, 8708.608112634947, 8712.69505567302, 8777.824443511094, 8782.165694259034, 3322.2026713588566, 3343.823367800599, 3363.360961417323, 3376.139084533428, 3450.4824276358745, 1069.6202847989007, 1084.7017070305571, 1144.8053830848776, 1196.4855112301793, 1227.3679055286534, 33049.0, 33117.834410828276, 33681.49477757836, 33922.32155356929, 34208.74212331526, 1166.7666466706692, 1194.6416075177412, 1227.5089964014467, 1231.6428235811693, 1258.5972488803677, 17303.52920001789, 17585.648287034186, 17994.0, 18010.729839052423, 18077.7288340675, 582790.0, 585659.0, 587090.0, 588304.0, 591280.0, 60304.39695603075, 60689.393106069256, 60777.08834367488, 60827.695861520406, 61036.0, 1400.355836315286, 1413.9568323772255, 1416.767412351261, 1418.2979425184394, 1429.0925262458413, 32426.027219183463, 33300.66699332976, 33889.83055084808, 33911.3217735639, 34515.654843451244, 50659.0, 51158.488415115375, 51253.48746512487, 51336.0, 51386.48613513967, 430883.0, 435154.0, 436162.0, 437059.0, 437383.0, 28457.43085138327, 29684.703152969058, 29693.10920672383, 29699.703002969694, 29709.405811883207, 47115.76442117767, 48438.757806212154, 48834.755826220644, 49328.0, 49739.50260497349, 40163.93035443753, 41053.589464106175, 41737.79131104325, 41916.580834192486, 42308.36537451879, 40244.59755402408, 40544.1891162181, 40550.797246014765, 41404.58595414007, 41563.7921810389, 41687.374689379074, 41774.0, 41929.79035104805, 42746.78626606847, 42863.571364285955, 34451.82774086113, 35545.0, 36259.637403625624, 36278.0, 36809.0, 28270.57594136131, 28318.85840570784, 28703.282417939718, 28750.856245718638, 28877.0], "value": 19317.93037082575, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.11727031030983, 388.3320688415924, 396.98213580388887, 408.2264109512474, 412.1057305646746, 16591.66816663666, 19285.035748212587, 19288.710669339973, 19313.324033658824, 20183.69724454131, 19301.22795088195, 19481.90259048703, 20259.59480810383, 20344.287949921756, 20453.181872725112, 5632.098864181732, 5727.140928860663, 5827.213326200972, 5841.0, 5852.882942341151, 5700.600957932947, 5774.191613174159, 5836.2996440427205, 5845.56158288128, 5846.707664616773, 5627.296587926509, 5756.568257380693, 5791.131330300467, 5809.738561764731, 5811.6803575803315, 5695.914561281584, 5750.1949727038245, 5754.712264386785, 5757.222774925373, 5763.971180144073, 5055.115354812893, 5202.193659982709, 5210.505002024802, 5227.738613069331, 5232.947670523284, 23349.299521014396, 23556.882215588983, 24014.75985240143, 24106.276811695676, 24163.033478660833, 2228.465168359582, 2364.148906393697, 2417.2144053182788, 2514.7531521490278, 3051.420230156263, 10079.69760907174, 10283.640072597424, 10286.691399258034, 10306.278560500768, 12527.56153534622, 1984.7320611717523, 2030.979690203094, 2040.6020825938947, 2045.2841505472966, 2093.204582258732, 53594.0, 57741.711291443695, 71267.0, 73972.26027739707, 84494.0, 10733.731656708645, 10992.560297588007, 11206.327620342643, 11259.155563332783, 11370.09039276856, 13456.327183640778, 13847.584572462642, 14296.213708246147, 15860.682786344209, 15862.60343491422, 8414.200650938152, 8439.95780021096, 8540.957295213484, 8631.223189912938, 8681.782955426164, 8261.917380826235, 8265.297449716842, 8337.666493340319, 8440.198181172902, 8446.61990210442, 4684.367610372624, 4731.266653668687, 4741.383620129361, 4834.444038935497, 4853.102176097434, 1923.9711404328666, 2003.4891102768702, 2026.2097781865084, 2027.6212175720652, 2040.6632905570414, 985.8619793228954, 1040.5369610523244, 1110.5613282753268, 1111.5553778488509, 1114.7213196700825, 11612.419379031015, 11938.164328496921, 12138.817917731241, 12397.194182378296, 19696.113674884666, 495.212611947006, 503.16474652077375, 509.30479894943875, 510.91059064663534, 517.6816258001302, 6394.776182833579, 6464.676766161673, 6607.834804129839, 6625.503087268377, 6634.701438435282, 493909.0, 505171.0, 506193.0, 507538.0, 508946.0, 85827.0, 91913.08086919045, 103992.0, 104157.39606509898, 105493.47253263685, 478.7773685236312, 480.0950208856261, 485.934398856157, 486.86367817011296, 488.6530563300097, 9343.672971445967, 11440.199186057229, 11731.472083756422, 11747.530098795953, 11849.466773995018, 41245.38131927964, 42588.14823703447, 42712.145757084065, 43006.569934300256, 43605.78197108994, 54834.72582637061, 55587.0, 57702.0, 57917.710411449356, 58291.4170858286, 8919.598618062333, 8933.19601235892, 9050.27597792162, 9156.954215228881, 9228.95385523068, 9393.29550283745, 11971.221870578385, 12392.442340094898, 13062.673433164247, 13211.66970825737, 10976.0, 11126.833097503706, 11230.606928757454, 11497.885021149681, 11582.305061696325, 10799.460026998462, 10936.234463587472, 10951.780964380509, 11065.225434219527, 11086.61196858106, 11441.283807428952, 11553.480093395985, 11553.595624153451, 11648.184627076362, 11725.061995040547, 8956.417832840845, 9082.091790820865, 9235.630574776933, 9255.76860578491, 9301.441913485212, 8283.332501372783, 10808.0, 10939.0, 11115.055220306365, 11286.322820630789], "value": 8581.00570765252, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.1778245291403, 1513.281191434069, 1585.8493443122902, 1755.6664233795577, 1877.6338613970272, 8997.505137217458, 9372.625094996201, 9417.48203848788, 9705.36915100518, 9722.56248468819, 9364.62541498341, 9596.808063838736, 9599.184069354096, 9605.471699056567, 9619.230461563076, 2081.510844951435, 2425.1875621666763, 2454.0429232599254, 2479.987600061997, 2490.8915532588007, 2518.0305582350743, 2519.546481633305, 2530.8354956927833, 2532.6580911576884, 2532.8353657012326, 2402.615581506959, 2468.9876550617223, 2470.542949554338, 2471.888765005579, 2475.4801491686767, 2074.232533962436, 2351.8941647625898, 2398.37642213025, 2399.292208798391, 2410.590199666061, 2412.903483860661, 2421.092090466075, 2441.023590563771, 2441.9755802441928, 2445.193086281523, 9542.52287385635, 9584.95207523965, 9591.040895910353, 9597.856032159523, 9601.711948641552, 5640.2667653205235, 5777.220075289824, 5785.739641716133, 5797.275340582427, 5798.507126894219, 3569.428891377393, 3633.1280492681567, 3645.4167333226555, 3686.981565092184, 3693.132113953219, 1144.1133121830649, 1187.7861984842725, 1198.161287099034, 1209.6552482542434, 1220.3288191494642, 13573.11774734639, 13723.176609403368, 13736.313184340843, 13760.0, 13771.931140344333, 4919.434265059564, 4986.775595098229, 5031.547160755476, 5055.823046193365, 5110.28456016158, 11348.035416989484, 11407.11782555869, 11434.542618295342, 11435.828462573068, 11448.542058317575, 5694.572907031989, 5699.7435115419075, 5706.0, 5709.314882214161, 5709.9714501427225, 4578.702384345007, 5103.336566246364, 5247.055530004636, 5247.291615631841, 5274.314339135888, 4330.7401555906745, 4341.630961368256, 4395.692301538926, 4429.579189976947, 4432.711873728197, 1355.7288542291383, 1385.8336999560117, 1395.4487977249132, 1398.685295808435, 1403.1160368967485, 1031.9071283584512, 1034.5499707627207, 1036.896310368957, 1052.7473406382412, 1059.094474224527, 6243.968780156161, 6393.840153996187, 6410.134631824644, 6447.484201263887, 6478.967605161944, 472.16898259064294, 476.25703901913124, 483.2220125597785, 494.22900275570225, 495.89338292267337, 4228.344606585984, 4380.627646650007, 4431.82272709088, 4491.550844915548, 4509.616682582017, 11696.70758231034, 12087.395630218454, 12108.334041627619, 12117.575884844164, 12188.268703877795, 11456.770864582662, 11916.46375913086, 11991.940040299743, 12014.75970480603, 12034.157608967464, 8398.832023359499, 8756.693515727047, 8765.474071555727, 8798.86801697975, 8825.02924678285, 6530.0, 6695.296993815774, 6752.257251702407, 6799.966000169968, 6802.3537763912345, 10795.460226988776, 11091.833622495507, 11118.3329000257, 11126.443677815914, 11159.665210043711, 11184.776304474028, 11185.161112916401, 11186.720331991766, 11323.830142548033, 11328.0, 7423.146338170964, 7633.389328853567, 7634.351080158249, 8388.874166887623, 8759.12408759119, 8611.741647750578, 9098.272138229058, 9274.350795444525, 9314.25485961135, 9616.855747163938, 8299.336053115858, 8372.832543349221, 8411.957940210259, 8451.577421128795, 8534.487930724177, 8210.835783284181, 8324.916750832657, 8335.583220839055, 8407.2433480987, 8460.323174146177, 7253.782386528413, 7374.926250737424, 7383.261673832574, 7390.224026477357, 7391.815204619712, 5493.835184944458, 5507.208986177458, 5558.055130627722, 5666.603337766482, 5673.943260567342, 5436.401995780382, 5481.780728770805, 5485.698286594116, 5597.916031259453, 5647.350554686263], "value": 4902.574888016617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.70133730311375, 341.56450525579885, 348.65134865134866, 349.29616822103463, 349.6520961643162, 5314.937012597481, 5410.0802863513245, 5418.431064738204, 5421.322334708157, 5440.0, 5067.543921047105, 5197.012567612151, 5207.192885102815, 5243.0, 5336.6531175473565, 4089.3865920111925, 4115.135821477494, 4168.8332466701295, 4241.448611680493, 4242.066745316026, 4164.501299220474, 4219.556946520617, 4225.535191128974, 4229.830806767741, 4230.513490948534, 4028.5295867008476, 4178.268802959486, 4182.97908510457, 4191.287481128215, 4215.346621273708, 4150.335946248586, 4164.167166566668, 4168.62482376586, 4188.81150348235, 4199.706020578561, 3714.0, 3726.720495962814, 3730.9813450932843, 3731.5522137343423, 3734.1038150843874, 4942.9258561121615, 5088.898222035538, 5139.871503212412, 5143.305653736735, 5152.639315247934, 4712.680449474153, 4717.127331443694, 4750.833720819789, 4759.809607615691, 4778.259369797687, 5531.751071201806, 5540.252065971083, 5545.694986775726, 5555.611107222496, 5567.582431317667, 3953.1303113315025, 4052.7770972596495, 4060.533038700558, 4072.2466343726505, 4080.918556582508, 5743.540516758691, 5763.6253643513455, 5768.942310576925, 5780.739866705966, 5784.508316793078, 4168.895777605523, 4175.663787587942, 4181.393697913785, 4201.0, 4211.515675697273, 4044.0, 4173.537612808457, 4216.915661686749, 4233.936490952638, 4259.552746961586, 4722.268048452426, 4730.763461826895, 4750.767139444456, 4761.262004389325, 4762.571368576775, 5625.496653989017, 5631.971840140773, 5639.295088113986, 5639.689817060019, 5640.210370548127, 1338.1569611144917, 1364.4133022800092, 1447.1534152520683, 1474.6387135151956, 1475.151787722064, 950.6007476859736, 968.3463662028244, 971.32007594684, 971.9271054670928, 977.0473788056588, 4818.975905120522, 4863.756812159378, 4873.293372461044, 4882.194437917775, 4886.847983050912, 5022.7990880365105, 5064.012517559041, 5101.9744901276, 5117.309163262987, 5122.410922743858, 4504.954950450519, 4833.879153021203, 4839.274108883626, 4849.927251091236, 4860.95139048605, 2721.7278272172625, 2728.6356821589206, 2742.90283886443, 2772.2930652683435, 2774.5283301838735, 5213.973930130325, 5238.214267859776, 5248.711320877388, 5249.265102885599, 5276.657017293864, 4771.618270538349, 5098.796048158032, 5153.768080436389, 5193.766280517386, 5196.401799100449, 5181.35233095863, 5216.765245563883, 5218.191180367049, 5235.0, 5261.710605916712, 4876.366072410564, 5050.77271522775, 5075.923861142012, 5088.6946783193125, 5119.769610367468, 5043.167877300204, 5043.739065233692, 5069.31564238823, 5083.949160508496, 5102.72431892027, 5204.34945631796, 5212.817551385835, 5232.712200828991, 5241.475852414728, 5281.762320695655, 3734.3278209922473, 3812.1613245086037, 3829.0427393151713, 3860.4788353571907, 3891.5094339622096, 5184.818531351535, 5188.533032027136, 5584.860378490408, 5629.577781666309, 5679.9148012779015, 5589.357223919139, 5598.916016259678, 5618.747156377891, 5663.32040155184, 5683.175939488834, 5495.587830912617, 5564.1051264116095, 5621.297337832771, 5639.154126880837, 5660.54715622741, 5559.694216818114, 5561.860953476195, 5567.888642227052, 5574.442555744393, 5580.804671836466, 5644.943550564606, 5657.179708942263, 5686.289213848269, 5696.0, 5720.141978703228, 5261.868453288698, 5510.889782204414, 5517.44825517445, 5559.8888022240135, 5562.666240025612], "value": 4133.340418825648, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.3938010211725, 757.170897866836, 852.5609311204729, 879.6129702930708, 885.4510203673723, 14369.497067602637, 14580.708385832277, 14586.92706536467, 14654.560363189095, 15384.230788460574, 13453.932730336335, 13460.46158153675, 13463.124896881696, 13522.661933451669, 13614.455421783117, 688.3426327856885, 836.3518273338151, 844.5988155626089, 848.3128665780706, 850.1710831938872, 836.3058661311128, 840.9873851892227, 846.5005646668465, 847.9364047696416, 848.5248260973874, 901.4681338010572, 904.3624244907325, 904.4030939579878, 904.674317245791, 905.5924833824772, 735.2096496266508, 878.3193025405299, 892.901780804111, 893.0221407558778, 905.9909400906039, 661.5237029338869, 661.7022339947043, 661.758458162772, 663.2505269045947, 664.3555750921647, 14232.146071235758, 14248.145111293252, 14273.429062837475, 14284.142951422844, 14312.0, 14081.436742530286, 14380.56858294253, 14418.639534011629, 14469.638259043502, 14609.123452592878, 12258.632241032783, 12379.690507737288, 12402.75194496105, 12535.749285014248, 12680.302583358003, 4396.450443694538, 4423.51341352451, 4447.799849006802, 4449.087936972913, 4449.176902273058, 60514.0, 61052.694736526035, 62013.0, 62040.7265382443, 62146.378536214965, 11490.195686302046, 11683.357415342069, 11690.590829320932, 11866.466009029615, 11999.940000300117, 21067.789322106582, 21095.68356474654, 21103.261385851423, 21106.9446527673, 21150.894245528674, 8237.423380363436, 8305.501669899826, 8333.291670207978, 8603.0, 8637.222649961534, 4705.976470117696, 4754.215554433549, 4763.1426343258545, 4779.976100119547, 4930.6795058321095, 3477.078574177845, 3563.7149028077692, 3577.7495575309476, 3578.3201191774, 3579.463080537888, 629.3926361061598, 637.2830565613684, 643.8744444833212, 647.2007071266981, 648.3289795062088, 725.1189804387694, 782.6986610155018, 823.8887750153652, 824.4229039672133, 834.6911642692243, 9622.807543849223, 10062.69811905644, 10101.595936162617, 10232.693019209286, 10236.641717539851, 761.0, 790.3282210121466, 804.8511025460191, 822.8971378577678, 823.8228780812034, 4598.448186217675, 4732.148213321635, 4843.588294994895, 4964.8758781030765, 4967.7019378837385, 68890.0, 70371.2962870375, 71306.0, 72310.55378892392, 72330.27669723236, 62361.68819155875, 63228.367716322246, 63286.08266630853, 64044.0338352631, 65328.67335663291, 55903.72048139733, 55947.0, 56545.649065621204, 57374.7131264341, 58605.0, 8819.955900220459, 9020.954895225483, 9038.593263303299, 9081.409708368936, 9134.588943497425, 36264.63735362612, 36649.45025824668, 36767.63232367749, 37268.627313726516, 37618.43572346362, 47026.52973470358, 50181.74909125577, 50416.0, 50734.746326268134, 50867.491325086274, 12116.333601651993, 12167.574134905235, 12289.754204915673, 12310.0, 12632.2420654761, 12701.0, 13142.474301028235, 13996.16023038621, 14025.438982440995, 14351.0, 12352.691182720504, 12782.74434511286, 12793.168444051107, 12824.102312838011, 12972.545960891324, 11359.886401135884, 11495.770084598094, 12800.0, 12833.807492887427, 13391.129576577492, 11609.825852612048, 12317.384130793245, 12376.938115309726, 12513.687157820763, 12751.872481275439, 10733.892661073289, 10785.838212426977, 10977.615783447542, 10983.286086404358, 11150.219484636322, 10149.898501014895, 10394.896051039394, 10402.27184097106, 10629.840552391566, 10813.189010824062], "value": 7107.597259837782, "unit": "score"}], "name": "dromaeo_css", "value": 7117.442327781042, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 110.0, 108.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 127.0, 149.0, 203.0, 124.0, 148.0, 149.0, 125.0, 184.0, 125.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 160.0, 161.0, 162.0, 162.0, 163.0, 162.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 152.0, 76.0, 75.0, 76.0, 75.0, 132.0, 75.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 148.0, 104.0, 92.0, 92.0, 125.0, 91.0, 91.0, 121.0], "value": 104.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 94.0, 95.0, 106.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 55.0, 55.0, 56.0, 55.0, 107.0, 53.0, 53.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 58.0, 58.0, 59.0, 59.0, 58.0, 58.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 77.0, 79.0, 78.0, 76.0, 85.0, 77.0, 78.0, 80.0, 79.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 128.0, 118.0, 119.0, 131.0, 116.0, 127.0, 125.0, 196.0, 125.0], "value": 130.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 159.0, 160.0, 157.0, 163.0, 161.0, 165.0, 159.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 62.0, 62.0, 63.0, 64.0, 62.0, 64.0, 61.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1523.0999999999997, "alertThreshold": 2.0}]} 15:08:10 INFO - Return code: 0 15:08:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:08:10 ERROR - # TBPL SUCCESS # 15:08:10 INFO - Running post-action listener: _resource_record_post_action 15:08:10 INFO - ##### 15:08:10 INFO - ##### Finished run-tests step (success) 15:08:10 INFO - ##### 15:08:10 INFO - Running post-run listener: _resource_record_post_run 15:08:10 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 3313664; Write bytes: 301580288; Read time: 404; Write time: 263672 15:08:10 INFO - TinderboxPrint: CPU usage
13.2% 15:08:10 INFO - TinderboxPrint: I/O read bytes / time
3,313,664 / 404 15:08:10 INFO - TinderboxPrint: I/O write bytes / time
301,580,288 / 263,672 15:08:10 INFO - TinderboxPrint: CPU idle
8,271.8 (86.8%) 15:08:10 INFO - TinderboxPrint: CPU user
1,208.1 (12.7%) 15:08:10 INFO - TinderboxPrint: Swap in / out
0 / 0 15:08:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 17268736; Read time: 0; Write time: 7752 15:08:10 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 3313664; Write bytes: 284311552; Read time: 404; Write time: 255920 15:08:11 INFO - Running post-run listener: _upload_blobber_files 15:08:11 INFO - Blob upload gear active. 15:08:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:08:11 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 15:08:11 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'] 15:08:11 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 15:08:11 INFO - (blobuploader) - INFO - Open directory for files ... 15:08:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:08:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:08:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:15 INFO - (blobuploader) - INFO - Done attempting. 15:08:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:08:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:08:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:16 INFO - (blobuploader) - INFO - Done attempting. 15:08:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:08:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:08:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:17 INFO - (blobuploader) - INFO - Done attempting. 15:08:17 INFO - (blobuploader) - INFO - Iteration through files over. 15:08:17 INFO - Return code: 0 15:08:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:08:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:08:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4aee10591d283f0aa53bd0a867cff09db5962498861a025e39884198421e05b2ed9433f1cd476276d4d1f99292d802f017e090ad246f8918afa409ca74b8a42b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c304691e436073a5e667e820b8315772e3d680b9b9ef1a617b4293c5f7d9d5acffb38bae2176d147264d65f3646e336d53117ac891b6626a54372e488dc33e2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/211505b2392c0d968c48700f2645954f21ab38fb4e83143db8789985fdc8ee4ffd34a7a7bb3095c1c52d6c03adaa3dc9b255a67a497c0438159634fc93f23edc"} 15:08:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:08:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:08:17 INFO - Contents: 15:08:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4aee10591d283f0aa53bd0a867cff09db5962498861a025e39884198421e05b2ed9433f1cd476276d4d1f99292d802f017e090ad246f8918afa409ca74b8a42b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c304691e436073a5e667e820b8315772e3d680b9b9ef1a617b4293c5f7d9d5acffb38bae2176d147264d65f3646e336d53117ac891b6626a54372e488dc33e2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/211505b2392c0d968c48700f2645954f21ab38fb4e83143db8789985fdc8ee4ffd34a7a7bb3095c1c52d6c03adaa3dc9b255a67a497c0438159634fc93f23edc"} 15:08:17 INFO - Running post-run listener: copy_logs_to_upload_dir 15:08:17 INFO - Copying logs to upload dir... 15:08:17 INFO - mkdir: /builds/slave/test/build/upload/logs 15:08:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.337585 ========= master_lag: 25.70 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-13 15:08:43.177589) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 15:08:43.182704) ========= 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=52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457 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/4aee10591d283f0aa53bd0a867cff09db5962498861a025e39884198421e05b2ed9433f1cd476276d4d1f99292d802f017e090ad246f8918afa409ca74b8a42b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c304691e436073a5e667e820b8315772e3d680b9b9ef1a617b4293c5f7d9d5acffb38bae2176d147264d65f3646e336d53117ac891b6626a54372e488dc33e2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/211505b2392c0d968c48700f2645954f21ab38fb4e83143db8789985fdc8ee4ffd34a7a7bb3095c1c52d6c03adaa3dc9b255a67a497c0438159634fc93f23edc"} build_url:https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010213 build_url: 'https://queue.taskcluster.net/v1/task/TgOAhaGlRhSGofhl78advA/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/4aee10591d283f0aa53bd0a867cff09db5962498861a025e39884198421e05b2ed9433f1cd476276d4d1f99292d802f017e090ad246f8918afa409ca74b8a42b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c304691e436073a5e667e820b8315772e3d680b9b9ef1a617b4293c5f7d9d5acffb38bae2176d147264d65f3646e336d53117ac891b6626a54372e488dc33e2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/211505b2392c0d968c48700f2645954f21ab38fb4e83143db8789985fdc8ee4ffd34a7a7bb3095c1c52d6c03adaa3dc9b255a67a497c0438159634fc93f23edc"}' ========= master_lag: 0.29 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 15:08:43.486393) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 15:08:43.486735) ========= 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=52faae308a556c440401f8e5000001e8-1463176058.533351-1608891457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 1.32 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 15:08:44.812287) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 15:08:44.812648) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 15:08:44.820167) ========= ========= Total master_lag: 27.38 =========