builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1463281292.9 results: success (0) buildid: 20160514173010 builduid: c0ac523baf474938b02204cb6bff301d revision: 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:32.895818) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:32.896276) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:32.896581) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:32.916532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:32.916943) ========= 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=31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-14 20:01:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.9M=0s 2016-05-14 20:01:33 (40.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085505 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.017219) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.017700) ========= 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=31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020309 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.069724) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.070108) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a --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 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a --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=31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-14 20:01:33,290 truncating revision to first 12 chars 2016-05-14 20:01:33,290 Setting DEBUG logging. 2016-05-14 20:01:33,290 attempt 1/10 2016-05-14 20:01:33,290 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/091bc7c572db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 20:01:33,546 unpacking tar archive at: fx-team-091bc7c572db/testing/mozharness/ program finished with exit code 0 elapsedTime=0.537304 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.620863) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.621171) ========= script_repo_revision: 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.621618) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.621882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 20:01:33.630614) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-14 20:01:33.630952) ========= /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=31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:01:33 INFO - MultiFileLogger online at 20160514 20:01:33 in /builds/slave/test 20:01:33 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 20:01:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:01:33 INFO - {'append_to_log': False, 20:01:33 INFO - 'base_work_dir': '/builds/slave/test', 20:01:33 INFO - 'blob_upload_branch': 'Fx-Team', 20:01:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:01:33 INFO - 'branch': 'Fx-Team', 20:01:33 INFO - 'buildbot_json_path': 'buildprops.json', 20:01:33 INFO - 'config_files': ('talos/linux_config.py',), 20:01:33 INFO - 'default_actions': ('clobber', 20:01:33 INFO - 'read-buildbot-config', 20:01:33 INFO - 'download-and-extract', 20:01:33 INFO - 'populate-webroot', 20:01:33 INFO - 'create-virtualenv', 20:01:33 INFO - 'install', 20:01:33 INFO - 'run-tests'), 20:01:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:01:33 INFO - 'download_minidump_stackwalk': True, 20:01:33 INFO - 'download_symbols': 'ondemand', 20:01:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:01:33 INFO - 'tooltool.py': '/tools/tooltool.py', 20:01:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:01:33 INFO - '/tools/misc-python/virtualenv.py')}, 20:01:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:01:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:01:33 INFO - 'installer_path': 'installer.exe', 20:01:33 INFO - 'log_level': 'info', 20:01:33 INFO - 'log_name': 'talos', 20:01:33 INFO - 'log_to_console': True, 20:01:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:01:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:01:33 INFO - 'opt_config_files': (), 20:01:33 INFO - 'pip_index': False, 20:01:33 INFO - 'sps_profile': False, 20:01:33 INFO - 'sps_profile_interval': 0, 20:01:33 INFO - 'suite': 'dromaeojs', 20:01:33 INFO - 'system_bits': '32', 20:01:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:01:33 INFO - 'title': 'talos-linux64-ix-014', 20:01:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:01:33 INFO - 'use_talos_json': True, 20:01:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:01:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:01:33 INFO - 'work_dir': 'build'} 20:01:33 INFO - ##### 20:01:33 INFO - ##### Running clobber step. 20:01:33 INFO - ##### 20:01:33 INFO - Running pre-action listener: _resource_record_pre_action 20:01:33 INFO - Running main action method: clobber 20:01:33 INFO - rmtree: /builds/slave/test/build 20:01:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:01:34 INFO - Running post-action listener: _resource_record_post_action 20:01:34 INFO - ##### 20:01:34 INFO - ##### Finished clobber step (success) 20:01:34 INFO - ##### 20:01:34 INFO - ##### 20:01:34 INFO - ##### Running read-buildbot-config step. 20:01:34 INFO - ##### 20:01:34 INFO - Running pre-action listener: _resource_record_pre_action 20:01:34 INFO - Running main action method: read_buildbot_config 20:01:34 INFO - Using buildbot properties: 20:01:34 INFO - { 20:01:34 INFO - "project": "", 20:01:34 INFO - "product": "firefox", 20:01:34 INFO - "script_repo_revision": "production", 20:01:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:01:34 INFO - "repository": "", 20:01:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:01:34 INFO - "buildid": "20160514173010", 20:01:34 INFO - "pgo_build": "True", 20:01:34 INFO - "basedir": "/builds/slave/test", 20:01:34 INFO - "buildnumber": 680, 20:01:34 INFO - "slavename": "talos-linux64-ix-014", 20:01:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:01:34 INFO - "platform": "ubuntu64_hw", 20:01:34 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:01:34 INFO - "branch": "fx-team", 20:01:34 INFO - "repo_path": "integration/fx-team", 20:01:34 INFO - "revision": "091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a", 20:01:34 INFO - "stage_platform": "linux64-pgo", 20:01:34 INFO - "builduid": "c0ac523baf474938b02204cb6bff301d", 20:01:34 INFO - "slavebuilddir": "test" 20:01:34 INFO - } 20:01:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 20:01:34 INFO - Running post-action listener: _resource_record_post_action 20:01:34 INFO - ##### 20:01:34 INFO - ##### Finished read-buildbot-config step (success) 20:01:34 INFO - ##### 20:01:34 INFO - ##### 20:01:34 INFO - ##### Running download-and-extract step. 20:01:34 INFO - ##### 20:01:34 INFO - Running pre-action listener: _resource_record_pre_action 20:01:34 INFO - Running main action method: download_and_extract 20:01:34 INFO - mkdir: /builds/slave/test/build/tests 20:01:34 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')]} 20:01:34 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:01:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:01:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:01:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:39 INFO - Downloaded 1361 bytes. 20:01:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:01:39 INFO - Using the following test package requirements: 20:01:39 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:01:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:01:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:01:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:01:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:01:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:01:39 INFO - u'jsshell-linux-x86_64.zip'], 20:01:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:01:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:01:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:01:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:01:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:01:39 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:01:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:01:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:01:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:01:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:01:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:01:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:01:39 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')]} 20:01:39 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:01:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:01:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:01:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:43 INFO - Downloaded 24022619 bytes. 20:01:43 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'] 20:01:43 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 20:01:45 INFO - Return code: 0 20:01:45 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 20:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:45 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:01:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:01:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:01:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:46 INFO - Downloaded 24022619 bytes. 20:01:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:01:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:01:48 INFO - Return code: 0 20:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:48 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:01:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:01:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:01:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:48 INFO - Downloaded 10840194 bytes. 20:01:48 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'] 20:01:48 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 20:01:49 INFO - Return code: 0 20:01:49 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')]} 20:01:49 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:01:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:01:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:01:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:01:50 INFO - Downloaded 58398130 bytes. 20:01:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:01:50 INFO - mkdir: /builds/slave/test/properties 20:01:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:01:50 INFO - Writing to file /builds/slave/test/properties/build_url 20:01:50 INFO - Contents: 20:01:50 INFO - build_url:https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:01:52 INFO - Running post-action listener: _resource_record_post_action 20:01:52 INFO - Running post-action listener: set_extra_try_arguments 20:01:52 INFO - ##### 20:01:52 INFO - ##### Finished download-and-extract step (success) 20:01:52 INFO - ##### 20:01:52 INFO - ##### 20:01:52 INFO - ##### Running populate-webroot step. 20:01:52 INFO - ##### 20:01:52 INFO - Running pre-action listener: _resource_record_pre_action 20:01:52 INFO - Running main action method: populate_webroot 20:01:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:01:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:01:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:01:52 INFO - '--tppagecycles', 20:01:52 INFO - '7'], 20:01:52 INFO - 'tests': ['tsvgm']}}, 20:01:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:01:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:01:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:01:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:01:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:52 INFO - 'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:01:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:01:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:52 INFO - 'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['damp', 'tps']}, 20:01:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:52 INFO - 'tests': ['damp', 'tps']}, 20:01:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['dromaeo_dom']}, 20:01:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:01:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['a11yr', 20:01:52 INFO - 'ts_paint', 20:01:52 INFO - 'tpaint', 20:01:52 INFO - 'sessionrestore', 20:01:52 INFO - 'sessionrestore_no_auto_restore', 20:01:52 INFO - 'tabpaint']}, 20:01:52 INFO - 'other-e10s': {'tests': ['a11yr', 20:01:52 INFO - 'ts_paint', 20:01:52 INFO - 'tpaint', 20:01:52 INFO - 'sessionrestore', 20:01:52 INFO - 'sessionrestore_no_auto_restore', 20:01:52 INFO - 'tabpaint']}, 20:01:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:01:52 INFO - 'ts_paint', 20:01:52 INFO - 'tpaint', 20:01:52 INFO - 'sessionrestore', 20:01:52 INFO - 'sessionrestore_no_auto_restore', 20:01:52 INFO - 'tabpaint']}, 20:01:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:01:52 INFO - 'ts_paint', 20:01:52 INFO - 'tpaint', 20:01:52 INFO - 'sessionrestore', 20:01:52 INFO - 'sessionrestore_no_auto_restore', 20:01:52 INFO - 'tabpaint']}, 20:01:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['a11yr', 20:01:52 INFO - 'ts_paint', 20:01:52 INFO - 'tpaint', 20:01:52 INFO - 'sessionrestore', 20:01:52 INFO - 'sessionrestore_no_auto_restore', 20:01:52 INFO - 'tabpaint']}, 20:01:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['a11yr', 20:01:52 INFO - 'ts_paint', 20:01:52 INFO - 'tpaint', 20:01:52 INFO - 'sessionrestore', 20:01:52 INFO - 'sessionrestore_no_auto_restore', 20:01:52 INFO - 'tabpaint']}, 20:01:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['tsvgx', 20:01:52 INFO - 'tsvgr_opacity', 20:01:52 INFO - 'tart', 20:01:52 INFO - 'tscrollx', 20:01:52 INFO - 'cart']}, 20:01:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:01:52 INFO - 'tsvgr_opacity', 20:01:52 INFO - 'tart', 20:01:52 INFO - 'tscrollx', 20:01:52 INFO - 'cart']}, 20:01:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:52 INFO - 'talos_options': ['--disable-e10s'], 20:01:52 INFO - 'tests': ['tp5o']}, 20:01:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:52 INFO - 'tests': ['tp5o']}, 20:01:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:52 INFO - 'talos_options': ['--disable-e10s', 20:01:52 INFO - '--xperf_path', 20:01:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:01:52 INFO - 'tests': ['tp5n']}, 20:01:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:52 INFO - 'talos_options': ['--xperf_path', 20:01:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:01:52 INFO - 'tests': ['tp5n']}}, 20:01:52 INFO - 'talos.zip': {'path': '', 20:01:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:01:52 INFO - Running post-action listener: _resource_record_post_action 20:01:52 INFO - ##### 20:01:52 INFO - ##### Finished populate-webroot step (success) 20:01:52 INFO - ##### 20:01:52 INFO - ##### 20:01:52 INFO - ##### Running create-virtualenv step. 20:01:52 INFO - ##### 20:01:52 INFO - Running pre-action listener: _resource_record_pre_action 20:01:52 INFO - Running main action method: create_virtualenv 20:01:52 INFO - Creating virtualenv /builds/slave/test/build/venv 20:01:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:01:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:01:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:01:52 INFO - Using real prefix '/usr' 20:01:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:01:52 INFO - Installing distribute.............................................................................................................................................................................................done. 20:01:54 INFO - Installing pip.................done. 20:01:54 INFO - Return code: 0 20:01:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:01:54 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')]} 20:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:54 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')]} 20:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:54 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 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:01:54 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 20:01:54 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 20:01:54 INFO - Using env: {'DISPLAY': ':0', 20:01:54 INFO - 'HOME': '/home/cltbld', 20:01:54 INFO - 'LANG': 'en_US.UTF-8', 20:01:54 INFO - 'LANGUAGE': 'en_US:en', 20:01:54 INFO - 'LOGNAME': 'cltbld', 20:01:54 INFO - 'MAIL': '/var/mail/cltbld', 20:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:54 INFO - 'MOZ_NO_REMOTE': '1', 20:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:01:54 INFO - 'NO_EM_RESTART': '1', 20:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:54 INFO - 'PWD': '/builds/slave/test', 20:01:54 INFO - 'SHELL': '/bin/bash', 20:01:54 INFO - 'SHLVL': '1', 20:01:54 INFO - 'TERM': 'linux', 20:01:54 INFO - 'TMOUT': '86400', 20:01:54 INFO - 'USER': 'cltbld', 20:01:54 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:54 INFO - '_': '/tools/buildbot/bin/python'} 20:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:01:55 INFO - Cleaning up... 20:01:55 INFO - Return code: 0 20:01:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:01:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:01:55 INFO - Using env: {'DISPLAY': ':0', 20:01:55 INFO - 'HOME': '/home/cltbld', 20:01:55 INFO - 'LANG': 'en_US.UTF-8', 20:01:55 INFO - 'LANGUAGE': 'en_US:en', 20:01:55 INFO - 'LOGNAME': 'cltbld', 20:01:55 INFO - 'MAIL': '/var/mail/cltbld', 20:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:55 INFO - 'MOZ_NO_REMOTE': '1', 20:01:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:01:55 INFO - 'NO_EM_RESTART': '1', 20:01:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:55 INFO - 'PWD': '/builds/slave/test', 20:01:55 INFO - 'SHELL': '/bin/bash', 20:01:55 INFO - 'SHLVL': '1', 20:01:55 INFO - 'TERM': 'linux', 20:01:55 INFO - 'TMOUT': '86400', 20:01:55 INFO - 'USER': 'cltbld', 20:01:55 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:55 INFO - '_': '/tools/buildbot/bin/python'} 20:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:55 INFO - Downloading/unpacking psutil>=3.1.1 20:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:01:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:01:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:01:58 INFO - Installing collected packages: psutil 20:01:58 INFO - Running setup.py install for psutil 20:01:58 INFO - building 'psutil._psutil_linux' extension 20:01:58 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 20:01:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:01:58 INFO - building 'psutil._psutil_posix' extension 20:01:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:01:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:01:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:01:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:01:58 INFO - Successfully installed psutil 20:01:58 INFO - Cleaning up... 20:01:58 INFO - Return code: 0 20:01:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:01:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:01:58 INFO - Using env: {'DISPLAY': ':0', 20:01:58 INFO - 'HOME': '/home/cltbld', 20:01:58 INFO - 'LANG': 'en_US.UTF-8', 20:01:58 INFO - 'LANGUAGE': 'en_US:en', 20:01:58 INFO - 'LOGNAME': 'cltbld', 20:01:58 INFO - 'MAIL': '/var/mail/cltbld', 20:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:58 INFO - 'MOZ_NO_REMOTE': '1', 20:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:01:58 INFO - 'NO_EM_RESTART': '1', 20:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:58 INFO - 'PWD': '/builds/slave/test', 20:01:58 INFO - 'SHELL': '/bin/bash', 20:01:58 INFO - 'SHLVL': '1', 20:01:58 INFO - 'TERM': 'linux', 20:01:58 INFO - 'TMOUT': '86400', 20:01:58 INFO - 'USER': 'cltbld', 20:01:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:58 INFO - '_': '/tools/buildbot/bin/python'} 20:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:01: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 20:01: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 20:01: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 20:01: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 20:01: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 20:01: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 20:02:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:02:01 INFO - Installing collected packages: mozsystemmonitor 20:02:01 INFO - Running setup.py install for mozsystemmonitor 20:02:01 INFO - Successfully installed mozsystemmonitor 20:02:01 INFO - Cleaning up... 20:02:01 INFO - Return code: 0 20:02:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:02:01 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')]} 20:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:01 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')]} 20:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:01 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 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:02:01 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 20:02:01 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 20:02:01 INFO - Using env: {'DISPLAY': ':0', 20:02:01 INFO - 'HOME': '/home/cltbld', 20:02:01 INFO - 'LANG': 'en_US.UTF-8', 20:02:01 INFO - 'LANGUAGE': 'en_US:en', 20:02:01 INFO - 'LOGNAME': 'cltbld', 20:02:01 INFO - 'MAIL': '/var/mail/cltbld', 20:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:01 INFO - 'MOZ_NO_REMOTE': '1', 20:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:02:01 INFO - 'NO_EM_RESTART': '1', 20:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:01 INFO - 'PWD': '/builds/slave/test', 20:02:01 INFO - 'SHELL': '/bin/bash', 20:02:01 INFO - 'SHLVL': '1', 20:02:01 INFO - 'TERM': 'linux', 20:02:01 INFO - 'TMOUT': '86400', 20:02:01 INFO - 'USER': 'cltbld', 20:02:01 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:01 INFO - '_': '/tools/buildbot/bin/python'} 20:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:01 INFO - Downloading/unpacking blobuploader==1.2.4 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02:03 INFO - Downloading blobuploader-1.2.4.tar.gz 20:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:02:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:02:03 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 20:02:03 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 20:02:03 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 20:02:03 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 20:02:03 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 20:02:03 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 20:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:02:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:02:03 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 20:02:03 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 20:02:03 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 20:02:03 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 20:02:03 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 20:02:03 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 20:02:03 INFO - Downloading docopt-0.6.1.tar.gz 20:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:02:03 INFO - Installing collected packages: blobuploader, requests, docopt 20:02:03 INFO - Running setup.py install for blobuploader 20:02:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:02:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:02:04 INFO - Running setup.py install for requests 20:02:04 INFO - Running setup.py install for docopt 20:02:04 INFO - Successfully installed blobuploader requests docopt 20:02:04 INFO - Cleaning up... 20:02:04 INFO - Return code: 0 20:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:04 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 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:02:04 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 20:02:04 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 20:02:04 INFO - Using env: {'DISPLAY': ':0', 20:02:04 INFO - 'HOME': '/home/cltbld', 20:02:04 INFO - 'LANG': 'en_US.UTF-8', 20:02:04 INFO - 'LANGUAGE': 'en_US:en', 20:02:04 INFO - 'LOGNAME': 'cltbld', 20:02:04 INFO - 'MAIL': '/var/mail/cltbld', 20:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:04 INFO - 'MOZ_NO_REMOTE': '1', 20:02:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:02:04 INFO - 'NO_EM_RESTART': '1', 20:02:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:04 INFO - 'PWD': '/builds/slave/test', 20:02:04 INFO - 'SHELL': '/bin/bash', 20:02:04 INFO - 'SHLVL': '1', 20:02:04 INFO - 'TERM': 'linux', 20:02:04 INFO - 'TMOUT': '86400', 20:02:04 INFO - 'USER': 'cltbld', 20:02:04 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:04 INFO - '_': '/tools/buildbot/bin/python'} 20:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:02:04 INFO - Running setup.py (path:/tmp/pip-eFfoWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:02:04 INFO - Running setup.py (path:/tmp/pip-mWPArM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:02:04 INFO - Running setup.py (path:/tmp/pip-xgczMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:02:05 INFO - Running setup.py (path:/tmp/pip-6Xrc79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:02:05 INFO - Running setup.py (path:/tmp/pip-czZyRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:02:05 INFO - Running setup.py (path:/tmp/pip-lYQE6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:02:05 INFO - Running setup.py (path:/tmp/pip-dD1ogV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:02:05 INFO - Running setup.py (path:/tmp/pip-AHSSyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:02:05 INFO - Running setup.py (path:/tmp/pip-4ITEGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:02:05 INFO - Running setup.py (path:/tmp/pip-4EOV8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:02:05 INFO - Running setup.py (path:/tmp/pip-Yuy0IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:02:05 INFO - Running setup.py (path:/tmp/pip-8yw1SS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:02:05 INFO - Running setup.py (path:/tmp/pip-ifMMqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:02:05 INFO - Running setup.py (path:/tmp/pip-5HK7cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:05 INFO - Running setup.py (path:/tmp/pip-o67L6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:02:05 INFO - Running setup.py (path:/tmp/pip-4sCj0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:02:05 INFO - Running setup.py (path:/tmp/pip-4ZlGAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:02:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:02:06 INFO - Running setup.py install for manifestparser 20:02:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Running setup.py install for mozcrash 20:02:06 INFO - Running setup.py install for mozdebug 20:02:06 INFO - Running setup.py install for mozdevice 20:02:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Running setup.py install for mozfile 20:02:06 INFO - Running setup.py install for mozhttpd 20:02:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Running setup.py install for mozinfo 20:02:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Running setup.py install for mozInstall 20:02:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:02:06 INFO - Running setup.py install for mozleak 20:02:06 INFO - Running setup.py install for mozlog 20:02:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:02:07 INFO - Running setup.py install for moznetwork 20:02:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:02:07 INFO - Running setup.py install for mozprocess 20:02:07 INFO - Running setup.py install for mozprofile 20:02:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:02:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:02:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:02:07 INFO - Running setup.py install for mozrunner 20:02:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:02:07 INFO - Running setup.py install for mozscreenshot 20:02:07 INFO - Running setup.py install for moztest 20:02:07 INFO - Running setup.py install for mozversion 20:02:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:02:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:02:07 INFO - Cleaning up... 20:02:07 INFO - Return code: 0 20:02:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:07 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 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:02:07 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 20:02:07 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 20:02:07 INFO - Using env: {'DISPLAY': ':0', 20:02:07 INFO - 'HOME': '/home/cltbld', 20:02:07 INFO - 'LANG': 'en_US.UTF-8', 20:02:07 INFO - 'LANGUAGE': 'en_US:en', 20:02:07 INFO - 'LOGNAME': 'cltbld', 20:02:07 INFO - 'MAIL': '/var/mail/cltbld', 20:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:07 INFO - 'MOZ_NO_REMOTE': '1', 20:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:02:07 INFO - 'NO_EM_RESTART': '1', 20:02:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:07 INFO - 'PWD': '/builds/slave/test', 20:02:07 INFO - 'SHELL': '/bin/bash', 20:02:07 INFO - 'SHLVL': '1', 20:02:07 INFO - 'TERM': 'linux', 20:02:07 INFO - 'TMOUT': '86400', 20:02:07 INFO - 'USER': 'cltbld', 20:02:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:07 INFO - '_': '/tools/buildbot/bin/python'} 20:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:02:08 INFO - Running setup.py (path:/tmp/pip-fOh2T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:02:08 INFO - Running setup.py (path:/tmp/pip-yhWJr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:02:08 INFO - Running setup.py (path:/tmp/pip-8xdQpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:02:08 INFO - Running setup.py (path:/tmp/pip-uWWmjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:02:08 INFO - Running setup.py (path:/tmp/pip-t68Eyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:02:08 INFO - Running setup.py (path:/tmp/pip-_DWqG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:02:08 INFO - Running setup.py (path:/tmp/pip-3W97Ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:02:08 INFO - Running setup.py (path:/tmp/pip-huEyAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:02:08 INFO - Running setup.py (path:/tmp/pip-fldAa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:02:08 INFO - Running setup.py (path:/tmp/pip-4v7T2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:02:08 INFO - Running setup.py (path:/tmp/pip-_IVRDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:02:08 INFO - Running setup.py (path:/tmp/pip-J2lwAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:02:08 INFO - Running setup.py (path:/tmp/pip-JDD09o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:02:08 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)) 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:02:08 INFO - Running setup.py (path:/tmp/pip-E3LYuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:02:09 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)) 20:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:09 INFO - Running setup.py (path:/tmp/pip-4AeclY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:09 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)) 20:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:02:09 INFO - Running setup.py (path:/tmp/pip-IWZ4HF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:02:09 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)) 20:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:02:09 INFO - Running setup.py (path:/tmp/pip-YzQ4xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:02:09 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)) 20:02:09 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)) 20:02:09 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)) 20:02:09 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)) 20:02:09 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)) 20:02:09 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)) 20:02:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:11 INFO - Downloading blessings-1.6.tar.gz 20:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:02:11 INFO - Installing collected packages: blessings 20:02:11 INFO - Running setup.py install for blessings 20:02:11 INFO - Successfully installed blessings 20:02:11 INFO - Cleaning up... 20:02:11 INFO - Return code: 0 20:02:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:02:11 INFO - Reading from file tmpfile_stdout 20:02:11 INFO - Current package versions: 20:02:11 INFO - argparse == 1.2.1 20:02:11 INFO - blessings == 1.6 20:02:11 INFO - blobuploader == 1.2.4 20:02:11 INFO - docopt == 0.6.1 20:02:11 INFO - manifestparser == 1.1 20:02:11 INFO - mozInstall == 1.12 20:02:11 INFO - mozcrash == 0.17 20:02:11 INFO - mozdebug == 0.1 20:02:11 INFO - mozdevice == 0.48 20:02:11 INFO - mozfile == 1.2 20:02:11 INFO - mozhttpd == 0.7 20:02:11 INFO - mozinfo == 0.9 20:02:11 INFO - mozleak == 0.1 20:02:11 INFO - mozlog == 3.2 20:02:11 INFO - moznetwork == 0.27 20:02:11 INFO - mozprocess == 0.22 20:02:11 INFO - mozprofile == 0.28 20:02:11 INFO - mozrunner == 6.11 20:02:11 INFO - mozscreenshot == 0.1 20:02:11 INFO - mozsystemmonitor == 0.3 20:02:11 INFO - moztest == 0.7 20:02:11 INFO - mozversion == 1.4 20:02:11 INFO - psutil == 3.1.1 20:02:11 INFO - requests == 1.2.3 20:02:11 INFO - wsgiref == 0.1.2 20:02:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:11 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 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:02:11 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 20:02:11 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 20:02:11 INFO - Using env: {'DISPLAY': ':0', 20:02:11 INFO - 'HOME': '/home/cltbld', 20:02:11 INFO - 'LANG': 'en_US.UTF-8', 20:02:11 INFO - 'LANGUAGE': 'en_US:en', 20:02:11 INFO - 'LOGNAME': 'cltbld', 20:02:11 INFO - 'MAIL': '/var/mail/cltbld', 20:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:11 INFO - 'MOZ_NO_REMOTE': '1', 20:02:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:02:11 INFO - 'NO_EM_RESTART': '1', 20:02:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:11 INFO - 'PWD': '/builds/slave/test', 20:02:11 INFO - 'SHELL': '/bin/bash', 20:02:11 INFO - 'SHLVL': '1', 20:02:11 INFO - 'TERM': 'linux', 20:02:11 INFO - 'TMOUT': '86400', 20:02:11 INFO - 'USER': 'cltbld', 20:02:11 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:11 INFO - '_': '/tools/buildbot/bin/python'} 20:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 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)) 20:02:11 INFO - Cleaning up... 20:02:11 INFO - Return code: 0 20:02:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:02:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:02:11 INFO - Using env: {'DISPLAY': ':0', 20:02:11 INFO - 'HOME': '/home/cltbld', 20:02:11 INFO - 'LANG': 'en_US.UTF-8', 20:02:11 INFO - 'LANGUAGE': 'en_US:en', 20:02:11 INFO - 'LOGNAME': 'cltbld', 20:02:11 INFO - 'MAIL': '/var/mail/cltbld', 20:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:11 INFO - 'MOZ_NO_REMOTE': '1', 20:02:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:02:11 INFO - 'NO_EM_RESTART': '1', 20:02:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:11 INFO - 'PWD': '/builds/slave/test', 20:02:11 INFO - 'SHELL': '/bin/bash', 20:02:11 INFO - 'SHLVL': '1', 20:02:11 INFO - 'TERM': 'linux', 20:02:11 INFO - 'TMOUT': '86400', 20:02:11 INFO - 'USER': 'cltbld', 20:02:11 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:11 INFO - '_': '/tools/buildbot/bin/python'} 20:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:12 INFO - Downloading/unpacking jsonschema 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:02:13 INFO - Downloading/unpacking functools32 (from jsonschema) 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02:14 INFO - Downloading functools32-3.2.3-2.tar.gz 20:02:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:02:14 INFO - warning: no files found matching '*.txt' 20:02:14 INFO - no previously-included directories found matching 'build' 20:02:14 INFO - no previously-included directories found matching 'dist' 20:02:14 INFO - no previously-included directories found matching '.git*' 20:02:14 INFO - Installing collected packages: jsonschema, functools32 20:02:14 INFO - Running setup.py install for functools32 20:02:14 INFO - warning: no files found matching '*.txt' 20:02:14 INFO - no previously-included directories found matching 'build' 20:02:14 INFO - no previously-included directories found matching 'dist' 20:02:14 INFO - no previously-included directories found matching '.git*' 20:02:14 INFO - Successfully installed jsonschema functools32 20:02:14 INFO - Cleaning up... 20:02:14 INFO - Return code: 0 20:02:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:14 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 0x7f952aec0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x142d770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, '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': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', '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 20:02:14 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 20:02:14 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 20:02:14 INFO - Using env: {'DISPLAY': ':0', 20:02:14 INFO - 'HOME': '/home/cltbld', 20:02:14 INFO - 'LANG': 'en_US.UTF-8', 20:02:14 INFO - 'LANGUAGE': 'en_US:en', 20:02:14 INFO - 'LOGNAME': 'cltbld', 20:02:14 INFO - 'MAIL': '/var/mail/cltbld', 20:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:14 INFO - 'MOZ_NO_REMOTE': '1', 20:02:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:02:14 INFO - 'NO_EM_RESTART': '1', 20:02:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:14 INFO - 'PWD': '/builds/slave/test', 20:02:14 INFO - 'SHELL': '/bin/bash', 20:02:14 INFO - 'SHLVL': '1', 20:02:14 INFO - 'TERM': 'linux', 20:02:14 INFO - 'TMOUT': '86400', 20:02:14 INFO - 'USER': 'cltbld', 20:02:14 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:14 INFO - '_': '/tools/buildbot/bin/python'} 20:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:14 INFO - Downloading/unpacking flake8 20:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:02:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:02:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:16 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 20:02:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:02:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:02:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:02:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:02:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:02:17 INFO - no previously-included directories found matching 'docs/_build' 20:02:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:02:17 INFO - Running setup.py install for pep8 20:02:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:02:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:02:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:02:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:02:17 INFO - no previously-included directories found matching 'docs/_build' 20:02:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:02:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:02:17 INFO - Cleaning up... 20:02:17 INFO - Return code: 0 20:02:17 INFO - Running post-action listener: _resource_record_post_action 20:02:17 INFO - Running post-action listener: _start_resource_monitoring 20:02:17 INFO - Starting resource monitoring. 20:02:17 INFO - ##### 20:02:17 INFO - ##### Finished create-virtualenv step (success) 20:02:17 INFO - ##### 20:02:17 INFO - ##### 20:02:17 INFO - ##### Running install step. 20:02:17 INFO - ##### 20:02:17 INFO - Running pre-action listener: _resource_record_pre_action 20:02:17 INFO - Running main action method: install 20:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:02:17 INFO - Reading from file tmpfile_stdout 20:02:17 INFO - Detecting whether we're running mozinstall >=1.0... 20:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:02:17 INFO - Reading from file tmpfile_stdout 20:02:17 INFO - Output received: 20:02:17 INFO - Usage: mozinstall [options] installer 20:02:17 INFO - Options: 20:02:17 INFO - -h, --help show this help message and exit 20:02:17 INFO - -d DEST, --destination=DEST 20:02:17 INFO - Directory to install application into. [default: 20:02:17 INFO - "/builds/slave/test"] 20:02:17 INFO - --app=APP Application being installed. [default: firefox] 20:02:17 INFO - mkdir: /builds/slave/test/build/application 20:02:17 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'] 20:02:17 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 20:02:34 INFO - Reading from file tmpfile_stdout 20:02:34 INFO - Output received: 20:02:34 INFO - /builds/slave/test/build/application/firefox/firefox 20:02:34 INFO - Running post-action listener: _resource_record_post_action 20:02:34 INFO - ##### 20:02:34 INFO - ##### Finished install step (success) 20:02:34 INFO - ##### 20:02:34 INFO - ##### 20:02:34 INFO - ##### Running run-tests step. 20:02:34 INFO - ##### 20:02:34 INFO - Running pre-action listener: _resource_record_pre_action 20:02:34 INFO - Running main action method: run_tests 20:02:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:02:34 INFO - Python 2.7.3 20:02:34 INFO - Return code: 0 20:02:34 INFO - grabbing minidump binary from tooltool 20:02:34 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')]} 20:02:34 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 0x14bc9c0>, '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 0x14bd8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bdd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:02:34 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 20:02:34 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 20:02:34 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 20:02:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:02:34 INFO - Return code: 0 20:02:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:02:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:02:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:02:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:02:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:02:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:02:35 INFO - Return code: 0 20:02:35 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/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 20:02:35 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/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 20:02:35 INFO - Using env: {'DISPLAY': ':0', 20:02:35 INFO - 'HOME': '/home/cltbld', 20:02:35 INFO - 'LANG': 'en_US.UTF-8', 20:02:35 INFO - 'LANGUAGE': 'en_US:en', 20:02:35 INFO - 'LOGNAME': 'cltbld', 20:02:35 INFO - 'MAIL': '/var/mail/cltbld', 20:02:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:02:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:35 INFO - 'MOZ_NO_REMOTE': '1', 20:02:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:02:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:02:35 INFO - 'NO_EM_RESTART': '1', 20:02:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:35 INFO - 'PWD': '/builds/slave/test', 20:02:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:02:35 INFO - 'SHELL': '/bin/bash', 20:02:35 INFO - 'SHLVL': '1', 20:02:35 INFO - 'TERM': 'linux', 20:02:35 INFO - 'TMOUT': '86400', 20:02:35 INFO - 'USER': 'cltbld', 20:02:35 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857', 20:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:35 INFO - '_': '/tools/buildbot/bin/python'} 20:02:35 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/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 20:02:35 INFO - mozversion application_buildid: 20160514173010 20:02:35 INFO - mozversion application_changeset: 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a 20:02:35 INFO - mozversion application_display_name: Nightly 20:02:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:02:35 INFO - mozversion application_name: Firefox 20:02:35 INFO - mozversion application_remotingname: firefox 20:02:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:02:35 INFO - mozversion application_vendor: Mozilla 20:02:35 INFO - mozversion application_version: 49.0a1 20:02:35 INFO - mozversion platform_buildid: 20160514173010 20:02:35 INFO - mozversion platform_changeset: 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a 20:02:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:02:35 INFO - mozversion platform_version: 49.0a1 20:02:35 INFO - using testdate: 1463281355 20:02:35 INFO - actual date: 1463281355 20:02:35 INFO - starting webserver on 'localhost:55075' 20:02:35 INFO - SUITE-START | Running 2 tests 20:02:35 INFO - TEST-START | dromaeo_css 20:02:35 INFO - operating with platform_type : linux_ 20:02:35 INFO - Initialising browser for dromaeo_css test... 20:02:35 INFO - TEST-INFO | started process 27574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplEn_41/profile http://localhost:55075/getInfo.html) 20:02:39 INFO - PROCESS | 27574 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 20:02:39 INFO - PROCESS | 27574 | __metrics Screen width/height:1600/1200 20:02:39 INFO - PROCESS | 27574 | colorDepth:24 20:02:39 INFO - PROCESS | 27574 | Browser inner width/height: 1024/697 20:02:39 INFO - PROCESS | 27574 | __metrics 20:02:40 ERROR - PROCESS | 27574 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:02:41 INFO - TEST-INFO | 27574: exit 0 20:02:41 INFO - Browser initialized. 20:02:41 INFO - Running cycle 1/1 for dromaeo_css test... 20:02:41 INFO - TEST-INFO | started process 27653 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplEn_41/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:02:53 INFO - PROCESS | 27653 | RSS: Main: 152854528 20:02:53 INFO - PROCESS | 27653 | 20:03:44 INFO - PROCESS | 27653 | 1463281424534 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] 20:05:52 INFO - PROCESS | 27653 | Cycle 1(1): loaded http://localhost:55075/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55075/tests/dromaeo/cssquery-ext.html) 20:05:53 INFO - PROCESS | 27653 | RSS: Main: 276320256 20:05:53 INFO - PROCESS | 27653 | 20:08:55 INFO - PROCESS | 27653 | Cycle 1(1): loaded http://localhost:55075/tests/dromaeo/cssquery-ext.html (next: http://localhost:55075/tests/dromaeo/cssquery-jquery.html) 20:08:56 INFO - PROCESS | 27653 | RSS: Main: 233082880 20:08:56 INFO - PROCESS | 27653 | 20:11:58 INFO - PROCESS | 27653 | Cycle 1(1): loaded http://localhost:55075/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55075/tests/dromaeo/cssquery-mootools.html) 20:11:59 INFO - PROCESS | 27653 | RSS: Main: 237060096 20:11:59 INFO - PROCESS | 27653 | 20:15:01 INFO - PROCESS | 27653 | Cycle 1(1): loaded http://localhost:55075/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55075/tests/dromaeo/cssquery-prototype.html) 20:15:01 INFO - PROCESS | 27653 | RSS: Main: 232538112 20:15:01 INFO - PROCESS | 27653 | 20:18:04 INFO - PROCESS | 27653 | Cycle 1(1): loaded http://localhost:55075/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55075/tests/dromaeo/cssquery-yui.html) 20:18:04 INFO - PROCESS | 27653 | RSS: Main: 221794304 20:18:04 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | Cycle 1(1): loaded http://localhost:55075/tests/dromaeo/cssquery-yui.html (next: http://localhost:55075/tests/dromaeo/cssquery-dojo.html) 20:21:07 INFO - PROCESS | 27653 | RSS: Main: 286232576 20:21:07 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | __start_tp_report 20:21:07 INFO - PROCESS | 27653 | _x_x_mozilla_page_load 20:21:07 INFO - PROCESS | 27653 | _x_x_mozilla_page_load_details 20:21:07 INFO - PROCESS | 27653 | |i|pagename|runs| 20:21:07 INFO - PROCESS | 27653 | |0;dojo.html;3162.541431492433;3184.6337671167817;3195.344954284372;3240.967590324099;3257.788243764155;5270.051390749663;5334.03987282289;5352.090144675405;5429.891402171959;5474.370447398545;5320.866978325544;5357.598180136496;5447.727613619313;5476.58925580581;5504.614676972614;4765.857024289268;4777.163996300651;4834.758262086899;4836.903261934759;4982.078315511624;4934.876628084308;4943.209086546153;4950.381202349707;4953.23224900141;4996.050750357439;4705.176594096037;4731;4753.809847606091;4862;4908.75456227189;4817.975910120462;4826.251930950708;4828.782704778293;4850.029994001214;4902.362692849907;4770.356001939728;4785.856424307276;4789.976050119762;4792.7124372537755;4888.853334399938;5331.013762453958;5339.5461385782255;5384.326959130109;5387.703676297802;5398.78404863805;5666.801661941812;5756.165356023395;5795.913061304083;5819.709014549256;5874.970625146847;5858.355580886099;5890.3815099414805;5923.911141332884;5929.14027466015;5936.376680448534;5490.917636235459;5493.285872836505;5537.390887002426;5543.334799824047;5556.499915007668;5696.544276457874;5858.619189752652;5940.584159108907;5944.227250457499;6029.849253768603;5725.198472213893;5729.3984131665575;5733.226014487991;5782.653040817564;5783.826485205451;5656.208130861601;5810.564207684441;5811.27359080115;5821.126830975303;5857.355690874;5688.11834165705;5737.168110623936;5747.4252574742195;5761.078227483583;5764.193012978187;5789.536837053026;5808.099744539603;5812.680302583399;5813.82558523245;5819.301683797971;4286.978565107154;4346.065595896897;4387.473503179639;4390.341448782644;4442.067165895198;3913.3347330953807;3950.743201691881;3957.08986933007;3986.4817573715814;4018.537868145142;5493.807716729895;5500.477454641803;5506.256655351476;5509.889802203934;5511.779528818883;5709.457601527848;5736.569757268222;5754.482096611325;5842.384565788855;5883.617564858356;5398.217258497496;5413.539849112791;5414.40441551429;5457.208704737791;5469.0155771960635;4954.727489988013;4957.677750946249;4958.628102892298;4959.925601115986;5005.849824505271;5849.970750146222;5948.821535353946;5970.58205925581;5981.282246130405;5987.281526216795;5836.182934389274;5837.503812176013;5852.882942341214;5853.2976042875125;5882.294124704892;6042.637441753509;6047.637141771508;6053.667048312385;6058.939410605837;6064.757409703562;5582.078956972054;5675.205471234113;5692.800751973827;5733.971330143323;5751.8562035949435;5782.884342313214;5846.707664616837;5853.678047707417;5865.706714664335;5917.556183286357;5694.259746232963;5696.0316746154085;5696.174054762119;5701.800436984686;5706.258186435737;5511.807086751945;5609.186667933045;5631.521320687787;5665.66006039639;5670.177824215548;5559.8888022240135;5588.105903055492;5589.944100558942;5591.692456914909;5605.019121653613;5714.228579141763;5730.398308177748;5779.450952159538;5779.653220806765;5785.27684039495;5485.561155107662;5591.720413979366;5628.493435590816;5671.17767923657;5681.119426488901 20:21:07 INFO - PROCESS | 27653 | |1;ext.html;609.2719200555335;644.4007073421717;657.7434800427834;662.016904896229;671.5802623360399;32146.196345091354;43781.78109109458;44551.55448445515;44780.55219447805;44920;44793.32810007844;45161.32258016124;45255.547444525546;45255.547444525546;45328.3200751989;9133.862992055125;9259.49072800999;9261.444313341188;9272.443653380786;9273.07269273074;9101.453912765222;9210.125038121401;9218.35471516994;9220.446773193597;9221.35450518464;9177.357584969055;9225.769355766126;9229.907700922971;9230.815383692323;9231.076892310783;9205.769855753593;9236.076392360745;9254.907450925471;9258.953705231497;9263.25893928484;9022.729318120468;9059.456432614064;9067.727968160965;9072.591733372014;9089.227415669677;42833.14333713339;43109.78445107755;43158.13683726308;43165.784171079256;43174.78412607917;3897.610238976108;4259.488861336628;4263.978680106579;4344.304911214191;4409.250427427345;32590.674093259;33452.163695907555;33569.48228882843;33888.49167262493;34754.478682819776;4170.16596680662;4218.915621687549;4273.636740877029;4370.912581748379;4418.6023257906945;246135.9999999982;246268;246899;247015;247397;59863.70068149632;60107.699461502416;60455.69772151199;60497.69751151304;60543;39063;39996.40005399921;40013.79993100074;40048.59951400448;63970.68014659897;17757.467275981482;17942.461726148238;17961.371352002618;17978.280868765363;17982.5504362392;12758.936205318914;12794.808077878839;12795.232286062866;12801;12807.487700492062;20641.48396290105;20822.687659685118;20823.8958805205;20873;20876.895615521826;7287.453440991947;7316.207529155391;7340.4127669786285;7425.1832298447125;7439.181690014094;985.4579981010415;1033.7002269341956;1071.0842229893328;1096.9780604387868;1210.2798834693344;33145.337093258;33224.83387583047;33306.500402493984;33431.83284083564;33843.32313353719;1149.350616901448;1171.1918776044502;1175.1421462332546;1185.4487663236637;1237.5173682263924;17428.323627803245;17656.646867062587;17851.107444627716;17868.195931183127;18049.187786549635;522562;526580;527917;528162;529267;60756;61130;61224.38775612276;61460;61470;1379.5102738527878;1414.653409914557;1425.2659880161725;1425.2659880161725;1426.5577670922048;32356.191095222806;33271;33688.66311336855;33714;33723.232857306124;53897;53913.19130213129;54165.729171353894;54288.72855635697;54306.72846635742;422928;423109;423467;424956;427629;28697.856510717313;28869.711302886702;38570.228595428496;42379.06693063035;43632.56367436285;50561;50833.74583127061;51198;51327.74336128445;51751;42809.1438171241;44188.33717494176;44322.55677443184;44993.55006449894;45034;42432;42788.57211427846;43022.35466467943;43245;43725.562744372146;41738.58261417347;42836.57163428326;42970.35544466773;43979.12041759083;44127.77936110299;34686.306273874885;36826.447603286506;37094;37213.81393093017;37538.81230593939;28231.08698451638;28444.43111137807;28464.157338759003;28493.430131397672;31273.687263127078 20:21:07 INFO - PROCESS | 27653 | |2;jquery.html;402.8187315707932;402.9717919745617;406.46955722781775;408.799688152805;418.3954186200939;13869.653258668539;17922.551936201584;19515.804841951576;19707.802921970775;19730;14837.109773413602;17658.293668253285;18283.90858045708;19082.809171908313;19437.805621943775;5613.691246981414;5758.712064396785;5762.423757624246;5775.306963164426;5812.854678633025;5705.315362156547;5726.513246374063;5757;5776.191333213333;5777.740001699934;4115.444415003966;6019.608725432833;6323.019931910539;6331.968340158292;6459.6447195404235;6275.121482992385;6331.746730130789;6335.936640633604;6350.047492876082;6356.300806911236;5449.1826226066405;5566.554675625981;5578.497935185812;5603.551715862762;5698.772049118072;19163.041847907687;19666.410007699793;21982.34052978397;22238.666420003712;23012.19457318986;2425.842320249196;2476.6904136982876;2580.8580528070947;2614.2026681862217;3043.7260646541918;9250.72247832566;9442.433453992782;9593.808123837483;9690;9747.171490423323;1928.9148112390517;1934;1935.7096435534643;1954.4722924810364;2009.9698504522444;72845.6357718208;79566.60216698937;86957.5652121748;87689;89485;10188.541515631814;10254.6410875619;10292.228082893813;10521.894781052244;10766.78466430667;14239.359228834728;14299;14305.85694143066;14366.13803171813;14374.35315410809;7896.802579935547;8157.510549367057;8176.959115204386;8246.134155913664;8280.58597070144;7983;8005.679772809139;8055;8169.877451838227;8173.283610441755;4208.579142085767;4368.148211098869;4443.511213766483;4466.977665111719;4745.6440766942615;1934.4390126863334;1935.374455052426;1970.7078440535524;1987.5627361980341;2025.2607798153567;980.4411485453365;991.0733464210986;1044.5508431374583;1049.559185142242;1091.770728147082;18427.26290948347;18507.814921850608;19058.523536911413;19181.904090479456;19506.609867802847;963.2871674960479;1039.6465201831413;1044.738815296176;1044.7649278912336;1064.1274155192668;10279.640212592523;10673.412962286993;10715.571377144826;10773.622923197807;10796.67609971702;477542;477757;479892;488592;493927;91309;93866.0613393871;94476;94649;94800;472.50151090599746;494.0538868067682;495.7397366382606;991.3754334769194;1001.369137443406;10312.742181445525;10587.841182382117;10612.628558000433;10806.405647689453;10864.511097000812;39131.80434097811;41177.38233926553;41721.79139104285;42254.78872605618;42671.1465770689;57989.4201057984;58789.11816322673;59309.70345148247;60854.39145608487;62138.378616215065;8512.191341822518;8577.914220857961;8590.398672092893;8737.257333126501;8853.114688531354;9090.909090909006;12177.634670959886;12284.754304914031;12488.625341239778;12962.80555791645;11722.94138529302;12105.939470302945;12140.817887731515;12291.44688489038;12300.81548776787;11513.712157196138;11900.523979040741;11948.522059117886;12016.459259333176;12167.209131406431;12188.329641869783;12391.132620716824;12463.688407789878;12681.492740290285;12703.682407939876;8284.585770711561;8327.292180164754;8394.748157555283;8760.167784060504;8829;10641.574337026743;11927.284362938251;12044;12054;12400.19398739079 20:21:07 INFO - PROCESS | 27653 | |3;mootools.html;1377.4834437086092;1532.3028022249878;1548.1485183149268;1719.3896166860764;1844.0687452836316;8644.092370301121;9020.188183063523;9025.413348132375;9332.533373331324;9337.953310233457;9025.548722563863;9206.447613143217;9210.67762628308;9223.031581683927;9225.215856652192;2017.2838642282;2216.8004879560835;2299.3331933739196;2354.2466410748566;2390.7967822735;2284.246198754407;2285.817134629234;2293.025464177726;2294.4837411582425;2296.5062511560013;2311.456807650201;2357.834951553392;2367.088670861722;2369.336585755991;2375.406148462884;2023.5952809438097;2301.5742087713743;2360.055977608953;2363.6690863279155;2386.009805930554;2283.8058765004994;2287.290939808648;2288.39357570244;2290.2900100968754;2322.674825524428;9107.180353768126;9146.176844084064;9151.862722059175;9170.266378689688;9172;5395.271638328814;5540.778368865281;5545.085060964976;5547.389787123413;5554.527865131468;3300.273939733255;3413.7781044232047;3433.055909624848;3465.1856813648774;3497.9825100874586;1114.1060197664003;1125.352922069813;1132.4224645430807;1179.8584169899582;1186.4601606269127;12990.545330913466;13149.145305555108;13162.407691653898;13182.4067916943;13189.340532973409;4647.09381670571;4699.201135806853;4795.808167673324;4796.496367881392;4812.542808433193;10286.891187306584;10301.639442619473;10314.27800053989;10317.329373590692;10327.638532651321;4809.254565542346;5508.394076651565;5518.917216241679;5525.668459892419;5528.032594295983;4944.357233559686;5088.287639730367;5099.643024988215;5152.6650767699975;5156.355455568054;3906.746061505993;3937.8818635440985;4005.719599627998;4011.2178125265877;4061.106556557612;1171.1158075652997;1172.9589464368705;1173.8532683414574;1182.7752726981846;1183.2545496337425;956.8660387545749;980.0297705271872;994.855745916838;997.1275134257525;1008.1229330482381;5784.566157538202;5907.793227237112;5998.070299103646;6009.278886533644;6124.969375153096;897.1656359585648;930.3348106104168;939.5724945150077;964.2767924056957;966.6809952715587;4026.3960405939342;4096.610821971908;4110.34234522475;4110.712250142461;4133.7106402551535;11443;11694.356810375511;11834.289942603471;11881.168318217815;11889.464974076187;11643.76712465746;11681.240719353214;11704.765904682028;11721.062315014779;11746.471408786625;8155.306798922158;8447.98624165104;8451.943507061616;8485.575721213914;8659;6057;6110.541709371903;6181.227346581677;6219.626822390671;6334.809955701337;10068.44623545712;10254.179665626882;10545.103666188154;10561.683149505527;10597.682069537927;10183.13443357323;10523.526441310007;10631.840522392326;10728.946355268174;10753.354798712102;7076.186238582423;7903.130655627761;8094.635741391769;8224;8347.165283471848;9011.414258073204;9025.41334813235;9050.592723327334;9093.818123637622;9104.317176211944;7872.409569282441;7953.801154971172;8015.238552337519;8027.197280272159;8050.074241462306;7886.84226315459;7938.603069846369;7967.2431119043595;8057.959710201411;8166.836663266583;6916.585004899722;7112.786616401516;7339.119305683352;7415.666295016629;7433.851322973402;4865.343178670834;4882.780274887709;4940.357753492023;4946.3817022872145;5015.749212539431;5017.071841709296;5035.773390197377;5052.065367906949;5115.616328775432;5132.409772876167 20:21:07 INFO - PROCESS | 27653 | |4;prototype.html;329.81695159186654;331.20841189556955;335.7800640580418;337.2243839169909;340.83810190159676;5327.174287985364;5340.706261155635;5372.7045012524295;5378.838634840956;5413;5277.551408130314;5282.366116066078;5309.548688361494;5312.521873031426;5315.14957606782;3970.9602903970954;3990.840366385341;4093.447384603085;4099.7745124018165;4133.1940271647045;4075.286824805662;4093.6520395766397;4105.897352566195;4113.5269444013875;4129.525104612963;3952.6245006724316;4048.797560121997;4068.16602596467;4081.8163182656854;4090.365993271048;3976.5228172619327;4040.838366465338;4059.817308221137;4068.3897415387632;4068.558864681197;3594.7663401878795;3613.33153366628;3618.981905090484;3621.438677005042;3633.6184700606586;5149.562287205592;5151.922721159186;5166.328377310963;5167.940572182693;5174.301469301671;4561.292999585036;4710.670253082286;4736;4753.809847606091;4759.143354196243;5513.15309371361;5570;5582.218489411485;5612.607117501777;5633.57748168889;4036.6972477064337;4041.8585349512628;4052.290849101425;4056.5052492717064;4092.4066010428323;5723.683552782883;5751.856203594902;5763.711814409263;5819.7672093116225;5824.592278540462;4100.753954762664;4105.009070600756;4108.8356465741035;4113.197926404322;4135.29699951003;4172.207280616673;4186.618415922799;4211.347241177622;4233.894152646209;4238.554951730086;4591.219492686251;4730.219513780256;4750;4760.452547957029;4797.544233297831;5604.719764011783;5609.326880774333;5612.607117501736;5633.915491267634;5640.717964101778;1436.1239643817264;1442.9134251944918;1446.6383404148962;1448.8406275309708;1456.410153887677;920.8342498350361;933.6125507914139;951.4957072751453;996.6561536269975;1005.9144972677258;5326.733663316896;5349;5360.812371566976;5369.543588794918;5372.5970552207955;5670.8015219467125;5691.8292451226525;5692.82921512347;5705.429457054344;5725.828225153169;5135.460776618401;5277.182036784305;5277.445868183785;5416.350037995466;5509.586780991442;3071.616047994001;3075.0467355119986;3093.1957691000503;3098.597182366278;3104.4412005838713;5761.9135712964335;5935.614185077956;5984.2818861736;6049.667268300198;6064.3935606439;5799.3040835100055;5880.911786323209;5885.970570147208;5905;5909.940900590939;5963.254593175853;5971.9701401493585;5980.611260268069;5988.401159883977;6003.699815009232;5659.943400565941;5709.971450142889;5742.339630942328;5782.653040817564;5798.855028624318;5721;5733.426657334233;5734.168545560954;5743.511801496752;5772.181762744722;5683.261176047087;5925.377835327401;5974.492168165581;5988.37122102173;5993.891130140938;4099.672026237953;4103.9179216414905;4121.361189015707;4140.979295103505;4153.439285696392;5800.332961709458;5800.56495762811;5851.824445266649;5877.177195192762;5877.647341159544;5276.498732620395;5461.924468702026;5583.41374155724;5584.134459158837;5642.576806739429;5626.606137570331;5627.577931655224;5650.63270887391;5653.208550802973;5653.85865353353;5292.788288468418;5498.422665620053;5535;5553.666779993213;5586.608937374344;5657.745401457027;5669.994902701575;5676.262085928803;5681.715914204356;5727.799527016535;5560.499555040065;5565.1930470082425;5590.524805391425;5597.468240517144;5605.299337582802 20:21:07 INFO - PROCESS | 27653 | |5;yui.html;799.0451410564378;825.6037102190949;861.8060936289335;880.6145395928277;899.581694512052;14614.634634134152;15572.221388930551;15688.450904218354;15703;16195;14922.925385373084;15521.68956620867;15537.22313884307;15549.378024879019;15570.766438503431;707.0348973650981;830.4104086098877;856.751542052804;862.6894318045514;875.2297977779546;868.9782755431102;869.5304535550795;872.2280781508379;872.4198408058634;872.9170728780756;910.4628269321099;914.4147745442922;915.0666320353236;915.4324318922278;915.890093188815;738.1253214940286;870.9695160669378;897.6768363389175;897.771068377566;906.4742449379344;655.6819942328015;658.8352911772057;659.1036190780535;659.7591878964143;660.2044536333707;14146.92926535371;14150.433982640681;14175.503857364944;14184;14185.574432767033;13141.671458213526;13177.472901083946;13336.666583335495;13460.721231482994;13554.254516001616;12592;12688.111832171751;12711.491540338375;12977.41601627929;13118.212907225598;4438.3564383164585;4561.498235194159;4641.7911193996015;4673.042026384617;4673.439187297512;58695;59212;59656.40343596552;61303.693481532304;61467.077993829684;10719.732006699895;10937.726556836144;11144.665660030212;11395.772084558263;11527.88472115268;19889.60220795605;20020.099095540736;20062.79937200609;20080.39758807238;20099.095540700702;8637.654493820302;8794.780130496789;8854.601542930584;8984.833695851403;9063.954680226689;4882.7314497702255;4887.148848107414;4959.950400495949;5090.821821236239;5094.235864620263;3586.336527742324;3664.0656632558525;3668.559772827278;3669.798161101165;3677.3932301170544;586.5337057039578;671.6809515480107;672.122879642062;684.2029036172803;692.5533031194851;720.2797202797203;792.9801754956172;809.5142914251402;820.6799348254185;823.0248642752624;10026.649067282757;10252.794944101077;10255.33340332876;10494.580216791395;10599.258051936446;611.400827189357;749.8350362920258;775.3874439192974;782.4913806026128;800.3637108498757;4557.088582283556;4638.443386793607;4661.3707149534375;4728.645351598644;4741.691790033637;69342.3065769346;69353.65323173352;72941;73065.26934730691;73208.63395682987;45207.54792452099;61004.38995610076;62250.68874655598;63837;64706.352936470976;58022;58396.416035839095;58587.70706146527;58688.70655646695;59855.401445984986;8618.827623447622;8697.608607612547;8717.128287171232;8794;8851;37394.813025934694;37406.812965934994;37929.241415172095;38367;39161.60838391694;50675.746621266655;51267.743661281456;51424.48575514197;51661;52376;11815;11831.230969986924;11923.165378423428;12054.638360848838;12207.816882746944;13352.198868067948;13454;13537.187768733907;13929.582112536234;13949.860501394856;12002.279863208236;12096.75806483883;12145.089118315986;12340.753184936431;12477.93761031189;12185.451654675739;12281.140320177501;12784.61646150617;13199.67000824987;13213.933930330288;11406.48670809799;11811.645650630495;11968.042556595625;12184.634460966185;12213.206141600767;10436.478176091014;10575.894241057491;10713.089387401846;10755.516001780095;10941.945290273497;9744.464054476788;9755.9024409755;9971.40171589678;10054.245931555017;10056.949715251378 20:21:07 INFO - PROCESS | 27653 | __end_tp_report 20:21:07 INFO - PROCESS | 27653 | __start_cc_report 20:21:07 INFO - PROCESS | 27653 | _x_x_mozilla_cycle_collect,1031 20:21:07 INFO - PROCESS | 27653 | __end_cc_report 20:21:07 INFO - PROCESS | 27653 | __startTimestamp1463282467742__endTimestamp 20:21:07 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | ------- Summary: start ------- 20:21:07 INFO - PROCESS | 27653 | Number of tests: 6 20:21:07 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | [#0] dojo.html Cycles:165 Average:5378.70 Median:5591.72 stddev:621.41 (11.1%) stddev-sans-first:598.51 20:21:07 INFO - PROCESS | 27653 | Values: 3162.5 3184.6 3195.3 3241.0 3257.8 5270.1 5334.0 5352.1 5429.9 5474.4 5320.9 5357.6 5447.7 5476.6 5504.6 4765.9 4777.2 4834.8 4836.9 4982.1 4934.9 4943.2 4950.4 4953.2 4996.1 4705.2 4731.0 4753.8 4862.0 4908.8 4818.0 4826.3 4828.8 4850.0 4902.4 4770.4 4785.9 4790.0 4792.7 4888.9 5331.0 5339.5 5384.3 5387.7 5398.8 5666.8 5756.2 5795.9 5819.7 5875.0 5858.4 5890.4 5923.9 5929.1 5936.4 5490.9 5493.3 5537.4 5543.3 5556.5 5696.5 5858.6 5940.6 5944.2 6029.8 5725.2 5729.4 5733.2 5782.7 5783.8 5656.2 5810.6 5811.3 5821.1 5857.4 5688.1 5737.2 5747.4 5761.1 5764.2 5789.5 5808.1 5812.7 5813.8 5819.3 4287.0 4346.1 4387.5 4390.3 4442.1 3913.3 3950.7 3957.1 3986.5 4018.5 5493.8 5500.5 5506.3 5509.9 5511.8 5709.5 5736.6 5754.5 5842.4 5883.6 5398.2 5413.5 5414.4 5457.2 5469.0 4954.7 4957.7 4958.6 4959.9 5005.8 5850.0 5948.8 5970.6 5981.3 5987.3 5836.2 5837.5 5852.9 5853.3 5882.3 6042.6 6047.6 6053.7 6058.9 6064.8 5582.1 5675.2 5692.8 5734.0 5751.9 5782.9 5846.7 5853.7 5865.7 5917.6 5694.3 5696.0 5696.2 5701.8 5706.3 5511.8 5609.2 5631.5 5665.7 5670.2 5559.9 5588.1 5589.9 5591.7 5605.0 5714.2 5730.4 5779.5 5779.7 5785.3 5485.6 5591.7 5628.5 5671.2 5681.1 20:21:07 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | [#1] ext.html Cycles:180 Average:57447.09 Median:42996.36 stddev:110215.14 (256.3%) stddev-sans-first:110441.71 20:21:07 INFO - PROCESS | 27653 | Values: 609.3 644.4 657.7 662.0 671.6 32146.2 43781.8 44551.6 44780.6 44920.0 44793.3 45161.3 45255.5 45255.5 45328.3 9133.9 9259.5 9261.4 9272.4 9273.1 9101.5 9210.1 9218.4 9220.4 9221.4 9177.4 9225.8 9229.9 9230.8 9231.1 9205.8 9236.1 9254.9 9259.0 9263.3 9022.7 9059.5 9067.7 9072.6 9089.2 42833.1 43109.8 43158.1 43165.8 43174.8 3897.6 4259.5 4264.0 4344.3 4409.3 32590.7 33452.2 33569.5 33888.5 34754.5 4170.2 4218.9 4273.6 4370.9 4418.6 246136.0 246268.0 246899.0 247015.0 247397.0 59863.7 60107.7 60455.7 60497.7 60543.0 39063.0 39996.4 40013.8 40048.6 63970.7 17757.5 17942.5 17961.4 17978.3 17982.6 12758.9 12794.8 12795.2 12801.0 12807.5 20641.5 20822.7 20823.9 20873.0 20876.9 7287.5 7316.2 7340.4 7425.2 7439.2 985.5 1033.7 1071.1 1097.0 1210.3 33145.3 33224.8 33306.5 33431.8 33843.3 1149.4 1171.2 1175.1 1185.4 1237.5 17428.3 17656.6 17851.1 17868.2 18049.2 522562.0 526580.0 527917.0 528162.0 529267.0 60756.0 61130.0 61224.4 61460.0 61470.0 1379.5 1414.7 1425.3 1425.3 1426.6 32356.2 33271.0 33688.7 33714.0 33723.2 53897.0 53913.2 54165.7 54288.7 54306.7 422928.0 423109.0 423467.0 424956.0 427629.0 28697.9 28869.7 38570.2 42379.1 43632.6 50561.0 50833.7 51198.0 51327.7 51751.0 42809.1 44188.3 44322.6 44993.6 45034.0 42432.0 42788.6 43022.4 43245.0 43725.6 41738.6 42836.6 42970.4 43979.1 44127.8 34686.3 36826.4 37094.0 37213.8 37538.8 28231.1 28444.4 28464.2 28493.4 31273.7 20:21:07 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | [#2] jquery.html Cycles:180 Average:28625.66 Median:407.63 stddev:79934.90 (19609.4%) stddev-sans-first:80131.05 20:21:07 INFO - PROCESS | 27653 | Values: 402.8 403.0 406.5 408.8 418.4 13869.7 17922.6 19515.8 19707.8 19730.0 14837.1 17658.3 18283.9 19082.8 19437.8 5613.7 5758.7 5762.4 5775.3 5812.9 5705.3 5726.5 5757.0 5776.2 5777.7 4115.4 6019.6 6323.0 6332.0 6459.6 6275.1 6331.7 6335.9 6350.0 6356.3 5449.2 5566.6 5578.5 5603.6 5698.8 19163.0 19666.4 21982.3 22238.7 23012.2 2425.8 2476.7 2580.9 2614.2 3043.7 9250.7 9442.4 9593.8 9690.0 9747.2 1928.9 1934.0 1935.7 1954.5 2010.0 72845.6 79566.6 86957.6 87689.0 89485.0 10188.5 10254.6 10292.2 10521.9 10766.8 14239.4 14299.0 14305.9 14366.1 14374.4 7896.8 8157.5 8177.0 8246.1 8280.6 7983.0 8005.7 8055.0 8169.9 8173.3 4208.6 4368.1 4443.5 4467.0 4745.6 1934.4 1935.4 1970.7 1987.6 2025.3 980.4 991.1 1044.6 1049.6 1091.8 18427.3 18507.8 19058.5 19181.9 19506.6 963.3 1039.6 1044.7 1044.8 1064.1 10279.6 10673.4 10715.6 10773.6 10796.7 477542.0 477757.0 479892.0 488592.0 493927.0 91309.0 93866.1 94476.0 94649.0 94800.0 472.5 494.1 495.7 991.4 1001.4 10312.7 10587.8 10612.6 10806.4 10864.5 39131.8 41177.4 41721.8 42254.8 42671.1 57989.4 58789.1 59309.7 60854.4 62138.4 8512.2 8577.9 8590.4 8737.3 8853.1 9090.9 12177.6 12284.8 12488.6 12962.8 11722.9 12105.9 12140.8 12291.4 12300.8 11513.7 11900.5 11948.5 12016.5 12167.2 12188.3 12391.1 12463.7 12681.5 12703.7 8284.6 8327.3 8394.7 8760.2 8829.0 10641.6 11927.3 12044.0 12054.0 12400.2 20:21:07 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | [#3] mootools.html Cycles:180 Average:5990.00 Median:4810.90 stddev:3500.66 (72.8%) stddev-sans-first:3493.32 20:21:07 INFO - PROCESS | 27653 | Values: 1377.5 1532.3 1548.1 1719.4 1844.1 8644.1 9020.2 9025.4 9332.5 9338.0 9025.5 9206.4 9210.7 9223.0 9225.2 2017.3 2216.8 2299.3 2354.2 2390.8 2284.2 2285.8 2293.0 2294.5 2296.5 2311.5 2357.8 2367.1 2369.3 2375.4 2023.6 2301.6 2360.1 2363.7 2386.0 2283.8 2287.3 2288.4 2290.3 2322.7 9107.2 9146.2 9151.9 9170.3 9172.0 5395.3 5540.8 5545.1 5547.4 5554.5 3300.3 3413.8 3433.1 3465.2 3498.0 1114.1 1125.4 1132.4 1179.9 1186.5 12990.5 13149.1 13162.4 13182.4 13189.3 4647.1 4699.2 4795.8 4796.5 4812.5 10286.9 10301.6 10314.3 10317.3 10327.6 4809.3 5508.4 5518.9 5525.7 5528.0 4944.4 5088.3 5099.6 5152.7 5156.4 3906.7 3937.9 4005.7 4011.2 4061.1 1171.1 1173.0 1173.9 1182.8 1183.3 956.9 980.0 994.9 997.1 1008.1 5784.6 5907.8 5998.1 6009.3 6125.0 897.2 930.3 939.6 964.3 966.7 4026.4 4096.6 4110.3 4110.7 4133.7 11443.0 11694.4 11834.3 11881.2 11889.5 11643.8 11681.2 11704.8 11721.1 11746.5 8155.3 8448.0 8451.9 8485.6 8659.0 6057.0 6110.5 6181.2 6219.6 6334.8 10068.4 10254.2 10545.1 10561.7 10597.7 10183.1 10523.5 10631.8 10728.9 10753.4 7076.2 7903.1 8094.6 8224.0 8347.2 9011.4 9025.4 9050.6 9093.8 9104.3 7872.4 7953.8 8015.2 8027.2 8050.1 7886.8 7938.6 7967.2 8058.0 8166.8 6916.6 7112.8 7339.1 7415.7 7433.9 4865.3 4882.8 4940.4 4946.4 5015.7 5017.1 5035.8 5052.1 5115.6 5132.4 20:21:07 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | [#4] prototype.html Cycles:180 Average:4716.05 Median:5365.18 stddev:1393.55 (26.0%) stddev-sans-first:1358.02 20:21:07 INFO - PROCESS | 27653 | Values: 329.8 331.2 335.8 337.2 340.8 5327.2 5340.7 5372.7 5378.8 5413.0 5277.6 5282.4 5309.5 5312.5 5315.1 3971.0 3990.8 4093.4 4099.8 4133.2 4075.3 4093.7 4105.9 4113.5 4129.5 3952.6 4048.8 4068.2 4081.8 4090.4 3976.5 4040.8 4059.8 4068.4 4068.6 3594.8 3613.3 3619.0 3621.4 3633.6 5149.6 5151.9 5166.3 5167.9 5174.3 4561.3 4710.7 4736.0 4753.8 4759.1 5513.2 5570.0 5582.2 5612.6 5633.6 4036.7 4041.9 4052.3 4056.5 4092.4 5723.7 5751.9 5763.7 5819.8 5824.6 4100.8 4105.0 4108.8 4113.2 4135.3 4172.2 4186.6 4211.3 4233.9 4238.6 4591.2 4730.2 4750.0 4760.5 4797.5 5604.7 5609.3 5612.6 5633.9 5640.7 1436.1 1442.9 1446.6 1448.8 1456.4 920.8 933.6 951.5 996.7 1005.9 5326.7 5349.0 5360.8 5369.5 5372.6 5670.8 5691.8 5692.8 5705.4 5725.8 5135.5 5277.2 5277.4 5416.4 5509.6 3071.6 3075.0 3093.2 3098.6 3104.4 5761.9 5935.6 5984.3 6049.7 6064.4 5799.3 5880.9 5886.0 5905.0 5909.9 5963.3 5972.0 5980.6 5988.4 6003.7 5659.9 5710.0 5742.3 5782.7 5798.9 5721.0 5733.4 5734.2 5743.5 5772.2 5683.3 5925.4 5974.5 5988.4 5993.9 4099.7 4103.9 4121.4 4141.0 4153.4 5800.3 5800.6 5851.8 5877.2 5877.6 5276.5 5461.9 5583.4 5584.1 5642.6 5626.6 5627.6 5650.6 5653.2 5653.9 5292.8 5498.4 5535.0 5553.7 5586.6 5657.7 5670.0 5676.3 5681.7 5727.8 5560.5 5565.2 5590.5 5597.5 5605.3 20:21:07 INFO - PROCESS | 27653 | 20:21:07 INFO - PROCESS | 27653 | [#5] yui.html Cycles:180 Average:16051.41 Median:4701.04 stddev:19405.02 (412.8%) stddev-sans-first:19425.65 20:21:07 INFO - PROCESS | 27653 | Values: 799.0 825.6 861.8 880.6 899.6 14614.6 15572.2 15688.5 15703.0 16195.0 14922.9 15521.7 15537.2 15549.4 15570.8 707.0 830.4 856.8 862.7 875.2 869.0 869.5 872.2 872.4 872.9 910.5 914.4 915.1 915.4 915.9 738.1 871.0 897.7 897.8 906.5 655.7 658.8 659.1 659.8 660.2 14146.9 14150.4 14175.5 14184.0 14185.6 13141.7 13177.5 13336.7 13460.7 13554.3 12592.0 12688.1 12711.5 12977.4 13118.2 4438.4 4561.5 4641.8 4673.0 4673.4 58695.0 59212.0 59656.4 61303.7 61467.1 10719.7 10937.7 11144.7 11395.8 11527.9 19889.6 20020.1 20062.8 20080.4 20099.1 8637.7 8794.8 8854.6 8984.8 9064.0 4882.7 4887.1 4960.0 5090.8 5094.2 3586.3 3664.1 3668.6 3669.8 3677.4 586.5 671.7 672.1 684.2 692.6 720.3 793.0 809.5 820.7 823.0 10026.6 10252.8 10255.3 10494.6 10599.3 611.4 749.8 775.4 782.5 800.4 4557.1 4638.4 4661.4 4728.6 4741.7 69342.3 69353.7 72941.0 73065.3 73208.6 45207.5 61004.4 62250.7 63837.0 64706.4 58022.0 58396.4 58587.7 58688.7 59855.4 8618.8 8697.6 8717.1 8794.0 8851.0 37394.8 37406.8 37929.2 38367.0 39161.6 50675.7 51267.7 51424.5 51661.0 52376.0 11815.0 11831.2 11923.2 12054.6 12207.8 13352.2 13454.0 13537.2 13929.6 13949.9 12002.3 12096.8 12145.1 12340.8 12477.9 12185.5 12281.1 12784.6 13199.7 13213.9 11406.5 11811.6 11968.0 12184.6 12213.2 10436.5 10575.9 10713.1 10755.5 10941.9 9744.5 9755.9 9971.4 10054.2 10056.9 20:21:07 INFO - PROCESS | 27653 | -------- Summary: end -------- 20:21:07 INFO - PROCESS | 27653 | 20:21:08 INFO - TEST-INFO | 27653: exit 0 20:21:08 INFO - TEST-OK | dromaeo_css | took 1112884ms 20:21:08 INFO - TEST-START | kraken 20:21:08 INFO - operating with platform_type : linux_ 20:21:08 INFO - Initialising browser for kraken test... 20:21:08 INFO - TEST-INFO | started process 27843 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpik2vwE/profile http://localhost:55075/getInfo.html) 20:21:12 INFO - PROCESS | 27843 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 20:21:12 INFO - PROCESS | 27843 | __metrics Screen width/height:1600/1200 20:21:12 INFO - PROCESS | 27843 | colorDepth:24 20:21:12 INFO - PROCESS | 27843 | Browser inner width/height: 1024/697 20:21:12 INFO - PROCESS | 27843 | __metrics 20:21:13 ERROR - PROCESS | 27843 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:21:14 INFO - TEST-INFO | 27843: exit 0 20:21:14 INFO - Browser initialized. 20:21:14 INFO - Running cycle 1/1 for kraken test... 20:21:14 INFO - TEST-INFO | started process 27925 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpik2vwE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:21:25 INFO - PROCESS | 27925 | RSS: Main: 152731648 20:21:25 INFO - PROCESS | 27925 | 20:21:28 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55075/tests/kraken/driver.html?testName=audio-beat-detection) 20:21:29 INFO - PROCESS | 27925 | RSS: Main: 207925248 20:21:29 INFO - PROCESS | 27925 | 20:21:32 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55075/tests/kraken/driver.html?testName=audio-dft) 20:21:32 INFO - PROCESS | 27925 | RSS: Main: 197165056 20:21:32 INFO - PROCESS | 27925 | 20:21:35 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55075/tests/kraken/driver.html?testName=audio-fft) 20:21:36 INFO - PROCESS | 27925 | RSS: Main: 265027584 20:21:36 INFO - PROCESS | 27925 | 20:21:38 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55075/tests/kraken/driver.html?testName=audio-oscillator) 20:21:39 INFO - PROCESS | 27925 | RSS: Main: 173969408 20:21:39 INFO - PROCESS | 27925 | 20:21:41 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55075/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:21:42 INFO - PROCESS | 27925 | RSS: Main: 178786304 20:21:42 INFO - PROCESS | 27925 | 20:21:45 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55075/tests/kraken/driver.html?testName=imaging-darkroom) 20:21:45 INFO - PROCESS | 27925 | RSS: Main: 214437888 20:21:45 INFO - PROCESS | 27925 | 20:21:49 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55075/tests/kraken/driver.html?testName=imaging-desaturate) 20:21:50 INFO - PROCESS | 27925 | RSS: Main: 212463616 20:21:50 INFO - PROCESS | 27925 | 20:21:52 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55075/tests/kraken/driver.html?testName=json-parse-financial) 20:21:53 INFO - PROCESS | 27925 | RSS: Main: 213676032 20:21:53 INFO - PROCESS | 27925 | 20:21:55 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55075/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:21:55 INFO - PROCESS | 27925 | RSS: Main: 201760768 20:21:55 INFO - PROCESS | 27925 | 20:21:57 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55075/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:21:58 INFO - PROCESS | 27925 | RSS: Main: 207986688 20:21:58 INFO - PROCESS | 27925 | 20:22:00 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55075/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:22:01 INFO - PROCESS | 27925 | RSS: Main: 215654400 20:22:01 INFO - PROCESS | 27925 | 20:22:03 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55075/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:22:04 INFO - PROCESS | 27925 | RSS: Main: 223621120 20:22:04 INFO - PROCESS | 27925 | 20:22:07 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55075/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:22:07 INFO - PROCESS | 27925 | RSS: Main: 219729920 20:22:07 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | Cycle 1(1): loaded http://localhost:55075/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55075/tests/kraken/driver.html?testName=ai-astar) 20:22:09 INFO - PROCESS | 27925 | RSS: Main: 265568256 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | __start_tp_report 20:22:09 INFO - PROCESS | 27925 | _x_x_mozilla_page_load 20:22:09 INFO - PROCESS | 27925 | _x_x_mozilla_page_load_details 20:22:09 INFO - PROCESS | 27925 | |i|pagename|runs| 20:22:09 INFO - PROCESS | 27925 | |0;ai-astar;111;103;111;109;108;109;108;109;109;109 20:22:09 INFO - PROCESS | 27925 | |1;audio-beat-detection;149;124;163;212;126;149;162;131;175;122 20:22:09 INFO - PROCESS | 27925 | |2;audio-dft;161;160;161;160;161;161;163;161;160;162 20:22:09 INFO - PROCESS | 27925 | |3;audio-fft;75;73;76;150;75;75;75;74;140;73 20:22:09 INFO - PROCESS | 27925 | |4;audio-oscillator;92;92;143;91;92;92;122;92;91;123 20:22:09 INFO - PROCESS | 27925 | |5;imaging-gaussian-blur;116;117;116;116;117;116;116;117;117;117 20:22:09 INFO - PROCESS | 27925 | |6;imaging-darkroom;145;145;147;145;146;145;145;145;145;145 20:22:09 INFO - PROCESS | 27925 | |7;imaging-desaturate;94;94;95;95;95;95;95;95;95;94 20:22:09 INFO - PROCESS | 27925 | |8;json-parse-financial;56;55;55;54;55;54;55;107;52;53 20:22:09 INFO - PROCESS | 27925 | |9;json-stringify-tinderbox;60;61;60;60;61;60;60;60;60;60 20:22:09 INFO - PROCESS | 27925 | |10;stanford-crypto-aes;80;82;79;77;77;79;83;78;81;78 20:22:09 INFO - PROCESS | 27925 | |11;stanford-crypto-ccm;135;129;136;122;116;133;120;120;189;138 20:22:09 INFO - PROCESS | 27925 | |12;stanford-crypto-pbkdf2;167;159;160;164;165;161;167;163;159;173 20:22:09 INFO - PROCESS | 27925 | |13;stanford-crypto-sha256-iterative;63;64;62;62;62;62;62;61;65;60 20:22:09 INFO - PROCESS | 27925 | __end_tp_report 20:22:09 INFO - PROCESS | 27925 | __start_cc_report 20:22:09 INFO - PROCESS | 27925 | _x_x_mozilla_cycle_collect,1831 20:22:09 INFO - PROCESS | 27925 | __end_cc_report 20:22:09 INFO - PROCESS | 27925 | __startTimestamp1463282529663__endTimestamp 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | ------- Summary: start ------- 20:22:09 INFO - PROCESS | 27925 | Number of tests: 14 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#0] ai-astar Cycles:10 Average:108.60 Median:109.00 stddev:2.22 (2.0%) stddev-sans-first:2.18 20:22:09 INFO - PROCESS | 27925 | Values: 111.0 103.0 111.0 109.0 108.0 109.0 108.0 109.0 109.0 109.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#1] audio-beat-detection Cycles:10 Average:151.30 Median:155.50 stddev:28.21 (18.1%) stddev-sans-first:29.91 20:22:09 INFO - PROCESS | 27925 | Values: 149.0 124.0 163.0 212.0 126.0 149.0 162.0 131.0 175.0 122.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 20:22:09 INFO - PROCESS | 27925 | Values: 161.0 160.0 161.0 160.0 161.0 161.0 163.0 161.0 160.0 162.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#3] audio-fft Cycles:10 Average:88.60 Median:75.00 stddev:29.83 (39.8%) stddev-sans-first:31.23 20:22:09 INFO - PROCESS | 27925 | Values: 75.0 73.0 76.0 150.0 75.0 75.0 75.0 74.0 140.0 73.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:92.00 stddev:19.01 (20.7%) stddev-sans-first:19.75 20:22:09 INFO - PROCESS | 27925 | Values: 92.0 92.0 143.0 91.0 92.0 92.0 122.0 92.0 91.0 123.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 20:22:09 INFO - PROCESS | 27925 | Values: 116.0 117.0 116.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 20:22:09 INFO - PROCESS | 27925 | Values: 145.0 145.0 147.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 20:22:09 INFO - PROCESS | 27925 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:55.00 stddev:16.69 (30.4%) stddev-sans-first:17.66 20:22:09 INFO - PROCESS | 27925 | Values: 56.0 55.0 55.0 54.0 55.0 54.0 55.0 107.0 52.0 53.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 20:22:09 INFO - PROCESS | 27925 | Values: 60.0 61.0 60.0 60.0 61.0 60.0 60.0 60.0 60.0 60.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.50 stddev:2.07 (2.6%) stddev-sans-first:2.18 20:22:09 INFO - PROCESS | 27925 | Values: 80.0 82.0 79.0 77.0 77.0 79.0 83.0 78.0 81.0 78.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#11] stanford-crypto-ccm Cycles:10 Average:133.80 Median:134.00 stddev:20.90 (15.6%) stddev-sans-first:22.16 20:22:09 INFO - PROCESS | 27925 | Values: 135.0 129.0 136.0 122.0 116.0 133.0 120.0 120.0 189.0 138.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:164.50 stddev:4.42 (2.7%) stddev-sans-first:4.53 20:22:09 INFO - PROCESS | 27925 | Values: 167.0 159.0 160.0 164.0 165.0 161.0 167.0 163.0 159.0 173.0 20:22:09 INFO - PROCESS | 27925 | 20:22:09 INFO - PROCESS | 27925 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.00 stddev:1.42 (2.3%) stddev-sans-first:1.48 20:22:09 INFO - PROCESS | 27925 | Values: 63.0 64.0 62.0 62.0 62.0 62.0 62.0 61.0 65.0 60.0 20:22:09 INFO - PROCESS | 27925 | -------- Summary: end -------- 20:22:09 INFO - PROCESS | 27925 | 20:22:10 INFO - TEST-INFO | 27925: exit 0 20:22:10 INFO - TEST-OK | kraken | took 62190ms 20:22:10 INFO - SUITE-END | took 1175s 20:22:10 INFO - Completed test suite (00:19:35) 20:22:10 INFO - javascript benchmark 20:22:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3162.541431492433, 3184.6337671167817, 3195.344954284372, 3240.967590324099, 3257.788243764155, 5270.051390749663, 5334.03987282289, 5352.090144675405, 5429.891402171959, 5474.370447398545, 5320.866978325544, 5357.598180136496, 5447.727613619313, 5476.58925580581, 5504.614676972614, 4765.857024289268, 4777.163996300651, 4834.758262086899, 4836.903261934759, 4982.078315511624, 4934.876628084308, 4943.209086546153, 4950.381202349707, 4953.23224900141, 4996.050750357439, 4705.176594096037, 4731.0, 4753.809847606091, 4862.0, 4908.75456227189, 4817.975910120462, 4826.251930950708, 4828.782704778293, 4850.029994001214, 4902.362692849907, 4770.356001939728, 4785.856424307276, 4789.976050119762, 4792.7124372537755, 4888.853334399938, 5331.013762453958, 5339.5461385782255, 5384.326959130109, 5387.703676297802, 5398.78404863805, 5666.801661941812, 5756.165356023395, 5795.913061304083, 5819.709014549256, 5874.970625146847, 5858.355580886099, 5890.3815099414805, 5923.911141332884, 5929.14027466015, 5936.376680448534, 5490.917636235459, 5493.285872836505, 5537.390887002426, 5543.334799824047, 5556.499915007668, 5696.544276457874, 5858.619189752652, 5940.584159108907, 5944.227250457499, 6029.849253768603, 5725.198472213893, 5729.3984131665575, 5733.226014487991, 5782.653040817564, 5783.826485205451, 5656.208130861601, 5810.564207684441, 5811.27359080115, 5821.126830975303, 5857.355690874, 5688.11834165705, 5737.168110623936, 5747.4252574742195, 5761.078227483583, 5764.193012978187, 5789.536837053026, 5808.099744539603, 5812.680302583399, 5813.82558523245, 5819.301683797971, 4286.978565107154, 4346.065595896897, 4387.473503179639, 4390.341448782644, 4442.067165895198, 3913.3347330953807, 3950.743201691881, 3957.08986933007, 3986.4817573715814, 4018.537868145142, 5493.807716729895, 5500.477454641803, 5506.256655351476, 5509.889802203934, 5511.779528818883, 5709.457601527848, 5736.569757268222, 5754.482096611325, 5842.384565788855, 5883.617564858356, 5398.217258497496, 5413.539849112791, 5414.40441551429, 5457.208704737791, 5469.0155771960635, 4954.727489988013, 4957.677750946249, 4958.628102892298, 4959.925601115986, 5005.849824505271, 5849.970750146222, 5948.821535353946, 5970.58205925581, 5981.282246130405, 5987.281526216795, 5836.182934389274, 5837.503812176013, 5852.882942341214, 5853.2976042875125, 5882.294124704892, 6042.637441753509, 6047.637141771508, 6053.667048312385, 6058.939410605837, 6064.757409703562, 5582.078956972054, 5675.205471234113, 5692.800751973827, 5733.971330143323, 5751.8562035949435, 5782.884342313214, 5846.707664616837, 5853.678047707417, 5865.706714664335, 5917.556183286357, 5694.259746232963, 5696.0316746154085, 5696.174054762119, 5701.800436984686, 5706.258186435737, 5511.807086751945, 5609.186667933045, 5631.521320687787, 5665.66006039639, 5670.177824215548, 5559.8888022240135, 5588.105903055492, 5589.944100558942, 5591.692456914909, 5605.019121653613, 5714.228579141763, 5730.398308177748, 5779.450952159538, 5779.653220806765, 5785.27684039495, 5485.561155107662, 5591.720413979366, 5628.493435590816, 5671.17767923657, 5681.119426488901], "value": 5336.77474269222, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.2719200555335, 644.4007073421717, 657.7434800427834, 662.016904896229, 671.5802623360399, 32146.196345091354, 43781.78109109458, 44551.55448445515, 44780.55219447805, 44920.0, 44793.32810007844, 45161.32258016124, 45255.547444525546, 45255.547444525546, 45328.3200751989, 9133.862992055125, 9259.49072800999, 9261.444313341188, 9272.443653380786, 9273.07269273074, 9101.453912765222, 9210.125038121401, 9218.35471516994, 9220.446773193597, 9221.35450518464, 9177.357584969055, 9225.769355766126, 9229.907700922971, 9230.815383692323, 9231.076892310783, 9205.769855753593, 9236.076392360745, 9254.907450925471, 9258.953705231497, 9263.25893928484, 9022.729318120468, 9059.456432614064, 9067.727968160965, 9072.591733372014, 9089.227415669677, 42833.14333713339, 43109.78445107755, 43158.13683726308, 43165.784171079256, 43174.78412607917, 3897.610238976108, 4259.488861336628, 4263.978680106579, 4344.304911214191, 4409.250427427345, 32590.674093259, 33452.163695907555, 33569.48228882843, 33888.49167262493, 34754.478682819776, 4170.16596680662, 4218.915621687549, 4273.636740877029, 4370.912581748379, 4418.6023257906945, 246135.9999999982, 246268.0, 246899.0, 247015.0, 247397.0, 59863.70068149632, 60107.699461502416, 60455.69772151199, 60497.69751151304, 60543.0, 39063.0, 39996.40005399921, 40013.79993100074, 40048.59951400448, 63970.68014659897, 17757.467275981482, 17942.461726148238, 17961.371352002618, 17978.280868765363, 17982.5504362392, 12758.936205318914, 12794.808077878839, 12795.232286062866, 12801.0, 12807.487700492062, 20641.48396290105, 20822.687659685118, 20823.8958805205, 20873.0, 20876.895615521826, 7287.453440991947, 7316.207529155391, 7340.4127669786285, 7425.1832298447125, 7439.181690014094, 985.4579981010415, 1033.7002269341956, 1071.0842229893328, 1096.9780604387868, 1210.2798834693344, 33145.337093258, 33224.83387583047, 33306.500402493984, 33431.83284083564, 33843.32313353719, 1149.350616901448, 1171.1918776044502, 1175.1421462332546, 1185.4487663236637, 1237.5173682263924, 17428.323627803245, 17656.646867062587, 17851.107444627716, 17868.195931183127, 18049.187786549635, 522562.0, 526580.0, 527917.0, 528162.0, 529267.0, 60756.0, 61130.0, 61224.38775612276, 61460.0, 61470.0, 1379.5102738527878, 1414.653409914557, 1425.2659880161725, 1425.2659880161725, 1426.5577670922048, 32356.191095222806, 33271.0, 33688.66311336855, 33714.0, 33723.232857306124, 53897.0, 53913.19130213129, 54165.729171353894, 54288.72855635697, 54306.72846635742, 422928.0, 423109.0, 423467.0, 424956.0, 427629.0, 28697.856510717313, 28869.711302886702, 38570.228595428496, 42379.06693063035, 43632.56367436285, 50561.0, 50833.74583127061, 51198.0, 51327.74336128445, 51751.0, 42809.1438171241, 44188.33717494176, 44322.55677443184, 44993.55006449894, 45034.0, 42432.0, 42788.57211427846, 43022.35466467943, 43245.0, 43725.562744372146, 41738.58261417347, 42836.57163428326, 42970.35544466773, 43979.12041759083, 44127.77936110299, 34686.306273874885, 36826.447603286506, 37094.0, 37213.81393093017, 37538.81230593939, 28231.08698451638, 28444.43111137807, 28464.157338759003, 28493.430131397672, 31273.687263127078], "value": 20643.417068425548, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.8187315707932, 402.9717919745617, 406.46955722781775, 408.799688152805, 418.3954186200939, 13869.653258668539, 17922.551936201584, 19515.804841951576, 19707.802921970775, 19730.0, 14837.109773413602, 17658.293668253285, 18283.90858045708, 19082.809171908313, 19437.805621943775, 5613.691246981414, 5758.712064396785, 5762.423757624246, 5775.306963164426, 5812.854678633025, 5705.315362156547, 5726.513246374063, 5757.0, 5776.191333213333, 5777.740001699934, 4115.444415003966, 6019.608725432833, 6323.019931910539, 6331.968340158292, 6459.6447195404235, 6275.121482992385, 6331.746730130789, 6335.936640633604, 6350.047492876082, 6356.300806911236, 5449.1826226066405, 5566.554675625981, 5578.497935185812, 5603.551715862762, 5698.772049118072, 19163.041847907687, 19666.410007699793, 21982.34052978397, 22238.666420003712, 23012.19457318986, 2425.842320249196, 2476.6904136982876, 2580.8580528070947, 2614.2026681862217, 3043.7260646541918, 9250.72247832566, 9442.433453992782, 9593.808123837483, 9690.0, 9747.171490423323, 1928.9148112390517, 1934.0, 1935.7096435534643, 1954.4722924810364, 2009.9698504522444, 72845.6357718208, 79566.60216698937, 86957.5652121748, 87689.0, 89485.0, 10188.541515631814, 10254.6410875619, 10292.228082893813, 10521.894781052244, 10766.78466430667, 14239.359228834728, 14299.0, 14305.85694143066, 14366.13803171813, 14374.35315410809, 7896.802579935547, 8157.510549367057, 8176.959115204386, 8246.134155913664, 8280.58597070144, 7983.0, 8005.679772809139, 8055.0, 8169.877451838227, 8173.283610441755, 4208.579142085767, 4368.148211098869, 4443.511213766483, 4466.977665111719, 4745.6440766942615, 1934.4390126863334, 1935.374455052426, 1970.7078440535524, 1987.5627361980341, 2025.2607798153567, 980.4411485453365, 991.0733464210986, 1044.5508431374583, 1049.559185142242, 1091.770728147082, 18427.26290948347, 18507.814921850608, 19058.523536911413, 19181.904090479456, 19506.609867802847, 963.2871674960479, 1039.6465201831413, 1044.738815296176, 1044.7649278912336, 1064.1274155192668, 10279.640212592523, 10673.412962286993, 10715.571377144826, 10773.622923197807, 10796.67609971702, 477542.0, 477757.0, 479892.0, 488592.0, 493927.0, 91309.0, 93866.0613393871, 94476.0, 94649.0, 94800.0, 472.50151090599746, 494.0538868067682, 495.7397366382606, 991.3754334769194, 1001.369137443406, 10312.742181445525, 10587.841182382117, 10612.628558000433, 10806.405647689453, 10864.511097000812, 39131.80434097811, 41177.38233926553, 41721.79139104285, 42254.78872605618, 42671.1465770689, 57989.4201057984, 58789.11816322673, 59309.70345148247, 60854.39145608487, 62138.378616215065, 8512.191341822518, 8577.914220857961, 8590.398672092893, 8737.257333126501, 8853.114688531354, 9090.909090909006, 12177.634670959886, 12284.754304914031, 12488.625341239778, 12962.80555791645, 11722.94138529302, 12105.939470302945, 12140.817887731515, 12291.44688489038, 12300.81548776787, 11513.712157196138, 11900.523979040741, 11948.522059117886, 12016.459259333176, 12167.209131406431, 12188.329641869783, 12391.132620716824, 12463.688407789878, 12681.492740290285, 12703.682407939876, 8284.585770711561, 8327.292180164754, 8394.748157555283, 8760.167784060504, 8829.0, 10641.574337026743, 11927.284362938251, 12044.0, 12054.0, 12400.19398739079], "value": 8995.540218571374, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.4834437086092, 1532.3028022249878, 1548.1485183149268, 1719.3896166860764, 1844.0687452836316, 8644.092370301121, 9020.188183063523, 9025.413348132375, 9332.533373331324, 9337.953310233457, 9025.548722563863, 9206.447613143217, 9210.67762628308, 9223.031581683927, 9225.215856652192, 2017.2838642282, 2216.8004879560835, 2299.3331933739196, 2354.2466410748566, 2390.7967822735, 2284.246198754407, 2285.817134629234, 2293.025464177726, 2294.4837411582425, 2296.5062511560013, 2311.456807650201, 2357.834951553392, 2367.088670861722, 2369.336585755991, 2375.406148462884, 2023.5952809438097, 2301.5742087713743, 2360.055977608953, 2363.6690863279155, 2386.009805930554, 2283.8058765004994, 2287.290939808648, 2288.39357570244, 2290.2900100968754, 2322.674825524428, 9107.180353768126, 9146.176844084064, 9151.862722059175, 9170.266378689688, 9172.0, 5395.271638328814, 5540.778368865281, 5545.085060964976, 5547.389787123413, 5554.527865131468, 3300.273939733255, 3413.7781044232047, 3433.055909624848, 3465.1856813648774, 3497.9825100874586, 1114.1060197664003, 1125.352922069813, 1132.4224645430807, 1179.8584169899582, 1186.4601606269127, 12990.545330913466, 13149.145305555108, 13162.407691653898, 13182.4067916943, 13189.340532973409, 4647.09381670571, 4699.201135806853, 4795.808167673324, 4796.496367881392, 4812.542808433193, 10286.891187306584, 10301.639442619473, 10314.27800053989, 10317.329373590692, 10327.638532651321, 4809.254565542346, 5508.394076651565, 5518.917216241679, 5525.668459892419, 5528.032594295983, 4944.357233559686, 5088.287639730367, 5099.643024988215, 5152.6650767699975, 5156.355455568054, 3906.746061505993, 3937.8818635440985, 4005.719599627998, 4011.2178125265877, 4061.106556557612, 1171.1158075652997, 1172.9589464368705, 1173.8532683414574, 1182.7752726981846, 1183.2545496337425, 956.8660387545749, 980.0297705271872, 994.855745916838, 997.1275134257525, 1008.1229330482381, 5784.566157538202, 5907.793227237112, 5998.070299103646, 6009.278886533644, 6124.969375153096, 897.1656359585648, 930.3348106104168, 939.5724945150077, 964.2767924056957, 966.6809952715587, 4026.3960405939342, 4096.610821971908, 4110.34234522475, 4110.712250142461, 4133.7106402551535, 11443.0, 11694.356810375511, 11834.289942603471, 11881.168318217815, 11889.464974076187, 11643.76712465746, 11681.240719353214, 11704.765904682028, 11721.062315014779, 11746.471408786625, 8155.306798922158, 8447.98624165104, 8451.943507061616, 8485.575721213914, 8659.0, 6057.0, 6110.541709371903, 6181.227346581677, 6219.626822390671, 6334.809955701337, 10068.44623545712, 10254.179665626882, 10545.103666188154, 10561.683149505527, 10597.682069537927, 10183.13443357323, 10523.526441310007, 10631.840522392326, 10728.946355268174, 10753.354798712102, 7076.186238582423, 7903.130655627761, 8094.635741391769, 8224.0, 8347.165283471848, 9011.414258073204, 9025.41334813235, 9050.592723327334, 9093.818123637622, 9104.317176211944, 7872.409569282441, 7953.801154971172, 8015.238552337519, 8027.197280272159, 8050.074241462306, 7886.84226315459, 7938.603069846369, 7967.2431119043595, 8057.959710201411, 8166.836663266583, 6916.585004899722, 7112.786616401516, 7339.119305683352, 7415.666295016629, 7433.851322973402, 4865.343178670834, 4882.780274887709, 4940.357753492023, 4946.3817022872145, 5015.749212539431, 5017.071841709296, 5035.773390197377, 5052.065367906949, 5115.616328775432, 5132.409772876167], "value": 4736.8976071677625, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.81695159186654, 331.20841189556955, 335.7800640580418, 337.2243839169909, 340.83810190159676, 5327.174287985364, 5340.706261155635, 5372.7045012524295, 5378.838634840956, 5413.0, 5277.551408130314, 5282.366116066078, 5309.548688361494, 5312.521873031426, 5315.14957606782, 3970.9602903970954, 3990.840366385341, 4093.447384603085, 4099.7745124018165, 4133.1940271647045, 4075.286824805662, 4093.6520395766397, 4105.897352566195, 4113.5269444013875, 4129.525104612963, 3952.6245006724316, 4048.797560121997, 4068.16602596467, 4081.8163182656854, 4090.365993271048, 3976.5228172619327, 4040.838366465338, 4059.817308221137, 4068.3897415387632, 4068.558864681197, 3594.7663401878795, 3613.33153366628, 3618.981905090484, 3621.438677005042, 3633.6184700606586, 5149.562287205592, 5151.922721159186, 5166.328377310963, 5167.940572182693, 5174.301469301671, 4561.292999585036, 4710.670253082286, 4736.0, 4753.809847606091, 4759.143354196243, 5513.15309371361, 5570.0, 5582.218489411485, 5612.607117501777, 5633.57748168889, 4036.6972477064337, 4041.8585349512628, 4052.290849101425, 4056.5052492717064, 4092.4066010428323, 5723.683552782883, 5751.856203594902, 5763.711814409263, 5819.7672093116225, 5824.592278540462, 4100.753954762664, 4105.009070600756, 4108.8356465741035, 4113.197926404322, 4135.29699951003, 4172.207280616673, 4186.618415922799, 4211.347241177622, 4233.894152646209, 4238.554951730086, 4591.219492686251, 4730.219513780256, 4750.0, 4760.452547957029, 4797.544233297831, 5604.719764011783, 5609.326880774333, 5612.607117501736, 5633.915491267634, 5640.717964101778, 1436.1239643817264, 1442.9134251944918, 1446.6383404148962, 1448.8406275309708, 1456.410153887677, 920.8342498350361, 933.6125507914139, 951.4957072751453, 996.6561536269975, 1005.9144972677258, 5326.733663316896, 5349.0, 5360.812371566976, 5369.543588794918, 5372.5970552207955, 5670.8015219467125, 5691.8292451226525, 5692.82921512347, 5705.429457054344, 5725.828225153169, 5135.460776618401, 5277.182036784305, 5277.445868183785, 5416.350037995466, 5509.586780991442, 3071.616047994001, 3075.0467355119986, 3093.1957691000503, 3098.597182366278, 3104.4412005838713, 5761.9135712964335, 5935.614185077956, 5984.2818861736, 6049.667268300198, 6064.3935606439, 5799.3040835100055, 5880.911786323209, 5885.970570147208, 5905.0, 5909.940900590939, 5963.254593175853, 5971.9701401493585, 5980.611260268069, 5988.401159883977, 6003.699815009232, 5659.943400565941, 5709.971450142889, 5742.339630942328, 5782.653040817564, 5798.855028624318, 5721.0, 5733.426657334233, 5734.168545560954, 5743.511801496752, 5772.181762744722, 5683.261176047087, 5925.377835327401, 5974.492168165581, 5988.37122102173, 5993.891130140938, 4099.672026237953, 4103.9179216414905, 4121.361189015707, 4140.979295103505, 4153.439285696392, 5800.332961709458, 5800.56495762811, 5851.824445266649, 5877.177195192762, 5877.647341159544, 5276.498732620395, 5461.924468702026, 5583.41374155724, 5584.134459158837, 5642.576806739429, 5626.606137570331, 5627.577931655224, 5650.63270887391, 5653.208550802973, 5653.85865353353, 5292.788288468418, 5498.422665620053, 5535.0, 5553.666779993213, 5586.608937374344, 5657.745401457027, 5669.994902701575, 5676.262085928803, 5681.715914204356, 5727.799527016535, 5560.499555040065, 5565.1930470082425, 5590.524805391425, 5597.468240517144, 5605.299337582802], "value": 4273.559174101238, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.0451410564378, 825.6037102190949, 861.8060936289335, 880.6145395928277, 899.581694512052, 14614.634634134152, 15572.221388930551, 15688.450904218354, 15703.0, 16195.0, 14922.925385373084, 15521.68956620867, 15537.22313884307, 15549.378024879019, 15570.766438503431, 707.0348973650981, 830.4104086098877, 856.751542052804, 862.6894318045514, 875.2297977779546, 868.9782755431102, 869.5304535550795, 872.2280781508379, 872.4198408058634, 872.9170728780756, 910.4628269321099, 914.4147745442922, 915.0666320353236, 915.4324318922278, 915.890093188815, 738.1253214940286, 870.9695160669378, 897.6768363389175, 897.771068377566, 906.4742449379344, 655.6819942328015, 658.8352911772057, 659.1036190780535, 659.7591878964143, 660.2044536333707, 14146.92926535371, 14150.433982640681, 14175.503857364944, 14184.0, 14185.574432767033, 13141.671458213526, 13177.472901083946, 13336.666583335495, 13460.721231482994, 13554.254516001616, 12592.0, 12688.111832171751, 12711.491540338375, 12977.41601627929, 13118.212907225598, 4438.3564383164585, 4561.498235194159, 4641.7911193996015, 4673.042026384617, 4673.439187297512, 58695.0, 59212.0, 59656.40343596552, 61303.693481532304, 61467.077993829684, 10719.732006699895, 10937.726556836144, 11144.665660030212, 11395.772084558263, 11527.88472115268, 19889.60220795605, 20020.099095540736, 20062.79937200609, 20080.39758807238, 20099.095540700702, 8637.654493820302, 8794.780130496789, 8854.601542930584, 8984.833695851403, 9063.954680226689, 4882.7314497702255, 4887.148848107414, 4959.950400495949, 5090.821821236239, 5094.235864620263, 3586.336527742324, 3664.0656632558525, 3668.559772827278, 3669.798161101165, 3677.3932301170544, 586.5337057039578, 671.6809515480107, 672.122879642062, 684.2029036172803, 692.5533031194851, 720.2797202797203, 792.9801754956172, 809.5142914251402, 820.6799348254185, 823.0248642752624, 10026.649067282757, 10252.794944101077, 10255.33340332876, 10494.580216791395, 10599.258051936446, 611.400827189357, 749.8350362920258, 775.3874439192974, 782.4913806026128, 800.3637108498757, 4557.088582283556, 4638.443386793607, 4661.3707149534375, 4728.645351598644, 4741.691790033637, 69342.3065769346, 69353.65323173352, 72941.0, 73065.26934730691, 73208.63395682987, 45207.54792452099, 61004.38995610076, 62250.68874655598, 63837.0, 64706.352936470976, 58022.0, 58396.416035839095, 58587.70706146527, 58688.70655646695, 59855.401445984986, 8618.827623447622, 8697.608607612547, 8717.128287171232, 8794.0, 8851.0, 37394.813025934694, 37406.812965934994, 37929.241415172095, 38367.0, 39161.60838391694, 50675.746621266655, 51267.743661281456, 51424.48575514197, 51661.0, 52376.0, 11815.0, 11831.230969986924, 11923.165378423428, 12054.638360848838, 12207.816882746944, 13352.198868067948, 13454.0, 13537.187768733907, 13929.582112536234, 13949.860501394856, 12002.279863208236, 12096.75806483883, 12145.089118315986, 12340.753184936431, 12477.93761031189, 12185.451654675739, 12281.140320177501, 12784.61646150617, 13199.67000824987, 13213.933930330288, 11406.48670809799, 11811.645650630495, 11968.042556595625, 12184.634460966185, 12213.206141600767, 10436.478176091014, 10575.894241057491, 10713.089387401846, 10755.516001780095, 10941.945290273497, 9744.464054476788, 9755.9024409755, 9971.40171589678, 10054.245931555017, 10056.949715251378], "value": 7115.950398559443, "unit": "score"}], "name": "dromaeo_css", "value": 7229.498204859543, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 103.0, 111.0, 109.0, 108.0, 109.0, 108.0, 109.0, 109.0, 109.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 163.0, 212.0, 126.0, 149.0, 162.0, 131.0, 175.0, 122.0], "value": 151.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 161.0, 161.0, 163.0, 161.0, 160.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 76.0, 150.0, 75.0, 75.0, 75.0, 74.0, 140.0, 73.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 143.0, 91.0, 92.0, 92.0, 122.0, 92.0, 91.0, 123.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 147.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 54.0, 55.0, 54.0, 55.0, 107.0, 52.0, 53.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 79.0, 77.0, 77.0, 79.0, 83.0, 78.0, 81.0, 78.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 129.0, 136.0, 122.0, 116.0, 133.0, 120.0, 120.0, 189.0, 138.0], "value": 133.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 159.0, 160.0, 164.0, 165.0, 161.0, 167.0, 163.0, 159.0, 173.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 62.0, 62.0, 62.0, 62.0, 62.0, 61.0, 65.0, 60.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1528.1, "alertThreshold": 2.0}]} 20:22:10 INFO - Return code: 0 20:22:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:22:10 ERROR - # TBPL SUCCESS # 20:22:10 INFO - Running post-action listener: _resource_record_post_action 20:22:10 INFO - ##### 20:22:10 INFO - ##### Finished run-tests step (success) 20:22:10 INFO - ##### 20:22:10 INFO - Running post-run listener: _resource_record_post_run 20:22:11 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 536879104; Read time: 0; Write time: 389552 20:22:11 INFO - TinderboxPrint: CPU usage
13.1% 20:22:11 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:22:11 INFO - TinderboxPrint: I/O write bytes / time
536,879,104 / 389,552 20:22:11 INFO - TinderboxPrint: CPU idle
8,276.7 (86.9%) 20:22:11 INFO - TinderboxPrint: CPU user
1,201.7 (12.6%) 20:22:11 INFO - TinderboxPrint: Swap in / out
0 / 0 20:22:11 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 197758976; Read time: 0; Write time: 186672 20:22:11 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 290553856; Read time: 0; Write time: 186400 20:22:11 INFO - Running post-run listener: _upload_blobber_files 20:22:11 INFO - Blob upload gear active. 20:22:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:22: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 20:22: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'] 20:22: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 20:22:12 INFO - (blobuploader) - INFO - Open directory for files ... 20:22:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:22:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:22:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:22:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:22:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:22:12 INFO - (blobuploader) - INFO - Done attempting. 20:22:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:22:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:22:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:22:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:22:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:22:18 INFO - (blobuploader) - INFO - Done attempting. 20:22:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:22:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:22:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:22:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:22:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:22:18 INFO - (blobuploader) - INFO - Done attempting. 20:22:18 INFO - (blobuploader) - INFO - Iteration through files over. 20:22:18 INFO - Return code: 0 20:22:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:22:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:22:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d44a9d245772ef651014eae966f4f805dac34d772a0902f01dcc4be2916a8924a99fd8c9218b9e3502e4986b02ff8aa349cadd1deca0caf0962d6aec2873ffa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f520a97a827d6e5f84c5f9ef08b5964a4d051975430ae8940034d4da07c171e5c20c0361defff8189d196b407d02771c467f5c20e7f50cd10aa3ecd8c15b069", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf9b6228434ccbf51b12e329d9cba7fe44528d26975a6cd08762bc3316ffd6b80de460e86ffb7dee830654f8b732214cf616032edd7cf8d89c10e8decb4f78ce"} 20:22:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:22:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:22:18 INFO - Contents: 20:22:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d44a9d245772ef651014eae966f4f805dac34d772a0902f01dcc4be2916a8924a99fd8c9218b9e3502e4986b02ff8aa349cadd1deca0caf0962d6aec2873ffa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f520a97a827d6e5f84c5f9ef08b5964a4d051975430ae8940034d4da07c171e5c20c0361defff8189d196b407d02771c467f5c20e7f50cd10aa3ecd8c15b069", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf9b6228434ccbf51b12e329d9cba7fe44528d26975a6cd08762bc3316ffd6b80de460e86ffb7dee830654f8b732214cf616032edd7cf8d89c10e8decb4f78ce"} 20:22:18 INFO - Running post-run listener: copy_logs_to_upload_dir 20:22:18 INFO - Copying logs to upload dir... 20:22:18 INFO - mkdir: /builds/slave/test/build/upload/logs 20:22:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.089502 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-14 20:22:18.739002) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 20:22:18.742892) ========= 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=31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857 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/9d44a9d245772ef651014eae966f4f805dac34d772a0902f01dcc4be2916a8924a99fd8c9218b9e3502e4986b02ff8aa349cadd1deca0caf0962d6aec2873ffa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f520a97a827d6e5f84c5f9ef08b5964a4d051975430ae8940034d4da07c171e5c20c0361defff8189d196b407d02771c467f5c20e7f50cd10aa3ecd8c15b069", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf9b6228434ccbf51b12e329d9cba7fe44528d26975a6cd08762bc3316ffd6b80de460e86ffb7dee830654f8b732214cf616032edd7cf8d89c10e8decb4f78ce"} build_url:https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/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/9d44a9d245772ef651014eae966f4f805dac34d772a0902f01dcc4be2916a8924a99fd8c9218b9e3502e4986b02ff8aa349cadd1deca0caf0962d6aec2873ffa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f520a97a827d6e5f84c5f9ef08b5964a4d051975430ae8940034d4da07c171e5c20c0361defff8189d196b407d02771c467f5c20e7f50cd10aa3ecd8c15b069", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf9b6228434ccbf51b12e329d9cba7fe44528d26975a6cd08762bc3316ffd6b80de460e86ffb7dee830654f8b732214cf616032edd7cf8d89c10e8decb4f78ce"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 20:22:18.768819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:22:18.769291) ========= 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=31acecf42b6a79ec49471d4a000001bb-1463279977.380872-534921857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:22:18.853714) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 20:22:18.854159) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 20:22:18.854595) ========= ========= Total master_lag: 0.17 =========