builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1464130893.13 results: success (0) buildid: 20160524133017 builduid: c3555eaa92d24d1b8d1c483faf505551 revision: 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.130656) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.131172) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.131479) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.146860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.147301) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-24 16: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% 224M=0s 2016-05-24 16:01:33 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092040 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.254546) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.254852) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016241 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.305574) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.305907) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 --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=d6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-24 16:01:33,947 truncating revision to first 12 chars 2016-05-24 16:01:33,947 Setting DEBUG logging. 2016-05-24 16:01:33,947 attempt 1/10 2016-05-24 16:01:33,947 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5511d54a3f17?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 16:01:34,231 unpacking tar archive at: mozilla-central-5511d54a3f17/testing/mozharness/ program finished with exit code 0 elapsedTime=0.580900 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.903541) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.904282) ========= script_repo_revision: 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.905155) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.905425) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 16:01:33.916971) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-05-24 16:01:33.917404) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=d6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:01:34 INFO - MultiFileLogger online at 20160524 16:01:34 in /builds/slave/test 16:01:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:01:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:34 INFO - {'append_to_log': False, 16:01:34 INFO - 'base_work_dir': '/builds/slave/test', 16:01:34 INFO - 'blob_upload_branch': 'Firefox', 16:01:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:34 INFO - 'branch': 'Firefox', 16:01:34 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:34 INFO - 'config_files': ('talos/linux_config.py',), 16:01:34 INFO - 'default_actions': ('clobber', 16:01:34 INFO - 'read-buildbot-config', 16:01:34 INFO - 'download-and-extract', 16:01:34 INFO - 'populate-webroot', 16:01:34 INFO - 'create-virtualenv', 16:01:34 INFO - 'install', 16:01:34 INFO - 'run-tests'), 16:01:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:34 INFO - 'download_minidump_stackwalk': True, 16:01:34 INFO - 'download_symbols': 'ondemand', 16:01:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:01:34 INFO - 'tooltool.py': '/tools/tooltool.py', 16:01:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:01:34 INFO - '/tools/misc-python/virtualenv.py')}, 16:01:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:34 INFO - 'installer_path': 'installer.exe', 16:01:34 INFO - 'log_level': 'info', 16:01:34 INFO - 'log_name': 'talos', 16:01:34 INFO - 'log_to_console': True, 16:01:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:01:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:01:34 INFO - 'opt_config_files': (), 16:01:34 INFO - 'pip_index': False, 16:01:34 INFO - 'sps_profile': False, 16:01:34 INFO - 'sps_profile_interval': 0, 16:01:34 INFO - 'suite': 'dromaeojs', 16:01:34 INFO - 'system_bits': '32', 16:01:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:34 INFO - 'title': 'talos-linux64-ix-034', 16:01:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:34 INFO - 'use_talos_json': True, 16:01:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:34 INFO - 'work_dir': 'build'} 16:01:34 INFO - ##### 16:01:34 INFO - ##### Running clobber step. 16:01:34 INFO - ##### 16:01:34 INFO - Running pre-action listener: _resource_record_pre_action 16:01:34 INFO - Running main action method: clobber 16:01:34 INFO - rmtree: /builds/slave/test/build 16:01:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:35 INFO - Running post-action listener: _resource_record_post_action 16:01:35 INFO - ##### 16:01:35 INFO - ##### Finished clobber step (success) 16:01:35 INFO - ##### 16:01:35 INFO - ##### 16:01:35 INFO - ##### Running read-buildbot-config step. 16:01:35 INFO - ##### 16:01:35 INFO - Running pre-action listener: _resource_record_pre_action 16:01:35 INFO - Running main action method: read_buildbot_config 16:01:35 INFO - Using buildbot properties: 16:01:35 INFO - { 16:01:35 INFO - "project": "", 16:01:35 INFO - "product": "firefox", 16:01:35 INFO - "script_repo_revision": "production", 16:01:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:01:35 INFO - "repository": "", 16:01:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 16:01:35 INFO - "buildid": "20160524133017", 16:01:35 INFO - "pgo_build": "True", 16:01:35 INFO - "basedir": "/builds/slave/test", 16:01:35 INFO - "buildnumber": 455, 16:01:35 INFO - "slavename": "talos-linux64-ix-034", 16:01:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:01:35 INFO - "platform": "ubuntu64_hw", 16:01:35 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 16:01:35 INFO - "branch": "mozilla-central", 16:01:35 INFO - "repo_path": "mozilla-central", 16:01:35 INFO - "revision": "5511d54a3f172c1d68f98cc55dce4de1d0ba1b51", 16:01:35 INFO - "stage_platform": "linux64-pgo", 16:01:35 INFO - "builduid": "c3555eaa92d24d1b8d1c483faf505551", 16:01:35 INFO - "slavebuilddir": "test" 16:01:35 INFO - } 16:01:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 16:01:35 INFO - Running post-action listener: _resource_record_post_action 16:01:35 INFO - ##### 16:01:35 INFO - ##### Finished read-buildbot-config step (success) 16:01:35 INFO - ##### 16:01:35 INFO - ##### 16:01:35 INFO - ##### Running download-and-extract step. 16:01:35 INFO - ##### 16:01:35 INFO - Running pre-action listener: _resource_record_pre_action 16:01:35 INFO - Running main action method: download_and_extract 16:01:35 INFO - mkdir: /builds/slave/test/build/tests 16:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:35 INFO - https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:01:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:01:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:01:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:01:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:01:35 INFO - Downloaded 1361 bytes. 16:01:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:01:35 INFO - Using the following test package requirements: 16:01:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:01:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:01:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:01:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:35 INFO - u'jsshell-linux-x86_64.zip'], 16:01:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:01:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:01:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:01:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:01:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:01:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:01:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:35 INFO - https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:01:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:01:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:01:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:01:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:01:36 INFO - Downloaded 24417085 bytes. 16:01:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:01:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:01:38 INFO - Return code: 0 16:01:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:38 INFO - https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:01:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:01:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:01:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:01:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:01:39 INFO - Downloaded 24417085 bytes. 16:01:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:01:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:01:40 INFO - Return code: 0 16:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:40 INFO - https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:01:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:01:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:01:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:01:41 INFO - Downloaded 10840359 bytes. 16:01:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:01:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:01:41 INFO - Return code: 0 16:01:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:41 INFO - https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:01:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:01:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:01:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:01:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:01:43 INFO - Downloaded 58628183 bytes. 16:01:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:01:44 INFO - mkdir: /builds/slave/test/properties 16:01:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:44 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:44 INFO - Contents: 16:01:44 INFO - build_url:https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:01:44 INFO - Running post-action listener: _resource_record_post_action 16:01:44 INFO - Running post-action listener: set_extra_try_arguments 16:01:44 INFO - ##### 16:01:44 INFO - ##### Finished download-and-extract step (success) 16:01:44 INFO - ##### 16:01:44 INFO - ##### 16:01:44 INFO - ##### Running populate-webroot step. 16:01:44 INFO - ##### 16:01:44 INFO - Running pre-action listener: _resource_record_pre_action 16:01:44 INFO - Running main action method: populate_webroot 16:01:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:01:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:01:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:01:44 INFO - '--tppagecycles', 16:01:44 INFO - '7'], 16:01:44 INFO - 'tests': ['tsvgm']}}, 16:01:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:01:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:01:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:44 INFO - 'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:44 INFO - 'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['damp', 'tps']}, 16:01:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:44 INFO - 'tests': ['damp', 'tps']}, 16:01:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['dromaeo_dom']}, 16:01:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['a11yr', 16:01:44 INFO - 'ts_paint', 16:01:44 INFO - 'tpaint', 16:01:44 INFO - 'sessionrestore', 16:01:44 INFO - 'sessionrestore_no_auto_restore', 16:01:44 INFO - 'tabpaint']}, 16:01:44 INFO - 'other-e10s': {'tests': ['a11yr', 16:01:44 INFO - 'ts_paint', 16:01:44 INFO - 'tpaint', 16:01:44 INFO - 'sessionrestore', 16:01:44 INFO - 'sessionrestore_no_auto_restore', 16:01:44 INFO - 'tabpaint']}, 16:01:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:01:44 INFO - 'ts_paint', 16:01:44 INFO - 'tpaint', 16:01:44 INFO - 'sessionrestore', 16:01:44 INFO - 'sessionrestore_no_auto_restore', 16:01:44 INFO - 'tabpaint']}, 16:01:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:01:44 INFO - 'ts_paint', 16:01:44 INFO - 'tpaint', 16:01:44 INFO - 'sessionrestore', 16:01:44 INFO - 'sessionrestore_no_auto_restore', 16:01:44 INFO - 'tabpaint']}, 16:01:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['a11yr', 16:01:44 INFO - 'ts_paint', 16:01:44 INFO - 'tpaint', 16:01:44 INFO - 'sessionrestore', 16:01:44 INFO - 'sessionrestore_no_auto_restore', 16:01:44 INFO - 'tabpaint']}, 16:01:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['a11yr', 16:01:44 INFO - 'ts_paint', 16:01:44 INFO - 'tpaint', 16:01:44 INFO - 'sessionrestore', 16:01:44 INFO - 'sessionrestore_no_auto_restore', 16:01:44 INFO - 'tabpaint']}, 16:01:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['tsvgx', 16:01:44 INFO - 'tsvgr_opacity', 16:01:44 INFO - 'tart', 16:01:44 INFO - 'tscrollx', 16:01:44 INFO - 'cart']}, 16:01:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:01:44 INFO - 'tsvgr_opacity', 16:01:44 INFO - 'tart', 16:01:44 INFO - 'tscrollx', 16:01:44 INFO - 'cart']}, 16:01:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:44 INFO - 'talos_options': ['--disable-e10s'], 16:01:44 INFO - 'tests': ['tp5o']}, 16:01:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:44 INFO - 'tests': ['tp5o']}, 16:01:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:44 INFO - 'talos_options': ['--disable-e10s', 16:01:44 INFO - '--xperf_path', 16:01:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:44 INFO - 'tests': ['tp5n']}, 16:01:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:44 INFO - 'talos_options': ['--xperf_path', 16:01:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:44 INFO - 'tests': ['tp5n']}}, 16:01:44 INFO - 'talos.zip': {'path': '', 16:01:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:01:44 INFO - Running post-action listener: _resource_record_post_action 16:01:44 INFO - ##### 16:01:44 INFO - ##### Finished populate-webroot step (success) 16:01:44 INFO - ##### 16:01:44 INFO - ##### 16:01:44 INFO - ##### Running create-virtualenv step. 16:01:44 INFO - ##### 16:01:44 INFO - Running pre-action listener: _resource_record_pre_action 16:01:44 INFO - Running main action method: create_virtualenv 16:01:44 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:01:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:01:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:45 INFO - Using real prefix '/usr' 16:01:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:45 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:47 INFO - Installing pip.................done. 16:01:47 INFO - Return code: 0 16:01:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:47 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 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:01:47 INFO - Using env: {'DISPLAY': ':0', 16:01:47 INFO - 'HOME': '/home/cltbld', 16:01:47 INFO - 'LANG': 'en_US.UTF-8', 16:01:47 INFO - 'LANGUAGE': 'en_US:en', 16:01:47 INFO - 'LOGNAME': 'cltbld', 16:01:47 INFO - 'MAIL': '/var/mail/cltbld', 16:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:47 INFO - 'MOZ_NO_REMOTE': '1', 16:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:47 INFO - 'NO_EM_RESTART': '1', 16:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:47 INFO - 'PWD': '/builds/slave/test', 16:01:47 INFO - 'SHELL': '/bin/bash', 16:01:47 INFO - 'SHLVL': '1', 16:01:47 INFO - 'TERM': 'linux', 16:01:47 INFO - 'TMOUT': '86400', 16:01:47 INFO - 'USER': 'cltbld', 16:01:47 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:47 INFO - '_': '/tools/buildbot/bin/python'} 16:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:01:47 INFO - Cleaning up... 16:01:47 INFO - Return code: 0 16:01:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:47 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 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:01:47 INFO - Using env: {'DISPLAY': ':0', 16:01:47 INFO - 'HOME': '/home/cltbld', 16:01:47 INFO - 'LANG': 'en_US.UTF-8', 16:01:47 INFO - 'LANGUAGE': 'en_US:en', 16:01:47 INFO - 'LOGNAME': 'cltbld', 16:01:47 INFO - 'MAIL': '/var/mail/cltbld', 16:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:47 INFO - 'MOZ_NO_REMOTE': '1', 16:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:47 INFO - 'NO_EM_RESTART': '1', 16:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:47 INFO - 'PWD': '/builds/slave/test', 16:01:47 INFO - 'SHELL': '/bin/bash', 16:01:47 INFO - 'SHLVL': '1', 16:01:47 INFO - 'TERM': 'linux', 16:01:47 INFO - 'TMOUT': '86400', 16:01:47 INFO - 'USER': 'cltbld', 16:01:47 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:47 INFO - '_': '/tools/buildbot/bin/python'} 16:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:47 INFO - Downloading/unpacking psutil>=3.1.1 16:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:49 INFO - Installing collected packages: psutil 16:01:49 INFO - Running setup.py install for psutil 16:01:50 INFO - building 'psutil._psutil_linux' extension 16:01:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:01:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:01:50 INFO - building 'psutil._psutil_posix' extension 16:01:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:01:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:01:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:50 INFO - Successfully installed psutil 16:01:50 INFO - Cleaning up... 16:01:50 INFO - Return code: 0 16:01:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:50 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 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:01:50 INFO - Using env: {'DISPLAY': ':0', 16:01:50 INFO - 'HOME': '/home/cltbld', 16:01:50 INFO - 'LANG': 'en_US.UTF-8', 16:01:50 INFO - 'LANGUAGE': 'en_US:en', 16:01:50 INFO - 'LOGNAME': 'cltbld', 16:01:50 INFO - 'MAIL': '/var/mail/cltbld', 16:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:50 INFO - 'MOZ_NO_REMOTE': '1', 16:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:50 INFO - 'NO_EM_RESTART': '1', 16:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:50 INFO - 'PWD': '/builds/slave/test', 16:01:50 INFO - 'SHELL': '/bin/bash', 16:01:50 INFO - 'SHLVL': '1', 16:01:50 INFO - 'TERM': 'linux', 16:01:50 INFO - 'TMOUT': '86400', 16:01:50 INFO - 'USER': 'cltbld', 16:01:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:50 INFO - '_': '/tools/buildbot/bin/python'} 16:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:01:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:01:52 INFO - Installing collected packages: mozsystemmonitor 16:01:52 INFO - Running setup.py install for mozsystemmonitor 16:01:52 INFO - Successfully installed mozsystemmonitor 16:01:52 INFO - Cleaning up... 16:01:52 INFO - Return code: 0 16:01:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:01:52 INFO - Using env: {'DISPLAY': ':0', 16:01:52 INFO - 'HOME': '/home/cltbld', 16:01:52 INFO - 'LANG': 'en_US.UTF-8', 16:01:52 INFO - 'LANGUAGE': 'en_US:en', 16:01:52 INFO - 'LOGNAME': 'cltbld', 16:01:52 INFO - 'MAIL': '/var/mail/cltbld', 16:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:52 INFO - 'MOZ_NO_REMOTE': '1', 16:01:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:52 INFO - 'NO_EM_RESTART': '1', 16:01:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:52 INFO - 'PWD': '/builds/slave/test', 16:01:52 INFO - 'SHELL': '/bin/bash', 16:01:52 INFO - 'SHLVL': '1', 16:01:52 INFO - 'TERM': 'linux', 16:01:52 INFO - 'TMOUT': '86400', 16:01:52 INFO - 'USER': 'cltbld', 16:01:52 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:52 INFO - '_': '/tools/buildbot/bin/python'} 16:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:54 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16: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 16: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 16: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 16: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 16: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 16: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 16:01:55 INFO - Downloading docopt-0.6.1.tar.gz 16:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:55 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:55 INFO - Running setup.py install for blobuploader 16:01:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:55 INFO - Running setup.py install for requests 16:01:56 INFO - Running setup.py install for docopt 16:01:56 INFO - Successfully installed blobuploader requests docopt 16:01:56 INFO - Cleaning up... 16:01:56 INFO - Return code: 0 16:01:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:56 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 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:01:56 INFO - Using env: {'DISPLAY': ':0', 16:01:56 INFO - 'HOME': '/home/cltbld', 16:01:56 INFO - 'LANG': 'en_US.UTF-8', 16:01:56 INFO - 'LANGUAGE': 'en_US:en', 16:01:56 INFO - 'LOGNAME': 'cltbld', 16:01:56 INFO - 'MAIL': '/var/mail/cltbld', 16:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:56 INFO - 'MOZ_NO_REMOTE': '1', 16:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:56 INFO - 'NO_EM_RESTART': '1', 16:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:56 INFO - 'PWD': '/builds/slave/test', 16:01:56 INFO - 'SHELL': '/bin/bash', 16:01:56 INFO - 'SHLVL': '1', 16:01:56 INFO - 'TERM': 'linux', 16:01:56 INFO - 'TMOUT': '86400', 16:01:56 INFO - 'USER': 'cltbld', 16:01:56 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:56 INFO - '_': '/tools/buildbot/bin/python'} 16:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:56 INFO - Running setup.py (path:/tmp/pip-TmzlC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:56 INFO - Running setup.py (path:/tmp/pip-TPrBW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:56 INFO - Running setup.py (path:/tmp/pip-Rmc0Xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:56 INFO - Running setup.py (path:/tmp/pip-h8zOFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:56 INFO - Running setup.py (path:/tmp/pip-DT9xkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:56 INFO - Running setup.py (path:/tmp/pip-BoFUg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:56 INFO - Running setup.py (path:/tmp/pip-LV7MRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:56 INFO - Running setup.py (path:/tmp/pip-EGQlXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:57 INFO - Running setup.py (path:/tmp/pip-3p9qSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:57 INFO - Running setup.py (path:/tmp/pip-JC0lh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:57 INFO - Running setup.py (path:/tmp/pip-Wq4f6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:57 INFO - Running setup.py (path:/tmp/pip-TsG9w0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:57 INFO - Running setup.py (path:/tmp/pip-YvIoZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:57 INFO - Running setup.py (path:/tmp/pip-lz5gZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:57 INFO - Running setup.py (path:/tmp/pip-q68YmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:57 INFO - Running setup.py (path:/tmp/pip-cPPszy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:57 INFO - Running setup.py (path:/tmp/pip-lsTjy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:57 INFO - Running setup.py install for manifestparser 16:01:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:57 INFO - Running setup.py install for mozcrash 16:01:57 INFO - Running setup.py install for mozdebug 16:01:57 INFO - Running setup.py install for mozdevice 16:01:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Running setup.py install for mozfile 16:01:58 INFO - Running setup.py install for mozhttpd 16:01:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Running setup.py install for mozinfo 16:01:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Running setup.py install for mozInstall 16:01:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Running setup.py install for mozleak 16:01:58 INFO - Running setup.py install for mozlog 16:01:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Running setup.py install for moznetwork 16:01:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Running setup.py install for mozprocess 16:01:58 INFO - Running setup.py install for mozprofile 16:01:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozrunner 16:01:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozscreenshot 16:01:59 INFO - Running setup.py install for moztest 16:01:59 INFO - Running setup.py install for mozversion 16:01:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:01:59 INFO - Cleaning up... 16:01:59 INFO - Return code: 0 16:01:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:59 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 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:01:59 INFO - Using env: {'DISPLAY': ':0', 16:01:59 INFO - 'HOME': '/home/cltbld', 16:01:59 INFO - 'LANG': 'en_US.UTF-8', 16:01:59 INFO - 'LANGUAGE': 'en_US:en', 16:01:59 INFO - 'LOGNAME': 'cltbld', 16:01:59 INFO - 'MAIL': '/var/mail/cltbld', 16:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:59 INFO - 'MOZ_NO_REMOTE': '1', 16:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:59 INFO - 'NO_EM_RESTART': '1', 16:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:59 INFO - 'PWD': '/builds/slave/test', 16:01:59 INFO - 'SHELL': '/bin/bash', 16:01:59 INFO - 'SHLVL': '1', 16:01:59 INFO - 'TERM': 'linux', 16:01:59 INFO - 'TMOUT': '86400', 16:01:59 INFO - 'USER': 'cltbld', 16:01:59 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:59 INFO - '_': '/tools/buildbot/bin/python'} 16:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:59 INFO - Running setup.py (path:/tmp/pip-V0vwLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:59 INFO - Running setup.py (path:/tmp/pip-DYPkl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:59 INFO - Running setup.py (path:/tmp/pip-p8TRaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:59 INFO - Running setup.py (path:/tmp/pip-_i388g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:00 INFO - Running setup.py (path:/tmp/pip-M0kV85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:00 INFO - Running setup.py (path:/tmp/pip-N3RoRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:00 INFO - Running setup.py (path:/tmp/pip-lWtmQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:00 INFO - Running setup.py (path:/tmp/pip-izhFmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:00 INFO - Running setup.py (path:/tmp/pip-qC1KdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:00 INFO - Running setup.py (path:/tmp/pip-u9987k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:00 INFO - Running setup.py (path:/tmp/pip-BO6Lx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:00 INFO - Running setup.py (path:/tmp/pip-WAEQvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:00 INFO - Running setup.py (path:/tmp/pip-Jqd1BL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:00 INFO - Running setup.py (path:/tmp/pip-e7sTy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:00 INFO - Running setup.py (path:/tmp/pip-FWaMxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:00 INFO - Running setup.py (path:/tmp/pip-YPUrlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:00 INFO - Running setup.py (path:/tmp/pip-NlyTha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:02:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:02 INFO - Downloading blessings-1.6.tar.gz 16:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:02:02 INFO - Installing collected packages: blessings 16:02:02 INFO - Running setup.py install for blessings 16:02:02 INFO - Successfully installed blessings 16:02:02 INFO - Cleaning up... 16:02:03 INFO - Return code: 0 16:02:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:03 INFO - Reading from file tmpfile_stdout 16:02:03 INFO - Current package versions: 16:02:03 INFO - argparse == 1.2.1 16:02:03 INFO - blessings == 1.6 16:02:03 INFO - blobuploader == 1.2.4 16:02:03 INFO - docopt == 0.6.1 16:02:03 INFO - manifestparser == 1.1 16:02:03 INFO - mozInstall == 1.12 16:02:03 INFO - mozcrash == 0.17 16:02:03 INFO - mozdebug == 0.1 16:02:03 INFO - mozdevice == 0.48 16:02:03 INFO - mozfile == 1.2 16:02:03 INFO - mozhttpd == 0.7 16:02:03 INFO - mozinfo == 0.9 16:02:03 INFO - mozleak == 0.1 16:02:03 INFO - mozlog == 3.2 16:02:03 INFO - moznetwork == 0.27 16:02:03 INFO - mozprocess == 0.22 16:02:03 INFO - mozprofile == 0.28 16:02:03 INFO - mozrunner == 6.11 16:02:03 INFO - mozscreenshot == 0.1 16:02:03 INFO - mozsystemmonitor == 0.3 16:02:03 INFO - moztest == 0.7 16:02:03 INFO - mozversion == 1.4 16:02:03 INFO - psutil == 3.1.1 16:02:03 INFO - requests == 1.2.3 16:02:03 INFO - wsgiref == 0.1.2 16:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:03 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 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:02:03 INFO - Using env: {'DISPLAY': ':0', 16:02:03 INFO - 'HOME': '/home/cltbld', 16:02:03 INFO - 'LANG': 'en_US.UTF-8', 16:02:03 INFO - 'LANGUAGE': 'en_US:en', 16:02:03 INFO - 'LOGNAME': 'cltbld', 16:02:03 INFO - 'MAIL': '/var/mail/cltbld', 16:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:03 INFO - 'MOZ_NO_REMOTE': '1', 16:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:03 INFO - 'NO_EM_RESTART': '1', 16:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:03 INFO - 'PWD': '/builds/slave/test', 16:02:03 INFO - 'SHELL': '/bin/bash', 16:02:03 INFO - 'SHLVL': '1', 16:02:03 INFO - 'TERM': 'linux', 16:02:03 INFO - 'TMOUT': '86400', 16:02:03 INFO - 'USER': 'cltbld', 16:02:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:03 INFO - '_': '/tools/buildbot/bin/python'} 16:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:02:03 INFO - Cleaning up... 16:02:03 INFO - Return code: 0 16:02:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:03 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 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:02:03 INFO - Using env: {'DISPLAY': ':0', 16:02:03 INFO - 'HOME': '/home/cltbld', 16:02:03 INFO - 'LANG': 'en_US.UTF-8', 16:02:03 INFO - 'LANGUAGE': 'en_US:en', 16:02:03 INFO - 'LOGNAME': 'cltbld', 16:02:03 INFO - 'MAIL': '/var/mail/cltbld', 16:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:03 INFO - 'MOZ_NO_REMOTE': '1', 16:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:03 INFO - 'NO_EM_RESTART': '1', 16:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:03 INFO - 'PWD': '/builds/slave/test', 16:02:03 INFO - 'SHELL': '/bin/bash', 16:02:03 INFO - 'SHLVL': '1', 16:02:03 INFO - 'TERM': 'linux', 16:02:03 INFO - 'TMOUT': '86400', 16:02:03 INFO - 'USER': 'cltbld', 16:02:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:03 INFO - '_': '/tools/buildbot/bin/python'} 16:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:03 INFO - Downloading/unpacking jsonschema 16: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 16: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 16: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 16: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 16: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 16: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 16:02:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:02:05 INFO - Downloading/unpacking functools32 (from jsonschema) 16:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:02:05 INFO - warning: no files found matching '*.txt' 16:02:05 INFO - no previously-included directories found matching 'build' 16:02:05 INFO - no previously-included directories found matching 'dist' 16:02:05 INFO - no previously-included directories found matching '.git*' 16:02:05 INFO - Installing collected packages: jsonschema, functools32 16:02:05 INFO - Running setup.py install for functools32 16:02:06 INFO - warning: no files found matching '*.txt' 16:02:06 INFO - no previously-included directories found matching 'build' 16:02:06 INFO - no previously-included directories found matching 'dist' 16:02:06 INFO - no previously-included directories found matching '.git*' 16:02:06 INFO - Successfully installed jsonschema functools32 16:02:06 INFO - Cleaning up... 16:02:06 INFO - Return code: 0 16:02:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f46b6219f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c928e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:02:06 INFO - Using env: {'DISPLAY': ':0', 16:02:06 INFO - 'HOME': '/home/cltbld', 16:02:06 INFO - 'LANG': 'en_US.UTF-8', 16:02:06 INFO - 'LANGUAGE': 'en_US:en', 16:02:06 INFO - 'LOGNAME': 'cltbld', 16:02:06 INFO - 'MAIL': '/var/mail/cltbld', 16:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:06 INFO - 'MOZ_NO_REMOTE': '1', 16:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:06 INFO - 'NO_EM_RESTART': '1', 16:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:06 INFO - 'PWD': '/builds/slave/test', 16:02:06 INFO - 'SHELL': '/bin/bash', 16:02:06 INFO - 'SHLVL': '1', 16:02:06 INFO - 'TERM': 'linux', 16:02:06 INFO - 'TMOUT': '86400', 16:02:06 INFO - 'USER': 'cltbld', 16:02:06 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:06 INFO - '_': '/tools/buildbot/bin/python'} 16:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:06 INFO - Downloading/unpacking flake8 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:02:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:02:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:02:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:02:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:02:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:02:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:02:08 INFO - no previously-included directories found matching 'docs/_build' 16:02:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:02:08 INFO - Running setup.py install for pep8 16:02:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:02:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:02:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:02:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:02:09 INFO - no previously-included directories found matching 'docs/_build' 16:02:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:02:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:02:09 INFO - Cleaning up... 16:02:09 INFO - Return code: 0 16:02:09 INFO - Running post-action listener: _resource_record_post_action 16:02:09 INFO - Running post-action listener: _start_resource_monitoring 16:02:09 INFO - Starting resource monitoring. 16:02:09 INFO - ##### 16:02:09 INFO - ##### Finished create-virtualenv step (success) 16:02:09 INFO - ##### 16:02:09 INFO - ##### 16:02:09 INFO - ##### Running install step. 16:02:09 INFO - ##### 16:02:09 INFO - Running pre-action listener: _resource_record_pre_action 16:02:09 INFO - Running main action method: install 16:02:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:09 INFO - Reading from file tmpfile_stdout 16:02:09 INFO - Detecting whether we're running mozinstall >=1.0... 16:02:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:02:09 INFO - Reading from file tmpfile_stdout 16:02:09 INFO - Output received: 16:02:09 INFO - Usage: mozinstall [options] installer 16:02:09 INFO - Options: 16:02:09 INFO - -h, --help show this help message and exit 16:02:09 INFO - -d DEST, --destination=DEST 16:02:09 INFO - Directory to install application into. [default: 16:02:09 INFO - "/builds/slave/test"] 16:02:09 INFO - --app=APP Application being installed. [default: firefox] 16:02:09 INFO - mkdir: /builds/slave/test/build/application 16:02:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:02:26 INFO - Reading from file tmpfile_stdout 16:02:26 INFO - Output received: 16:02:26 INFO - /builds/slave/test/build/application/firefox/firefox 16:02:26 INFO - Running post-action listener: _resource_record_post_action 16:02:26 INFO - ##### 16:02:26 INFO - ##### Finished install step (success) 16:02:26 INFO - ##### 16:02:26 INFO - ##### 16:02:26 INFO - ##### Running run-tests step. 16:02:26 INFO - ##### 16:02:26 INFO - Running pre-action listener: _resource_record_pre_action 16:02:26 INFO - Running main action method: run_tests 16:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:26 INFO - Python 2.7.3 16:02:26 INFO - Return code: 0 16:02:26 INFO - grabbing minidump binary from tooltool 16:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:26 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 0x2d21b20>, '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 0x2d22a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d22eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:02:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:02:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:02:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:26 INFO - Return code: 0 16:02:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:02:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:02:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:02:27 INFO - Return code: 0 16:02:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:02:27 INFO - Using env: {'DISPLAY': ':0', 16:02:27 INFO - 'HOME': '/home/cltbld', 16:02:27 INFO - 'LANG': 'en_US.UTF-8', 16:02:27 INFO - 'LANGUAGE': 'en_US:en', 16:02:27 INFO - 'LOGNAME': 'cltbld', 16:02:27 INFO - 'MAIL': '/var/mail/cltbld', 16:02:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:27 INFO - 'MOZ_NO_REMOTE': '1', 16:02:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:27 INFO - 'NO_EM_RESTART': '1', 16:02:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:27 INFO - 'PWD': '/builds/slave/test', 16:02:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:27 INFO - 'SHELL': '/bin/bash', 16:02:27 INFO - 'SHLVL': '1', 16:02:27 INFO - 'TERM': 'linux', 16:02:27 INFO - 'TMOUT': '86400', 16:02:27 INFO - 'USER': 'cltbld', 16:02:27 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616', 16:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:27 INFO - '_': '/tools/buildbot/bin/python'} 16:02:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:02:27 INFO - mozversion application_buildid: 20160524133017 16:02:27 INFO - mozversion application_changeset: 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 16:02:27 INFO - mozversion application_display_name: Nightly 16:02:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:27 INFO - mozversion application_name: Firefox 16:02:27 INFO - mozversion application_remotingname: firefox 16:02:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:02:27 INFO - mozversion application_vendor: Mozilla 16:02:27 INFO - mozversion application_version: 49.0a1 16:02:27 INFO - mozversion platform_buildid: 20160524133017 16:02:27 INFO - mozversion platform_changeset: 5511d54a3f172c1d68f98cc55dce4de1d0ba1b51 16:02:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:02:27 INFO - mozversion platform_version: 49.0a1 16:02:27 INFO - using testdate: 1464130947 16:02:27 INFO - actual date: 1464130947 16:02:27 INFO - starting webserver on 'localhost:36849' 16:02:27 INFO - SUITE-START | Running 2 tests 16:02:27 INFO - TEST-START | dromaeo_css 16:02:27 INFO - operating with platform_type : linux_ 16:02:27 INFO - Initialising browser for dromaeo_css test... 16:02:27 INFO - TEST-INFO | started process 14967 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplbK9fy/profile http://localhost:36849/getInfo.html) 16:02:31 INFO - PROCESS | 14967 | __metrics Screen width/height:1600/1200 16:02:31 INFO - PROCESS | 14967 | colorDepth:24 16:02:31 INFO - PROCESS | 14967 | Browser inner width/height: 1024/697 16:02:31 INFO - PROCESS | 14967 | __metrics 16:02:32 ERROR - PROCESS | 14967 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:02:33 INFO - TEST-INFO | 14967: exit 0 16:02:33 INFO - Browser initialized. 16:02:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:33 INFO - TEST-INFO | started process 15046 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplbK9fy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:45 INFO - PROCESS | 15046 | RSS: Main: 153260032 16:02:45 INFO - PROCESS | 15046 | 16:03:36 INFO - PROCESS | 15046 | 1464131016117 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:05:43 INFO - PROCESS | 15046 | Cycle 1(1): loaded http://localhost:36849/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36849/tests/dromaeo/cssquery-ext.html) 16:05:44 INFO - PROCESS | 15046 | RSS: Main: 256024576 16:05:44 INFO - PROCESS | 15046 | 16:08:47 INFO - PROCESS | 15046 | Cycle 1(1): loaded http://localhost:36849/tests/dromaeo/cssquery-ext.html (next: http://localhost:36849/tests/dromaeo/cssquery-jquery.html) 16:08:47 INFO - PROCESS | 15046 | RSS: Main: 235868160 16:08:47 INFO - PROCESS | 15046 | 16:11:49 INFO - PROCESS | 15046 | Cycle 1(1): loaded http://localhost:36849/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36849/tests/dromaeo/cssquery-mootools.html) 16:11:50 INFO - PROCESS | 15046 | RSS: Main: 235216896 16:11:50 INFO - PROCESS | 15046 | 16:14:52 INFO - PROCESS | 15046 | Cycle 1(1): loaded http://localhost:36849/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36849/tests/dromaeo/cssquery-prototype.html) 16:14:53 INFO - PROCESS | 15046 | RSS: Main: 233209856 16:14:53 INFO - PROCESS | 15046 | 16:17:55 INFO - PROCESS | 15046 | Cycle 1(1): loaded http://localhost:36849/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36849/tests/dromaeo/cssquery-yui.html) 16:17:56 INFO - PROCESS | 15046 | RSS: Main: 199933952 16:17:56 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | Cycle 1(1): loaded http://localhost:36849/tests/dromaeo/cssquery-yui.html (next: http://localhost:36849/tests/dromaeo/cssquery-dojo.html) 16:20:59 INFO - PROCESS | 15046 | RSS: Main: 282583040 16:20:59 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | __start_tp_report 16:20:59 INFO - PROCESS | 15046 | _x_x_mozilla_page_load 16:20:59 INFO - PROCESS | 15046 | _x_x_mozilla_page_load_details 16:20:59 INFO - PROCESS | 15046 | |i|pagename|runs| 16:20:59 INFO - PROCESS | 15046 | |0;dojo.html;3125.046860707484;3133.890313839016;3157.6526582075967;3171.0962375722925;3198.2963747975436;5326;5329.493698098684;5382.784688612451;5467.644603100795;5512.80705175319;5358.517733403993;5415.296011518497;5432.6468779529305;5469.480399362064;5496.862578435531;4805.255185446262;4898.510148985109;4950.727709975949;4955.554000140005;5158.40678321994;5051.974740126313;5071.518205770446;5098.617603679721;5129.204973229155;5129.615278854082;4843.297721830332;4880.926786098212;4926.310316555685;4927.531884470969;4934.1611925972675;4930.359053323081;4942.901141977176;4987.0524600325825;4996.500349965011;5019.272205530177;4671.135839869635;4963.900721985576;4977.875553111201;5001.3998320201445;5048.949510504922;5301.6818990860675;5361.115415956362;5376.569874410038;5434.592405569598;5454.618176727591;5773.624714393551;5817.854553636151;5854.590178687494;5864.325602555676;5866.677332746697;5769.971150144264;5788.0739081746715;5884.117382392633;5904;5947.762089516415;5382.838514844561;5485.451454854483;5513.917291240595;5524.281843360337;5534.225208470817;6010.459058684652;6053.697315134225;6073.0283154695035;6075.453209211192;6080.75676972925;5574.609777315549;5585.692786896679;5594.552435805126;5815.447532484407;5828.825135245949;5750.079987202112;5751.683657398883;5753.597248192586;5758.712064396764;5790.392008839095;5689.089745640677;5707.914381284284;5733.8566535836935;5733.8566535836935;5733.971330143406;5690.459406356389;5728.79949201776;5755.079187330007;5800.622959507703;5825.155352473952;4413.757243351646;4418.558144185556;4422.09347083847;4430.357598148251;4452.30989196675;3943.783091929914;3962.385830196324;3970.50368703912;3971.0866500705;4002.979985100114;5349.893002139936;5417.404085550586;5498.5876059295715;5566.387697353288;5574.9721251393485;5928.140419639129;5938.673372964442;5956.761729530857;5956.851078723066;6000.579959402888;5590.944090559043;5593;5615.354234263031;5615.775368985195;5616.438356164351;4726.983698504838;4863.683860549053;4915.992221594564;4924.507549245119;4942.9258561121615;6092.603980741238;6098.847528811815;6100.267967843887;6109.633421994606;6118.357572454828;5699.7435115419075;5860.882782344329;5877.089051197071;5893.116032595145;5893.381194974466;5985.281766188085;6004.369541198112;6072.089186622042;6083.6349819011;6103.115048318058;5739.196512488172;5786.913196302143;5790.363060063305;5791.160281759206;5793.565482588739;5690.77236910519;5822.679752613647;5892.410758924073;5893.027650437802;5906.379830117776;5705.4579814917515;5769.884602308014;5777.22007528995;5790.971045144747;5838.095095260242;5704.857378565403;5719.857003574777;5734.59857809966;5748.45389687979;5749.080147176432;5478.753456094565;5559.694216817952;5602.0196465619165;5666.291713535808;5691.317041954826;5756.395578464201;5783.710814459176;5795.0148474760235;5797.043487824461;5801.82594522165;5580.693061881636;5612.130119831432;5618.185363122406;5621.494065533958;5667.688277144796 16:20:59 INFO - PROCESS | 15046 | |1;ext.html;571.528488996578;602.5781952633158;602.8975074237379;604.0637012630425;612.9754809807608;34710.30579388414;37484.25031499372;37513.8124309378;37646.811765941166;42876.57123428765;41086.38370424446;45380.77309613456;45637;45670.314945275844;45705.31442028372;8622.137786221374;8834.55827208637;8855.601497932608;8857.512836793974;8870.024297327289;8739.475631462132;8882.600282987281;8895.466272023667;8898.37711360205;8904.154105359981;8898.822023559525;8906.109389061106;8948.06045365237;8973.192412682858;8988.77528061797;8845.469271843709;8851.911480885205;8862.734117976472;8878.86681699775;8894.155055269805;8682.435641683334;8774.736757897273;8799.252063574604;8807.559622018873;8809.03100658927;42219.36670949938;42519.787401063106;42679.35980960288;42709;42727.786361068305;3566.982165089184;3695.334839728848;3699.3341198584244;3746.9437958430644;4052.6555242804397;31997.52003719946;32425.83787081073;32472.837635811906;33343;34098.249192588795;4111.629953304187;4146.771927543984;4148.771817550033;4171.853985110507;4408.162449134654;254427;254579;255233;255613;356187.2190639056;62222;62483.687581561804;62929.370706293266;62960;62983;35566.28867422689;36681;66305.33694663088;66527.33472665308;66620.33379666238;16976.57558561046;17216.91391543034;17300.65398692019;17302.394416195624;17306.22122004513;12824.487020519075;12964.67588310281;12993.220406775623;12999.545015924587;12999.740005200032;19279.64314599174;19524.395529935733;19584.314548991002;19610.01949902499;19617.117229724696;7017;7099.858002839915;7249.963750181215;7312.890306645405;7364.668589913466;1004.7839714461426;1143.3025854228913;1146.277844957688;1147.8278258261328;1198.1972078707288;33822.661773382446;34106;34119;34169.487457688156;34222.14444638904;1116.2019156303281;1134.2740645986576;1151.9827202591969;1185.3065956415594;1239.0954603139758;17404.564885877702;17528.82471175272;17589;17683.911580442014;17712.026451021873;499498;504642;505024;506033;508500;59566.404335956955;61252.38747612467;61260;61312;61313.693431532556;1275.043717212091;1365.2013572060455;1396.6857457072078;1399.5381524097227;1413.7234864534864;31399.341286540428;33048.17379565434;33079.50380744242;33162.66837331693;33331.333373332884;51689.224661630855;52130.478695212565;52252.7387363076;52297;52335.47664523306;305791;312775;313406;313737;313901.02692811284;26185.214443566725;26686.59970100489;27179.72820271772;27609.723902760714;29131.563026554195;48964.2655360177;49135.03459861565;49544.75227623839;50410.243846343066;50870.49129508805;40972;41339.1732165361;41505;42172.789136054125;43134;41779.58220417757;42233.36649950314;42459.363109554;42519;42522.787386062875;41247.58752412558;41799;41812;42121.578784211764;42219.57780422157;34991.30017399587;37415.81292093522;37500.812495937345;37799.62200377961;37902.62097378991;28125.57811632868;28262.85868570644;28509;28572.714272857007;28682.282942926595 16:20:59 INFO - PROCESS | 15046 | |2;jquery.html;380.7410960546829;388.4425848906817;394.4202023026152;401.60642570281124;415.8523724077952;14356.569302920903;15577.922110389432;17877.999999999985;18908.90545547272;19144.61710765784;14572.417103315855;17622.383216587423;17774.200160992754;18302.450926472226;18730;5576.553875689935;5662.263905692254;5767.798127065554;5771.826845194651;5774.624649397797;4076.347784354504;5545.805896793613;5692.658440493584;5757.366689664133;6076;6299.748010079591;6402.775902843378;6416.711247993851;6422.743090276384;6425.710843012076;6261.812145635638;6327.462165715897;6386.64873431961;6452.967735161317;6457.806265812033;5579.469950354751;5588.86027849307;5591.10542313228;5595.804146854841;5597.888042239132;13460.192388456626;16194.514164575081;20562.177512899616;21294.680579791315;21384.46538836526;2172.1745736619982;2206.646936490154;2208.381653137124;2306.688597039395;2518.445941892781;10009.649662261858;10068.848967265498;10140.442275674835;10521.894781052168;10522.158227341793;1796.173760070369;1888.4901076709211;1930.2954421636002;1997.1911375892641;2075.201047596671;80376.59811700962;80502.19497805006;81614;82012.58993705054;82024.58987705084;11018.614348497764;11046.834297485544;11187.888121118847;11200.551977920955;11352.886471135349;15232.695346093016;15386.307616157299;15483;15503.689926201638;15505.534833955;7330.743423980135;7344.449166312548;7751.961240193763;8104.0680321762575;8207.261346478846;7548.094228692482;7559.697612095454;7707.653155607899;7801.2198780121535;7818.061832580127;4500.954990450119;4606.585407313358;4623.098495793288;4683.976580117078;4770.642201834877;1920.4142736465512;1927.88432694036;1965.8034196580227;1992.681171012631;2005.8896760678304;957.3585697582637;1036.43514284715;1087.5535537734793;1125.5441546173813;1129.2095533126844;18495.630087398178;18906;18933;19313.130909109124;19422.611547769247;942.6370847223868;997.576030187182;1035.3649960849655;1092.7650555130526;1143.74837535742;10146.543405546769;10440.269181157246;10503.52734126966;10551.20865935058;10552.788944221073;426174;434059;451972;452141;454681;87558.56220718983;93110;94950.52524737333;95674;95880;953.9809203815885;998.2313618513704;1003.6988903328972;1029.3206483720708;1047.638564695179;15094;15380.846191538389;16129.75805362897;16565.42021029258;16836.77292687677;42136.57863421449;43434.348484773385;43574.34638480362;43703.377797332265;44102.779486102365;42101;52498;55504.72247638736;57125.14312285236;59064.70467647634;8472.279856212044;8625.741227763177;8671.956640216758;8714.607842646968;8856.202941735275;8804.207621314112;8850.247728943112;8895.555222238732;9448.527573621428;9751.024897510193;8093.635786389509;8274.379421543288;8313.750587482386;8416;8685.218330350299;6949.617771022642;8114.350851931713;8265.132161123236;8269.255766981001;8362.456440331602;8130.67477300925;8640.308775298086;8717.564121794012;8811.955940220258;9253.768655783659;12837.229766214057;12854.485820567073;13196.868031319564;13330;13415.664608384479;11924.165308428674;12020.278783272683;12071.57749478764;12102.092343074128;12199.207051541622 16:20:59 INFO - PROCESS | 15046 | |3;mootools.html;1249.518935209945;1502.9473968411105;1526.1834918318698;1655.6688662267543;1804.557883318587;8416.45293055951;8780.297576193905;8785.64857405703;8993.550322483867;8997;8759.868601970975;8934;8962.058983806704;8972.192502674758;8972.923249210104;2031.6038372517291;2297.517521320525;2328.1153161798534;2371.087131454385;2381.1070848431837;2425.920465392901;2433.987830060856;2434.160214725917;2435.415500279938;2438.3782135555407;2355.4346956730433;2362.5393048355577;2380.7619238076227;2381.3808409813487;2385.439421735891;1999.8700084494535;2258.5821622999715;2287.9542409151722;2354.917577884783;2355.5759963206615;2305.1586171047616;2314.328844635054;2320.6286994080865;2322.6864373309727;2327.0459111764267;9131;9156.771080722969;9157.633694652273;9164.770880727969;9169.495677737721;5401.054815407328;5517.36550296717;5520.309961254843;5532.225488431623;5534.142207957773;3430.7255419566377;3479.495473156378;3496.807675577842;3542.5040494330815;3568.2506673598577;1109.8224284114583;1131.4795194210674;1139.7606502634458;1152.1589239854952;1161.2451906260912;12953.676158096028;13000.219986800823;13005.739885202338;13013.08908376414;13024.804627930589;4625.814967401334;4743.075100355398;4817.205161148441;4825.227963525819;4843.878903027453;10802.459877006118;10848.566057357775;10855.294405863593;10865.348079115278;10886.67339979802;5430.484104010113;5492.368377636543;5506.421825708243;5510.118381059092;5513.145462453326;4339.848105316299;4860.73265970375;4982.277569752366;5013.849584512471;5065.240213967934;4069.9389509157386;4082.408050832613;4103.753774773523;4162.313218319004;4169.332906734908;1269.6381531263546;1278.6931136527166;1297.4680381043727;1297.9675508112373;1316.7629826631107;1016.5628779624832;1019.5004447820514;1028.922830787694;1032.5611615063567;1036.8185567525652;6277.52918531112;6297.622142671455;6350.714217860207;6472.611643301417;6493.837654058591;458.3354136502085;463.4369241371682;463.7403054289609;466.80627539570696;470.96703230774204;4289.463817022872;4488.977555112204;4499.212637788374;4560.68075234737;4593.701409408444;11514.596989105341;11603.245789023855;11682.766344673058;11704.122190835722;11742.471588778355;10860.565577376816;11434.085273178292;11528.193026488234;11546.191766576427;11557.422128893522;8501.362397820189;8617.655293788179;8672.26285765717;8702.738917832476;8707.608157632922;6266.404691554295;6594.373534514213;6624.8343791405605;6632.502562307943;6637.53537252407;10515.631952881919;10731.302465339728;10842.078423334106;10912.890871091187;10915.890841091805;10910.509027093958;11012.5044373005;11020.338779673246;11021.944890275498;11037.11703063769;7460.925390746023;7868.881966770388;8091.919080809116;8207.95896020516;8339.708110216114;9003.009668936542;9010.14403631654;9085.636574536944;9113.225375843127;9119.726408207764;7945.1657575953695;8163.1428699985645;8213.753587392388;8332.375071869756;8378.958105209435;8002;8064.516129032277;8114.756557303291;8119.228673276029;8147.307478864363;7090.574565526085;7409.888851667121;7410;7447.366973807287;7525.059367579052;5350.9732451337495;5365.812196573102;5450.155225939986;5466.5900057495055;5495.038368285614;5460.645058071213;5500.559955203654;5527.309086364205;5593.608447408642;5764.971175144097 16:20:59 INFO - PROCESS | 15046 | |4;prototype.html;319.6883039036938;329.3002369963827;339.69766907452356;340.86195090988144;342.2436415521697;5146.485351464851;5284.392294886089;5352.330958630171;5365.382980957191;5378.89242215156;5291.550218231455;5337.919931201035;5341.759620817063;5348.893022139555;5352.357717073956;4164.833406663737;4187.81154848031;4199.874003779876;4200.726952748077;4204.2432362174795;3958.762474251554;3990.261801566705;4180.912962629723;4183.895402614929;4208.97895510522;4093.5497095319492;4112.773797441139;4160.812763425653;4166.166766646667;4173.353130264813;4155.355919832431;4157.253953339575;4163.521195062576;4165.895852603679;4184.895377615553;3405.404054290514;3694.390425579776;3703.6296370363016;3715.6841668458214;3728.384816505273;5091.439941606421;5125.103106956305;5133.101707201262;5138.511841375063;5169.405518365398;4660.953390466086;4682.531746825324;4693.6479764017495;4693.694909830884;4722.504137065627;5435.565154787608;5520.861978450531;5561.138023606348;5585.268566744322;5593.266087512884;3921.627445392683;3947.4473573699706;3950.5654378018394;3951.6169340730517;3954.121792148735;5682.801101961411;5686.886262274731;5742.506948193458;5748.482636562688;5819.127130930352;3914.9021274468364;3985.5217373915316;4003.8598649047144;4012;4022.517297924268;4033.213523362916;4055.5058638936844;4061.411095391152;4073.551909289976;4115.88871004827;4675.755637294118;4689;4692.202325604655;4707.435107787088;4730.9763451182525;5576.247206627135;5576.386597474275;5585.301837270341;5607.80372686954;5609.635373700697;1370.2189751841554;1412.1668215752754;1412.5903487988573;1416.964575885611;1418.8439271680106;873.4715497124275;884.787650963764;901.378049146087;904.3533873280504;909.6134142989204;4786.425628924551;4826.517348265145;4841.8547443576745;4845.200541910615;4884.389451318585;4993.127577158565;4996.6502344836235;5121.3854337479;5132.461091585405;5169.508896654812;4818.68678535894;4832.081904438145;4847.5394837490385;4847.95152048482;5061.291419201315;3063.3107550801337;3065.708757668018;3080.430120427728;3081;3098.3338582204483;5871.500922421556;5902.786455079346;5910.99513082777;5936.673482958478;5951.9702401488585;5579.832605021856;5743.8564035899435;5770.5088421505225;5791.7683292667825;5814.360420353758;5762.481376676203;5824.825255242349;5842.9908597072545;5852.6195797273895;5900.380460051719;5495.642783219078;5684.545236381162;5702.37273899879;5736.541076713769;5745.2818397700285;5720.656760594211;5739.368669446274;5748.827535173951;5749.1663708762835;5750.769969201185;5834.94165058344;5955.702214889152;5986.790462333972;5995.370486098896;6011.308699499614;3704.685101766272;3867.555231148454;4063.2889244382704;4073.51117865858;4089.9591004090767;5648.164346587293;5710.65736055838;5719.342275638355;5775.797847075332;5806.82579522615;5567.135009771772;5591.748371323219;5620.634658747168;5627.549796016391;5645.774169033192;5506.752196151103;5529.917051244315;5556.38879723222;5606.187102870143;5616.129499927518;5520.475554822286;5532.253145825262;5548.667079975215;5552.7778888845605;5554;5413.431589683027;5665;5668.631538949955;5672.234248376416;5688.488036076773;5546.389897111234;5589.720513974366;5599.132134519156;5600.85997850057;5600.943990560042 16:20:59 INFO - PROCESS | 15046 | |5;yui.html;792.0772300270186;796.1759578835906;803.8995125609297;841.957902104895;870.6125774030551;14689.632759181013;15206.619834504128;15226.619334516628;15570.53288401347;15633.140177290245;15792.84207157928;15810.051396916193;15835.366585336573;15836.84163158368;15844.76232856508;817.0889458254063;827.8716798896181;828.9378296627747;830.0205757206494;830.9210624990616;700.989485157723;820.8522465956155;827.4042689263721;844.0757370679097;847.995760021199;874.8687696845492;875.8686197070426;879.0901417033372;879.5074758135464;880.2737741363369;870.9303255739526;872.5157537566648;874.2743522876061;874.3486102853383;874.6851133591933;533.0777754484724;623.3953065526432;637.7417146055855;640.3404493371835;640.826976716284;13466.192028478225;13501.662458438519;13522.661933451644;13537.52618658352;13538.661533461644;13033.674158146123;13324.666883327898;13381.330933453288;13460.05779595419;13564.45742170312;10891.292066015763;11172.162087843362;11392.480664138317;11528.193026488148;11743.412829358498;3730.7015438764834;3784.6593806557544;3788.1855401088624;3876.573576906538;3900.7854567998747;60711;60737;61231;61857;62323.376766232665;10755.139588832875;10878.456077196108;10881.292715973595;10997.505112269968;11000.449977501094;19663.50841228952;20703.27538536174;20740.792592074187;20767.480812979797;20867.791322086887;7993.9200607994335;8267.586620668942;8296.170382961776;8358.540280284642;8362.749117526484;4520.728756274634;4568.794404251817;4604.838830640979;4721.763911804396;4956.529129732669;3364.9327013460083;3452.2750222453064;3461.7922924624604;3462.79223246606;3463.7748546344405;652.0480099055337;652.7030201258416;656.0520048529828;667.4893706314673;677.9118714567074;727.421699748701;736.3446532586003;742.9442791790638;753.6231884057971;759.9468037237451;9246.953765231265;9319.487428191514;9321.533923303808;9499.24006079512;9594.616215351447;659.7855696898528;701.1165930927068;720.3372896934833;726.7674344209908;730.9049823523014;4438.60052595268;4668.649851261169;4717.858464246078;4754.215554433549;4768.332433459318;47759;47805.04389912252;47913;68728.31271687319;69275.65362173157;61231.387686122565;62162;62324.37675623186;62409;62766;55058.17412738812;55556;55720.72139639276;56279.71860140755;57357;9104.635814567344;9163.908360916306;9211.723648290563;9234.261259099121;9295.581698823435;38166.42750358803;39036.80481597574;39338;39347.80326098351;39456.40815387829;50277.74861125671;51731.48268517267;51994.22008669949;52672.20991685204;52724;11303.491342889425;11339.546418143513;11441.48513316886;11549.769004619693;11819.349935753617;12495.229031282843;12885.291308978096;12922.418491168108;13191.142575732547;13245.735085298433;11215.943920280346;11549.076073913899;12023.879761202275;12359.134860559674;12432.689182770142;11879.287242765462;12368.505259789868;12488.500459981862;12971.35143242853;13301.334933253493;12905.225686458842;13178.193881017474;13234.801477978031;13684.794728078889;13822.101563398346;10931.672049838518;11003.394813285346;11012.17408694335;11181.496832642713;11310;10663.733406664585;11299.887001129884;11406.429678516208;11512.251703639236;11721.824172637247 16:20:59 INFO - PROCESS | 15046 | __end_tp_report 16:20:59 INFO - PROCESS | 15046 | __start_cc_report 16:20:59 INFO - PROCESS | 15046 | _x_x_mozilla_cycle_collect,845 16:20:59 INFO - PROCESS | 15046 | __end_cc_report 16:20:59 INFO - PROCESS | 15046 | __startTimestamp1464132059285__endTimestamp 16:20:59 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | ------- Summary: start ------- 16:20:59 INFO - PROCESS | 15046 | Number of tests: 6 16:20:59 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | [#0] dojo.html Cycles:165 Average:5419.79 Median:5616.44 stddev:627.57 (11.2%) stddev-sans-first:603.13 16:20:59 INFO - PROCESS | 15046 | Values: 3125.0 3133.9 3157.7 3171.1 3198.3 5326.0 5329.5 5382.8 5467.6 5512.8 5358.5 5415.3 5432.6 5469.5 5496.9 4805.3 4898.5 4950.7 4955.6 5158.4 5052.0 5071.5 5098.6 5129.2 5129.6 4843.3 4880.9 4926.3 4927.5 4934.2 4930.4 4942.9 4987.1 4996.5 5019.3 4671.1 4963.9 4977.9 5001.4 5048.9 5301.7 5361.1 5376.6 5434.6 5454.6 5773.6 5817.9 5854.6 5864.3 5866.7 5770.0 5788.1 5884.1 5904.0 5947.8 5382.8 5485.5 5513.9 5524.3 5534.2 6010.5 6053.7 6073.0 6075.5 6080.8 5574.6 5585.7 5594.6 5815.4 5828.8 5750.1 5751.7 5753.6 5758.7 5790.4 5689.1 5707.9 5733.9 5733.9 5734.0 5690.5 5728.8 5755.1 5800.6 5825.2 4413.8 4418.6 4422.1 4430.4 4452.3 3943.8 3962.4 3970.5 3971.1 4003.0 5349.9 5417.4 5498.6 5566.4 5575.0 5928.1 5938.7 5956.8 5956.9 6000.6 5590.9 5593.0 5615.4 5615.8 5616.4 4727.0 4863.7 4916.0 4924.5 4942.9 6092.6 6098.8 6100.3 6109.6 6118.4 5699.7 5860.9 5877.1 5893.1 5893.4 5985.3 6004.4 6072.1 6083.6 6103.1 5739.2 5786.9 5790.4 5791.2 5793.6 5690.8 5822.7 5892.4 5893.0 5906.4 5705.5 5769.9 5777.2 5791.0 5838.1 5704.9 5719.9 5734.6 5748.5 5749.1 5478.8 5559.7 5602.0 5666.3 5691.3 5756.4 5783.7 5795.0 5797.0 5801.8 5580.7 5612.1 5618.2 5621.5 5667.7 16:20:59 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | [#1] ext.html Cycles:180 Average:54075.11 Median:22742.97 stddev:100091.51 (440.1%) stddev-sans-first:100291.68 16:20:59 INFO - PROCESS | 15046 | Values: 571.5 602.6 602.9 604.1 613.0 34710.3 37484.3 37513.8 37646.8 42876.6 41086.4 45380.8 45637.0 45670.3 45705.3 8622.1 8834.6 8855.6 8857.5 8870.0 8739.5 8882.6 8895.5 8898.4 8904.2 8898.8 8906.1 8948.1 8973.2 8988.8 8845.5 8851.9 8862.7 8878.9 8894.2 8682.4 8774.7 8799.3 8807.6 8809.0 42219.4 42519.8 42679.4 42709.0 42727.8 3567.0 3695.3 3699.3 3746.9 4052.7 31997.5 32425.8 32472.8 33343.0 34098.2 4111.6 4146.8 4148.8 4171.9 4408.2 254427.0 254579.0 255233.0 255613.0 356187.2 62222.0 62483.7 62929.4 62960.0 62983.0 35566.3 36681.0 66305.3 66527.3 66620.3 16976.6 17216.9 17300.7 17302.4 17306.2 12824.5 12964.7 12993.2 12999.5 12999.7 19279.6 19524.4 19584.3 19610.0 19617.1 7017.0 7099.9 7250.0 7312.9 7364.7 1004.8 1143.3 1146.3 1147.8 1198.2 33822.7 34106.0 34119.0 34169.5 34222.1 1116.2 1134.3 1152.0 1185.3 1239.1 17404.6 17528.8 17589.0 17683.9 17712.0 499498.0 504642.0 505024.0 506033.0 508500.0 59566.4 61252.4 61260.0 61312.0 61313.7 1275.0 1365.2 1396.7 1399.5 1413.7 31399.3 33048.2 33079.5 33162.7 33331.3 51689.2 52130.5 52252.7 52297.0 52335.5 305791.0 312775.0 313406.0 313737.0 313901.0 26185.2 26686.6 27179.7 27609.7 29131.6 48964.3 49135.0 49544.8 50410.2 50870.5 40972.0 41339.2 41505.0 42172.8 43134.0 41779.6 42233.4 42459.4 42519.0 42522.8 41247.6 41799.0 41812.0 42121.6 42219.6 34991.3 37415.8 37500.8 37799.6 37902.6 28125.6 28262.9 28509.0 28572.7 28682.3 16:20:59 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | [#2] jquery.html Cycles:180 Average:27165.96 Median:43504.35 stddev:73577.16 (169.1%) stddev-sans-first:73756.08 16:20:59 INFO - PROCESS | 15046 | Values: 380.7 388.4 394.4 401.6 415.9 14356.6 15577.9 17878.0 18908.9 19144.6 14572.4 17622.4 17774.2 18302.5 18730.0 5576.6 5662.3 5767.8 5771.8 5774.6 4076.3 5545.8 5692.7 5757.4 6076.0 6299.7 6402.8 6416.7 6422.7 6425.7 6261.8 6327.5 6386.6 6453.0 6457.8 5579.5 5588.9 5591.1 5595.8 5597.9 13460.2 16194.5 20562.2 21294.7 21384.5 2172.2 2206.6 2208.4 2306.7 2518.4 10009.6 10068.8 10140.4 10521.9 10522.2 1796.2 1888.5 1930.3 1997.2 2075.2 80376.6 80502.2 81614.0 82012.6 82024.6 11018.6 11046.8 11187.9 11200.6 11352.9 15232.7 15386.3 15483.0 15503.7 15505.5 7330.7 7344.4 7752.0 8104.1 8207.3 7548.1 7559.7 7707.7 7801.2 7818.1 4501.0 4606.6 4623.1 4684.0 4770.6 1920.4 1927.9 1965.8 1992.7 2005.9 957.4 1036.4 1087.6 1125.5 1129.2 18495.6 18906.0 18933.0 19313.1 19422.6 942.6 997.6 1035.4 1092.8 1143.7 10146.5 10440.3 10503.5 10551.2 10552.8 426174.0 434059.0 451972.0 452141.0 454681.0 87558.6 93110.0 94950.5 95674.0 95880.0 954.0 998.2 1003.7 1029.3 1047.6 15094.0 15380.8 16129.8 16565.4 16836.8 42136.6 43434.3 43574.3 43703.4 44102.8 42101.0 52498.0 55504.7 57125.1 59064.7 8472.3 8625.7 8672.0 8714.6 8856.2 8804.2 8850.2 8895.6 9448.5 9751.0 8093.6 8274.4 8313.8 8416.0 8685.2 6949.6 8114.4 8265.1 8269.3 8362.5 8130.7 8640.3 8717.6 8812.0 9253.8 12837.2 12854.5 13196.9 13330.0 13415.7 11924.2 12020.3 12071.6 12102.1 12199.2 16:20:59 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | [#3] mootools.html Cycles:180 Average:6074.71 Median:2526.02 stddev:3522.03 (139.4%) stddev-sans-first:3513.24 16:20:59 INFO - PROCESS | 15046 | Values: 1249.5 1502.9 1526.2 1655.7 1804.6 8416.5 8780.3 8785.6 8993.6 8997.0 8759.9 8934.0 8962.1 8972.2 8972.9 2031.6 2297.5 2328.1 2371.1 2381.1 2425.9 2434.0 2434.2 2435.4 2438.4 2355.4 2362.5 2380.8 2381.4 2385.4 1999.9 2258.6 2288.0 2354.9 2355.6 2305.2 2314.3 2320.6 2322.7 2327.0 9131.0 9156.8 9157.6 9164.8 9169.5 5401.1 5517.4 5520.3 5532.2 5534.1 3430.7 3479.5 3496.8 3542.5 3568.3 1109.8 1131.5 1139.8 1152.2 1161.2 12953.7 13000.2 13005.7 13013.1 13024.8 4625.8 4743.1 4817.2 4825.2 4843.9 10802.5 10848.6 10855.3 10865.3 10886.7 5430.5 5492.4 5506.4 5510.1 5513.1 4339.8 4860.7 4982.3 5013.8 5065.2 4069.9 4082.4 4103.8 4162.3 4169.3 1269.6 1278.7 1297.5 1298.0 1316.8 1016.6 1019.5 1028.9 1032.6 1036.8 6277.5 6297.6 6350.7 6472.6 6493.8 458.3 463.4 463.7 466.8 471.0 4289.5 4489.0 4499.2 4560.7 4593.7 11514.6 11603.2 11682.8 11704.1 11742.5 10860.6 11434.1 11528.2 11546.2 11557.4 8501.4 8617.7 8672.3 8702.7 8707.6 6266.4 6594.4 6624.8 6632.5 6637.5 10515.6 10731.3 10842.1 10912.9 10915.9 10910.5 11012.5 11020.3 11021.9 11037.1 7460.9 7868.9 8091.9 8208.0 8339.7 9003.0 9010.1 9085.6 9113.2 9119.7 7945.2 8163.1 8213.8 8332.4 8379.0 8002.0 8064.5 8114.8 8119.2 8147.3 7090.6 7409.9 7410.0 7447.4 7525.1 5351.0 5365.8 5450.2 5466.6 5495.0 5460.6 5500.6 5527.3 5593.6 5765.0 16:20:59 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | [#4] prototype.html Cycles:180 Average:4646.49 Median:5226.95 stddev:1373.63 (26.3%) stddev-sans-first:1338.54 16:20:59 INFO - PROCESS | 15046 | Values: 319.7 329.3 339.7 340.9 342.2 5146.5 5284.4 5352.3 5365.4 5378.9 5291.6 5337.9 5341.8 5348.9 5352.4 4164.8 4187.8 4199.9 4200.7 4204.2 3958.8 3990.3 4180.9 4183.9 4209.0 4093.5 4112.8 4160.8 4166.2 4173.4 4155.4 4157.3 4163.5 4165.9 4184.9 3405.4 3694.4 3703.6 3715.7 3728.4 5091.4 5125.1 5133.1 5138.5 5169.4 4661.0 4682.5 4693.6 4693.7 4722.5 5435.6 5520.9 5561.1 5585.3 5593.3 3921.6 3947.4 3950.6 3951.6 3954.1 5682.8 5686.9 5742.5 5748.5 5819.1 3914.9 3985.5 4003.9 4012.0 4022.5 4033.2 4055.5 4061.4 4073.6 4115.9 4675.8 4689.0 4692.2 4707.4 4731.0 5576.2 5576.4 5585.3 5607.8 5609.6 1370.2 1412.2 1412.6 1417.0 1418.8 873.5 884.8 901.4 904.4 909.6 4786.4 4826.5 4841.9 4845.2 4884.4 4993.1 4996.7 5121.4 5132.5 5169.5 4818.7 4832.1 4847.5 4848.0 5061.3 3063.3 3065.7 3080.4 3081.0 3098.3 5871.5 5902.8 5911.0 5936.7 5952.0 5579.8 5743.9 5770.5 5791.8 5814.4 5762.5 5824.8 5843.0 5852.6 5900.4 5495.6 5684.5 5702.4 5736.5 5745.3 5720.7 5739.4 5748.8 5749.2 5750.8 5834.9 5955.7 5986.8 5995.4 6011.3 3704.7 3867.6 4063.3 4073.5 4090.0 5648.2 5710.7 5719.3 5775.8 5806.8 5567.1 5591.7 5620.6 5627.5 5645.8 5506.8 5529.9 5556.4 5606.2 5616.1 5520.5 5532.3 5548.7 5552.8 5554.0 5413.4 5665.0 5668.6 5672.2 5688.5 5546.4 5589.7 5599.1 5600.9 5600.9 16:20:59 INFO - PROCESS | 15046 | 16:20:59 INFO - PROCESS | 15046 | [#5] yui.html Cycles:180 Average:15629.54 Median:4719.81 stddev:18560.89 (393.3%) stddev-sans-first:18579.52 16:20:59 INFO - PROCESS | 15046 | Values: 792.1 796.2 803.9 842.0 870.6 14689.6 15206.6 15226.6 15570.5 15633.1 15792.8 15810.1 15835.4 15836.8 15844.8 817.1 827.9 828.9 830.0 830.9 701.0 820.9 827.4 844.1 848.0 874.9 875.9 879.1 879.5 880.3 870.9 872.5 874.3 874.3 874.7 533.1 623.4 637.7 640.3 640.8 13466.2 13501.7 13522.7 13537.5 13538.7 13033.7 13324.7 13381.3 13460.1 13564.5 10891.3 11172.2 11392.5 11528.2 11743.4 3730.7 3784.7 3788.2 3876.6 3900.8 60711.0 60737.0 61231.0 61857.0 62323.4 10755.1 10878.5 10881.3 10997.5 11000.4 19663.5 20703.3 20740.8 20767.5 20867.8 7993.9 8267.6 8296.2 8358.5 8362.7 4520.7 4568.8 4604.8 4721.8 4956.5 3364.9 3452.3 3461.8 3462.8 3463.8 652.0 652.7 656.1 667.5 677.9 727.4 736.3 742.9 753.6 759.9 9247.0 9319.5 9321.5 9499.2 9594.6 659.8 701.1 720.3 726.8 730.9 4438.6 4668.6 4717.9 4754.2 4768.3 47759.0 47805.0 47913.0 68728.3 69275.7 61231.4 62162.0 62324.4 62409.0 62766.0 55058.2 55556.0 55720.7 56279.7 57357.0 9104.6 9163.9 9211.7 9234.3 9295.6 38166.4 39036.8 39338.0 39347.8 39456.4 50277.7 51731.5 51994.2 52672.2 52724.0 11303.5 11339.5 11441.5 11549.8 11819.3 12495.2 12885.3 12922.4 13191.1 13245.7 11215.9 11549.1 12023.9 12359.1 12432.7 11879.3 12368.5 12488.5 12971.4 13301.3 12905.2 13178.2 13234.8 13684.8 13822.1 10931.7 11003.4 11012.2 11181.5 11310.0 10663.7 11299.9 11406.4 11512.3 11721.8 16:20:59 INFO - PROCESS | 15046 | -------- Summary: end -------- 16:20:59 INFO - PROCESS | 15046 | 16:21:00 INFO - TEST-INFO | 15046: exit 0 16:21:00 INFO - TEST-OK | dromaeo_css | took 1112706ms 16:21:00 INFO - TEST-START | kraken 16:21:00 INFO - operating with platform_type : linux_ 16:21:00 INFO - Initialising browser for kraken test... 16:21:00 INFO - TEST-INFO | started process 15227 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYG7_qh/profile http://localhost:36849/getInfo.html) 16:21:03 INFO - PROCESS | 15227 | __metrics Screen width/height:1600/1200 16:21:03 INFO - PROCESS | 15227 | colorDepth:24 16:21:03 INFO - PROCESS | 15227 | Browser inner width/height: 1024/697 16:21:03 INFO - PROCESS | 15227 | __metrics 16:21:05 ERROR - PROCESS | 15227 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:21:05 INFO - TEST-INFO | 15227: exit 0 16:21:05 INFO - Browser initialized. 16:21:05 INFO - Running cycle 1/1 for kraken test... 16:21:05 INFO - TEST-INFO | started process 15307 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYG7_qh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:17 INFO - PROCESS | 15307 | RSS: Main: 152567808 16:21:17 INFO - PROCESS | 15307 | 16:21:20 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36849/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:20 INFO - PROCESS | 15307 | RSS: Main: 209534976 16:21:20 INFO - PROCESS | 15307 | 16:21:23 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36849/tests/kraken/driver.html?testName=audio-dft) 16:21:24 INFO - PROCESS | 15307 | RSS: Main: 198299648 16:21:24 INFO - PROCESS | 15307 | 16:21:27 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36849/tests/kraken/driver.html?testName=audio-fft) 16:21:28 INFO - PROCESS | 15307 | RSS: Main: 265818112 16:21:28 INFO - PROCESS | 15307 | 16:21:30 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36849/tests/kraken/driver.html?testName=audio-oscillator) 16:21:30 INFO - PROCESS | 15307 | RSS: Main: 175194112 16:21:30 INFO - PROCESS | 15307 | 16:21:32 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36849/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:33 INFO - PROCESS | 15307 | RSS: Main: 179232768 16:21:33 INFO - PROCESS | 15307 | 16:21:36 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36849/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:37 INFO - PROCESS | 15307 | RSS: Main: 215326720 16:21:37 INFO - PROCESS | 15307 | 16:21:40 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36849/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:41 INFO - PROCESS | 15307 | RSS: Main: 215822336 16:21:41 INFO - PROCESS | 15307 | 16:21:43 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36849/tests/kraken/driver.html?testName=json-parse-financial) 16:21:44 INFO - PROCESS | 15307 | RSS: Main: 217100288 16:21:44 INFO - PROCESS | 15307 | 16:21:45 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36849/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:46 INFO - PROCESS | 15307 | RSS: Main: 204492800 16:21:46 INFO - PROCESS | 15307 | 16:21:48 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36849/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:48 INFO - PROCESS | 15307 | RSS: Main: 208953344 16:21:48 INFO - PROCESS | 15307 | 16:21:50 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36849/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:51 INFO - PROCESS | 15307 | RSS: Main: 218533888 16:21:51 INFO - PROCESS | 15307 | 16:21:54 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36849/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:54 INFO - PROCESS | 15307 | RSS: Main: 223502336 16:21:54 INFO - PROCESS | 15307 | 16:21:57 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36849/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:58 INFO - PROCESS | 15307 | RSS: Main: 221028352 16:21:58 INFO - PROCESS | 15307 | 16:21:59 INFO - PROCESS | 15307 | Cycle 1(1): loaded http://localhost:36849/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36849/tests/kraken/driver.html?testName=ai-astar) 16:22:00 INFO - PROCESS | 15307 | RSS: Main: 266485760 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | __start_tp_report 16:22:00 INFO - PROCESS | 15307 | _x_x_mozilla_page_load 16:22:00 INFO - PROCESS | 15307 | _x_x_mozilla_page_load_details 16:22:00 INFO - PROCESS | 15307 | |i|pagename|runs| 16:22:00 INFO - PROCESS | 15307 | |0;ai-astar;112;104;109;107;110;108;109;109;109;109 16:22:00 INFO - PROCESS | 15307 | |1;audio-beat-detection;163;125;131;183;130;127;150;126;164;125 16:22:00 INFO - PROCESS | 15307 | |2;audio-dft;161;159;160;161;161;160;159;162;162;161 16:22:00 INFO - PROCESS | 15307 | |3;audio-fft;73;74;75;121;74;76;74;74;100;75 16:22:00 INFO - PROCESS | 15307 | |4;audio-oscillator;83;83;127;83;83;83;121;83;84;127 16:22:00 INFO - PROCESS | 15307 | |5;imaging-gaussian-blur;117;117;117;118;117;117;118;117;117;117 16:22:00 INFO - PROCESS | 15307 | |6;imaging-darkroom;145;145;145;145;145;145;146;145;145;146 16:22:00 INFO - PROCESS | 15307 | |7;imaging-desaturate;95;96;95;95;95;94;95;95;95;95 16:22:00 INFO - PROCESS | 15307 | |8;json-parse-financial;55;55;56;55;54;55;54;70;53;53 16:22:00 INFO - PROCESS | 15307 | |9;json-stringify-tinderbox;61;62;62;62;63;63;62;62;62;62 16:22:00 INFO - PROCESS | 15307 | |10;stanford-crypto-aes;81;85;80;79;80;81;82;80;86;80 16:22:00 INFO - PROCESS | 15307 | |11;stanford-crypto-ccm;133;130;129;125;119;119;122;127;172;125 16:22:00 INFO - PROCESS | 15307 | |12;stanford-crypto-pbkdf2;167;164;167;163;164;164;160;159;161;160 16:22:00 INFO - PROCESS | 15307 | |13;stanford-crypto-sha256-iterative;65;62;63;63;62;63;67;62;65;61 16:22:00 INFO - PROCESS | 15307 | __end_tp_report 16:22:00 INFO - PROCESS | 15307 | __start_cc_report 16:22:00 INFO - PROCESS | 15307 | _x_x_mozilla_cycle_collect,1655 16:22:00 INFO - PROCESS | 15307 | __end_cc_report 16:22:00 INFO - PROCESS | 15307 | __startTimestamp1464132120051__endTimestamp 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | ------- Summary: start ------- 16:22:00 INFO - PROCESS | 15307 | Number of tests: 14 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#0] ai-astar Cycles:10 Average:108.60 Median:109.00 stddev:2.07 (1.9%) stddev-sans-first:1.79 16:22:00 INFO - PROCESS | 15307 | Values: 112.0 104.0 109.0 107.0 110.0 108.0 109.0 109.0 109.0 109.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:140.50 stddev:21.06 (15.0%) stddev-sans-first:20.98 16:22:00 INFO - PROCESS | 15307 | Values: 163.0 125.0 131.0 183.0 130.0 127.0 150.0 126.0 164.0 125.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 16:22:00 INFO - PROCESS | 15307 | Values: 161.0 159.0 160.0 161.0 161.0 160.0 159.0 162.0 162.0 161.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#3] audio-fft Cycles:10 Average:81.60 Median:74.00 stddev:16.04 (21.7%) stddev-sans-first:16.70 16:22:00 INFO - PROCESS | 15307 | Values: 73.0 74.0 75.0 121.0 74.0 76.0 74.0 74.0 100.0 75.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:83.00 stddev:20.29 (24.4%) stddev-sans-first:20.99 16:22:00 INFO - PROCESS | 15307 | Values: 83.0 83.0 127.0 83.0 83.0 83.0 121.0 83.0 84.0 127.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:22:00 INFO - PROCESS | 15307 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 16:22:00 INFO - PROCESS | 15307 | Values: 145.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 16:22:00 INFO - PROCESS | 15307 | Values: 95.0 96.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:55.00 stddev:5.01 (9.1%) stddev-sans-first:5.30 16:22:00 INFO - PROCESS | 15307 | Values: 55.0 55.0 56.0 55.0 54.0 55.0 54.0 70.0 53.0 53.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 16:22:00 INFO - PROCESS | 15307 | Values: 61.0 62.0 62.0 62.0 63.0 63.0 62.0 62.0 62.0 62.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:2.32 (2.9%) stddev-sans-first:2.46 16:22:00 INFO - PROCESS | 15307 | Values: 81.0 85.0 80.0 79.0 80.0 81.0 82.0 80.0 86.0 80.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#11] stanford-crypto-ccm Cycles:10 Average:130.10 Median:128.00 stddev:15.42 (12.0%) stddev-sans-first:16.32 16:22:00 INFO - PROCESS | 15307 | Values: 133.0 130.0 129.0 125.0 119.0 119.0 122.0 127.0 172.0 125.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.90 Median:164.00 stddev:2.85 (1.7%) stddev-sans-first:2.60 16:22:00 INFO - PROCESS | 15307 | Values: 167.0 164.0 167.0 163.0 164.0 164.0 160.0 159.0 161.0 160.0 16:22:00 INFO - PROCESS | 15307 | 16:22:00 INFO - PROCESS | 15307 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:1.83 (2.9%) stddev-sans-first:1.83 16:22:00 INFO - PROCESS | 15307 | Values: 65.0 62.0 63.0 63.0 62.0 63.0 67.0 62.0 65.0 61.0 16:22:00 INFO - PROCESS | 15307 | -------- Summary: end -------- 16:22:00 INFO - PROCESS | 15307 | 16:22:01 INFO - TEST-INFO | 15307: exit 0 16:22:01 INFO - TEST-OK | kraken | took 61072ms 16:22:01 INFO - SUITE-END | took 1173s 16:22:01 INFO - Completed test suite (00:19:33) 16:22:01 INFO - javascript benchmark 16:22:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3125.046860707484, 3133.890313839016, 3157.6526582075967, 3171.0962375722925, 3198.2963747975436, 5326.0, 5329.493698098684, 5382.784688612451, 5467.644603100795, 5512.80705175319, 5358.517733403993, 5415.296011518497, 5432.6468779529305, 5469.480399362064, 5496.862578435531, 4805.255185446262, 4898.510148985109, 4950.727709975949, 4955.554000140005, 5158.40678321994, 5051.974740126313, 5071.518205770446, 5098.617603679721, 5129.204973229155, 5129.615278854082, 4843.297721830332, 4880.926786098212, 4926.310316555685, 4927.531884470969, 4934.1611925972675, 4930.359053323081, 4942.901141977176, 4987.0524600325825, 4996.500349965011, 5019.272205530177, 4671.135839869635, 4963.900721985576, 4977.875553111201, 5001.3998320201445, 5048.949510504922, 5301.6818990860675, 5361.115415956362, 5376.569874410038, 5434.592405569598, 5454.618176727591, 5773.624714393551, 5817.854553636151, 5854.590178687494, 5864.325602555676, 5866.677332746697, 5769.971150144264, 5788.0739081746715, 5884.117382392633, 5904.0, 5947.762089516415, 5382.838514844561, 5485.451454854483, 5513.917291240595, 5524.281843360337, 5534.225208470817, 6010.459058684652, 6053.697315134225, 6073.0283154695035, 6075.453209211192, 6080.75676972925, 5574.609777315549, 5585.692786896679, 5594.552435805126, 5815.447532484407, 5828.825135245949, 5750.079987202112, 5751.683657398883, 5753.597248192586, 5758.712064396764, 5790.392008839095, 5689.089745640677, 5707.914381284284, 5733.8566535836935, 5733.8566535836935, 5733.971330143406, 5690.459406356389, 5728.79949201776, 5755.079187330007, 5800.622959507703, 5825.155352473952, 4413.757243351646, 4418.558144185556, 4422.09347083847, 4430.357598148251, 4452.30989196675, 3943.783091929914, 3962.385830196324, 3970.50368703912, 3971.0866500705, 4002.979985100114, 5349.893002139936, 5417.404085550586, 5498.5876059295715, 5566.387697353288, 5574.9721251393485, 5928.140419639129, 5938.673372964442, 5956.761729530857, 5956.851078723066, 6000.579959402888, 5590.944090559043, 5593.0, 5615.354234263031, 5615.775368985195, 5616.438356164351, 4726.983698504838, 4863.683860549053, 4915.992221594564, 4924.507549245119, 4942.9258561121615, 6092.603980741238, 6098.847528811815, 6100.267967843887, 6109.633421994606, 6118.357572454828, 5699.7435115419075, 5860.882782344329, 5877.089051197071, 5893.116032595145, 5893.381194974466, 5985.281766188085, 6004.369541198112, 6072.089186622042, 6083.6349819011, 6103.115048318058, 5739.196512488172, 5786.913196302143, 5790.363060063305, 5791.160281759206, 5793.565482588739, 5690.77236910519, 5822.679752613647, 5892.410758924073, 5893.027650437802, 5906.379830117776, 5705.4579814917515, 5769.884602308014, 5777.22007528995, 5790.971045144747, 5838.095095260242, 5704.857378565403, 5719.857003574777, 5734.59857809966, 5748.45389687979, 5749.080147176432, 5478.753456094565, 5559.694216817952, 5602.0196465619165, 5666.291713535808, 5691.317041954826, 5756.395578464201, 5783.710814459176, 5795.0148474760235, 5797.043487824461, 5801.82594522165, 5580.693061881636, 5612.130119831432, 5618.185363122406, 5621.494065533958, 5667.688277144796], "value": 5376.869854717876, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.528488996578, 602.5781952633158, 602.8975074237379, 604.0637012630425, 612.9754809807608, 34710.30579388414, 37484.25031499372, 37513.8124309378, 37646.811765941166, 42876.57123428765, 41086.38370424446, 45380.77309613456, 45637.0, 45670.314945275844, 45705.31442028372, 8622.137786221374, 8834.55827208637, 8855.601497932608, 8857.512836793974, 8870.024297327289, 8739.475631462132, 8882.600282987281, 8895.466272023667, 8898.37711360205, 8904.154105359981, 8898.822023559525, 8906.109389061106, 8948.06045365237, 8973.192412682858, 8988.77528061797, 8845.469271843709, 8851.911480885205, 8862.734117976472, 8878.86681699775, 8894.155055269805, 8682.435641683334, 8774.736757897273, 8799.252063574604, 8807.559622018873, 8809.03100658927, 42219.36670949938, 42519.787401063106, 42679.35980960288, 42709.0, 42727.786361068305, 3566.982165089184, 3695.334839728848, 3699.3341198584244, 3746.9437958430644, 4052.6555242804397, 31997.52003719946, 32425.83787081073, 32472.837635811906, 33343.0, 34098.249192588795, 4111.629953304187, 4146.771927543984, 4148.771817550033, 4171.853985110507, 4408.162449134654, 254427.0, 254579.0, 255233.0, 255613.0, 356187.2190639056, 62222.0, 62483.687581561804, 62929.370706293266, 62960.0, 62983.0, 35566.28867422689, 36681.0, 66305.33694663088, 66527.33472665308, 66620.33379666238, 16976.57558561046, 17216.91391543034, 17300.65398692019, 17302.394416195624, 17306.22122004513, 12824.487020519075, 12964.67588310281, 12993.220406775623, 12999.545015924587, 12999.740005200032, 19279.64314599174, 19524.395529935733, 19584.314548991002, 19610.01949902499, 19617.117229724696, 7017.0, 7099.858002839915, 7249.963750181215, 7312.890306645405, 7364.668589913466, 1004.7839714461426, 1143.3025854228913, 1146.277844957688, 1147.8278258261328, 1198.1972078707288, 33822.661773382446, 34106.0, 34119.0, 34169.487457688156, 34222.14444638904, 1116.2019156303281, 1134.2740645986576, 1151.9827202591969, 1185.3065956415594, 1239.0954603139758, 17404.564885877702, 17528.82471175272, 17589.0, 17683.911580442014, 17712.026451021873, 499498.0, 504642.0, 505024.0, 506033.0, 508500.0, 59566.404335956955, 61252.38747612467, 61260.0, 61312.0, 61313.693431532556, 1275.043717212091, 1365.2013572060455, 1396.6857457072078, 1399.5381524097227, 1413.7234864534864, 31399.341286540428, 33048.17379565434, 33079.50380744242, 33162.66837331693, 33331.333373332884, 51689.224661630855, 52130.478695212565, 52252.7387363076, 52297.0, 52335.47664523306, 305791.0, 312775.0, 313406.0, 313737.0, 313901.02692811284, 26185.214443566725, 26686.59970100489, 27179.72820271772, 27609.723902760714, 29131.563026554195, 48964.2655360177, 49135.03459861565, 49544.75227623839, 50410.243846343066, 50870.49129508805, 40972.0, 41339.1732165361, 41505.0, 42172.789136054125, 43134.0, 41779.58220417757, 42233.36649950314, 42459.363109554, 42519.0, 42522.787386062875, 41247.58752412558, 41799.0, 41812.0, 42121.578784211764, 42219.57780422157, 34991.30017399587, 37415.81292093522, 37500.812495937345, 37799.62200377961, 37902.62097378991, 28125.57811632868, 28262.85868570644, 28509.0, 28572.714272857007, 28682.282942926595], "value": 19993.904589273665, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.7410960546829, 388.4425848906817, 394.4202023026152, 401.60642570281124, 415.8523724077952, 14356.569302920903, 15577.922110389432, 17877.999999999985, 18908.90545547272, 19144.61710765784, 14572.417103315855, 17622.383216587423, 17774.200160992754, 18302.450926472226, 18730.0, 5576.553875689935, 5662.263905692254, 5767.798127065554, 5771.826845194651, 5774.624649397797, 4076.347784354504, 5545.805896793613, 5692.658440493584, 5757.366689664133, 6076.0, 6299.748010079591, 6402.775902843378, 6416.711247993851, 6422.743090276384, 6425.710843012076, 6261.812145635638, 6327.462165715897, 6386.64873431961, 6452.967735161317, 6457.806265812033, 5579.469950354751, 5588.86027849307, 5591.10542313228, 5595.804146854841, 5597.888042239132, 13460.192388456626, 16194.514164575081, 20562.177512899616, 21294.680579791315, 21384.46538836526, 2172.1745736619982, 2206.646936490154, 2208.381653137124, 2306.688597039395, 2518.445941892781, 10009.649662261858, 10068.848967265498, 10140.442275674835, 10521.894781052168, 10522.158227341793, 1796.173760070369, 1888.4901076709211, 1930.2954421636002, 1997.1911375892641, 2075.201047596671, 80376.59811700962, 80502.19497805006, 81614.0, 82012.58993705054, 82024.58987705084, 11018.614348497764, 11046.834297485544, 11187.888121118847, 11200.551977920955, 11352.886471135349, 15232.695346093016, 15386.307616157299, 15483.0, 15503.689926201638, 15505.534833955, 7330.743423980135, 7344.449166312548, 7751.961240193763, 8104.0680321762575, 8207.261346478846, 7548.094228692482, 7559.697612095454, 7707.653155607899, 7801.2198780121535, 7818.061832580127, 4500.954990450119, 4606.585407313358, 4623.098495793288, 4683.976580117078, 4770.642201834877, 1920.4142736465512, 1927.88432694036, 1965.8034196580227, 1992.681171012631, 2005.8896760678304, 957.3585697582637, 1036.43514284715, 1087.5535537734793, 1125.5441546173813, 1129.2095533126844, 18495.630087398178, 18906.0, 18933.0, 19313.130909109124, 19422.611547769247, 942.6370847223868, 997.576030187182, 1035.3649960849655, 1092.7650555130526, 1143.74837535742, 10146.543405546769, 10440.269181157246, 10503.52734126966, 10551.20865935058, 10552.788944221073, 426174.0, 434059.0, 451972.0, 452141.0, 454681.0, 87558.56220718983, 93110.0, 94950.52524737333, 95674.0, 95880.0, 953.9809203815885, 998.2313618513704, 1003.6988903328972, 1029.3206483720708, 1047.638564695179, 15094.0, 15380.846191538389, 16129.75805362897, 16565.42021029258, 16836.77292687677, 42136.57863421449, 43434.348484773385, 43574.34638480362, 43703.377797332265, 44102.779486102365, 42101.0, 52498.0, 55504.72247638736, 57125.14312285236, 59064.70467647634, 8472.279856212044, 8625.741227763177, 8671.956640216758, 8714.607842646968, 8856.202941735275, 8804.207621314112, 8850.247728943112, 8895.555222238732, 9448.527573621428, 9751.024897510193, 8093.635786389509, 8274.379421543288, 8313.750587482386, 8416.0, 8685.218330350299, 6949.617771022642, 8114.350851931713, 8265.132161123236, 8269.255766981001, 8362.456440331602, 8130.67477300925, 8640.308775298086, 8717.564121794012, 8811.955940220258, 9253.768655783659, 12837.229766214057, 12854.485820567073, 13196.868031319564, 13330.0, 13415.664608384479, 11924.165308428674, 12020.278783272683, 12071.57749478764, 12102.092343074128, 12199.207051541622], "value": 8870.862574910318, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.518935209945, 1502.9473968411105, 1526.1834918318698, 1655.6688662267543, 1804.557883318587, 8416.45293055951, 8780.297576193905, 8785.64857405703, 8993.550322483867, 8997.0, 8759.868601970975, 8934.0, 8962.058983806704, 8972.192502674758, 8972.923249210104, 2031.6038372517291, 2297.517521320525, 2328.1153161798534, 2371.087131454385, 2381.1070848431837, 2425.920465392901, 2433.987830060856, 2434.160214725917, 2435.415500279938, 2438.3782135555407, 2355.4346956730433, 2362.5393048355577, 2380.7619238076227, 2381.3808409813487, 2385.439421735891, 1999.8700084494535, 2258.5821622999715, 2287.9542409151722, 2354.917577884783, 2355.5759963206615, 2305.1586171047616, 2314.328844635054, 2320.6286994080865, 2322.6864373309727, 2327.0459111764267, 9131.0, 9156.771080722969, 9157.633694652273, 9164.770880727969, 9169.495677737721, 5401.054815407328, 5517.36550296717, 5520.309961254843, 5532.225488431623, 5534.142207957773, 3430.7255419566377, 3479.495473156378, 3496.807675577842, 3542.5040494330815, 3568.2506673598577, 1109.8224284114583, 1131.4795194210674, 1139.7606502634458, 1152.1589239854952, 1161.2451906260912, 12953.676158096028, 13000.219986800823, 13005.739885202338, 13013.08908376414, 13024.804627930589, 4625.814967401334, 4743.075100355398, 4817.205161148441, 4825.227963525819, 4843.878903027453, 10802.459877006118, 10848.566057357775, 10855.294405863593, 10865.348079115278, 10886.67339979802, 5430.484104010113, 5492.368377636543, 5506.421825708243, 5510.118381059092, 5513.145462453326, 4339.848105316299, 4860.73265970375, 4982.277569752366, 5013.849584512471, 5065.240213967934, 4069.9389509157386, 4082.408050832613, 4103.753774773523, 4162.313218319004, 4169.332906734908, 1269.6381531263546, 1278.6931136527166, 1297.4680381043727, 1297.9675508112373, 1316.7629826631107, 1016.5628779624832, 1019.5004447820514, 1028.922830787694, 1032.5611615063567, 1036.8185567525652, 6277.52918531112, 6297.622142671455, 6350.714217860207, 6472.611643301417, 6493.837654058591, 458.3354136502085, 463.4369241371682, 463.7403054289609, 466.80627539570696, 470.96703230774204, 4289.463817022872, 4488.977555112204, 4499.212637788374, 4560.68075234737, 4593.701409408444, 11514.596989105341, 11603.245789023855, 11682.766344673058, 11704.122190835722, 11742.471588778355, 10860.565577376816, 11434.085273178292, 11528.193026488234, 11546.191766576427, 11557.422128893522, 8501.362397820189, 8617.655293788179, 8672.26285765717, 8702.738917832476, 8707.608157632922, 6266.404691554295, 6594.373534514213, 6624.8343791405605, 6632.502562307943, 6637.53537252407, 10515.631952881919, 10731.302465339728, 10842.078423334106, 10912.890871091187, 10915.890841091805, 10910.509027093958, 11012.5044373005, 11020.338779673246, 11021.944890275498, 11037.11703063769, 7460.925390746023, 7868.881966770388, 8091.919080809116, 8207.95896020516, 8339.708110216114, 9003.009668936542, 9010.14403631654, 9085.636574536944, 9113.225375843127, 9119.726408207764, 7945.1657575953695, 8163.1428699985645, 8213.753587392388, 8332.375071869756, 8378.958105209435, 8002.0, 8064.516129032277, 8114.756557303291, 8119.228673276029, 8147.307478864363, 7090.574565526085, 7409.888851667121, 7410.0, 7447.366973807287, 7525.059367579052, 5350.9732451337495, 5365.812196573102, 5450.155225939986, 5466.5900057495055, 5495.038368285614, 5460.645058071213, 5500.559955203654, 5527.309086364205, 5593.608447408642, 5764.971175144097], "value": 4737.9825101109545, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.6883039036938, 329.3002369963827, 339.69766907452356, 340.86195090988144, 342.2436415521697, 5146.485351464851, 5284.392294886089, 5352.330958630171, 5365.382980957191, 5378.89242215156, 5291.550218231455, 5337.919931201035, 5341.759620817063, 5348.893022139555, 5352.357717073956, 4164.833406663737, 4187.81154848031, 4199.874003779876, 4200.726952748077, 4204.2432362174795, 3958.762474251554, 3990.261801566705, 4180.912962629723, 4183.895402614929, 4208.97895510522, 4093.5497095319492, 4112.773797441139, 4160.812763425653, 4166.166766646667, 4173.353130264813, 4155.355919832431, 4157.253953339575, 4163.521195062576, 4165.895852603679, 4184.895377615553, 3405.404054290514, 3694.390425579776, 3703.6296370363016, 3715.6841668458214, 3728.384816505273, 5091.439941606421, 5125.103106956305, 5133.101707201262, 5138.511841375063, 5169.405518365398, 4660.953390466086, 4682.531746825324, 4693.6479764017495, 4693.694909830884, 4722.504137065627, 5435.565154787608, 5520.861978450531, 5561.138023606348, 5585.268566744322, 5593.266087512884, 3921.627445392683, 3947.4473573699706, 3950.5654378018394, 3951.6169340730517, 3954.121792148735, 5682.801101961411, 5686.886262274731, 5742.506948193458, 5748.482636562688, 5819.127130930352, 3914.9021274468364, 3985.5217373915316, 4003.8598649047144, 4012.0, 4022.517297924268, 4033.213523362916, 4055.5058638936844, 4061.411095391152, 4073.551909289976, 4115.88871004827, 4675.755637294118, 4689.0, 4692.202325604655, 4707.435107787088, 4730.9763451182525, 5576.247206627135, 5576.386597474275, 5585.301837270341, 5607.80372686954, 5609.635373700697, 1370.2189751841554, 1412.1668215752754, 1412.5903487988573, 1416.964575885611, 1418.8439271680106, 873.4715497124275, 884.787650963764, 901.378049146087, 904.3533873280504, 909.6134142989204, 4786.425628924551, 4826.517348265145, 4841.8547443576745, 4845.200541910615, 4884.389451318585, 4993.127577158565, 4996.6502344836235, 5121.3854337479, 5132.461091585405, 5169.508896654812, 4818.68678535894, 4832.081904438145, 4847.5394837490385, 4847.95152048482, 5061.291419201315, 3063.3107550801337, 3065.708757668018, 3080.430120427728, 3081.0, 3098.3338582204483, 5871.500922421556, 5902.786455079346, 5910.99513082777, 5936.673482958478, 5951.9702401488585, 5579.832605021856, 5743.8564035899435, 5770.5088421505225, 5791.7683292667825, 5814.360420353758, 5762.481376676203, 5824.825255242349, 5842.9908597072545, 5852.6195797273895, 5900.380460051719, 5495.642783219078, 5684.545236381162, 5702.37273899879, 5736.541076713769, 5745.2818397700285, 5720.656760594211, 5739.368669446274, 5748.827535173951, 5749.1663708762835, 5750.769969201185, 5834.94165058344, 5955.702214889152, 5986.790462333972, 5995.370486098896, 6011.308699499614, 3704.685101766272, 3867.555231148454, 4063.2889244382704, 4073.51117865858, 4089.9591004090767, 5648.164346587293, 5710.65736055838, 5719.342275638355, 5775.797847075332, 5806.82579522615, 5567.135009771772, 5591.748371323219, 5620.634658747168, 5627.549796016391, 5645.774169033192, 5506.752196151103, 5529.917051244315, 5556.38879723222, 5606.187102870143, 5616.129499927518, 5520.475554822286, 5532.253145825262, 5548.667079975215, 5552.7778888845605, 5554.0, 5413.431589683027, 5665.0, 5668.631538949955, 5672.234248376416, 5688.488036076773, 5546.389897111234, 5589.720513974366, 5599.132134519156, 5600.85997850057, 5600.943990560042], "value": 4207.344301627459, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.0772300270186, 796.1759578835906, 803.8995125609297, 841.957902104895, 870.6125774030551, 14689.632759181013, 15206.619834504128, 15226.619334516628, 15570.53288401347, 15633.140177290245, 15792.84207157928, 15810.051396916193, 15835.366585336573, 15836.84163158368, 15844.76232856508, 817.0889458254063, 827.8716798896181, 828.9378296627747, 830.0205757206494, 830.9210624990616, 700.989485157723, 820.8522465956155, 827.4042689263721, 844.0757370679097, 847.995760021199, 874.8687696845492, 875.8686197070426, 879.0901417033372, 879.5074758135464, 880.2737741363369, 870.9303255739526, 872.5157537566648, 874.2743522876061, 874.3486102853383, 874.6851133591933, 533.0777754484724, 623.3953065526432, 637.7417146055855, 640.3404493371835, 640.826976716284, 13466.192028478225, 13501.662458438519, 13522.661933451644, 13537.52618658352, 13538.661533461644, 13033.674158146123, 13324.666883327898, 13381.330933453288, 13460.05779595419, 13564.45742170312, 10891.292066015763, 11172.162087843362, 11392.480664138317, 11528.193026488148, 11743.412829358498, 3730.7015438764834, 3784.6593806557544, 3788.1855401088624, 3876.573576906538, 3900.7854567998747, 60711.0, 60737.0, 61231.0, 61857.0, 62323.376766232665, 10755.139588832875, 10878.456077196108, 10881.292715973595, 10997.505112269968, 11000.449977501094, 19663.50841228952, 20703.27538536174, 20740.792592074187, 20767.480812979797, 20867.791322086887, 7993.9200607994335, 8267.586620668942, 8296.170382961776, 8358.540280284642, 8362.749117526484, 4520.728756274634, 4568.794404251817, 4604.838830640979, 4721.763911804396, 4956.529129732669, 3364.9327013460083, 3452.2750222453064, 3461.7922924624604, 3462.79223246606, 3463.7748546344405, 652.0480099055337, 652.7030201258416, 656.0520048529828, 667.4893706314673, 677.9118714567074, 727.421699748701, 736.3446532586003, 742.9442791790638, 753.6231884057971, 759.9468037237451, 9246.953765231265, 9319.487428191514, 9321.533923303808, 9499.24006079512, 9594.616215351447, 659.7855696898528, 701.1165930927068, 720.3372896934833, 726.7674344209908, 730.9049823523014, 4438.60052595268, 4668.649851261169, 4717.858464246078, 4754.215554433549, 4768.332433459318, 47759.0, 47805.04389912252, 47913.0, 68728.31271687319, 69275.65362173157, 61231.387686122565, 62162.0, 62324.37675623186, 62409.0, 62766.0, 55058.17412738812, 55556.0, 55720.72139639276, 56279.71860140755, 57357.0, 9104.635814567344, 9163.908360916306, 9211.723648290563, 9234.261259099121, 9295.581698823435, 38166.42750358803, 39036.80481597574, 39338.0, 39347.80326098351, 39456.40815387829, 50277.74861125671, 51731.48268517267, 51994.22008669949, 52672.20991685204, 52724.0, 11303.491342889425, 11339.546418143513, 11441.48513316886, 11549.769004619693, 11819.349935753617, 12495.229031282843, 12885.291308978096, 12922.418491168108, 13191.142575732547, 13245.735085298433, 11215.943920280346, 11549.076073913899, 12023.879761202275, 12359.134860559674, 12432.689182770142, 11879.287242765462, 12368.505259789868, 12488.500459981862, 12971.35143242853, 13301.334933253493, 12905.225686458842, 13178.193881017474, 13234.801477978031, 13684.794728078889, 13822.101563398346, 10931.672049838518, 11003.394813285346, 11012.17408694335, 11181.496832642713, 11310.0, 10663.733406664585, 11299.887001129884, 11406.429678516208, 11512.251703639236, 11721.824172637247], "value": 6933.8500655196, "unit": "score"}], "name": "dromaeo_css", "value": 7134.038364852086, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 109.0, 107.0, 110.0, 108.0, 109.0, 109.0, 109.0, 109.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 125.0, 131.0, 183.0, 130.0, 127.0, 150.0, 126.0, 164.0, 125.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 161.0, 161.0, 160.0, 159.0, 162.0, 162.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 121.0, 74.0, 76.0, 74.0, 74.0, 100.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 127.0, 83.0, 83.0, 83.0, 121.0, 83.0, 84.0, 127.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 55.0, 54.0, 55.0, 54.0, 70.0, 53.0, 53.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 63.0, 63.0, 62.0, 62.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 80.0, 79.0, 80.0, 81.0, 82.0, 80.0, 86.0, 80.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 130.0, 129.0, 125.0, 119.0, 119.0, 122.0, 127.0, 172.0, 125.0], "value": 130.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 167.0, 163.0, 164.0, 164.0, 160.0, 159.0, 161.0, 160.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 63.0, 63.0, 62.0, 63.0, 67.0, 62.0, 65.0, 61.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1502.1000000000001, "alertThreshold": 2.0}]} 16:22:01 INFO - Return code: 0 16:22:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:22:01 ERROR - # TBPL SUCCESS # 16:22:01 INFO - Running post-action listener: _resource_record_post_action 16:22:01 INFO - ##### 16:22:01 INFO - ##### Finished run-tests step (success) 16:22:01 INFO - ##### 16:22:01 INFO - Running post-run listener: _resource_record_post_run 16:22:01 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 548192256; Read time: 80; Write time: 574636 16:22:01 INFO - TinderboxPrint: CPU usage
13.2% 16:22:01 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 80 16:22:01 INFO - TinderboxPrint: I/O write bytes / time
548,192,256 / 574,636 16:22:01 INFO - TinderboxPrint: CPU idle
8,261.3 (86.8%) 16:22:01 INFO - TinderboxPrint: CPU user
1,208.1 (12.7%) 16:22:01 INFO - TinderboxPrint: Swap in / out
0 / 0 16:22:01 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 256806912; Read time: 0; Write time: 359360 16:22:01 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 289939456; Read time: 80; Write time: 215212 16:22:01 INFO - Running post-run listener: _upload_blobber_files 16:22:01 INFO - Blob upload gear active. 16:22:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:22:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:22:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:22:02 INFO - (blobuploader) - INFO - Open directory for files ... 16:22:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:22:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:22:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:03 INFO - (blobuploader) - INFO - Done attempting. 16:22:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:22:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:04 INFO - (blobuploader) - INFO - Done attempting. 16:22:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:22:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:22:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:08 INFO - (blobuploader) - INFO - Done attempting. 16:22:08 INFO - (blobuploader) - INFO - Iteration through files over. 16:22:08 INFO - Return code: 0 16:22:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:22:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:22:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35e896c374b354771634e7ea629275bc9aacdce222609dd30846b760d56151647af0f2dca7552592726962e9d0f8ba47e47359229a18216ed5586a6f8e49d936", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e6fb8cb59e508d32eb5706fa10cb39d0b852dd9d88833bf26c1ae9c7ebcd9dbfa4573b5ed0abd16b6c7bf7935306c11b2a2dc8160056cc33d93d7e29464f92e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e41a54a9b7defd83a4828b173ba8795e653d445cab3f499c5ef83b6f6842bd8e851b600ad043895e6c44a55a8a81466ae7743d5bb50f3662c4d774ede209101"} 16:22:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:22:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:22:08 INFO - Contents: 16:22:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35e896c374b354771634e7ea629275bc9aacdce222609dd30846b760d56151647af0f2dca7552592726962e9d0f8ba47e47359229a18216ed5586a6f8e49d936", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e6fb8cb59e508d32eb5706fa10cb39d0b852dd9d88833bf26c1ae9c7ebcd9dbfa4573b5ed0abd16b6c7bf7935306c11b2a2dc8160056cc33d93d7e29464f92e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e41a54a9b7defd83a4828b173ba8795e653d445cab3f499c5ef83b6f6842bd8e851b600ad043895e6c44a55a8a81466ae7743d5bb50f3662c4d774ede209101"} 16:22:08 INFO - Running post-run listener: copy_logs_to_upload_dir 16:22:08 INFO - Copying logs to upload dir... 16:22:08 INFO - mkdir: /builds/slave/test/build/upload/logs 16:22:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.947594 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-05-24 16:22:07.882175) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 16:22:07.886218) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35e896c374b354771634e7ea629275bc9aacdce222609dd30846b760d56151647af0f2dca7552592726962e9d0f8ba47e47359229a18216ed5586a6f8e49d936", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e6fb8cb59e508d32eb5706fa10cb39d0b852dd9d88833bf26c1ae9c7ebcd9dbfa4573b5ed0abd16b6c7bf7935306c11b2a2dc8160056cc33d93d7e29464f92e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e41a54a9b7defd83a4828b173ba8795e653d445cab3f499c5ef83b6f6842bd8e851b600ad043895e6c44a55a8a81466ae7743d5bb50f3662c4d774ede209101"} build_url:https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011444 build_url: 'https://queue.taskcluster.net/v1/task/Gl4S4zQlQE-24HTv9XuDXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35e896c374b354771634e7ea629275bc9aacdce222609dd30846b760d56151647af0f2dca7552592726962e9d0f8ba47e47359229a18216ed5586a6f8e49d936", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e6fb8cb59e508d32eb5706fa10cb39d0b852dd9d88833bf26c1ae9c7ebcd9dbfa4573b5ed0abd16b6c7bf7935306c11b2a2dc8160056cc33d93d7e29464f92e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e41a54a9b7defd83a4828b173ba8795e653d445cab3f499c5ef83b6f6842bd8e851b600ad043895e6c44a55a8a81466ae7743d5bb50f3662c4d774ede209101"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 16:22:07.915961) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:22:07.916321) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1464129626.5309-1673448616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 16:22:08.000613) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 16:22:08.000975) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 16:22:08.001310) ========= ========= Total master_lag: 0.18 =========