builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1469810160.49 results: success (0) buildid: 20160729063002 builduid: 5a0c5b8ab4a4458580c568b3a0b44e98 revision: f3d6887279f8175b0d1e3835d1ca2a152bc4453f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.492258) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.492691) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.493011) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.533652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.533945) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-29 09:36:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-07-29 09:36:01 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113106 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.661115) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.661450) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020049 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.711098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:00.711519) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f3d6887279f8175b0d1e3835d1ca2a152bc4453f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev f3d6887279f8175b0d1e3835d1ca2a152bc4453f --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-29 09:36:01,333 truncating revision to first 12 chars 2016-07-29 09:36:01,333 Setting DEBUG logging. 2016-07-29 09:36:01,333 attempt 1/10 2016-07-29 09:36:01,333 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f3d6887279f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-29 09:36:01,694 unpacking tar archive at: autoland-f3d6887279f8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.689261 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:01.414991) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:01.415363) ========= script_repo_revision: f3d6887279f8175b0d1e3835d1ca2a152bc4453f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:01.415813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:01.416126) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-29 09:36:01.428024) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-29 09:36:01.428759) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:36:02 INFO - MultiFileLogger online at 20160729 09:36:02 in /builds/slave/test 09:36:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 09:36:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:02 INFO - {'append_to_log': False, 09:36:02 INFO - 'base_work_dir': '/builds/slave/test', 09:36:02 INFO - 'blob_upload_branch': 'Autoland', 09:36:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:02 INFO - 'branch': 'Autoland', 09:36:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:02 INFO - 'config_files': ('talos/linux_config.py',), 09:36:02 INFO - 'default_actions': ('clobber', 09:36:02 INFO - 'read-buildbot-config', 09:36:02 INFO - 'download-and-extract', 09:36:02 INFO - 'populate-webroot', 09:36:02 INFO - 'create-virtualenv', 09:36:02 INFO - 'install', 09:36:02 INFO - 'run-tests'), 09:36:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:02 INFO - 'download_minidump_stackwalk': True, 09:36:02 INFO - 'download_symbols': 'ondemand', 09:36:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:02 INFO - 'installer_path': 'installer.exe', 09:36:02 INFO - 'log_level': 'info', 09:36:02 INFO - 'log_name': 'talos', 09:36:02 INFO - 'log_to_console': True, 09:36:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:36:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:36:02 INFO - 'opt_config_files': (), 09:36:02 INFO - 'pip_index': False, 09:36:02 INFO - 'sps_profile': False, 09:36:02 INFO - 'sps_profile_interval': 0, 09:36:02 INFO - 'suite': 'dromaeojs', 09:36:02 INFO - 'system_bits': '32', 09:36:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:02 INFO - 'title': 'talos-linux64-ix-056', 09:36:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:02 INFO - 'use_talos_json': True, 09:36:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:02 INFO - 'work_dir': 'build'} 09:36:02 INFO - [mozharness: 2016-07-29 16:36:02.151203Z] Running clobber step. 09:36:02 INFO - Running pre-action listener: _resource_record_pre_action 09:36:02 INFO - Running main action method: clobber 09:36:02 INFO - rmtree: /builds/slave/test/build 09:36:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:03 INFO - Running post-action listener: _resource_record_post_action 09:36:03 INFO - [mozharness: 2016-07-29 16:36:03.133475Z] Finished clobber step (success) 09:36:03 INFO - [mozharness: 2016-07-29 16:36:03.133575Z] Running read-buildbot-config step. 09:36:03 INFO - Running pre-action listener: _resource_record_pre_action 09:36:03 INFO - Running main action method: read_buildbot_config 09:36:03 INFO - Using buildbot properties: 09:36:03 INFO - { 09:36:03 INFO - "project": "", 09:36:03 INFO - "product": "firefox", 09:36:03 INFO - "script_repo_revision": "production", 09:36:03 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:36:03 INFO - "repository": "", 09:36:03 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 09:36:03 INFO - "buildid": "20160729063002", 09:36:03 INFO - "pgo_build": "True", 09:36:03 INFO - "basedir": "/builds/slave/test", 09:36:03 INFO - "buildnumber": 75, 09:36:03 INFO - "slavename": "talos-linux64-ix-056", 09:36:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:36:03 INFO - "platform": "ubuntu64_hw", 09:36:03 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 09:36:03 INFO - "branch": "autoland", 09:36:03 INFO - "repo_path": "integration/autoland", 09:36:03 INFO - "revision": "f3d6887279f8175b0d1e3835d1ca2a152bc4453f", 09:36:03 INFO - "stage_platform": "linux64-pgo", 09:36:03 INFO - "builduid": "5a0c5b8ab4a4458580c568b3a0b44e98", 09:36:03 INFO - "slavebuilddir": "test" 09:36:03 INFO - } 09:36:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:36:03 INFO - Running post-action listener: _resource_record_post_action 09:36:03 INFO - [mozharness: 2016-07-29 16:36:03.136030Z] Finished read-buildbot-config step (success) 09:36:03 INFO - [mozharness: 2016-07-29 16:36:03.136120Z] Running download-and-extract step. 09:36:03 INFO - Running pre-action listener: _resource_record_pre_action 09:36:03 INFO - Running main action method: download_and_extract 09:36:03 INFO - mkdir: /builds/slave/test/build/tests 09:36: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')]} 09:36:03 INFO - https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:36:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:36:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:36:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:36:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:36:03 INFO - Downloaded 1361 bytes. 09:36:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:36:03 INFO - Using the following test package requirements: 09:36:03 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:36:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:36:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:36:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:36:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:36:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:36:03 INFO - u'jsshell-linux-x86_64.zip'], 09:36:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:36:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:36:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:36:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:36:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:36:03 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:36:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:36:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:36:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:36:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:36:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:36:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:36: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')]} 09:36:03 INFO - https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:36:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:36:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:36:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:36:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:36:04 INFO - Downloaded 24328566 bytes. 09:36:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:36:05 INFO - Return code: 0 09:36:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:36:05 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')]} 09:36:05 INFO - https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:36:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:36:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:36:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:36:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:36:07 INFO - Downloaded 24328566 bytes. 09:36:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:36:09 INFO - Return code: 0 09:36:09 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')]} 09:36:09 INFO - https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:36:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:36:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:36:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:36:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:36:10 INFO - Downloaded 14413725 bytes. 09:36:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:36:10 INFO - Return code: 0 09:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:10 INFO - https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:36:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:36:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:36:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:36:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:36:12 INFO - Downloaded 61032243 bytes. 09:36:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:36:12 INFO - mkdir: /builds/slave/test/properties 09:36:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:12 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:12 INFO - Contents: 09:36:12 INFO - build_url:https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:36:12 INFO - Running post-action listener: _resource_record_post_action 09:36:12 INFO - Running post-action listener: set_extra_try_arguments 09:36:12 INFO - [mozharness: 2016-07-29 16:36:12.798941Z] Finished download-and-extract step (success) 09:36:12 INFO - [mozharness: 2016-07-29 16:36:12.799263Z] Running populate-webroot step. 09:36:12 INFO - Running pre-action listener: _resource_record_pre_action 09:36:12 INFO - Running main action method: populate_webroot 09:36:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:12 INFO - '--tppagecycles', 09:36:12 INFO - '7'], 09:36:12 INFO - 'tests': ['tsvgm']}}, 09:36:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:12 INFO - 'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:12 INFO - 'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['damp', 'tps']}, 09:36:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:12 INFO - 'tests': ['damp', 'tps']}, 09:36:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['dromaeo_dom']}, 09:36:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['basic_compositor_video']}, 09:36:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:36:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['a11yr', 09:36:12 INFO - 'ts_paint', 09:36:12 INFO - 'tpaint', 09:36:12 INFO - 'sessionrestore', 09:36:12 INFO - 'sessionrestore_no_auto_restore', 09:36:12 INFO - 'tabpaint']}, 09:36:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:12 INFO - 'ts_paint', 09:36:12 INFO - 'tpaint', 09:36:12 INFO - 'sessionrestore', 09:36:12 INFO - 'sessionrestore_no_auto_restore', 09:36:12 INFO - 'tabpaint']}, 09:36:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:12 INFO - 'ts_paint', 09:36:12 INFO - 'tpaint', 09:36:12 INFO - 'sessionrestore', 09:36:12 INFO - 'sessionrestore_no_auto_restore', 09:36:12 INFO - 'tabpaint']}, 09:36:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:12 INFO - 'ts_paint', 09:36:12 INFO - 'tpaint', 09:36:12 INFO - 'sessionrestore', 09:36:12 INFO - 'sessionrestore_no_auto_restore', 09:36:12 INFO - 'tabpaint']}, 09:36:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['a11yr', 09:36:12 INFO - 'ts_paint', 09:36:12 INFO - 'tpaint', 09:36:12 INFO - 'sessionrestore', 09:36:12 INFO - 'sessionrestore_no_auto_restore', 09:36:12 INFO - 'tabpaint']}, 09:36:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['a11yr', 09:36:12 INFO - 'ts_paint', 09:36:12 INFO - 'tpaint', 09:36:12 INFO - 'sessionrestore', 09:36:12 INFO - 'sessionrestore_no_auto_restore', 09:36:12 INFO - 'tabpaint']}, 09:36:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['tsvgx', 09:36:12 INFO - 'tsvgr_opacity', 09:36:12 INFO - 'tart', 09:36:12 INFO - 'tscrollx', 09:36:12 INFO - 'cart']}, 09:36:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:12 INFO - 'tsvgr_opacity', 09:36:12 INFO - 'tart', 09:36:12 INFO - 'tscrollx', 09:36:12 INFO - 'cart']}, 09:36:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:12 INFO - 'talos_options': ['--disable-e10s'], 09:36:12 INFO - 'tests': ['tp5o']}, 09:36:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:12 INFO - 'tests': ['tp5o']}, 09:36:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:12 INFO - 'talos_options': ['--disable-e10s', 09:36:12 INFO - '--xperf_path', 09:36:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:12 INFO - 'tests': ['tp5n']}, 09:36:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:12 INFO - 'talos_options': ['--xperf_path', 09:36:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:12 INFO - 'tests': ['tp5n']}}, 09:36:12 INFO - 'talos.zip': {'path': '', 09:36:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:12 INFO - Running post-action listener: _resource_record_post_action 09:36:12 INFO - [mozharness: 2016-07-29 16:36:12.817777Z] Finished populate-webroot step (success) 09:36:12 INFO - [mozharness: 2016-07-29 16:36:12.817867Z] Running create-virtualenv step. 09:36:12 INFO - Running pre-action listener: _resource_record_pre_action 09:36:12 INFO - Running main action method: create_virtualenv 09:36:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:12 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 09:36:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:12 INFO - Using real prefix '/usr' 09:36:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:15 INFO - Installing pip.................done. 09:36:15 INFO - Return code: 0 09:36:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:36:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:36:15 INFO - Using env: {'DISPLAY': ':0', 09:36:15 INFO - 'HOME': '/home/cltbld', 09:36:15 INFO - 'LANG': 'en_US.UTF-8', 09:36:15 INFO - 'LANGUAGE': 'en_US:en', 09:36:15 INFO - 'LOGNAME': 'cltbld', 09:36:15 INFO - 'MAIL': '/var/mail/cltbld', 09:36:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:15 INFO - 'MOZ_NO_REMOTE': '1', 09:36:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:15 INFO - 'NO_EM_RESTART': '1', 09:36:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:15 INFO - 'PWD': '/builds/slave/test', 09:36:15 INFO - 'SHELL': '/bin/bash', 09:36:15 INFO - 'SHLVL': '1', 09:36:15 INFO - 'TERM': 'linux', 09:36:15 INFO - 'TMOUT': '86400', 09:36:15 INFO - 'USER': 'cltbld', 09:36:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:15 INFO - '_': '/tools/buildbot/bin/python'} 09:36:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:15 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 09:36:15 INFO - Cleaning up... 09:36:15 INFO - Return code: 0 09:36:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:36:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:36:15 INFO - Using env: {'DISPLAY': ':0', 09:36:15 INFO - 'HOME': '/home/cltbld', 09:36:15 INFO - 'LANG': 'en_US.UTF-8', 09:36:15 INFO - 'LANGUAGE': 'en_US:en', 09:36:15 INFO - 'LOGNAME': 'cltbld', 09:36:15 INFO - 'MAIL': '/var/mail/cltbld', 09:36:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:15 INFO - 'MOZ_NO_REMOTE': '1', 09:36:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:15 INFO - 'NO_EM_RESTART': '1', 09:36:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:15 INFO - 'PWD': '/builds/slave/test', 09:36:15 INFO - 'SHELL': '/bin/bash', 09:36:15 INFO - 'SHLVL': '1', 09:36:15 INFO - 'TERM': 'linux', 09:36:15 INFO - 'TMOUT': '86400', 09:36:15 INFO - 'USER': 'cltbld', 09:36:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:15 INFO - '_': '/tools/buildbot/bin/python'} 09:36:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:15 INFO - Downloading/unpacking psutil>=3.1.1 09:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:17 INFO - Installing collected packages: psutil 09:36:17 INFO - Running setup.py install for psutil 09:36:17 INFO - building 'psutil._psutil_linux' extension 09:36:17 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 09:36:18 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 09:36:18 INFO - building 'psutil._psutil_posix' extension 09:36:18 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 09:36:18 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 09:36:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:18 INFO - Successfully installed psutil 09:36:18 INFO - Cleaning up... 09:36:18 INFO - Return code: 0 09:36:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:36:18 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')]} 09:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:18 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')]} 09:36:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:18 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 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:18 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 09:36:18 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 09:36:18 INFO - Using env: {'DISPLAY': ':0', 09:36:18 INFO - 'HOME': '/home/cltbld', 09:36:18 INFO - 'LANG': 'en_US.UTF-8', 09:36:18 INFO - 'LANGUAGE': 'en_US:en', 09:36:18 INFO - 'LOGNAME': 'cltbld', 09:36:18 INFO - 'MAIL': '/var/mail/cltbld', 09:36:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:18 INFO - 'MOZ_NO_REMOTE': '1', 09:36:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:18 INFO - 'NO_EM_RESTART': '1', 09:36:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:18 INFO - 'PWD': '/builds/slave/test', 09:36:18 INFO - 'SHELL': '/bin/bash', 09:36:18 INFO - 'SHLVL': '1', 09:36:18 INFO - 'TERM': 'linux', 09:36:18 INFO - 'TMOUT': '86400', 09:36:18 INFO - 'USER': 'cltbld', 09:36:18 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:18 INFO - '_': '/tools/buildbot/bin/python'} 09:36:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:36:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:36:20 INFO - Installing collected packages: mozsystemmonitor 09:36:20 INFO - Running setup.py install for mozsystemmonitor 09:36:20 INFO - Successfully installed mozsystemmonitor 09:36:20 INFO - Cleaning up... 09:36:20 INFO - Return code: 0 09:36:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:36:20 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')]} 09:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:20 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')]} 09:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:20 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:20 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==2.5.1'] in /builds/slave/test/build 09:36:20 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==2.5.1 09:36:20 INFO - Using env: {'DISPLAY': ':0', 09:36:20 INFO - 'HOME': '/home/cltbld', 09:36:20 INFO - 'LANG': 'en_US.UTF-8', 09:36:20 INFO - 'LANGUAGE': 'en_US:en', 09:36:20 INFO - 'LOGNAME': 'cltbld', 09:36:20 INFO - 'MAIL': '/var/mail/cltbld', 09:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:20 INFO - 'MOZ_NO_REMOTE': '1', 09:36:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:20 INFO - 'NO_EM_RESTART': '1', 09:36:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:20 INFO - 'PWD': '/builds/slave/test', 09:36:20 INFO - 'SHELL': '/bin/bash', 09:36:20 INFO - 'SHLVL': '1', 09:36:20 INFO - 'TERM': 'linux', 09:36:20 INFO - 'TMOUT': '86400', 09:36:20 INFO - 'USER': 'cltbld', 09:36:20 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:20 INFO - '_': '/tools/buildbot/bin/python'} 09:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:20 INFO - Downloading/unpacking jsonschema==2.5.1 09:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:23 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:23 INFO - warning: no files found matching '*.txt' 09:36:23 INFO - no previously-included directories found matching 'build' 09:36:23 INFO - no previously-included directories found matching 'dist' 09:36:23 INFO - no previously-included directories found matching '.git*' 09:36:23 INFO - Installing collected packages: jsonschema, functools32 09:36:23 INFO - Running setup.py install for functools32 09:36:23 INFO - warning: no files found matching '*.txt' 09:36:23 INFO - no previously-included directories found matching 'build' 09:36:23 INFO - no previously-included directories found matching 'dist' 09:36:23 INFO - no previously-included directories found matching '.git*' 09:36:23 INFO - Successfully installed jsonschema functools32 09:36:23 INFO - Cleaning up... 09:36:23 INFO - Return code: 0 09:36:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:36:23 INFO - Using env: {'DISPLAY': ':0', 09:36:23 INFO - 'HOME': '/home/cltbld', 09:36:23 INFO - 'LANG': 'en_US.UTF-8', 09:36:23 INFO - 'LANGUAGE': 'en_US:en', 09:36:23 INFO - 'LOGNAME': 'cltbld', 09:36:23 INFO - 'MAIL': '/var/mail/cltbld', 09:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:23 INFO - 'MOZ_NO_REMOTE': '1', 09:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:23 INFO - 'NO_EM_RESTART': '1', 09:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:23 INFO - 'PWD': '/builds/slave/test', 09:36:23 INFO - 'SHELL': '/bin/bash', 09:36:23 INFO - 'SHLVL': '1', 09:36:23 INFO - 'TERM': 'linux', 09:36:23 INFO - 'TMOUT': '86400', 09:36:23 INFO - 'USER': 'cltbld', 09:36:23 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:23 INFO - '_': '/tools/buildbot/bin/python'} 09:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:36:23 INFO - Cleaning up... 09:36:23 INFO - Return code: 0 09:36:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:36:23 INFO - Using env: {'DISPLAY': ':0', 09:36:23 INFO - 'HOME': '/home/cltbld', 09:36:23 INFO - 'LANG': 'en_US.UTF-8', 09:36:23 INFO - 'LANGUAGE': 'en_US:en', 09:36:23 INFO - 'LOGNAME': 'cltbld', 09:36:23 INFO - 'MAIL': '/var/mail/cltbld', 09:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:23 INFO - 'MOZ_NO_REMOTE': '1', 09:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:23 INFO - 'NO_EM_RESTART': '1', 09:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:23 INFO - 'PWD': '/builds/slave/test', 09:36:23 INFO - 'SHELL': '/bin/bash', 09:36:23 INFO - 'SHLVL': '1', 09:36:23 INFO - 'TERM': 'linux', 09:36:23 INFO - 'TMOUT': '86400', 09:36:23 INFO - 'USER': 'cltbld', 09:36:23 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:23 INFO - '_': '/tools/buildbot/bin/python'} 09:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:23 INFO - Downloading/unpacking blobuploader==1.2.4 09:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:25 INFO - Downloading blobuploader-1.2.4.tar.gz 09:36:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:36:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:36:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:36:26 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 09:36:26 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 09:36:26 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 09:36:26 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 09:36:26 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 09:36:26 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 09:36:26 INFO - Downloading docopt-0.6.1.tar.gz 09:36:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:36:26 INFO - Installing collected packages: blobuploader, requests, docopt 09:36:26 INFO - Running setup.py install for blobuploader 09:36:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:36:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:36:26 INFO - Running setup.py install for requests 09:36:26 INFO - Running setup.py install for docopt 09:36:26 INFO - Successfully installed blobuploader requests docopt 09:36:26 INFO - Cleaning up... 09:36:26 INFO - Return code: 0 09:36:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:26 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 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:26 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 09:36:26 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 09:36:26 INFO - Using env: {'DISPLAY': ':0', 09:36:26 INFO - 'HOME': '/home/cltbld', 09:36:26 INFO - 'LANG': 'en_US.UTF-8', 09:36:26 INFO - 'LANGUAGE': 'en_US:en', 09:36:26 INFO - 'LOGNAME': 'cltbld', 09:36:26 INFO - 'MAIL': '/var/mail/cltbld', 09:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:26 INFO - 'MOZ_NO_REMOTE': '1', 09:36:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:26 INFO - 'NO_EM_RESTART': '1', 09:36:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:26 INFO - 'PWD': '/builds/slave/test', 09:36:26 INFO - 'SHELL': '/bin/bash', 09:36:26 INFO - 'SHLVL': '1', 09:36:26 INFO - 'TERM': 'linux', 09:36:26 INFO - 'TMOUT': '86400', 09:36:26 INFO - 'USER': 'cltbld', 09:36:26 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:26 INFO - '_': '/tools/buildbot/bin/python'} 09:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:27 INFO - Running setup.py (path:/tmp/pip-B4UHQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:27 INFO - Running setup.py (path:/tmp/pip-9djpCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:27 INFO - Running setup.py (path:/tmp/pip-HqZ_pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:27 INFO - Running setup.py (path:/tmp/pip-6vyCuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:27 INFO - Running setup.py (path:/tmp/pip-b6KOlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:27 INFO - Running setup.py (path:/tmp/pip-AAxFMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:27 INFO - Running setup.py (path:/tmp/pip-SrVdv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:27 INFO - Running setup.py (path:/tmp/pip-NQMkLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:27 INFO - Running setup.py (path:/tmp/pip-UCcjHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:27 INFO - Running setup.py (path:/tmp/pip-iD7I0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:27 INFO - Running setup.py (path:/tmp/pip-f4NzdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:28 INFO - Running setup.py (path:/tmp/pip-1IomQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:28 INFO - Running setup.py (path:/tmp/pip-1BnEs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:28 INFO - Running setup.py (path:/tmp/pip-6C5bWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:28 INFO - Running setup.py (path:/tmp/pip-pdi2hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:28 INFO - Running setup.py (path:/tmp/pip-kEX7PV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:28 INFO - Running setup.py (path:/tmp/pip-eWiAuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:36:28 INFO - Running setup.py install for manifestparser 09:36:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:36:28 INFO - Running setup.py install for mozcrash 09:36:28 INFO - Running setup.py install for mozdebug 09:36:28 INFO - Running setup.py install for mozdevice 09:36:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:36:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:36:28 INFO - Running setup.py install for mozfile 09:36:28 INFO - Running setup.py install for mozhttpd 09:36:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Running setup.py install for mozinfo 09:36:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Running setup.py install for mozInstall 09:36:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Running setup.py install for mozleak 09:36:29 INFO - Running setup.py install for mozlog 09:36:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Running setup.py install for moznetwork 09:36:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Running setup.py install for mozprocess 09:36:29 INFO - Running setup.py install for mozprofile 09:36:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Running setup.py install for mozrunner 09:36:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:36:29 INFO - Running setup.py install for mozscreenshot 09:36:30 INFO - Running setup.py install for moztest 09:36:30 INFO - Running setup.py install for mozversion 09:36:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:36:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:36:30 INFO - Cleaning up... 09:36:30 INFO - Return code: 0 09:36:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:30 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')]} 09:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:30 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')]} 09:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:30 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 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:30 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 09:36:30 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 09:36:30 INFO - Using env: {'DISPLAY': ':0', 09:36:30 INFO - 'HOME': '/home/cltbld', 09:36:30 INFO - 'LANG': 'en_US.UTF-8', 09:36:30 INFO - 'LANGUAGE': 'en_US:en', 09:36:30 INFO - 'LOGNAME': 'cltbld', 09:36:30 INFO - 'MAIL': '/var/mail/cltbld', 09:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:30 INFO - 'MOZ_NO_REMOTE': '1', 09:36:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:30 INFO - 'NO_EM_RESTART': '1', 09:36:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:30 INFO - 'PWD': '/builds/slave/test', 09:36:30 INFO - 'SHELL': '/bin/bash', 09:36:30 INFO - 'SHLVL': '1', 09:36:30 INFO - 'TERM': 'linux', 09:36:30 INFO - 'TMOUT': '86400', 09:36:30 INFO - 'USER': 'cltbld', 09:36:30 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:30 INFO - '_': '/tools/buildbot/bin/python'} 09:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:30 INFO - Running setup.py (path:/tmp/pip-JvLE8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:30 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)) 09:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:30 INFO - Running setup.py (path:/tmp/pip-vhjuGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:30 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)) 09:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:30 INFO - Running setup.py (path:/tmp/pip-5pq0oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:30 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)) 09:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:30 INFO - Running setup.py (path:/tmp/pip-AD56PV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:30 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)) 09:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:30 INFO - Running setup.py (path:/tmp/pip-BufiqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:30 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)) 09:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:30 INFO - Running setup.py (path:/tmp/pip-e2WFde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:30 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)) 09:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:30 INFO - Running setup.py (path:/tmp/pip-rQPEDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:30 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)) 09:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:30 INFO - Running setup.py (path:/tmp/pip-3NpGu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:31 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:31 INFO - Running setup.py (path:/tmp/pip-vk5VAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:31 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:31 INFO - Running setup.py (path:/tmp/pip-MKprVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:31 INFO - Running setup.py (path:/tmp/pip-YmydFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:31 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:31 INFO - Running setup.py (path:/tmp/pip-AvEJXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:31 INFO - Running setup.py (path:/tmp/pip-CcBZTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:31 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:31 INFO - Running setup.py (path:/tmp/pip-jhdLtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:31 INFO - Running setup.py (path:/tmp/pip-IeDu0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:31 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:31 INFO - Running setup.py (path:/tmp/pip-cVaRve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:31 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)) 09:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:31 INFO - Running setup.py (path:/tmp/pip-Nkafo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:31 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)) 09:36:31 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)) 09:36:31 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)) 09:36:31 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)) 09:36:31 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)) 09:36:31 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)) 09:36:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:31 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 09:36:31 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 09:36:31 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 09:36:31 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 09:36:31 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 09:36:31 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 09:36:33 INFO - Downloading blessings-1.6.tar.gz 09:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:36:33 INFO - Installing collected packages: blessings 09:36:33 INFO - Running setup.py install for blessings 09:36:33 INFO - Successfully installed blessings 09:36:33 INFO - Cleaning up... 09:36:33 INFO - Return code: 0 09:36:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:36:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:34 INFO - Reading from file tmpfile_stdout 09:36:34 INFO - Current package versions: 09:36:34 INFO - argparse == 1.2.1 09:36:34 INFO - blessings == 1.6 09:36:34 INFO - blobuploader == 1.2.4 09:36:34 INFO - docopt == 0.6.1 09:36:34 INFO - functools32 == 3.2.3-2 09:36:34 INFO - jsonschema == 2.5.1 09:36:34 INFO - manifestparser == 1.1 09:36:34 INFO - mozInstall == 1.12 09:36:34 INFO - mozcrash == 0.17 09:36:34 INFO - mozdebug == 0.1 09:36:34 INFO - mozdevice == 0.48 09:36:34 INFO - mozfile == 1.2 09:36:34 INFO - mozhttpd == 0.7 09:36:34 INFO - mozinfo == 0.9 09:36:34 INFO - mozleak == 0.1 09:36:34 INFO - mozlog == 3.3 09:36:34 INFO - moznetwork == 0.27 09:36:34 INFO - mozprocess == 0.23 09:36:34 INFO - mozprofile == 0.28 09:36:34 INFO - mozrunner == 6.12 09:36:34 INFO - mozscreenshot == 0.1 09:36:34 INFO - mozsystemmonitor == 0.3 09:36:34 INFO - moztest == 0.7 09:36:34 INFO - mozversion == 1.4 09:36:34 INFO - psutil == 3.1.1 09:36:34 INFO - requests == 1.2.3 09:36:34 INFO - wsgiref == 0.1.2 09:36:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:34 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 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:34 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 09:36:34 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 09:36:34 INFO - Using env: {'DISPLAY': ':0', 09:36:34 INFO - 'HOME': '/home/cltbld', 09:36:34 INFO - 'LANG': 'en_US.UTF-8', 09:36:34 INFO - 'LANGUAGE': 'en_US:en', 09:36:34 INFO - 'LOGNAME': 'cltbld', 09:36:34 INFO - 'MAIL': '/var/mail/cltbld', 09:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:34 INFO - 'MOZ_NO_REMOTE': '1', 09:36:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:34 INFO - 'NO_EM_RESTART': '1', 09:36:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:34 INFO - 'PWD': '/builds/slave/test', 09:36:34 INFO - 'SHELL': '/bin/bash', 09:36:34 INFO - 'SHLVL': '1', 09:36:34 INFO - 'TERM': 'linux', 09:36:34 INFO - 'TMOUT': '86400', 09:36:34 INFO - 'USER': 'cltbld', 09:36:34 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:34 INFO - '_': '/tools/buildbot/bin/python'} 09:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 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)) 09:36:34 INFO - Cleaning up... 09:36:34 INFO - Return code: 0 09:36:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:34 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 0xb03030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', '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 09:36:34 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 09:36:34 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 09:36:34 INFO - Using env: {'DISPLAY': ':0', 09:36:34 INFO - 'HOME': '/home/cltbld', 09:36:34 INFO - 'LANG': 'en_US.UTF-8', 09:36:34 INFO - 'LANGUAGE': 'en_US:en', 09:36:34 INFO - 'LOGNAME': 'cltbld', 09:36:34 INFO - 'MAIL': '/var/mail/cltbld', 09:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:34 INFO - 'MOZ_NO_REMOTE': '1', 09:36:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:34 INFO - 'NO_EM_RESTART': '1', 09:36:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:34 INFO - 'PWD': '/builds/slave/test', 09:36:34 INFO - 'SHELL': '/bin/bash', 09:36:34 INFO - 'SHLVL': '1', 09:36:34 INFO - 'TERM': 'linux', 09:36:34 INFO - 'TMOUT': '86400', 09:36:34 INFO - 'USER': 'cltbld', 09:36:34 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:34 INFO - '_': '/tools/buildbot/bin/python'} 09:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:36:34 INFO - Cleaning up... 09:36:34 INFO - Return code: 0 09:36:34 INFO - Running post-action listener: _resource_record_post_action 09:36:34 INFO - Running post-action listener: _start_resource_monitoring 09:36:34 INFO - Starting resource monitoring. 09:36:34 INFO - [mozharness: 2016-07-29 16:36:34.506941Z] Finished create-virtualenv step (success) 09:36:34 INFO - [mozharness: 2016-07-29 16:36:34.507507Z] Running install step. 09:36:34 INFO - Running pre-action listener: _resource_record_pre_action 09:36:34 INFO - Running main action method: install 09:36:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:34 INFO - Reading from file tmpfile_stdout 09:36:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:36:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:36:34 INFO - Reading from file tmpfile_stdout 09:36:34 INFO - Output received: 09:36:34 INFO - Usage: mozinstall [options] installer 09:36:34 INFO - Options: 09:36:34 INFO - -h, --help show this help message and exit 09:36:34 INFO - -d DEST, --destination=DEST 09:36:34 INFO - Directory to install application into. [default: 09:36:34 INFO - "/builds/slave/test"] 09:36:34 INFO - --app=APP Application being installed. [default: firefox] 09:36:34 INFO - mkdir: /builds/slave/test/build/application 09:36:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:36:52 INFO - Reading from file tmpfile_stdout 09:36:52 INFO - Output received: 09:36:52 INFO - /builds/slave/test/build/application/firefox/firefox 09:36:52 INFO - Running post-action listener: _resource_record_post_action 09:36:52 INFO - [mozharness: 2016-07-29 16:36:52.104637Z] Finished install step (success) 09:36:52 INFO - [mozharness: 2016-07-29 16:36:52.104919Z] Running run-tests step. 09:36:52 INFO - Running pre-action listener: _resource_record_pre_action 09:36:52 INFO - Running main action method: run_tests 09:36:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:36:52 INFO - Python 2.7.3 09:36:52 INFO - Return code: 0 09:36:52 INFO - grabbing minidump binary from tooltool 09:36: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')]} 09:36:52 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 0xdc23b0>, '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 0xdc32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdc3740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:36:52 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 09:36:52 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 09:36:52 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 09:36:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:36:52 INFO - Return code: 0 09:36:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:36:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:36:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:36:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:36:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:36:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:36:52 INFO - Using env: {'DISPLAY': ':0', 09:36:52 INFO - 'HOME': '/home/cltbld', 09:36:52 INFO - 'LANG': 'en_US.UTF-8', 09:36:52 INFO - 'LANGUAGE': 'en_US:en', 09:36:52 INFO - 'LOGNAME': 'cltbld', 09:36:52 INFO - 'MAIL': '/var/mail/cltbld', 09:36:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:36:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:52 INFO - 'MOZ_NO_REMOTE': '1', 09:36:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:36:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:52 INFO - 'NO_EM_RESTART': '1', 09:36:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:52 INFO - 'PWD': '/builds/slave/test', 09:36:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:36:52 INFO - 'SHELL': '/bin/bash', 09:36:52 INFO - 'SHLVL': '1', 09:36:52 INFO - 'TERM': 'linux', 09:36:52 INFO - 'TMOUT': '86400', 09:36:52 INFO - 'USER': 'cltbld', 09:36:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510', 09:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:52 INFO - '_': '/tools/buildbot/bin/python'} 09:36:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:36:52 INFO - mozversion application_buildid: 20160729063002 09:36:52 INFO - mozversion application_changeset: f3d6887279f8175b0d1e3835d1ca2a152bc4453f 09:36:52 INFO - mozversion application_display_name: Nightly 09:36:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:36:52 INFO - mozversion application_name: Firefox 09:36:52 INFO - mozversion application_remotingname: firefox 09:36:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:36:52 INFO - mozversion application_vendor: Mozilla 09:36:52 INFO - mozversion application_version: 50.0a1 09:36:52 INFO - mozversion platform_buildid: 20160729063002 09:36:52 INFO - mozversion platform_changeset: f3d6887279f8175b0d1e3835d1ca2a152bc4453f 09:36:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:36:52 INFO - mozversion platform_version: 50.0a1 09:36:52 INFO - using testdate: 1469810212 09:36:52 INFO - actual date: 1469810212 09:36:52 INFO - starting webserver on 'localhost:42826' 09:36:52 INFO - SUITE-START | Running 2 tests 09:36:52 INFO - TEST-START | dromaeo_css 09:36:52 INFO - operating with platform_type : linux_ 09:36:52 INFO - Initialising browser for dromaeo_css test... 09:36:52 INFO - TEST-INFO | started process 15961 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbNQeTl/profile http://localhost:42826/getInfo.html) 09:36:55 INFO - PROCESS | 15961 | JavaScript error: , line 0: uncaught exception: undefined 09:36:56 INFO - PROCESS | 15961 | __metrics Screen width/height:1600/1200 09:36:56 INFO - PROCESS | 15961 | colorDepth:24 09:36:56 INFO - PROCESS | 15961 | Browser inner width/height: 1024/697 09:36:56 INFO - PROCESS | 15961 | __metrics 09:36:57 INFO - PROCESS | 15961 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:36:57 INFO - PROCESS | 15961 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:36:58 INFO - TEST-INFO | 15961: exit 0 09:36:58 INFO - Browser initialized. 09:36:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:36:58 INFO - TEST-INFO | started process 16042 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbNQeTl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:10 INFO - PROCESS | 16042 | RSS: Main: 157507584 09:37:10 INFO - PROCESS | 16042 | 09:38:01 INFO - PROCESS | 16042 | 1469810281861 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] 09:40:10 INFO - PROCESS | 16042 | Cycle 1(1): loaded http://localhost:42826/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42826/tests/dromaeo/cssquery-ext.html) 09:40:11 INFO - PROCESS | 16042 | RSS: Main: 271323136 09:40:11 INFO - PROCESS | 16042 | 09:43:14 INFO - PROCESS | 16042 | Cycle 1(1): loaded http://localhost:42826/tests/dromaeo/cssquery-ext.html (next: http://localhost:42826/tests/dromaeo/cssquery-jquery.html) 09:43:14 INFO - PROCESS | 16042 | RSS: Main: 251260928 09:43:14 INFO - PROCESS | 16042 | 09:46:17 INFO - PROCESS | 16042 | Cycle 1(1): loaded http://localhost:42826/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42826/tests/dromaeo/cssquery-mootools.html) 09:46:17 INFO - PROCESS | 16042 | RSS: Main: 185049088 09:46:17 INFO - PROCESS | 16042 | 09:49:19 INFO - PROCESS | 16042 | Cycle 1(1): loaded http://localhost:42826/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42826/tests/dromaeo/cssquery-prototype.html) 09:49:20 INFO - PROCESS | 16042 | RSS: Main: 235180032 09:49:20 INFO - PROCESS | 16042 | 09:52:22 INFO - PROCESS | 16042 | Cycle 1(1): loaded http://localhost:42826/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42826/tests/dromaeo/cssquery-yui.html) 09:52:23 INFO - PROCESS | 16042 | RSS: Main: 187682816 09:52:23 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | Cycle 1(1): loaded http://localhost:42826/tests/dromaeo/cssquery-yui.html (next: http://localhost:42826/tests/dromaeo/cssquery-dojo.html) 09:55:26 INFO - PROCESS | 16042 | RSS: Main: 283504640 09:55:26 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | __start_tp_report 09:55:26 INFO - PROCESS | 16042 | _x_x_mozilla_page_load 09:55:26 INFO - PROCESS | 16042 | _x_x_mozilla_page_load_details 09:55:26 INFO - PROCESS | 16042 | |i|pagename|runs| 09:55:26 INFO - PROCESS | 16042 | |0;dojo.html;3000.3849210911753;3014.6533148687895;3047.75617950564;3068.8465576721164;3097.1947293703643;5154.561862241714;5225.764840582174;5268;5352.678839269646;5354.598405119617;5260.184671375943;5276.7889284428575;5327.041132596131;5354.196870469432;5370.194470829378;4793.185158523042;4819.5180481951875;4851.757412129397;4866.562009419152;4959.528844759742;4887.218045112782;4918.336024636682;4922.089413458504;4925.408950925894;4952.678075925053;4694.319323698045;4723.291506274052;4724.551167639069;4827.8310259140935;4840.806367745286;4786.664933454659;4796.016816552598;4804.8798780030775;4805.927911081337;4810;4733.029728905566;4750.904981900378;4756.025014871978;4765.046990601859;4767.76161191939;5229.058769421503;5245.6852588844795;5260.763265653055;5265.552428043583;5333.35999680037;5585.525230355424;5650.491455769001;5651.7739290428335;5676.631018983794;5727.742251598688;5699.829005129853;5757.165211044375;5771.365149833515;5777.364489906149;5781.161731548944;5194.402643695986;5244.029854476896;5268.973655131738;5284.9735751320995;5371.597130215249;5823.912641310384;5825.79609713658;5872.853178670482;5915.615484993462;5916.349201587822;5413.350397952271;5644.238027866268;5649.237352957381;5649.322081350264;5657.179708942263;5653.632513886584;5675.035244008528;5682.261306030189;5762.481376676035;5767;5699.373068962411;5702.344230413472;5704.600677952587;5710.086386178192;5748.655080695172;5476.972615136979;5670.092785154356;5732.484076433141;5734.77060917567;5738.626989245769;4341.45731783527;4343.717658352197;4366.214081465303;4378.890527736832;4413.7793110344355;3888.669463095612;3896.279188350164;3904.9804750976064;3934.370500719871;3950.8617198398492;5298.231756395378;5345.305110335631;5347.0909945309395;5349.384820745664;5359.544438722674;5491.176323551419;5685.800996965087;5688.232088667977;5740.799072032542;5751.913721294184;5452;5453.890922181534;5461.42655021225;5464.23500709901;5477;4980.626453015966;4985.950140498621;4990.5508504234795;4995.800167993312;5038.017586570583;5779.826605201851;5913.319968203626;5962;5962.850928726643;6014.248218972629;5750.8849823002465;5752.194692742935;5757.222774925332;5760.740766665594;5805.10020946754;5965.2245208124405;5968.194293770285;6012.669303188367;6017.097435384728;6027.035674292092;5499.175123731472;5533.225348451139;5548.889022219452;5590.329160500766;5631.493165615114;5717.885642287048;5717.942820571908;5721.713914304351;5740.1963725077685;5773.480386765212;5596.496315331639;5604.131359639099;5608.46719561641;5609.326880774333;5609.747561359667;5416.133418652997;5502.422245664147;5530.087535556588;5549.805756798492;5575.330960284792;5503.11950087984;5506.063969125178;5507.256520369699;5521.2270282161335;5554.111342185231;5596.3284405871555;5610.270664813548;5617.157426386075;5657.915131272952;5673.177389278614;5351.759170837244;5357.812476563302;5407.459254074561;5453.454654534515;5468.26178465902 09:55:26 INFO - PROCESS | 16042 | |1;ext.html;591.8520369907524;608.0514397539838;614.5544480251817;618.6659204029822;623.8128561431575;30484.542731859012;42589.78705106478;43112.35331470027;43324.566754332445;43461.13077738443;40630.187396252055;45109.77445112778;45337.77331113348;45464.09071818562;45516;8013;8927.151920567536;9115.726528204164;9115.863262051074;10591;10762.461876906162;10898.50956706946;10898.782024359509;10904.890951090467;10920.945395273013;10941.945290273577;10979;10991.06575941046;11003.22977391583;11026.393548354838;10901.945490272537;10942.890571094347;10945.398003109825;10975.670729878117;10981.780364392747;10739.892601073967;10813.783724325549;10838.349699018005;10838.891611083945;10840.566377344896;42225.36661950073;42628.3605745914;42652.78673606643;42668.78665606652;42708.572914271084;3397.4224381855142;3417.8632854685784;3503.7197024238;3833.9041523962123;4118.5057793065025;29534.852325738233;31930.680693193237;32370.352592948246;33762;33862.322753545035;4232.470941132358;4251.80866860992;4260.38224457455;4334.32817913224;4448.243798554254;348881;354091;355410;356898;358115.20942395384;50441.74779126154;50718;50744;56892.43107568954;57100.71449642725;59893;60291.69854150701;60294.698526507964;60617.393826062056;60624.696876515336;14227.786583201054;14434.639134021734;14455.49405770814;14464.927675361556;14485.855141448661;10898.346099234072;11083.889161108447;11099.611513596985;11129.554817807197;11163;14338;15654.217289135499;15705.842941570667;15761.211939403212;15774.903652091454;6451.29035806061;6586.473082153416;6615.305392933769;6626.834329141713;6698.933010669831;990.5691024404263;1051.952662130206;1057.7884423115408;1112.783007313566;1120.8374785656035;31704.682953170635;31969.52045719316;32036.519452208235;32271.838640807116;32802.67197328044;1114.3481063577735;1136.3011747775058;1158.5423757615695;1198.1074099795533;1218.2203389830515;16375;16516.339346426008;16641.41755038568;16862.747058794128;17287.481375558502;569821;571974;574038;575146;575616;57153.71423142941;57235;57276.713616431654;57313.14030289549;57504.42495575074;1184.2806416430517;1306.5753630070265;1340.4035204334173;1348.5751988123907;1362.7410791949499;31655.366892662478;32993.835030824695;33081.17297067496;33094.66905330916;33672.326553469284;49248.50751492583;49526;49607.50392496173;49829.75085124551;49901;421045;422549;423258;423558.3509154453;424106;26987;27986.160415187576;28017.579736303564;29204.977825775997;41625.167496649294;47242.29136563023;47244;47511.76244118757;47818.28272575984;48376.7581162106;40171.39742903917;40695;40754;40884;42590.5740942599;39634.80182599068;39738.2052358957;40586.391204131374;41206.587934120274;41857.79071104625;39460;40055.39916901307;40535.594644053184;40955.795221023705;41006;34631.4805277916;36353.27293454169;36613.81693091518;37140.07149821276;37372.81313593415;26839.926402943664;27786;28237;28260.293492662848;28299 09:55:26 INFO - PROCESS | 16042 | |2;jquery.html;373.30565148823166;377.26245190652276;390.36565580930977;396.9900752481188;414.2502071251036;14141.222232777192;15768.211589420525;18574.44276671702;19121.330753423634;19153.904230478864;17904.82095179048;17917.552061198476;17983.82016179838;18095.18571664275;18152.273909043655;4198.181354635847;5611.270534830466;5795.15970184323;5795.391483894194;5798.62308949919;4331.935020974688;5133.948660513384;6456.35436456353;6546.345365463463;6606.207255129391;6304.243490781113;6430.421262086411;6490.643014634194;6524.804255872332;6560.179977502812;6459.967700161493;6470.644114573673;6473.190851143607;6489.902651460232;6493.870122597546;5518.089515229983;5667.489925906647;5703.828885133453;5708.457696518824;5710.8001219957105;22229.77770222293;22314;22415.103395864146;22464.213752518746;22484.775152248432;2416.0007940840633;2450.1179575352858;2493.925182244536;2501.7248102708686;3058.556509306138;13599.524016659469;14397.13617182962;14785.926070369687;14890.181040042793;15124;1876.7278744582095;1940.1075505267595;1942.1357495914324;1981.524434135797;1988.3438465306415;54478.455215447735;64905.67547162281;73216.26783732201;74123.62938185329;79368;9834.655787047563;10227.590896364061;10313.948430257951;10377.63678271272;10605.416702081306;14421.78367324491;14442.711145777026;14446.638834029234;14455.927720361331;14478.782818257734;7345.632718364061;7642.656080476392;7655.464117511832;7704.114026886868;7786.532808031537;7233.240509746506;7274.96362518184;7453.776386708408;7456.701731930771;7473.663685134182;6268.4671802897265;6336.904946425807;6347.603527224003;6472.255690595548;6588.846951783419;1835;1902.6289873474539;1904.7714274286898;1906.551960289331;1929.5465565592074;920.2132176988579;1016.944068076263;1017.6387382479261;1021.8313978193663;1065.7708592652616;18954.39729732416;19181.61636767257;19252.4224273272;19283.807161928202;19421.2231510738;993.3543196922059;1009.8586197932295;1023.8976102389702;1028.4240825137947;1031.2781053262745;10482.318649287772;11038.889611103947;11185.72035699114;11282.661520154408;11307.378094204898;372101;376735;377909;509859;512722;83633.16366836254;92645.53677231571;93926.53036734773;94606.52696736473;94611.52694236484;992.7021893431942;1007.1388962437157;1009.5305682857506;1024.9282550221412;1028.0850043461326;16578.58553536171;16595.336186552402;16727.996320220827;16995.49013529596;17802.91098544551;31049.534256985713;31384.843075784476;41328.38007429951;41495.96260093401;42058.369124462544;58233.70883145557;58371.416285836596;58480.70759646175;59564.40435595762;60040.39959600348;7931.325837303893;7955.323797477279;8191.959040204761;8214.301784348398;8346.749597512084;10206;11805.114616403976;12088.75822483563;12229.3273869938;12756.42596083197;10002.199824014207;10242.6415075472;10386.48067596602;11264.436778161224;11298.830517542408;10582.788344233226;10790.298630588986;11097.223194376638;11137.832932505857;11163.72090697739;10575.259731819007;10927.289726167774;11195.60815371458;11196.496157672762;11494.540218391505;7867.488613240121;8064;8181.918180818354;8199.918000820155;8316.335160844093;10523.36887782395;10684.198685098803;10982.450877455936;11001.449927503752;11244.718882027688 09:55:26 INFO - PROCESS | 16042 | |3;mootools.html;1317.598132569566;1451.1583281696617;1461.123326004397;1658.510739331897;1814.1745505794856;8460.619272132753;8867.822643547133;8894.243989260913;9085.954570227139;9099.454032758023;8940.8658870117;9056.909430905689;9058.637654493828;9063;9074.319426043057;2338.24007197661;2359.6106642403984;2364.7635236476385;2365.7989070928907;2366.3374255208482;2178.9128434862587;2218;2218.7781221877844;2219.755826859044;2223.4552534628956;2183.443221978401;2189.507360843805;2216.1246307708448;2217.4345541886796;2219.678146668732;2276.704028476304;2298.770122987696;2308.8845557722047;2321.3152120124605;2322.7677232276806;2300.2179259051836;2315.0508291600363;2318.6406107053435;2322.268485427085;2348.459854233519;8821.779455513599;8865.335099867536;8903.243224325875;8928.15182557656;8949.463032218087;4989.750512474398;5165.9225111623355;5167.328247327823;5181.4559471255725;5195.19474481456;3378.797272163678;3526.6473352664784;3533.1873669056263;3545.9113522161892;3574.1064733816547;1021.5147804792738;1045.5974449836792;1055.6780182044474;1070.6413351527249;1079.3739631013993;12480.50077996879;12592.181508201938;12632.557860474839;12640.367981600975;12669.873301266962;4186.895327616773;4286.5284818669925;4417.823287068545;4421.53573874745;4448.021435284232;9965.750856228507;9980;9982.700518984442;9984.750381240527;9987.400755954666;5123.8719032023755;5131.871703207375;5135.922961155586;5139.820106296262;5142.537171654569;4173.812178451977;4560.635149188123;4734.976325118421;4749.833755818529;4755;3643.85424583019;3718.9772812476785;3737.252549490113;3787.1857550626746;3811.6760075393904;1081.1566279493445;1097.0346095435966;1097.7694684116425;1110.4947249001684;1112.6828853776635;863.0161615758041;871.6339137562239;930.8696782450462;951.110711484764;974.6734843827397;6142.140100385949;6147.96926015376;6169.475594574512;6172.938270617326;6438;936.2089034765612;987.8765154355706;995.4027583450016;1026.8870424253325;1044.6552637629563;4081.8163182656854;4267.573242675708;4284.721493102938;4284.764337961442;4427.5351088135285;11114.333140011626;11587.130965177645;11591;11600.18798684084;11607.477663505162;11188.608398706006;11463.254888432226;11495.252808567417;11514.481848316846;11519.48162332697;8254.711085112112;8545.786355341166;8549.401541892008;8554.743357699279;8565.057843637194;6166.537509686702;6271.153394291895;6334.986402175629;6343.682815859281;6350.460210882126;10418.635347762793;10419.374837509773;10540.051395374454;10550.261481696207;10562.630307939493;10390.688279351632;10419.843702344622;10618.36289822613;10726.463676816284;10748.301360411548;6986.47601429905;7639.57982310956;7849.4897831640765;8251.092379838094;8538.231559159705;8600.268977137013;8868.246199072893;8869.06874778139;8870;8877.644894204159;7604.201558836464;7606.315431611181;7776.027996500437;7777.961110194413;7819.648115834915;7319.268073192638;7507.099148102263;7591.316781489692;7603;7624.351930086005;7149.463790215858;7237.819054523468;7250.528715633467;7257.419406447366;7259.491835571459;5286.550643195218;5381.789097453042;5438.75525601341;5455.672659640434;5629.390969705158;5433.510984011458;5437.4018857926385;5479.53423958952;5505.009098362333;5605.243292155506 09:55:26 INFO - PROCESS | 16042 | |4;prototype.html;324.45815488134815;330.25692192566726;335.3226482505339;337.80744975364036;338.0837929211836;5108.2848401223855;5157.61317901158;5258.316418865547;5263.736813159341;5276.419593844679;5113.795448182078;5154.381474223089;5180.766865491052;5211.843644690656;5220.929709409572;4103.199876024177;4104.953236924595;4112.794360281974;4117.300058989964;4137.979275129158;4035.717499775002;4082.9183416331657;4087.713860029799;4145.274576949037;4166.416701661769;4044.5550989391145;4107.732997355162;4110.856120035799;4111.1161100363415;4121.155163191554;4056.9797151014054;4088.325426304656;4097.344424892033;4103.815328310233;4106.1582375612925;3570.393033184365;3649.835757390924;3653.0684675407865;3673.6142705015855;3683.410654295327;5057.317262169597;5083.466236045236;5118.513741194581;5131.1277082895995;5133.153029750087;4742.264948932921;4744.739039352834;4757.191277482836;4763.190257656207;4767.261074533453;5254.947450525522;5376.838694839161;5386.13821788512;5482.890342193174;5496.560275177976;3977.085270387798;3979.641832235113;3979.8408063677416;3984.940225896614;3993.740406873544;5617.859553511154;5639.238702775114;5651.660900345993;5670.68811215383;5680.943190568083;4064.4758215669126;4157.168566286755;4168.103857670615;4172.582741725864;4178;4224.176285624274;4224.197402493578;4224.598663126998;4226.154769046203;4268.317069268902;4776.901312698029;4788.736619485892;4799.568038876518;4807.543283388072;4814.711117332971;5288.629795914249;5494.835154945358;5551.361593416728;5581.5255703265675;5599.188117722989;1383.4327925550676;1407.3174510362467;1432.6561625209874;1445.3279225160352;1448.9492867749577;990.6180877675603;1010.7877345757327;1020.806046851096;1029.5367084811865;1030.3766221435924;5259.500347466985;5261.921071183935;5276.1030624793875;5287.9735601321745;5291.947080529222;5579.13523403873;5589.273394458694;5605.5515558755205;5608.439156084359;5614.6631202128;5088.211327244283;5238.476152384731;5244.2169662315;5296.364436267596;5337.932413517312;2964.925876853096;2965.481040817848;2984.7313741763346;3002.324476992659;3002.8198308101582;5835.620684655569;5851.531877449794;5863.501602363762;5889.146073819272;5921.526277897758;5527.2538207342295;5676.858078547987;5716.971415142898;5734.254546908958;5812.564057695689;5807.5644326675665;5821.359650438449;5863.413658634187;5911.226632010465;5913.674747888907;5416.8104116355735;5571.944280557304;5593.4126916674795;5620.6229473779185;5666.17840413146;5531.0873705839695;5569.526590239875;5574.9721251393485;5594;5629.746661400165;5851.473367396955;5889.234399528034;5891.410858914246;5914.911276330945;5918.319393269829;3779.3952967525065;3920.7059470539252;3924.372100463912;3935.468711723995;3945.1320709443876;5384.946150538444;5599.524040456607;5635.802746903839;5650.915236271541;5662.433756624469;5395.190721391823;5441.156620723636;5449.427810079885;5472.890542189213;5530.446955304437;5475.534579560782;5479.890402191854;5501.394846566955;5515.448455154453;5531.751071201725;5211.1922651989;5371.301730774974;5417.510184699239;5446.346438427414;5466.781328746805;5483.698396588066;5484.862878427912;5515.944840551703;5517.393086760373;5524.530414914777;5429.375621803385;5437.8368648940595;5442.265294185393;5448.809291674773;5457.836264912059 09:55:26 INFO - PROCESS | 16042 | |5;yui.html;701.667328304883;733.7028503456102;739.8779201431765;783.0485959559138;801.8276070644811;15282.159481228528;16299.511014669579;16335.101569413693;16360.263788129532;16364;14734.852651473482;14831.777523337132;14879.40482380706;14887.18120503372;14902.552923412288;659.1727382135438;793.5040599625235;793.5952664141287;810.3193317613204;812.2324403438753;817.2113910076774;821.2731732416825;821.8849361089423;822.3010441125026;823.7075838077486;841.2933136165619;849.2526576612572;852.1435956863344;853.0914575976597;853.4495250563383;679.5311235247688;696.4950410952044;700.2857085772534;828.6436832162168;838.0906716212917;620.633826042637;623.3361470034399;624.8250489862845;628.5034822490227;629.0187307799802;15827.841721582752;16112.677746445006;16162.11108389038;16162.191890405433;16223.432179873766;15281.847181528154;15580.844191558166;15609.843901560953;15674.921625391915;15721.68556628861;12596.81104783429;12609;12735.235885846787;12812.487500499969;12886.742265154644;3933.3005889036062;3966.3852102924093;3967.8016099194924;4009.4386785850006;4140.213359461693;81226;82747.58626206951;83168;83515;84563.57718211431;11841;11983.52065917371;12028.819567706492;12047.578334758242;12269.447874845653;23255;23634.64548031781;23669.644955325683;23679.052837886637;23873.045078197025;8950.507722075348;9080.046595107551;9149.35954483193;9154.908450915538;9171.77070573241;4347.5652434756275;4699.647526435531;4745.6440766942615;4759.857204283877;4767.976160119247;3615.1504396466808;3724.4413337999517;3741.933548938594;3753.5871054184017;3833.5783063862955;695.3220609905403;706.6925887239067;709.5281637710967;709.53170907201;709.5565271705184;569.5045310579823;578.6094386289238;617.8177437655902;640.4204195203348;735.5072101691879;8998.14517620825;9018.143276388733;9176.770580735534;9400.717978460658;9923.900760992297;681.9761308354184;692.6190595172635;715.0739791969412;715.8067321823079;732.5604637217733;4581.65637577183;4858.134313125136;4994.175960966472;5041.143005689041;5085.898282034413;117930;128377.35811320883;129081;131861.34069329593;134902.32548837192;62029.68985155045;63800.68099659472;64907.67546162239;109838;112224;94049.5297523508;94110.52944735232;96009.51995240118;97121.51439242759;98889.50555247322;9390.154886060287;9487.857682134636;9607.951960240434;9666.178374838217;9676.564554595201;53977.46022539724;54110.72944635252;54783;55911.16133257923;56175.43824561702;76593.23406766084;76734;77425.61287193529;78224.60887695753;80457.19542804497;11310.321380716854;11327.490262938352;11383.658490245305;11484.081273498266;11484.355991201048;12705.905786276193;12811.551595694105;12940.935295323463;12997.870021299666;13501.864981350061;13066.216027038408;13276.137051091648;13486.93256533711;13506.459741610226;13681.110727802661;12676.429560669607;12909.741805163656;12915.935420323214;12996.545120920722;13645.590632281048;11530.654080377602;11605.941970290094;11837.408129593658;11981.76036479283;12042.819357709466;9771.91912278896;10090.495475226357;10251.48742562854;10468.63359782404;10481.947590262305;10023.248256380946;10524.321035676123;10879.78240435171;11112;11128.44357782124 09:55:26 INFO - PROCESS | 16042 | __end_tp_report 09:55:26 INFO - PROCESS | 16042 | __start_cc_report 09:55:26 INFO - PROCESS | 16042 | _x_x_mozilla_cycle_collect,1054 09:55:26 INFO - PROCESS | 16042 | __end_cc_report 09:55:26 INFO - PROCESS | 16042 | __startTimestamp1469811326916__endTimestamp 09:55:26 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | ------- Summary: start ------- 09:55:26 INFO - PROCESS | 16042 | Number of tests: 6 09:55:26 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | [#0] dojo.html Cycles:165 Average:5293.01 Median:5477.00 stddev:611.51 (11.2%) stddev-sans-first:586.35 09:55:26 INFO - PROCESS | 16042 | Values: 3000.4 3014.7 3047.8 3068.8 3097.2 5154.6 5225.8 5268.0 5352.7 5354.6 5260.2 5276.8 5327.0 5354.2 5370.2 4793.2 4819.5 4851.8 4866.6 4959.5 4887.2 4918.3 4922.1 4925.4 4952.7 4694.3 4723.3 4724.6 4827.8 4840.8 4786.7 4796.0 4804.9 4805.9 4810.0 4733.0 4750.9 4756.0 4765.0 4767.8 5229.1 5245.7 5260.8 5265.6 5333.4 5585.5 5650.5 5651.8 5676.6 5727.7 5699.8 5757.2 5771.4 5777.4 5781.2 5194.4 5244.0 5269.0 5285.0 5371.6 5823.9 5825.8 5872.9 5915.6 5916.3 5413.4 5644.2 5649.2 5649.3 5657.2 5653.6 5675.0 5682.3 5762.5 5767.0 5699.4 5702.3 5704.6 5710.1 5748.7 5477.0 5670.1 5732.5 5734.8 5738.6 4341.5 4343.7 4366.2 4378.9 4413.8 3888.7 3896.3 3905.0 3934.4 3950.9 5298.2 5345.3 5347.1 5349.4 5359.5 5491.2 5685.8 5688.2 5740.8 5751.9 5452.0 5453.9 5461.4 5464.2 5477.0 4980.6 4986.0 4990.6 4995.8 5038.0 5779.8 5913.3 5962.0 5962.9 6014.2 5750.9 5752.2 5757.2 5760.7 5805.1 5965.2 5968.2 6012.7 6017.1 6027.0 5499.2 5533.2 5548.9 5590.3 5631.5 5717.9 5717.9 5721.7 5740.2 5773.5 5596.5 5604.1 5608.5 5609.3 5609.7 5416.1 5502.4 5530.1 5549.8 5575.3 5503.1 5506.1 5507.3 5521.2 5554.1 5596.3 5610.3 5617.2 5657.9 5673.2 5351.8 5357.8 5407.5 5453.5 5468.3 09:55:26 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | [#1] ext.html Cycles:180 Average:60838.13 Median:36876.94 stddev:121990.61 (330.8%) stddev-sans-first:122248.96 09:55:26 INFO - PROCESS | 16042 | Values: 591.9 608.1 614.6 618.7 623.8 30484.5 42589.8 43112.4 43324.6 43461.1 40630.2 45109.8 45337.8 45464.1 45516.0 8013.0 8927.2 9115.7 9115.9 10591.0 10762.5 10898.5 10898.8 10904.9 10920.9 10941.9 10979.0 10991.1 11003.2 11026.4 10901.9 10942.9 10945.4 10975.7 10981.8 10739.9 10813.8 10838.3 10838.9 10840.6 42225.4 42628.4 42652.8 42668.8 42708.6 3397.4 3417.9 3503.7 3833.9 4118.5 29534.9 31930.7 32370.4 33762.0 33862.3 4232.5 4251.8 4260.4 4334.3 4448.2 348881.0 354091.0 355410.0 356898.0 358115.2 50441.7 50718.0 50744.0 56892.4 57100.7 59893.0 60291.7 60294.7 60617.4 60624.7 14227.8 14434.6 14455.5 14464.9 14485.9 10898.3 11083.9 11099.6 11129.6 11163.0 14338.0 15654.2 15705.8 15761.2 15774.9 6451.3 6586.5 6615.3 6626.8 6698.9 990.6 1052.0 1057.8 1112.8 1120.8 31704.7 31969.5 32036.5 32271.8 32802.7 1114.3 1136.3 1158.5 1198.1 1218.2 16375.0 16516.3 16641.4 16862.7 17287.5 569821.0 571974.0 574038.0 575146.0 575616.0 57153.7 57235.0 57276.7 57313.1 57504.4 1184.3 1306.6 1340.4 1348.6 1362.7 31655.4 32993.8 33081.2 33094.7 33672.3 49248.5 49526.0 49607.5 49829.8 49901.0 421045.0 422549.0 423258.0 423558.4 424106.0 26987.0 27986.2 28017.6 29205.0 41625.2 47242.3 47244.0 47511.8 47818.3 48376.8 40171.4 40695.0 40754.0 40884.0 42590.6 39634.8 39738.2 40586.4 41206.6 41857.8 39460.0 40055.4 40535.6 40955.8 41006.0 34631.5 36353.3 36613.8 37140.1 37372.8 26839.9 27786.0 28237.0 28260.3 28299.0 09:55:26 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | [#2] jquery.html Cycles:180 Average:26787.78 Median:12450.39 stddev:71913.55 (577.6%) stddev-sans-first:72087.94 09:55:26 INFO - PROCESS | 16042 | Values: 373.3 377.3 390.4 397.0 414.3 14141.2 15768.2 18574.4 19121.3 19153.9 17904.8 17917.6 17983.8 18095.2 18152.3 4198.2 5611.3 5795.2 5795.4 5798.6 4331.9 5133.9 6456.4 6546.3 6606.2 6304.2 6430.4 6490.6 6524.8 6560.2 6460.0 6470.6 6473.2 6489.9 6493.9 5518.1 5667.5 5703.8 5708.5 5710.8 22229.8 22314.0 22415.1 22464.2 22484.8 2416.0 2450.1 2493.9 2501.7 3058.6 13599.5 14397.1 14785.9 14890.2 15124.0 1876.7 1940.1 1942.1 1981.5 1988.3 54478.5 64905.7 73216.3 74123.6 79368.0 9834.7 10227.6 10313.9 10377.6 10605.4 14421.8 14442.7 14446.6 14455.9 14478.8 7345.6 7642.7 7655.5 7704.1 7786.5 7233.2 7275.0 7453.8 7456.7 7473.7 6268.5 6336.9 6347.6 6472.3 6588.8 1835.0 1902.6 1904.8 1906.6 1929.5 920.2 1016.9 1017.6 1021.8 1065.8 18954.4 19181.6 19252.4 19283.8 19421.2 993.4 1009.9 1023.9 1028.4 1031.3 10482.3 11038.9 11185.7 11282.7 11307.4 372101.0 376735.0 377909.0 509859.0 512722.0 83633.2 92645.5 93926.5 94606.5 94611.5 992.7 1007.1 1009.5 1024.9 1028.1 16578.6 16595.3 16728.0 16995.5 17802.9 31049.5 31384.8 41328.4 41496.0 42058.4 58233.7 58371.4 58480.7 59564.4 60040.4 7931.3 7955.3 8192.0 8214.3 8346.7 10206.0 11805.1 12088.8 12229.3 12756.4 10002.2 10242.6 10386.5 11264.4 11298.8 10582.8 10790.3 11097.2 11137.8 11163.7 10575.3 10927.3 11195.6 11196.5 11494.5 7867.5 8064.0 8181.9 8199.9 8316.3 10523.4 10684.2 10982.5 11001.4 11244.7 09:55:26 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | [#3] mootools.html Cycles:180 Average:5895.77 Median:4752.42 stddev:3427.50 (72.1%) stddev-sans-first:3419.85 09:55:26 INFO - PROCESS | 16042 | Values: 1317.6 1451.2 1461.1 1658.5 1814.2 8460.6 8867.8 8894.2 9086.0 9099.5 8940.9 9056.9 9058.6 9063.0 9074.3 2338.2 2359.6 2364.8 2365.8 2366.3 2178.9 2218.0 2218.8 2219.8 2223.5 2183.4 2189.5 2216.1 2217.4 2219.7 2276.7 2298.8 2308.9 2321.3 2322.8 2300.2 2315.1 2318.6 2322.3 2348.5 8821.8 8865.3 8903.2 8928.2 8949.5 4989.8 5165.9 5167.3 5181.5 5195.2 3378.8 3526.6 3533.2 3545.9 3574.1 1021.5 1045.6 1055.7 1070.6 1079.4 12480.5 12592.2 12632.6 12640.4 12669.9 4186.9 4286.5 4417.8 4421.5 4448.0 9965.8 9980.0 9982.7 9984.8 9987.4 5123.9 5131.9 5135.9 5139.8 5142.5 4173.8 4560.6 4735.0 4749.8 4755.0 3643.9 3719.0 3737.3 3787.2 3811.7 1081.2 1097.0 1097.8 1110.5 1112.7 863.0 871.6 930.9 951.1 974.7 6142.1 6148.0 6169.5 6172.9 6438.0 936.2 987.9 995.4 1026.9 1044.7 4081.8 4267.6 4284.7 4284.8 4427.5 11114.3 11587.1 11591.0 11600.2 11607.5 11188.6 11463.3 11495.3 11514.5 11519.5 8254.7 8545.8 8549.4 8554.7 8565.1 6166.5 6271.2 6335.0 6343.7 6350.5 10418.6 10419.4 10540.1 10550.3 10562.6 10390.7 10419.8 10618.4 10726.5 10748.3 6986.5 7639.6 7849.5 8251.1 8538.2 8600.3 8868.2 8869.1 8870.0 8877.6 7604.2 7606.3 7776.0 7778.0 7819.6 7319.3 7507.1 7591.3 7603.0 7624.4 7149.5 7237.8 7250.5 7257.4 7259.5 5286.6 5381.8 5438.8 5455.7 5629.4 5433.5 5437.4 5479.5 5505.0 5605.2 09:55:26 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | [#4] prototype.html Cycles:180 Average:4642.97 Median:5256.63 stddev:1347.36 (25.6%) stddev-sans-first:1311.57 09:55:26 INFO - PROCESS | 16042 | Values: 324.5 330.3 335.3 337.8 338.1 5108.3 5157.6 5258.3 5263.7 5276.4 5113.8 5154.4 5180.8 5211.8 5220.9 4103.2 4105.0 4112.8 4117.3 4138.0 4035.7 4082.9 4087.7 4145.3 4166.4 4044.6 4107.7 4110.9 4111.1 4121.2 4057.0 4088.3 4097.3 4103.8 4106.2 3570.4 3649.8 3653.1 3673.6 3683.4 5057.3 5083.5 5118.5 5131.1 5133.2 4742.3 4744.7 4757.2 4763.2 4767.3 5254.9 5376.8 5386.1 5482.9 5496.6 3977.1 3979.6 3979.8 3984.9 3993.7 5617.9 5639.2 5651.7 5670.7 5680.9 4064.5 4157.2 4168.1 4172.6 4178.0 4224.2 4224.2 4224.6 4226.2 4268.3 4776.9 4788.7 4799.6 4807.5 4814.7 5288.6 5494.8 5551.4 5581.5 5599.2 1383.4 1407.3 1432.7 1445.3 1448.9 990.6 1010.8 1020.8 1029.5 1030.4 5259.5 5261.9 5276.1 5288.0 5291.9 5579.1 5589.3 5605.6 5608.4 5614.7 5088.2 5238.5 5244.2 5296.4 5337.9 2964.9 2965.5 2984.7 3002.3 3002.8 5835.6 5851.5 5863.5 5889.1 5921.5 5527.3 5676.9 5717.0 5734.3 5812.6 5807.6 5821.4 5863.4 5911.2 5913.7 5416.8 5571.9 5593.4 5620.6 5666.2 5531.1 5569.5 5575.0 5594.0 5629.7 5851.5 5889.2 5891.4 5914.9 5918.3 3779.4 3920.7 3924.4 3935.5 3945.1 5384.9 5599.5 5635.8 5650.9 5662.4 5395.2 5441.2 5449.4 5472.9 5530.4 5475.5 5479.9 5501.4 5515.4 5531.8 5211.2 5371.3 5417.5 5446.3 5466.8 5483.7 5484.9 5515.9 5517.4 5524.5 5429.4 5437.8 5442.3 5448.8 5457.8 09:55:26 INFO - PROCESS | 16042 | 09:55:26 INFO - PROCESS | 16042 | [#5] yui.html Cycles:180 Average:21373.48 Median:4763.92 stddev:31646.25 (664.3%) stddev-sans-first:31696.96 09:55:26 INFO - PROCESS | 16042 | Values: 701.7 733.7 739.9 783.0 801.8 15282.2 16299.5 16335.1 16360.3 16364.0 14734.9 14831.8 14879.4 14887.2 14902.6 659.2 793.5 793.6 810.3 812.2 817.2 821.3 821.9 822.3 823.7 841.3 849.3 852.1 853.1 853.4 679.5 696.5 700.3 828.6 838.1 620.6 623.3 624.8 628.5 629.0 15827.8 16112.7 16162.1 16162.2 16223.4 15281.8 15580.8 15609.8 15674.9 15721.7 12596.8 12609.0 12735.2 12812.5 12886.7 3933.3 3966.4 3967.8 4009.4 4140.2 81226.0 82747.6 83168.0 83515.0 84563.6 11841.0 11983.5 12028.8 12047.6 12269.4 23255.0 23634.6 23669.6 23679.1 23873.0 8950.5 9080.0 9149.4 9154.9 9171.8 4347.6 4699.6 4745.6 4759.9 4768.0 3615.2 3724.4 3741.9 3753.6 3833.6 695.3 706.7 709.5 709.5 709.6 569.5 578.6 617.8 640.4 735.5 8998.1 9018.1 9176.8 9400.7 9923.9 682.0 692.6 715.1 715.8 732.6 4581.7 4858.1 4994.2 5041.1 5085.9 117930.0 128377.4 129081.0 131861.3 134902.3 62029.7 63800.7 64907.7 109838.0 112224.0 94049.5 94110.5 96009.5 97121.5 98889.5 9390.2 9487.9 9608.0 9666.2 9676.6 53977.5 54110.7 54783.0 55911.2 56175.4 76593.2 76734.0 77425.6 78224.6 80457.2 11310.3 11327.5 11383.7 11484.1 11484.4 12705.9 12811.6 12940.9 12997.9 13501.9 13066.2 13276.1 13486.9 13506.5 13681.1 12676.4 12909.7 12915.9 12996.5 13645.6 11530.7 11605.9 11837.4 11981.8 12042.8 9771.9 10090.5 10251.5 10468.6 10481.9 10023.2 10524.3 10879.8 11112.0 11128.4 09:55:26 INFO - PROCESS | 16042 | -------- Summary: end -------- 09:55:26 INFO - PROCESS | 16042 | 09:55:27 INFO - TEST-INFO | 16042: exit 0 09:55:27 INFO - TEST-OK | dromaeo_css | took 1115413ms 09:55:27 INFO - TEST-START | kraken 09:55:27 INFO - operating with platform_type : linux_ 09:55:27 INFO - Initialising browser for kraken test... 09:55:27 INFO - TEST-INFO | started process 16227 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxcBPs0/profile http://localhost:42826/getInfo.html) 09:55:31 INFO - PROCESS | 16227 | JavaScript error: , line 0: uncaught exception: undefined 09:55:31 INFO - PROCESS | 16227 | __metrics Screen width/height:1600/1200 09:55:31 INFO - PROCESS | 16227 | colorDepth:24 09:55:31 INFO - PROCESS | 16227 | Browser inner width/height: 1024/697 09:55:31 INFO - PROCESS | 16227 | __metrics 09:55:32 INFO - PROCESS | 16227 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:55:32 INFO - PROCESS | 16227 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:55:33 INFO - TEST-INFO | 16227: exit 0 09:55:33 INFO - Browser initialized. 09:55:33 INFO - Running cycle 1/1 for kraken test... 09:55:33 INFO - TEST-INFO | started process 16305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxcBPs0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:55:45 INFO - PROCESS | 16305 | RSS: Main: 157847552 09:55:45 INFO - PROCESS | 16305 | 09:55:48 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42826/tests/kraken/driver.html?testName=audio-beat-detection) 09:55:48 INFO - PROCESS | 16305 | RSS: Main: 214700032 09:55:48 INFO - PROCESS | 16305 | 09:55:51 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42826/tests/kraken/driver.html?testName=audio-dft) 09:55:52 INFO - PROCESS | 16305 | RSS: Main: 188379136 09:55:52 INFO - PROCESS | 16305 | 09:55:55 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42826/tests/kraken/driver.html?testName=audio-fft) 09:55:55 INFO - PROCESS | 16305 | RSS: Main: 271114240 09:55:55 INFO - PROCESS | 16305 | 09:55:58 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42826/tests/kraken/driver.html?testName=audio-oscillator) 09:55:58 INFO - PROCESS | 16305 | RSS: Main: 180936704 09:55:58 INFO - PROCESS | 16305 | 09:56:00 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42826/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:56:01 INFO - PROCESS | 16305 | RSS: Main: 185917440 09:56:01 INFO - PROCESS | 16305 | 09:56:04 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42826/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:05 INFO - PROCESS | 16305 | RSS: Main: 220459008 09:56:05 INFO - PROCESS | 16305 | 09:56:08 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42826/tests/kraken/driver.html?testName=imaging-desaturate) 09:56:08 INFO - PROCESS | 16305 | RSS: Main: 221642752 09:56:08 INFO - PROCESS | 16305 | 09:56:11 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42826/tests/kraken/driver.html?testName=json-parse-financial) 09:56:12 INFO - PROCESS | 16305 | RSS: Main: 221523968 09:56:12 INFO - PROCESS | 16305 | 09:56:13 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42826/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:56:14 INFO - PROCESS | 16305 | RSS: Main: 185458688 09:56:14 INFO - PROCESS | 16305 | 09:56:16 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42826/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:56:16 INFO - PROCESS | 16305 | RSS: Main: 189812736 09:56:16 INFO - PROCESS | 16305 | 09:56:18 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42826/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:56:19 INFO - PROCESS | 16305 | RSS: Main: 195362816 09:56:19 INFO - PROCESS | 16305 | 09:56:22 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42826/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:56:22 INFO - PROCESS | 16305 | RSS: Main: 189435904 09:56:22 INFO - PROCESS | 16305 | 09:56:25 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42826/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:56:26 INFO - PROCESS | 16305 | RSS: Main: 194420736 09:56:26 INFO - PROCESS | 16305 | 09:56:27 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:42826/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42826/tests/kraken/driver.html?testName=ai-astar) 09:56:28 INFO - PROCESS | 16305 | RSS: Main: 244846592 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | __start_tp_report 09:56:28 INFO - PROCESS | 16305 | _x_x_mozilla_page_load 09:56:28 INFO - PROCESS | 16305 | _x_x_mozilla_page_load_details 09:56:28 INFO - PROCESS | 16305 | |i|pagename|runs| 09:56:28 INFO - PROCESS | 16305 | |0;ai-astar;102;111;111;112;110;112;111;112;111;111 09:56:28 INFO - PROCESS | 16305 | |1;audio-beat-detection;155;121;127;197;119;129;151;116;186;122 09:56:28 INFO - PROCESS | 16305 | |2;audio-dft;160;161;163;161;161;162;162;161;163;161 09:56:28 INFO - PROCESS | 16305 | |3;audio-fft;77;76;76;117;75;77;74;75;100;74 09:56:28 INFO - PROCESS | 16305 | |4;audio-oscillator;84;85;115;84;83;84;117;84;85;126 09:56:28 INFO - PROCESS | 16305 | |5;imaging-gaussian-blur;118;118;118;121;117;117;117;117;116;116 09:56:28 INFO - PROCESS | 16305 | |6;imaging-darkroom;146;146;147;147;149;145;147;147;146;146 09:56:28 INFO - PROCESS | 16305 | |7;imaging-desaturate;95;95;95;95;95;96;96;95;96;94 09:56:28 INFO - PROCESS | 16305 | |8;json-parse-financial;50;51;50;58;52;51;53;52;51;52 09:56:28 INFO - PROCESS | 16305 | |9;json-stringify-tinderbox;64;64;64;63;63;64;64;69;65;65 09:56:28 INFO - PROCESS | 16305 | |10;stanford-crypto-aes;85;80;79;79;76;77;76;77;76;75 09:56:28 INFO - PROCESS | 16305 | |11;stanford-crypto-ccm;123;125;122;132;126;144;130;123;160;136 09:56:28 INFO - PROCESS | 16305 | |12;stanford-crypto-pbkdf2;160;162;162;161;163;170;162;161;169;166 09:56:28 INFO - PROCESS | 16305 | |13;stanford-crypto-sha256-iterative;64;65;66;64;65;66;64;65;63;62 09:56:28 INFO - PROCESS | 16305 | __end_tp_report 09:56:28 INFO - PROCESS | 16305 | __start_cc_report 09:56:28 INFO - PROCESS | 16305 | _x_x_mozilla_cycle_collect,1633 09:56:28 INFO - PROCESS | 16305 | __end_cc_report 09:56:28 INFO - PROCESS | 16305 | __startTimestamp1469811388007__endTimestamp 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | ------- Summary: start ------- 09:56:28 INFO - PROCESS | 16305 | Number of tests: 14 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.98 (2.7%) stddev-sans-first:0.67 09:56:28 INFO - PROCESS | 16305 | Values: 102.0 111.0 111.0 112.0 110.0 112.0 111.0 112.0 111.0 111.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:140.00 stddev:29.15 (20.8%) stddev-sans-first:30.56 09:56:28 INFO - PROCESS | 16305 | Values: 155.0 121.0 127.0 197.0 119.0 129.0 151.0 116.0 186.0 122.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:0.97 (0.6%) stddev-sans-first:0.87 09:56:28 INFO - PROCESS | 16305 | Values: 160.0 161.0 163.0 161.0 161.0 162.0 162.0 161.0 163.0 161.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.50 stddev:14.52 (19.2%) stddev-sans-first:15.28 09:56:28 INFO - PROCESS | 16305 | Values: 77.0 76.0 76.0 117.0 75.0 77.0 74.0 75.0 100.0 74.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:84.00 stddev:17.23 (20.5%) stddev-sans-first:17.84 09:56:28 INFO - PROCESS | 16305 | Values: 84.0 85.0 115.0 84.0 83.0 84.0 117.0 84.0 85.0 126.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:1.43 (1.2%) stddev-sans-first:1.51 09:56:28 INFO - PROCESS | 16305 | Values: 118.0 118.0 118.0 121.0 117.0 117.0 117.0 117.0 116.0 116.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 09:56:28 INFO - PROCESS | 16305 | Values: 146.0 146.0 147.0 147.0 149.0 145.0 147.0 147.0 146.0 146.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 09:56:28 INFO - PROCESS | 16305 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 94.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:2.31 (4.4%) stddev-sans-first:2.33 09:56:28 INFO - PROCESS | 16305 | Values: 50.0 51.0 50.0 58.0 52.0 51.0 53.0 52.0 51.0 52.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.00 stddev:1.72 (2.7%) stddev-sans-first:1.81 09:56:28 INFO - PROCESS | 16305 | Values: 64.0 64.0 64.0 63.0 63.0 64.0 64.0 69.0 65.0 65.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:2.94 (3.8%) stddev-sans-first:1.72 09:56:28 INFO - PROCESS | 16305 | Values: 85.0 80.0 79.0 79.0 76.0 77.0 76.0 77.0 76.0 75.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#11] stanford-crypto-ccm Cycles:10 Average:132.10 Median:131.00 stddev:11.99 (9.2%) stddev-sans-first:12.26 09:56:28 INFO - PROCESS | 16305 | Values: 123.0 125.0 122.0 132.0 126.0 144.0 130.0 123.0 160.0 136.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.60 Median:162.50 stddev:3.50 (2.2%) stddev-sans-first:3.46 09:56:28 INFO - PROCESS | 16305 | Values: 160.0 162.0 162.0 161.0 163.0 170.0 162.0 161.0 169.0 166.0 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - PROCESS | 16305 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.26 (1.9%) stddev-sans-first:1.33 09:56:28 INFO - PROCESS | 16305 | Values: 64.0 65.0 66.0 64.0 65.0 66.0 64.0 65.0 63.0 62.0 09:56:28 INFO - PROCESS | 16305 | -------- Summary: end -------- 09:56:28 INFO - PROCESS | 16305 | 09:56:28 INFO - TEST-INFO | 16305: exit 0 09:56:29 INFO - TEST-OK | kraken | took 61242ms 09:56:29 INFO - SUITE-END | took 1176s 09:56:29 INFO - Completed test suite (00:19:36) 09:56:29 INFO - javascript benchmark 09:56:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3000.3849210911753, 3014.6533148687895, 3047.75617950564, 3068.8465576721164, 3097.1947293703643, 5154.561862241714, 5225.764840582174, 5268.0, 5352.678839269646, 5354.598405119617, 5260.184671375943, 5276.7889284428575, 5327.041132596131, 5354.196870469432, 5370.194470829378, 4793.185158523042, 4819.5180481951875, 4851.757412129397, 4866.562009419152, 4959.528844759742, 4887.218045112782, 4918.336024636682, 4922.089413458504, 4925.408950925894, 4952.678075925053, 4694.319323698045, 4723.291506274052, 4724.551167639069, 4827.8310259140935, 4840.806367745286, 4786.664933454659, 4796.016816552598, 4804.8798780030775, 4805.927911081337, 4810.0, 4733.029728905566, 4750.904981900378, 4756.025014871978, 4765.046990601859, 4767.76161191939, 5229.058769421503, 5245.6852588844795, 5260.763265653055, 5265.552428043583, 5333.35999680037, 5585.525230355424, 5650.491455769001, 5651.7739290428335, 5676.631018983794, 5727.742251598688, 5699.829005129853, 5757.165211044375, 5771.365149833515, 5777.364489906149, 5781.161731548944, 5194.402643695986, 5244.029854476896, 5268.973655131738, 5284.9735751320995, 5371.597130215249, 5823.912641310384, 5825.79609713658, 5872.853178670482, 5915.615484993462, 5916.349201587822, 5413.350397952271, 5644.238027866268, 5649.237352957381, 5649.322081350264, 5657.179708942263, 5653.632513886584, 5675.035244008528, 5682.261306030189, 5762.481376676035, 5767.0, 5699.373068962411, 5702.344230413472, 5704.600677952587, 5710.086386178192, 5748.655080695172, 5476.972615136979, 5670.092785154356, 5732.484076433141, 5734.77060917567, 5738.626989245769, 4341.45731783527, 4343.717658352197, 4366.214081465303, 4378.890527736832, 4413.7793110344355, 3888.669463095612, 3896.279188350164, 3904.9804750976064, 3934.370500719871, 3950.8617198398492, 5298.231756395378, 5345.305110335631, 5347.0909945309395, 5349.384820745664, 5359.544438722674, 5491.176323551419, 5685.800996965087, 5688.232088667977, 5740.799072032542, 5751.913721294184, 5452.0, 5453.890922181534, 5461.42655021225, 5464.23500709901, 5477.0, 4980.626453015966, 4985.950140498621, 4990.5508504234795, 4995.800167993312, 5038.017586570583, 5779.826605201851, 5913.319968203626, 5962.0, 5962.850928726643, 6014.248218972629, 5750.8849823002465, 5752.194692742935, 5757.222774925332, 5760.740766665594, 5805.10020946754, 5965.2245208124405, 5968.194293770285, 6012.669303188367, 6017.097435384728, 6027.035674292092, 5499.175123731472, 5533.225348451139, 5548.889022219452, 5590.329160500766, 5631.493165615114, 5717.885642287048, 5717.942820571908, 5721.713914304351, 5740.1963725077685, 5773.480386765212, 5596.496315331639, 5604.131359639099, 5608.46719561641, 5609.326880774333, 5609.747561359667, 5416.133418652997, 5502.422245664147, 5530.087535556588, 5549.805756798492, 5575.330960284792, 5503.11950087984, 5506.063969125178, 5507.256520369699, 5521.2270282161335, 5554.111342185231, 5596.3284405871555, 5610.270664813548, 5617.157426386075, 5657.915131272952, 5673.177389278614, 5351.759170837244, 5357.812476563302, 5407.459254074561, 5453.454654534515, 5468.26178465902], "value": 5250.989817635075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.8520369907524, 608.0514397539838, 614.5544480251817, 618.6659204029822, 623.8128561431575, 30484.542731859012, 42589.78705106478, 43112.35331470027, 43324.566754332445, 43461.13077738443, 40630.187396252055, 45109.77445112778, 45337.77331113348, 45464.09071818562, 45516.0, 8013.0, 8927.151920567536, 9115.726528204164, 9115.863262051074, 10591.0, 10762.461876906162, 10898.50956706946, 10898.782024359509, 10904.890951090467, 10920.945395273013, 10941.945290273577, 10979.0, 10991.06575941046, 11003.22977391583, 11026.393548354838, 10901.945490272537, 10942.890571094347, 10945.398003109825, 10975.670729878117, 10981.780364392747, 10739.892601073967, 10813.783724325549, 10838.349699018005, 10838.891611083945, 10840.566377344896, 42225.36661950073, 42628.3605745914, 42652.78673606643, 42668.78665606652, 42708.572914271084, 3397.4224381855142, 3417.8632854685784, 3503.7197024238, 3833.9041523962123, 4118.5057793065025, 29534.852325738233, 31930.680693193237, 32370.352592948246, 33762.0, 33862.322753545035, 4232.470941132358, 4251.80866860992, 4260.38224457455, 4334.32817913224, 4448.243798554254, 348881.0, 354091.0, 355410.0, 356898.0, 358115.20942395384, 50441.74779126154, 50718.0, 50744.0, 56892.43107568954, 57100.71449642725, 59893.0, 60291.69854150701, 60294.698526507964, 60617.393826062056, 60624.696876515336, 14227.786583201054, 14434.639134021734, 14455.49405770814, 14464.927675361556, 14485.855141448661, 10898.346099234072, 11083.889161108447, 11099.611513596985, 11129.554817807197, 11163.0, 14338.0, 15654.217289135499, 15705.842941570667, 15761.211939403212, 15774.903652091454, 6451.29035806061, 6586.473082153416, 6615.305392933769, 6626.834329141713, 6698.933010669831, 990.5691024404263, 1051.952662130206, 1057.7884423115408, 1112.783007313566, 1120.8374785656035, 31704.682953170635, 31969.52045719316, 32036.519452208235, 32271.838640807116, 32802.67197328044, 1114.3481063577735, 1136.3011747775058, 1158.5423757615695, 1198.1074099795533, 1218.2203389830515, 16375.0, 16516.339346426008, 16641.41755038568, 16862.747058794128, 17287.481375558502, 569821.0, 571974.0, 574038.0, 575146.0, 575616.0, 57153.71423142941, 57235.0, 57276.713616431654, 57313.14030289549, 57504.42495575074, 1184.2806416430517, 1306.5753630070265, 1340.4035204334173, 1348.5751988123907, 1362.7410791949499, 31655.366892662478, 32993.835030824695, 33081.17297067496, 33094.66905330916, 33672.326553469284, 49248.50751492583, 49526.0, 49607.50392496173, 49829.75085124551, 49901.0, 421045.0, 422549.0, 423258.0, 423558.3509154453, 424106.0, 26987.0, 27986.160415187576, 28017.579736303564, 29204.977825775997, 41625.167496649294, 47242.29136563023, 47244.0, 47511.76244118757, 47818.28272575984, 48376.7581162106, 40171.39742903917, 40695.0, 40754.0, 40884.0, 42590.5740942599, 39634.80182599068, 39738.2052358957, 40586.391204131374, 41206.587934120274, 41857.79071104625, 39460.0, 40055.39916901307, 40535.594644053184, 40955.795221023705, 41006.0, 34631.4805277916, 36353.27293454169, 36613.81693091518, 37140.07149821276, 37372.81313593415, 26839.926402943664, 27786.0, 28237.0, 28260.293492662848, 28299.0], "value": 20284.016360798585, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.30565148823166, 377.26245190652276, 390.36565580930977, 396.9900752481188, 414.2502071251036, 14141.222232777192, 15768.211589420525, 18574.44276671702, 19121.330753423634, 19153.904230478864, 17904.82095179048, 17917.552061198476, 17983.82016179838, 18095.18571664275, 18152.273909043655, 4198.181354635847, 5611.270534830466, 5795.15970184323, 5795.391483894194, 5798.62308949919, 4331.935020974688, 5133.948660513384, 6456.35436456353, 6546.345365463463, 6606.207255129391, 6304.243490781113, 6430.421262086411, 6490.643014634194, 6524.804255872332, 6560.179977502812, 6459.967700161493, 6470.644114573673, 6473.190851143607, 6489.902651460232, 6493.870122597546, 5518.089515229983, 5667.489925906647, 5703.828885133453, 5708.457696518824, 5710.8001219957105, 22229.77770222293, 22314.0, 22415.103395864146, 22464.213752518746, 22484.775152248432, 2416.0007940840633, 2450.1179575352858, 2493.925182244536, 2501.7248102708686, 3058.556509306138, 13599.524016659469, 14397.13617182962, 14785.926070369687, 14890.181040042793, 15124.0, 1876.7278744582095, 1940.1075505267595, 1942.1357495914324, 1981.524434135797, 1988.3438465306415, 54478.455215447735, 64905.67547162281, 73216.26783732201, 74123.62938185329, 79368.0, 9834.655787047563, 10227.590896364061, 10313.948430257951, 10377.63678271272, 10605.416702081306, 14421.78367324491, 14442.711145777026, 14446.638834029234, 14455.927720361331, 14478.782818257734, 7345.632718364061, 7642.656080476392, 7655.464117511832, 7704.114026886868, 7786.532808031537, 7233.240509746506, 7274.96362518184, 7453.776386708408, 7456.701731930771, 7473.663685134182, 6268.4671802897265, 6336.904946425807, 6347.603527224003, 6472.255690595548, 6588.846951783419, 1835.0, 1902.6289873474539, 1904.7714274286898, 1906.551960289331, 1929.5465565592074, 920.2132176988579, 1016.944068076263, 1017.6387382479261, 1021.8313978193663, 1065.7708592652616, 18954.39729732416, 19181.61636767257, 19252.4224273272, 19283.807161928202, 19421.2231510738, 993.3543196922059, 1009.8586197932295, 1023.8976102389702, 1028.4240825137947, 1031.2781053262745, 10482.318649287772, 11038.889611103947, 11185.72035699114, 11282.661520154408, 11307.378094204898, 372101.0, 376735.0, 377909.0, 509859.0, 512722.0, 83633.16366836254, 92645.53677231571, 93926.53036734773, 94606.52696736473, 94611.52694236484, 992.7021893431942, 1007.1388962437157, 1009.5305682857506, 1024.9282550221412, 1028.0850043461326, 16578.58553536171, 16595.336186552402, 16727.996320220827, 16995.49013529596, 17802.91098544551, 31049.534256985713, 31384.843075784476, 41328.38007429951, 41495.96260093401, 42058.369124462544, 58233.70883145557, 58371.416285836596, 58480.70759646175, 59564.40435595762, 60040.39959600348, 7931.325837303893, 7955.323797477279, 8191.959040204761, 8214.301784348398, 8346.749597512084, 10206.0, 11805.114616403976, 12088.75822483563, 12229.3273869938, 12756.42596083197, 10002.199824014207, 10242.6415075472, 10386.48067596602, 11264.436778161224, 11298.830517542408, 10582.788344233226, 10790.298630588986, 11097.223194376638, 11137.832932505857, 11163.72090697739, 10575.259731819007, 10927.289726167774, 11195.60815371458, 11196.496157672762, 11494.540218391505, 7867.488613240121, 8064.0, 8181.918180818354, 8199.918000820155, 8316.335160844093, 10523.36887782395, 10684.198685098803, 10982.450877455936, 11001.449927503752, 11244.718882027688], "value": 9130.624561153656, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.598132569566, 1451.1583281696617, 1461.123326004397, 1658.510739331897, 1814.1745505794856, 8460.619272132753, 8867.822643547133, 8894.243989260913, 9085.954570227139, 9099.454032758023, 8940.8658870117, 9056.909430905689, 9058.637654493828, 9063.0, 9074.319426043057, 2338.24007197661, 2359.6106642403984, 2364.7635236476385, 2365.7989070928907, 2366.3374255208482, 2178.9128434862587, 2218.0, 2218.7781221877844, 2219.755826859044, 2223.4552534628956, 2183.443221978401, 2189.507360843805, 2216.1246307708448, 2217.4345541886796, 2219.678146668732, 2276.704028476304, 2298.770122987696, 2308.8845557722047, 2321.3152120124605, 2322.7677232276806, 2300.2179259051836, 2315.0508291600363, 2318.6406107053435, 2322.268485427085, 2348.459854233519, 8821.779455513599, 8865.335099867536, 8903.243224325875, 8928.15182557656, 8949.463032218087, 4989.750512474398, 5165.9225111623355, 5167.328247327823, 5181.4559471255725, 5195.19474481456, 3378.797272163678, 3526.6473352664784, 3533.1873669056263, 3545.9113522161892, 3574.1064733816547, 1021.5147804792738, 1045.5974449836792, 1055.6780182044474, 1070.6413351527249, 1079.3739631013993, 12480.50077996879, 12592.181508201938, 12632.557860474839, 12640.367981600975, 12669.873301266962, 4186.895327616773, 4286.5284818669925, 4417.823287068545, 4421.53573874745, 4448.021435284232, 9965.750856228507, 9980.0, 9982.700518984442, 9984.750381240527, 9987.400755954666, 5123.8719032023755, 5131.871703207375, 5135.922961155586, 5139.820106296262, 5142.537171654569, 4173.812178451977, 4560.635149188123, 4734.976325118421, 4749.833755818529, 4755.0, 3643.85424583019, 3718.9772812476785, 3737.252549490113, 3787.1857550626746, 3811.6760075393904, 1081.1566279493445, 1097.0346095435966, 1097.7694684116425, 1110.4947249001684, 1112.6828853776635, 863.0161615758041, 871.6339137562239, 930.8696782450462, 951.110711484764, 974.6734843827397, 6142.140100385949, 6147.96926015376, 6169.475594574512, 6172.938270617326, 6438.0, 936.2089034765612, 987.8765154355706, 995.4027583450016, 1026.8870424253325, 1044.6552637629563, 4081.8163182656854, 4267.573242675708, 4284.721493102938, 4284.764337961442, 4427.5351088135285, 11114.333140011626, 11587.130965177645, 11591.0, 11600.18798684084, 11607.477663505162, 11188.608398706006, 11463.254888432226, 11495.252808567417, 11514.481848316846, 11519.48162332697, 8254.711085112112, 8545.786355341166, 8549.401541892008, 8554.743357699279, 8565.057843637194, 6166.537509686702, 6271.153394291895, 6334.986402175629, 6343.682815859281, 6350.460210882126, 10418.635347762793, 10419.374837509773, 10540.051395374454, 10550.261481696207, 10562.630307939493, 10390.688279351632, 10419.843702344622, 10618.36289822613, 10726.463676816284, 10748.301360411548, 6986.47601429905, 7639.57982310956, 7849.4897831640765, 8251.092379838094, 8538.231559159705, 8600.268977137013, 8868.246199072893, 8869.06874778139, 8870.0, 8877.644894204159, 7604.201558836464, 7606.315431611181, 7776.027996500437, 7777.961110194413, 7819.648115834915, 7319.268073192638, 7507.099148102263, 7591.316781489692, 7603.0, 7624.351930086005, 7149.463790215858, 7237.819054523468, 7250.528715633467, 7257.419406447366, 7259.491835571459, 5286.550643195218, 5381.789097453042, 5438.75525601341, 5455.672659640434, 5629.390969705158, 5433.510984011458, 5437.4018857926385, 5479.53423958952, 5505.009098362333, 5605.243292155506], "value": 4657.746390968366, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.45815488134815, 330.25692192566726, 335.3226482505339, 337.80744975364036, 338.0837929211836, 5108.2848401223855, 5157.61317901158, 5258.316418865547, 5263.736813159341, 5276.419593844679, 5113.795448182078, 5154.381474223089, 5180.766865491052, 5211.843644690656, 5220.929709409572, 4103.199876024177, 4104.953236924595, 4112.794360281974, 4117.300058989964, 4137.979275129158, 4035.717499775002, 4082.9183416331657, 4087.713860029799, 4145.274576949037, 4166.416701661769, 4044.5550989391145, 4107.732997355162, 4110.856120035799, 4111.1161100363415, 4121.155163191554, 4056.9797151014054, 4088.325426304656, 4097.344424892033, 4103.815328310233, 4106.1582375612925, 3570.393033184365, 3649.835757390924, 3653.0684675407865, 3673.6142705015855, 3683.410654295327, 5057.317262169597, 5083.466236045236, 5118.513741194581, 5131.1277082895995, 5133.153029750087, 4742.264948932921, 4744.739039352834, 4757.191277482836, 4763.190257656207, 4767.261074533453, 5254.947450525522, 5376.838694839161, 5386.13821788512, 5482.890342193174, 5496.560275177976, 3977.085270387798, 3979.641832235113, 3979.8408063677416, 3984.940225896614, 3993.740406873544, 5617.859553511154, 5639.238702775114, 5651.660900345993, 5670.68811215383, 5680.943190568083, 4064.4758215669126, 4157.168566286755, 4168.103857670615, 4172.582741725864, 4178.0, 4224.176285624274, 4224.197402493578, 4224.598663126998, 4226.154769046203, 4268.317069268902, 4776.901312698029, 4788.736619485892, 4799.568038876518, 4807.543283388072, 4814.711117332971, 5288.629795914249, 5494.835154945358, 5551.361593416728, 5581.5255703265675, 5599.188117722989, 1383.4327925550676, 1407.3174510362467, 1432.6561625209874, 1445.3279225160352, 1448.9492867749577, 990.6180877675603, 1010.7877345757327, 1020.806046851096, 1029.5367084811865, 1030.3766221435924, 5259.500347466985, 5261.921071183935, 5276.1030624793875, 5287.9735601321745, 5291.947080529222, 5579.13523403873, 5589.273394458694, 5605.5515558755205, 5608.439156084359, 5614.6631202128, 5088.211327244283, 5238.476152384731, 5244.2169662315, 5296.364436267596, 5337.932413517312, 2964.925876853096, 2965.481040817848, 2984.7313741763346, 3002.324476992659, 3002.8198308101582, 5835.620684655569, 5851.531877449794, 5863.501602363762, 5889.146073819272, 5921.526277897758, 5527.2538207342295, 5676.858078547987, 5716.971415142898, 5734.254546908958, 5812.564057695689, 5807.5644326675665, 5821.359650438449, 5863.413658634187, 5911.226632010465, 5913.674747888907, 5416.8104116355735, 5571.944280557304, 5593.4126916674795, 5620.6229473779185, 5666.17840413146, 5531.0873705839695, 5569.526590239875, 5574.9721251393485, 5594.0, 5629.746661400165, 5851.473367396955, 5889.234399528034, 5891.410858914246, 5914.911276330945, 5918.319393269829, 3779.3952967525065, 3920.7059470539252, 3924.372100463912, 3935.468711723995, 3945.1320709443876, 5384.946150538444, 5599.524040456607, 5635.802746903839, 5650.915236271541, 5662.433756624469, 5395.190721391823, 5441.156620723636, 5449.427810079885, 5472.890542189213, 5530.446955304437, 5475.534579560782, 5479.890402191854, 5501.394846566955, 5515.448455154453, 5531.751071201725, 5211.1922651989, 5371.301730774974, 5417.510184699239, 5446.346438427414, 5466.781328746805, 5483.698396588066, 5484.862878427912, 5515.944840551703, 5517.393086760373, 5524.530414914777, 5429.375621803385, 5437.8368648940595, 5442.265294185393, 5448.809291674773, 5457.836264912059], "value": 4219.373632496388, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.667328304883, 733.7028503456102, 739.8779201431765, 783.0485959559138, 801.8276070644811, 15282.159481228528, 16299.511014669579, 16335.101569413693, 16360.263788129532, 16364.0, 14734.852651473482, 14831.777523337132, 14879.40482380706, 14887.18120503372, 14902.552923412288, 659.1727382135438, 793.5040599625235, 793.5952664141287, 810.3193317613204, 812.2324403438753, 817.2113910076774, 821.2731732416825, 821.8849361089423, 822.3010441125026, 823.7075838077486, 841.2933136165619, 849.2526576612572, 852.1435956863344, 853.0914575976597, 853.4495250563383, 679.5311235247688, 696.4950410952044, 700.2857085772534, 828.6436832162168, 838.0906716212917, 620.633826042637, 623.3361470034399, 624.8250489862845, 628.5034822490227, 629.0187307799802, 15827.841721582752, 16112.677746445006, 16162.11108389038, 16162.191890405433, 16223.432179873766, 15281.847181528154, 15580.844191558166, 15609.843901560953, 15674.921625391915, 15721.68556628861, 12596.81104783429, 12609.0, 12735.235885846787, 12812.487500499969, 12886.742265154644, 3933.3005889036062, 3966.3852102924093, 3967.8016099194924, 4009.4386785850006, 4140.213359461693, 81226.0, 82747.58626206951, 83168.0, 83515.0, 84563.57718211431, 11841.0, 11983.52065917371, 12028.819567706492, 12047.578334758242, 12269.447874845653, 23255.0, 23634.64548031781, 23669.644955325683, 23679.052837886637, 23873.045078197025, 8950.507722075348, 9080.046595107551, 9149.35954483193, 9154.908450915538, 9171.77070573241, 4347.5652434756275, 4699.647526435531, 4745.6440766942615, 4759.857204283877, 4767.976160119247, 3615.1504396466808, 3724.4413337999517, 3741.933548938594, 3753.5871054184017, 3833.5783063862955, 695.3220609905403, 706.6925887239067, 709.5281637710967, 709.53170907201, 709.5565271705184, 569.5045310579823, 578.6094386289238, 617.8177437655902, 640.4204195203348, 735.5072101691879, 8998.14517620825, 9018.143276388733, 9176.770580735534, 9400.717978460658, 9923.900760992297, 681.9761308354184, 692.6190595172635, 715.0739791969412, 715.8067321823079, 732.5604637217733, 4581.65637577183, 4858.134313125136, 4994.175960966472, 5041.143005689041, 5085.898282034413, 117930.0, 128377.35811320883, 129081.0, 131861.34069329593, 134902.32548837192, 62029.68985155045, 63800.68099659472, 64907.67546162239, 109838.0, 112224.0, 94049.5297523508, 94110.52944735232, 96009.51995240118, 97121.51439242759, 98889.50555247322, 9390.154886060287, 9487.857682134636, 9607.951960240434, 9666.178374838217, 9676.564554595201, 53977.46022539724, 54110.72944635252, 54783.0, 55911.16133257923, 56175.43824561702, 76593.23406766084, 76734.0, 77425.61287193529, 78224.60887695753, 80457.19542804497, 11310.321380716854, 11327.490262938352, 11383.658490245305, 11484.081273498266, 11484.355991201048, 12705.905786276193, 12811.551595694105, 12940.935295323463, 12997.870021299666, 13501.864981350061, 13066.216027038408, 13276.137051091648, 13486.93256533711, 13506.459741610226, 13681.110727802661, 12676.429560669607, 12909.741805163656, 12915.935420323214, 12996.545120920722, 13645.590632281048, 11530.654080377602, 11605.941970290094, 11837.408129593658, 11981.76036479283, 12042.819357709466, 9771.91912278896, 10090.495475226357, 10251.48742562854, 10468.63359782404, 10481.947590262305, 10023.248256380946, 10524.321035676123, 10879.78240435171, 11112.0, 11128.44357782124], "value": 7568.093443906331, "unit": "score"}], "name": "dromaeo_css", "value": 7245.336880470393, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 112.0, 110.0, 112.0, 111.0, 112.0, 111.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 127.0, 197.0, 119.0, 129.0, 151.0, 116.0, 186.0, 122.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 161.0, 161.0, 162.0, 162.0, 161.0, 163.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 117.0, 75.0, 77.0, 74.0, 75.0, 100.0, 74.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 115.0, 84.0, 83.0, 84.0, 117.0, 84.0, 85.0, 126.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 121.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 149.0, 145.0, 147.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 58.0, 52.0, 51.0, 53.0, 52.0, 51.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 63.0, 64.0, 64.0, 69.0, 65.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 79.0, 79.0, 76.0, 77.0, 76.0, 77.0, 76.0, 75.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 125.0, 122.0, 132.0, 126.0, 144.0, 130.0, 123.0, 160.0, 136.0], "value": 132.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 161.0, 163.0, 170.0, 162.0, 161.0, 169.0, 166.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 64.0, 65.0, 66.0, 64.0, 65.0, 63.0, 62.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1504.8000000000002, "alertThreshold": 2.0}]} 09:56:29 INFO - Return code: 0 09:56:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:56:29 INFO - # TBPL SUCCESS # 09:56:29 INFO - Running post-action listener: _resource_record_post_action 09:56:29 INFO - [mozharness: 2016-07-29 16:56:29.294902Z] Finished run-tests step (success) 09:56:29 INFO - Running post-run listener: _resource_record_post_run 09:56:29 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 570527744; Read time: 12; Write time: 662736 09:56:29 INFO - TinderboxPrint: CPU usage
13.0% 09:56:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:56:29 INFO - TinderboxPrint: I/O write bytes / time
570,527,744 / 662,736 09:56:29 INFO - TinderboxPrint: CPU idle
8,287.7 (87.0%) 09:56:29 INFO - TinderboxPrint: CPU user
1,190.0 (12.5%) 09:56:29 INFO - TinderboxPrint: Swap in / out
0 / 0 09:56:29 INFO - install - Wall time: 18s; CPU: 15.0%; Read bytes: 0; Write bytes: 276021248; Read time: 0; Write time: 412248 09:56:29 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 294506496; Read time: 0; Write time: 250488 09:56:29 INFO - Running post-run listener: _upload_blobber_files 09:56:29 INFO - Blob upload gear active. 09:56:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:56:29 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 09:56:29 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:56:29 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:56:30 INFO - (blobuploader) - INFO - Open directory for files ... 09:56:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:56:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:56:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:38 INFO - (blobuploader) - INFO - Done attempting. 09:56:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:56:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:56:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:39 INFO - (blobuploader) - INFO - Done attempting. 09:56:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:56:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:56:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:39 INFO - (blobuploader) - INFO - Done attempting. 09:56:39 INFO - (blobuploader) - INFO - Iteration through files over. 09:56:39 INFO - Return code: 0 09:56:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:56:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:56:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa7f5943d6c61d529e75ca50e446c9de41ad7134181c04aee715bc79cdc6722036596b739471a1d47417810890177397e0e0e64455ebe4e366a97ecee2f99897", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bc4b97191ac3162a71f9c5d019f7672f94b7695ff440fa7d4333561fac9436500ff83889ff2992fb7d9e53c0d2c9090a066988509432dda5effc6bb04dee1c06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bdb31e163523105f3c6f630f1d626261fe64b4f8e67a667938a2683b6f7972034f72ab2ed5dc24d2bb79e8b9d67172b867bcb57b9a9bddab728d40770f3f27d0"} 09:56:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:56:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:56:39 INFO - Contents: 09:56:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa7f5943d6c61d529e75ca50e446c9de41ad7134181c04aee715bc79cdc6722036596b739471a1d47417810890177397e0e0e64455ebe4e366a97ecee2f99897", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bc4b97191ac3162a71f9c5d019f7672f94b7695ff440fa7d4333561fac9436500ff83889ff2992fb7d9e53c0d2c9090a066988509432dda5effc6bb04dee1c06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bdb31e163523105f3c6f630f1d626261fe64b4f8e67a667938a2683b6f7972034f72ab2ed5dc24d2bb79e8b9d67172b867bcb57b9a9bddab728d40770f3f27d0"} 09:56:39 INFO - Running post-run listener: copy_logs_to_upload_dir 09:56:39 INFO - Copying logs to upload dir... 09:56:39 INFO - mkdir: /builds/slave/test/build/upload/logs 09:56:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.783380 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-29 09:56:39.218006) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-29 09:56:39.221633) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa7f5943d6c61d529e75ca50e446c9de41ad7134181c04aee715bc79cdc6722036596b739471a1d47417810890177397e0e0e64455ebe4e366a97ecee2f99897", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bc4b97191ac3162a71f9c5d019f7672f94b7695ff440fa7d4333561fac9436500ff83889ff2992fb7d9e53c0d2c9090a066988509432dda5effc6bb04dee1c06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bdb31e163523105f3c6f630f1d626261fe64b4f8e67a667938a2683b6f7972034f72ab2ed5dc24d2bb79e8b9d67172b867bcb57b9a9bddab728d40770f3f27d0"} build_url:https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/dpUavv9MRyaLkgyDNVeu1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa7f5943d6c61d529e75ca50e446c9de41ad7134181c04aee715bc79cdc6722036596b739471a1d47417810890177397e0e0e64455ebe4e366a97ecee2f99897", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bc4b97191ac3162a71f9c5d019f7672f94b7695ff440fa7d4333561fac9436500ff83889ff2992fb7d9e53c0d2c9090a066988509432dda5effc6bb04dee1c06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bdb31e163523105f3c6f630f1d626261fe64b4f8e67a667938a2683b6f7972034f72ab2ed5dc24d2bb79e8b9d67172b867bcb57b9a9bddab728d40770f3f27d0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-29 09:56:39.250858) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 09:56:39.251189) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1469808038.341630-1084721510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 09:56:39.343652) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-29 09:56:39.344002) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-29 09:56:39.344332) ========= ========= Total master_lag: 0.17 =========