builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-027 starttime: 1469592071.61 results: success (0) buildid: 20160726194513 builduid: fdf88536afd44ec59f8a5ff45e3b4384 revision: b1832f8aecd0c1a3bd1579cb1e85d0c03b217a2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.608495) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.608985) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.609280) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.662555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.662851) ========= 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=1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 21:01:11-- 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% 33.5M=0s 2016-07-26 21:01:11 (33.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127741 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.816255) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.816595) ========= 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=1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019227 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.871896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:11.872227) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b1832f8aecd0c1a3bd1579cb1e85d0c03b217a2f --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 b1832f8aecd0c1a3bd1579cb1e85d0c03b217a2f --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=1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 21:01:12,025 truncating revision to first 12 chars 2016-07-26 21:01:12,025 Setting DEBUG logging. 2016-07-26 21:01:12,025 attempt 1/10 2016-07-26 21:01:12,025 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b1832f8aecd0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 21:01:12,338 unpacking tar archive at: autoland-b1832f8aecd0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.952584 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:12.839397) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:12.839728) ========= script_repo_revision: b1832f8aecd0c1a3bd1579cb1e85d0c03b217a2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:12.840139) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:12.840440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 21:01:12.854011) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-26 21:01:12.854294) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:01:13 INFO - MultiFileLogger online at 20160726 21:01:13 in /builds/slave/test 21:01:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 21:01:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:01:13 INFO - {'append_to_log': False, 21:01:13 INFO - 'base_work_dir': '/builds/slave/test', 21:01:13 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 21:01:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:01:13 INFO - 'branch': 'Autoland-Non-PGO', 21:01:13 INFO - 'buildbot_json_path': 'buildprops.json', 21:01:13 INFO - 'config_files': ('talos/linux_config.py',), 21:01:13 INFO - 'default_actions': ('clobber', 21:01:13 INFO - 'read-buildbot-config', 21:01:13 INFO - 'download-and-extract', 21:01:13 INFO - 'populate-webroot', 21:01:13 INFO - 'create-virtualenv', 21:01:13 INFO - 'install', 21:01:13 INFO - 'run-tests'), 21:01:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:01:13 INFO - 'download_minidump_stackwalk': True, 21:01:13 INFO - 'download_symbols': 'ondemand', 21:01:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:01:13 INFO - 'tooltool.py': '/tools/tooltool.py', 21:01:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:01:13 INFO - '/tools/misc-python/virtualenv.py')}, 21:01:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:01:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:01:13 INFO - 'installer_path': 'installer.exe', 21:01:13 INFO - 'log_level': 'info', 21:01:13 INFO - 'log_name': 'talos', 21:01:13 INFO - 'log_to_console': True, 21:01:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:01:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:01:13 INFO - 'opt_config_files': (), 21:01:13 INFO - 'pip_index': False, 21:01:13 INFO - 'sps_profile': False, 21:01:13 INFO - 'sps_profile_interval': 0, 21:01:13 INFO - 'suite': 'dromaeojs', 21:01:13 INFO - 'system_bits': '32', 21:01:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:01:13 INFO - 'title': 'talos-linux64-ix-027', 21:01:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:01:13 INFO - 'use_talos_json': True, 21:01:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:01:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:01:13 INFO - 'work_dir': 'build'} 21:01:13 INFO - [mozharness: 2016-07-27 04:01:13.105624Z] Running clobber step. 21:01:13 INFO - Running pre-action listener: _resource_record_pre_action 21:01:13 INFO - Running main action method: clobber 21:01:13 INFO - rmtree: /builds/slave/test/build 21:01:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:01:14 INFO - Running post-action listener: _resource_record_post_action 21:01:14 INFO - [mozharness: 2016-07-27 04:01:14.091470Z] Finished clobber step (success) 21:01:14 INFO - [mozharness: 2016-07-27 04:01:14.091573Z] Running read-buildbot-config step. 21:01:14 INFO - Running pre-action listener: _resource_record_pre_action 21:01:14 INFO - Running main action method: read_buildbot_config 21:01:14 INFO - Using buildbot properties: 21:01:14 INFO - { 21:01:14 INFO - "project": "", 21:01:14 INFO - "product": "firefox", 21:01:14 INFO - "script_repo_revision": "production", 21:01:14 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:01:14 INFO - "repository": "", 21:01:14 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 21:01:14 INFO - "buildid": "20160726194513", 21:01:14 INFO - "pgo_build": "False", 21:01:14 INFO - "basedir": "/builds/slave/test", 21:01:14 INFO - "buildnumber": 126, 21:01:14 INFO - "slavename": "talos-linux64-ix-027", 21:01:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:01:14 INFO - "platform": "ubuntu64_hw", 21:01:14 INFO - "scheduler": "tests-autoland-linux64-talos", 21:01:14 INFO - "branch": "autoland", 21:01:14 INFO - "repo_path": "integration/autoland", 21:01:14 INFO - "revision": "b1832f8aecd0c1a3bd1579cb1e85d0c03b217a2f", 21:01:14 INFO - "stage_platform": "linux64", 21:01:14 INFO - "builduid": "fdf88536afd44ec59f8a5ff45e3b4384", 21:01:14 INFO - "slavebuilddir": "test" 21:01:14 INFO - } 21:01:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 21:01:14 INFO - Running post-action listener: _resource_record_post_action 21:01:14 INFO - [mozharness: 2016-07-27 04:01:14.094045Z] Finished read-buildbot-config step (success) 21:01:14 INFO - [mozharness: 2016-07-27 04:01:14.094134Z] Running download-and-extract step. 21:01:14 INFO - Running pre-action listener: _resource_record_pre_action 21:01:14 INFO - Running main action method: download_and_extract 21:01:14 INFO - mkdir: /builds/slave/test/build/tests 21:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:14 INFO - https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:01:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:01:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:01:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:17 INFO - Downloaded 1361 bytes. 21:01:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:01:17 INFO - Using the following test package requirements: 21:01:17 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:01:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:01:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:01:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:17 INFO - u'jsshell-linux-x86_64.zip'], 21:01:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:01:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:01:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:01:17 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:01:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:01:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:01:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:17 INFO - https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:01:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:01:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:01:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:24 INFO - Downloaded 22744791 bytes. 21:01:24 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'] 21:01:24 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 21:01:25 INFO - Return code: 0 21:01:25 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 21:01:25 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')]} 21:01:25 INFO - https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:01:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:01:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:01:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:27 INFO - Downloaded 22744791 bytes. 21:01:27 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'] 21:01:27 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 21:01:29 INFO - Return code: 0 21:01:29 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')]} 21:01:29 INFO - https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:01:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:01:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:01:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:30 INFO - Downloaded 14413725 bytes. 21:01:30 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'] 21:01:30 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 21:01:30 INFO - Return code: 0 21:01: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')]} 21:01:30 INFO - https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:01:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:01:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:01:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bGq6capUSaKOfmcWhT798Q/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 21:01:32 INFO - Downloaded 57173529 bytes. 21:01:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:01:32 INFO - mkdir: /builds/slave/test/properties 21:01:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:01:32 INFO - Writing to file /builds/slave/test/properties/build_url 21:01:32 INFO - Contents: 21:01:32 INFO - build_url:https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:01:32 INFO - Running post-action listener: _resource_record_post_action 21:01:32 INFO - Running post-action listener: set_extra_try_arguments 21:01:32 INFO - [mozharness: 2016-07-27 04:01:32.741783Z] Finished download-and-extract step (success) 21:01:32 INFO - [mozharness: 2016-07-27 04:01:32.742017Z] Running populate-webroot step. 21:01:32 INFO - Running pre-action listener: _resource_record_pre_action 21:01:32 INFO - Running main action method: populate_webroot 21:01:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:01:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:01:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:01:32 INFO - '--tppagecycles', 21:01:32 INFO - '7'], 21:01:32 INFO - 'tests': ['tsvgm']}}, 21:01:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:01:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:01:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:01:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:01:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:32 INFO - 'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:01:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:01:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:32 INFO - 'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['damp', 'tps']}, 21:01:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:32 INFO - 'tests': ['damp', 'tps']}, 21:01:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['dromaeo_dom']}, 21:01:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:01:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['basic_compositor_video']}, 21:01:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:01:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['a11yr', 21:01:32 INFO - 'ts_paint', 21:01:32 INFO - 'tpaint', 21:01:32 INFO - 'sessionrestore', 21:01:32 INFO - 'sessionrestore_no_auto_restore', 21:01:32 INFO - 'tabpaint']}, 21:01:32 INFO - 'other-e10s': {'tests': ['a11yr', 21:01:32 INFO - 'ts_paint', 21:01:32 INFO - 'tpaint', 21:01:32 INFO - 'sessionrestore', 21:01:32 INFO - 'sessionrestore_no_auto_restore', 21:01:32 INFO - 'tabpaint']}, 21:01:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:01:32 INFO - 'ts_paint', 21:01:32 INFO - 'tpaint', 21:01:32 INFO - 'sessionrestore', 21:01:32 INFO - 'sessionrestore_no_auto_restore', 21:01:32 INFO - 'tabpaint']}, 21:01:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:01:32 INFO - 'ts_paint', 21:01:32 INFO - 'tpaint', 21:01:32 INFO - 'sessionrestore', 21:01:32 INFO - 'sessionrestore_no_auto_restore', 21:01:32 INFO - 'tabpaint']}, 21:01:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['a11yr', 21:01:32 INFO - 'ts_paint', 21:01:32 INFO - 'tpaint', 21:01:32 INFO - 'sessionrestore', 21:01:32 INFO - 'sessionrestore_no_auto_restore', 21:01:32 INFO - 'tabpaint']}, 21:01:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['a11yr', 21:01:32 INFO - 'ts_paint', 21:01:32 INFO - 'tpaint', 21:01:32 INFO - 'sessionrestore', 21:01:32 INFO - 'sessionrestore_no_auto_restore', 21:01:32 INFO - 'tabpaint']}, 21:01:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['tsvgx', 21:01:32 INFO - 'tsvgr_opacity', 21:01:32 INFO - 'tart', 21:01:32 INFO - 'tscrollx', 21:01:32 INFO - 'cart']}, 21:01:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:01:32 INFO - 'tsvgr_opacity', 21:01:32 INFO - 'tart', 21:01:32 INFO - 'tscrollx', 21:01:32 INFO - 'cart']}, 21:01:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:32 INFO - 'talos_options': ['--disable-e10s'], 21:01:32 INFO - 'tests': ['tp5o']}, 21:01:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:32 INFO - 'tests': ['tp5o']}, 21:01:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:32 INFO - 'talos_options': ['--disable-e10s', 21:01:32 INFO - '--xperf_path', 21:01:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:01:32 INFO - 'tests': ['tp5n']}, 21:01:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:32 INFO - 'talos_options': ['--xperf_path', 21:01:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:01:32 INFO - 'tests': ['tp5n']}}, 21:01:32 INFO - 'talos.zip': {'path': '', 21:01:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:01:32 INFO - Running post-action listener: _resource_record_post_action 21:01:32 INFO - [mozharness: 2016-07-27 04:01:32.760164Z] Finished populate-webroot step (success) 21:01:32 INFO - [mozharness: 2016-07-27 04:01:32.760254Z] Running create-virtualenv step. 21:01:32 INFO - Running pre-action listener: _resource_record_pre_action 21:01:32 INFO - Running main action method: create_virtualenv 21:01:32 INFO - Creating virtualenv /builds/slave/test/build/venv 21:01:32 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 21:01:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:01:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:01:32 INFO - Using real prefix '/usr' 21:01:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:01:33 INFO - Installing distribute.............................................................................................................................................................................................done. 21:01:35 INFO - Installing pip.................done. 21:01:35 INFO - Return code: 0 21:01:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:35 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:35 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 21:01:35 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 21:01:35 INFO - Using env: {'DISPLAY': ':0', 21:01:35 INFO - 'HOME': '/home/cltbld', 21:01:35 INFO - 'LANG': 'en_US.UTF-8', 21:01:35 INFO - 'LANGUAGE': 'en_US:en', 21:01:35 INFO - 'LOGNAME': 'cltbld', 21:01:35 INFO - 'MAIL': '/var/mail/cltbld', 21:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:35 INFO - 'MOZ_NO_REMOTE': '1', 21:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:35 INFO - 'NO_EM_RESTART': '1', 21:01:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:35 INFO - 'PWD': '/builds/slave/test', 21:01:35 INFO - 'SHELL': '/bin/bash', 21:01:35 INFO - 'SHLVL': '1', 21:01:35 INFO - 'TERM': 'linux', 21:01:35 INFO - 'TMOUT': '86400', 21:01:35 INFO - 'USER': 'cltbld', 21:01:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:35 INFO - '_': '/tools/buildbot/bin/python'} 21:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:35 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 21:01:35 INFO - Cleaning up... 21:01:35 INFO - Return code: 0 21:01:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:35 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:35 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 21:01:35 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 21:01:35 INFO - Using env: {'DISPLAY': ':0', 21:01:35 INFO - 'HOME': '/home/cltbld', 21:01:35 INFO - 'LANG': 'en_US.UTF-8', 21:01:35 INFO - 'LANGUAGE': 'en_US:en', 21:01:35 INFO - 'LOGNAME': 'cltbld', 21:01:35 INFO - 'MAIL': '/var/mail/cltbld', 21:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:35 INFO - 'MOZ_NO_REMOTE': '1', 21:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:35 INFO - 'NO_EM_RESTART': '1', 21:01:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:35 INFO - 'PWD': '/builds/slave/test', 21:01:35 INFO - 'SHELL': '/bin/bash', 21:01:35 INFO - 'SHLVL': '1', 21:01:35 INFO - 'TERM': 'linux', 21:01:35 INFO - 'TMOUT': '86400', 21:01:35 INFO - 'USER': 'cltbld', 21:01:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:35 INFO - '_': '/tools/buildbot/bin/python'} 21:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:35 INFO - Downloading/unpacking psutil>=3.1.1 21:01:35 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 21:01:35 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 21:01:35 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 21:01:35 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 21:01:35 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 21:01:35 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 21:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:01:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:01:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:01:37 INFO - Installing collected packages: psutil 21:01:37 INFO - Running setup.py install for psutil 21:01:37 INFO - building 'psutil._psutil_linux' extension 21:01:37 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 21:01:38 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 21:01:38 INFO - building 'psutil._psutil_posix' extension 21:01:38 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 21:01:38 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 21:01:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:01:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:01:38 INFO - Successfully installed psutil 21:01:38 INFO - Cleaning up... 21:01:38 INFO - Return code: 0 21:01:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:38 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:38 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 21:01:38 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 21:01:38 INFO - Using env: {'DISPLAY': ':0', 21:01:38 INFO - 'HOME': '/home/cltbld', 21:01:38 INFO - 'LANG': 'en_US.UTF-8', 21:01:38 INFO - 'LANGUAGE': 'en_US:en', 21:01:38 INFO - 'LOGNAME': 'cltbld', 21:01:38 INFO - 'MAIL': '/var/mail/cltbld', 21:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:38 INFO - 'MOZ_NO_REMOTE': '1', 21:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:38 INFO - 'NO_EM_RESTART': '1', 21:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:38 INFO - 'PWD': '/builds/slave/test', 21:01:38 INFO - 'SHELL': '/bin/bash', 21:01:38 INFO - 'SHLVL': '1', 21:01:38 INFO - 'TERM': 'linux', 21:01:38 INFO - 'TMOUT': '86400', 21:01:38 INFO - 'USER': 'cltbld', 21:01:38 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:38 INFO - '_': '/tools/buildbot/bin/python'} 21:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:01:38 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 21:01:38 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 21:01:38 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 21:01:38 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 21:01:38 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 21:01:38 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 21:01:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:01:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:01:40 INFO - Installing collected packages: mozsystemmonitor 21:01:40 INFO - Running setup.py install for mozsystemmonitor 21:01:40 INFO - Successfully installed mozsystemmonitor 21:01:40 INFO - Cleaning up... 21:01:40 INFO - Return code: 0 21:01:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:40 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:40 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 21:01:40 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 21:01:40 INFO - Using env: {'DISPLAY': ':0', 21:01:40 INFO - 'HOME': '/home/cltbld', 21:01:40 INFO - 'LANG': 'en_US.UTF-8', 21:01:40 INFO - 'LANGUAGE': 'en_US:en', 21:01:40 INFO - 'LOGNAME': 'cltbld', 21:01:40 INFO - 'MAIL': '/var/mail/cltbld', 21:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:40 INFO - 'MOZ_NO_REMOTE': '1', 21:01:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:40 INFO - 'NO_EM_RESTART': '1', 21:01:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:40 INFO - 'PWD': '/builds/slave/test', 21:01:40 INFO - 'SHELL': '/bin/bash', 21:01:40 INFO - 'SHLVL': '1', 21:01:40 INFO - 'TERM': 'linux', 21:01:40 INFO - 'TMOUT': '86400', 21:01:40 INFO - 'USER': 'cltbld', 21:01:40 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:40 INFO - '_': '/tools/buildbot/bin/python'} 21:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:40 INFO - Downloading/unpacking jsonschema==2.5.1 21:01:40 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 21:01:40 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 21:01:40 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 21:01:40 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 21:01:40 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 21:01:40 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 21:01:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:01:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:01:42 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 21:01:42 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 21:01:42 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 21:01:42 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 21:01:42 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 21:01:42 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 21:01:42 INFO - Downloading functools32-3.2.3-2.tar.gz 21:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:01:42 INFO - warning: no files found matching '*.txt' 21:01:42 INFO - no previously-included directories found matching 'build' 21:01:42 INFO - no previously-included directories found matching 'dist' 21:01:42 INFO - no previously-included directories found matching '.git*' 21:01:42 INFO - Installing collected packages: jsonschema, functools32 21:01:43 INFO - Running setup.py install for functools32 21:01:43 INFO - warning: no files found matching '*.txt' 21:01:43 INFO - no previously-included directories found matching 'build' 21:01:43 INFO - no previously-included directories found matching 'dist' 21:01:43 INFO - no previously-included directories found matching '.git*' 21:01:43 INFO - Successfully installed jsonschema functools32 21:01:43 INFO - Cleaning up... 21:01:43 INFO - Return code: 0 21:01:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:43 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:43 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 21:01:43 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 21:01:43 INFO - Using env: {'DISPLAY': ':0', 21:01:43 INFO - 'HOME': '/home/cltbld', 21:01:43 INFO - 'LANG': 'en_US.UTF-8', 21:01:43 INFO - 'LANGUAGE': 'en_US:en', 21:01:43 INFO - 'LOGNAME': 'cltbld', 21:01:43 INFO - 'MAIL': '/var/mail/cltbld', 21:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:43 INFO - 'MOZ_NO_REMOTE': '1', 21:01:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:43 INFO - 'NO_EM_RESTART': '1', 21:01:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:43 INFO - 'PWD': '/builds/slave/test', 21:01:43 INFO - 'SHELL': '/bin/bash', 21:01:43 INFO - 'SHLVL': '1', 21:01:43 INFO - 'TERM': 'linux', 21:01:43 INFO - 'TMOUT': '86400', 21:01:43 INFO - 'USER': 'cltbld', 21:01:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:43 INFO - '_': '/tools/buildbot/bin/python'} 21:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:01:43 INFO - Cleaning up... 21:01:43 INFO - Return code: 0 21:01:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:43 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:43 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 21:01:43 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 21:01:43 INFO - Using env: {'DISPLAY': ':0', 21:01:43 INFO - 'HOME': '/home/cltbld', 21:01:43 INFO - 'LANG': 'en_US.UTF-8', 21:01:43 INFO - 'LANGUAGE': 'en_US:en', 21:01:43 INFO - 'LOGNAME': 'cltbld', 21:01:43 INFO - 'MAIL': '/var/mail/cltbld', 21:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:43 INFO - 'MOZ_NO_REMOTE': '1', 21:01:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:43 INFO - 'NO_EM_RESTART': '1', 21:01:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:43 INFO - 'PWD': '/builds/slave/test', 21:01:43 INFO - 'SHELL': '/bin/bash', 21:01:43 INFO - 'SHLVL': '1', 21:01:43 INFO - 'TERM': 'linux', 21:01:43 INFO - 'TMOUT': '86400', 21:01:43 INFO - 'USER': 'cltbld', 21:01:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:43 INFO - '_': '/tools/buildbot/bin/python'} 21:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:43 INFO - Downloading/unpacking blobuploader==1.2.4 21:01:43 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 21:01:43 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 21:01:43 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 21:01:43 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 21:01:43 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 21:01:43 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 21:01:45 INFO - Downloading blobuploader-1.2.4.tar.gz 21:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:01:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:01:45 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 21:01:45 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 21:01:45 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 21:01:45 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 21:01:45 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 21:01:45 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 21:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:01:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:01:46 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 21:01:46 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 21:01:46 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 21:01:46 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 21:01:46 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 21:01:46 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 21:01:46 INFO - Downloading docopt-0.6.1.tar.gz 21:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:01:46 INFO - Installing collected packages: blobuploader, requests, docopt 21:01:46 INFO - Running setup.py install for blobuploader 21:01:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:01:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:01:46 INFO - Running setup.py install for requests 21:01:46 INFO - Running setup.py install for docopt 21:01:46 INFO - Successfully installed blobuploader requests docopt 21:01:46 INFO - Cleaning up... 21:01:46 INFO - Return code: 0 21:01:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:46 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:46 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 21:01:46 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 21:01:46 INFO - Using env: {'DISPLAY': ':0', 21:01:46 INFO - 'HOME': '/home/cltbld', 21:01:46 INFO - 'LANG': 'en_US.UTF-8', 21:01:46 INFO - 'LANGUAGE': 'en_US:en', 21:01:46 INFO - 'LOGNAME': 'cltbld', 21:01:46 INFO - 'MAIL': '/var/mail/cltbld', 21:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:46 INFO - 'MOZ_NO_REMOTE': '1', 21:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:46 INFO - 'NO_EM_RESTART': '1', 21:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:46 INFO - 'PWD': '/builds/slave/test', 21:01:46 INFO - 'SHELL': '/bin/bash', 21:01:46 INFO - 'SHLVL': '1', 21:01:46 INFO - 'TERM': 'linux', 21:01:46 INFO - 'TMOUT': '86400', 21:01:46 INFO - 'USER': 'cltbld', 21:01:46 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:46 INFO - '_': '/tools/buildbot/bin/python'} 21:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:01:47 INFO - Running setup.py (path:/tmp/pip-Q2mo2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:01:47 INFO - Running setup.py (path:/tmp/pip-3Ymruk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:01:47 INFO - Running setup.py (path:/tmp/pip-UHfTHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:01:47 INFO - Running setup.py (path:/tmp/pip-dFrbMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:01:47 INFO - Running setup.py (path:/tmp/pip-mQvSMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:01:47 INFO - Running setup.py (path:/tmp/pip-XZgZ82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:01:47 INFO - Running setup.py (path:/tmp/pip-uqyVC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:01:47 INFO - Running setup.py (path:/tmp/pip-HJu_8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:01:47 INFO - Running setup.py (path:/tmp/pip-AgiyWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:01:47 INFO - Running setup.py (path:/tmp/pip-W9aheV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:01:47 INFO - Running setup.py (path:/tmp/pip-8CmwCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:01:47 INFO - Running setup.py (path:/tmp/pip-97amJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:47 INFO - Running setup.py (path:/tmp/pip-e0npIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:48 INFO - Running setup.py (path:/tmp/pip-WA1aYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:48 INFO - Running setup.py (path:/tmp/pip-7QFB3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:48 INFO - Running setup.py (path:/tmp/pip-xHi10S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:48 INFO - Running setup.py (path:/tmp/pip-yzmRnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:01:48 INFO - Running setup.py install for manifestparser 21:01:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:01:48 INFO - Running setup.py install for mozcrash 21:01:48 INFO - Running setup.py install for mozdebug 21:01:48 INFO - Running setup.py install for mozdevice 21:01:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:01:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:01:48 INFO - Running setup.py install for mozfile 21:01:48 INFO - Running setup.py install for mozhttpd 21:01:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:01:48 INFO - Running setup.py install for mozinfo 21:01:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Running setup.py install for mozInstall 21:01:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Running setup.py install for mozleak 21:01:49 INFO - Running setup.py install for mozlog 21:01:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Running setup.py install for moznetwork 21:01:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Running setup.py install for mozprocess 21:01:49 INFO - Running setup.py install for mozprofile 21:01:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Running setup.py install for mozrunner 21:01:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:01:49 INFO - Running setup.py install for mozscreenshot 21:01:49 INFO - Running setup.py install for moztest 21:01:50 INFO - Running setup.py install for mozversion 21:01:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:01:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:01:50 INFO - Cleaning up... 21:01:50 INFO - Return code: 0 21:01:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:50 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:50 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 21:01:50 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 21:01:50 INFO - Using env: {'DISPLAY': ':0', 21:01:50 INFO - 'HOME': '/home/cltbld', 21:01:50 INFO - 'LANG': 'en_US.UTF-8', 21:01:50 INFO - 'LANGUAGE': 'en_US:en', 21:01:50 INFO - 'LOGNAME': 'cltbld', 21:01:50 INFO - 'MAIL': '/var/mail/cltbld', 21:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:50 INFO - 'MOZ_NO_REMOTE': '1', 21:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:50 INFO - 'NO_EM_RESTART': '1', 21:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:50 INFO - 'PWD': '/builds/slave/test', 21:01:50 INFO - 'SHELL': '/bin/bash', 21:01:50 INFO - 'SHLVL': '1', 21:01:50 INFO - 'TERM': 'linux', 21:01:50 INFO - 'TMOUT': '86400', 21:01:50 INFO - 'USER': 'cltbld', 21:01:50 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:50 INFO - '_': '/tools/buildbot/bin/python'} 21:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:01:50 INFO - Running setup.py (path:/tmp/pip-nCWZyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:01:50 INFO - Running setup.py (path:/tmp/pip-HuR3sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:01:50 INFO - Running setup.py (path:/tmp/pip-0T8mYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:01:50 INFO - Running setup.py (path:/tmp/pip-k3wL8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:01:50 INFO - Running setup.py (path:/tmp/pip-MknSu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:01:50 INFO - Running setup.py (path:/tmp/pip-yYYH1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:01:50 INFO - Running setup.py (path:/tmp/pip-pZJh7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:01:50 INFO - Running setup.py (path:/tmp/pip-R6Jr2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:01:50 INFO - Running setup.py (path:/tmp/pip-vKMedl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:01:50 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)) 21:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:01:51 INFO - Running setup.py (path:/tmp/pip-nbqVIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:01:51 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)) 21:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:01:51 INFO - Running setup.py (path:/tmp/pip-aOFd_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:01:51 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)) 21:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:01:51 INFO - Running setup.py (path:/tmp/pip-MTSs0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:51 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)) 21:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:51 INFO - Running setup.py (path:/tmp/pip-mUKEjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:51 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)) 21:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:51 INFO - Running setup.py (path:/tmp/pip-Xo4Jps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:51 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)) 21:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:51 INFO - Running setup.py (path:/tmp/pip-ADLBFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:51 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)) 21:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:51 INFO - Running setup.py (path:/tmp/pip-vx4zJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:51 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)) 21:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:51 INFO - Running setup.py (path:/tmp/pip-a6gyPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:51 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)) 21:01:51 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)) 21:01:51 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)) 21:01:51 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)) 21:01:51 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)) 21:01:51 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)) 21:01:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:01:51 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 21:01:51 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 21:01:51 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 21:01:51 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 21:01:51 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 21:01:51 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 21:01:53 INFO - Downloading blessings-1.6.tar.gz 21:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:01:53 INFO - Installing collected packages: blessings 21:01:53 INFO - Running setup.py install for blessings 21:01:53 INFO - Successfully installed blessings 21:01:53 INFO - Cleaning up... 21:01:53 INFO - Return code: 0 21:01:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:01:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:53 INFO - Reading from file tmpfile_stdout 21:01:53 INFO - Current package versions: 21:01:53 INFO - argparse == 1.2.1 21:01:53 INFO - blessings == 1.6 21:01:53 INFO - blobuploader == 1.2.4 21:01:53 INFO - docopt == 0.6.1 21:01:53 INFO - functools32 == 3.2.3-2 21:01:53 INFO - jsonschema == 2.5.1 21:01:53 INFO - manifestparser == 1.1 21:01:53 INFO - mozInstall == 1.12 21:01:53 INFO - mozcrash == 0.17 21:01:53 INFO - mozdebug == 0.1 21:01:53 INFO - mozdevice == 0.48 21:01:53 INFO - mozfile == 1.2 21:01:53 INFO - mozhttpd == 0.7 21:01:53 INFO - mozinfo == 0.9 21:01:53 INFO - mozleak == 0.1 21:01:53 INFO - mozlog == 3.3 21:01:53 INFO - moznetwork == 0.27 21:01:53 INFO - mozprocess == 0.23 21:01:53 INFO - mozprofile == 0.28 21:01:53 INFO - mozrunner == 6.12 21:01:53 INFO - mozscreenshot == 0.1 21:01:53 INFO - mozsystemmonitor == 0.3 21:01:53 INFO - moztest == 0.7 21:01:53 INFO - mozversion == 1.4 21:01:53 INFO - psutil == 3.1.1 21:01:53 INFO - requests == 1.2.3 21:01:53 INFO - wsgiref == 0.1.2 21:01:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:53 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')]} 21:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:53 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')]} 21:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:53 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 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:53 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 21:01:53 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 21:01:53 INFO - Using env: {'DISPLAY': ':0', 21:01:53 INFO - 'HOME': '/home/cltbld', 21:01:53 INFO - 'LANG': 'en_US.UTF-8', 21:01:53 INFO - 'LANGUAGE': 'en_US:en', 21:01:53 INFO - 'LOGNAME': 'cltbld', 21:01:53 INFO - 'MAIL': '/var/mail/cltbld', 21:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:53 INFO - 'MOZ_NO_REMOTE': '1', 21:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:53 INFO - 'NO_EM_RESTART': '1', 21:01:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:53 INFO - 'PWD': '/builds/slave/test', 21:01:53 INFO - 'SHELL': '/bin/bash', 21:01:53 INFO - 'SHLVL': '1', 21:01:53 INFO - 'TERM': 'linux', 21:01:53 INFO - 'TMOUT': '86400', 21:01:53 INFO - 'USER': 'cltbld', 21:01:53 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:53 INFO - '_': '/tools/buildbot/bin/python'} 21:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 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)) 21:01:54 INFO - Cleaning up... 21:01:54 INFO - Return code: 0 21:01:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xedb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, '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': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', '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 21:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:01:54 INFO - Using env: {'DISPLAY': ':0', 21:01:54 INFO - 'HOME': '/home/cltbld', 21:01:54 INFO - 'LANG': 'en_US.UTF-8', 21:01:54 INFO - 'LANGUAGE': 'en_US:en', 21:01:54 INFO - 'LOGNAME': 'cltbld', 21:01:54 INFO - 'MAIL': '/var/mail/cltbld', 21:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:54 INFO - 'MOZ_NO_REMOTE': '1', 21:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:54 INFO - 'NO_EM_RESTART': '1', 21:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:54 INFO - 'PWD': '/builds/slave/test', 21:01:54 INFO - 'SHELL': '/bin/bash', 21:01:54 INFO - 'SHLVL': '1', 21:01:54 INFO - 'TERM': 'linux', 21:01:54 INFO - 'TMOUT': '86400', 21:01:54 INFO - 'USER': 'cltbld', 21:01:54 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:54 INFO - '_': '/tools/buildbot/bin/python'} 21:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:01:54 INFO - Cleaning up... 21:01:54 INFO - Return code: 0 21:01:54 INFO - Running post-action listener: _resource_record_post_action 21:01:54 INFO - Running post-action listener: _start_resource_monitoring 21:01:54 INFO - Starting resource monitoring. 21:01:54 INFO - [mozharness: 2016-07-27 04:01:54.381133Z] Finished create-virtualenv step (success) 21:01:54 INFO - [mozharness: 2016-07-27 04:01:54.381673Z] Running install step. 21:01:54 INFO - Running pre-action listener: _resource_record_pre_action 21:01:54 INFO - Running main action method: install 21:01:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:54 INFO - Reading from file tmpfile_stdout 21:01:54 INFO - Detecting whether we're running mozinstall >=1.0... 21:01:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:01:54 INFO - Reading from file tmpfile_stdout 21:01:54 INFO - Output received: 21:01:54 INFO - Usage: mozinstall [options] installer 21:01:54 INFO - Options: 21:01:54 INFO - -h, --help show this help message and exit 21:01:54 INFO - -d DEST, --destination=DEST 21:01:54 INFO - Directory to install application into. [default: 21:01:54 INFO - "/builds/slave/test"] 21:01:54 INFO - --app=APP Application being installed. [default: firefox] 21:01:54 INFO - mkdir: /builds/slave/test/build/application 21:01:54 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'] 21:01:54 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 21:02:11 INFO - Reading from file tmpfile_stdout 21:02:11 INFO - Output received: 21:02:11 INFO - /builds/slave/test/build/application/firefox/firefox 21:02:11 INFO - Running post-action listener: _resource_record_post_action 21:02:11 INFO - [mozharness: 2016-07-27 04:02:11.093418Z] Finished install step (success) 21:02:11 INFO - [mozharness: 2016-07-27 04:02:11.093699Z] Running run-tests step. 21:02:11 INFO - Running pre-action listener: _resource_record_pre_action 21:02:11 INFO - Running main action method: run_tests 21:02:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:02:11 INFO - Python 2.7.3 21:02:11 INFO - Return code: 0 21:02:11 INFO - grabbing minidump binary from tooltool 21:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:11 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 0xed93b0>, '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 0xeda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:02:11 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 21:02:11 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 21:02:11 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 21:02:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:02:11 INFO - Return code: 0 21:02:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:02:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:02:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:02:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:02:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:02:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 21:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 21:02:11 INFO - Using env: {'DISPLAY': ':0', 21:02:11 INFO - 'HOME': '/home/cltbld', 21:02:11 INFO - 'LANG': 'en_US.UTF-8', 21:02:11 INFO - 'LANGUAGE': 'en_US:en', 21:02:11 INFO - 'LOGNAME': 'cltbld', 21:02:11 INFO - 'MAIL': '/var/mail/cltbld', 21:02:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:02:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:11 INFO - 'MOZ_NO_REMOTE': '1', 21:02:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:02:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:11 INFO - 'NO_EM_RESTART': '1', 21:02:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:02:11 INFO - 'PWD': '/builds/slave/test', 21:02:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:02:11 INFO - 'SHELL': '/bin/bash', 21:02:11 INFO - 'SHLVL': '1', 21:02:11 INFO - 'TERM': 'linux', 21:02:11 INFO - 'TMOUT': '86400', 21:02:11 INFO - 'USER': 'cltbld', 21:02:11 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459', 21:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:11 INFO - '_': '/tools/buildbot/bin/python'} 21:02:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 21:02:11 INFO - mozversion application_buildid: 20160726194513 21:02:11 INFO - mozversion application_changeset: b1832f8aecd0c1a3bd1579cb1e85d0c03b217a2f 21:02:11 INFO - mozversion application_display_name: Nightly 21:02:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:02:11 INFO - mozversion application_name: Firefox 21:02:11 INFO - mozversion application_remotingname: firefox 21:02:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:02:11 INFO - mozversion application_vendor: Mozilla 21:02:11 INFO - mozversion application_version: 50.0a1 21:02:11 INFO - mozversion platform_buildid: 20160726194513 21:02:11 INFO - mozversion platform_changeset: b1832f8aecd0c1a3bd1579cb1e85d0c03b217a2f 21:02:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:02:11 INFO - mozversion platform_version: 50.0a1 21:02:11 INFO - using testdate: 1469592131 21:02:11 INFO - actual date: 1469592131 21:02:11 INFO - starting webserver on 'localhost:35940' 21:02:11 INFO - SUITE-START | Running 2 tests 21:02:11 INFO - TEST-START | dromaeo_css 21:02:11 INFO - operating with platform_type : linux_ 21:02:11 INFO - Initialising browser for dromaeo_css test... 21:02:11 INFO - TEST-INFO | started process 16901 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLvILhA/profile http://localhost:35940/getInfo.html) 21:02:14 INFO - PROCESS | 16901 | JavaScript error: , line 0: uncaught exception: undefined 21:02:15 INFO - PROCESS | 16901 | __metrics Screen width/height:1600/1200 21:02:15 INFO - PROCESS | 16901 | colorDepth:24 21:02:15 INFO - PROCESS | 16901 | Browser inner width/height: 1024/697 21:02:15 INFO - PROCESS | 16901 | __metrics 21:02:16 INFO - PROCESS | 16901 | 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" 21:02:16 INFO - PROCESS | 16901 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:02:17 INFO - TEST-INFO | 16901: exit 0 21:02:17 INFO - Browser initialized. 21:02:17 INFO - Running cycle 1/1 for dromaeo_css test... 21:02:17 INFO - TEST-INFO | started process 16978 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLvILhA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:02:29 INFO - PROCESS | 16978 | RSS: Main: 159203328 21:02:29 INFO - PROCESS | 16978 | 21:03:20 INFO - PROCESS | 16978 | 1469592200670 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] 21:05:32 INFO - PROCESS | 16978 | Cycle 1(1): loaded http://localhost:35940/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35940/tests/dromaeo/cssquery-ext.html) 21:05:33 INFO - PROCESS | 16978 | RSS: Main: 279814144 21:05:33 INFO - PROCESS | 16978 | 21:08:36 INFO - PROCESS | 16978 | Cycle 1(1): loaded http://localhost:35940/tests/dromaeo/cssquery-ext.html (next: http://localhost:35940/tests/dromaeo/cssquery-jquery.html) 21:08:37 INFO - PROCESS | 16978 | RSS: Main: 250785792 21:08:37 INFO - PROCESS | 16978 | 21:11:39 INFO - PROCESS | 16978 | Cycle 1(1): loaded http://localhost:35940/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35940/tests/dromaeo/cssquery-mootools.html) 21:11:39 INFO - PROCESS | 16978 | RSS: Main: 231182336 21:11:39 INFO - PROCESS | 16978 | 21:14:41 INFO - PROCESS | 16978 | Cycle 1(1): loaded http://localhost:35940/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35940/tests/dromaeo/cssquery-prototype.html) 21:14:42 INFO - PROCESS | 16978 | RSS: Main: 196005888 21:14:42 INFO - PROCESS | 16978 | 21:17:45 INFO - PROCESS | 16978 | Cycle 1(1): loaded http://localhost:35940/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35940/tests/dromaeo/cssquery-yui.html) 21:17:46 INFO - PROCESS | 16978 | RSS: Main: 192163840 21:17:46 INFO - PROCESS | 16978 | 21:20:48 INFO - PROCESS | 16978 | Cycle 1(1): loaded http://localhost:35940/tests/dromaeo/cssquery-yui.html (next: http://localhost:35940/tests/dromaeo/cssquery-dojo.html) 21:20:49 INFO - PROCESS | 16978 | RSS: Main: 276758528 21:20:49 INFO - PROCESS | 16978 | 21:20:49 INFO - PROCESS | 16978 | __start_tp_report 21:20:49 INFO - PROCESS | 16978 | _x_x_mozilla_page_load 21:20:49 INFO - PROCESS | 16978 | _x_x_mozilla_page_load_details 21:20:49 INFO - PROCESS | 16978 | |i|pagename|runs| 21:20:49 INFO - PROCESS | 16978 | |0;dojo.html;2627.868606569671;2637.749413805688;2667.5731882898726;2669.3193235724893;2679.3435608275977;4545.499995000548;4665;4689.701876777063;4774.331593576902;4780.976095119524;4702.929456058162;4759.619230461563;4782.83260085897;4809.230523116302;4865.7810398532065;4302.032042790378;4334.97832510837;4377.321515165138;4398.7580683062415;4479.148961697267;4350.847720329789;4464.776761161945;4479.596836284733;4507.6619253555955;4555.681102322838;4275.337322714984;4428.114377124588;4450.799714012877;4498.325251212312;4549.499555048942;4294.720843145185;4295.806688699016;4341.804618792162;4344.087741574273;4420.668449866273;4315.4605674290715;4326.286162783137;4328.177646247203;4359.476862776487;4373.2565463871215;4744.07490539345;4774.73738944358;4804.039192161582;4806.663533552653;4815.783289751969;4929.556339929423;4995.925061124086;5064.063148317573;5065.214891691794;5110.6422550421485;5083.644144909821;5101.489851014869;5104.591632669378;5152.124138896396;5171.741412929338;4768.904621907612;4770.880727981794;4797.760111994421;4819.542143496362;4824.95175048245;5098.235264710249;5148.871278218;5153.742312884416;5208.583313334924;5292.364916210079;4945.554900059012;4996.125678006334;5011.523905228997;5047.470015648378;5048.192289233705;5076.035553244872;5118.976204759063;5121.948780512222;5122.846314610568;5124.9743751281;4849.830255941025;4927.310176575283;5018.924716129261;5031.396232452056;5034.11902916988;4898.485659005824;4931.186354251508;4932.087563800708;4934.8026078957155;5014;4227.767472788964;4248.129133527619;4251.851185208565;4259.680523960716;4299.333603291495;3725.9441108383394;3730.8880733578035;3731.048582611417;3752.6810221130963;3755.586885442599;4272.636825869774;4356.586124318184;4402.3396490526675;4409.96365854024;4461.643068554573;5146.614003949644;5154.974225128925;5160.922586161136;5161.741912904415;5198.428172900978;4412.338149277634;4415.09490554442;4419.226635338803;4451.020775429465;4460.553944605578;4470.619997300266;4514.774261286988;4545.5908968193025;4571.38286331341;4620.792064357112;4715.0805592909055;4723.149833030087;4725.1967165581245;4768.904621907612;4800.855974320706;5074.797008119782;5077.568406685473;5080.237964305384;5090.7200103993155;5090.796368145233;4609.746463944515;4697.0840686065885;4713.646476514344;4739.123262196505;4755.83354582588;4945.925811112764;4953.578945789504;4957.925631115464;4964.851054468371;5054.898902022012;5000.349954505891;5002.374703162105;5009.373828271466;5010.724410157476;5031.4213865405945;4748.810047598057;4873.853784386474;4908.950910490992;4923.286123512143;4944.554990050912;4936.9259461107395;4961.875953101201;4963.329950456787;4964.67729597575;4991.401031876198;4865.318855360323;4890.80436782539;4928.975355123202;4937.802487900443;4951.8266860659705;4842.346283251716;4905.1416002200185;4912.1894887344615;4930.827421040246;5007.24891266313;4848.636352273664;4860.902781944412;4870.853874383774;4872.952315252238;4916.213405855046 21:20:49 INFO - PROCESS | 16978 | |1;ext.html;562.1174755633657;569.8575356160959;570.0138759945291;589.0751520113421;591.101525680965;27306;39232.80383598081;40779.38830917539;40865.99999999996;45199.77400112995;40883.38674919871;41119.58880411195;41140.17719645613;41165.58834411655;41215.793921030425;7567.848643027136;7729.265719756642;7732.884006739903;7738.535687858718;7739.264769846855;7708.884366734504;7806.297433231008;7807.375409967189;7808.882866757003;7815.140334563193;7818.374530037583;7882.172371900954;7883.763487095368;7893.407994400414;7894.7236846710375;7799.37604991605;7802.765917022499;7849.607519623996;7852.842943141162;7860.646270917822;7720.961395192988;7792.220777922163;7797.610119494059;7800.375969922393;7802.297793198582;38810.223795524216;39119.41320880189;39159.216815663814;39206.41190382146;39211.21577568433;2886.2207204055;3025.667176610571;3302.9009129726146;3331.283773988604;3724.8137593120236;26785.598216026774;28830.855845720846;29373;29492.70507294921;29493.262668433246;3180.9343869803643;3192.9680703192903;3195.472746996743;3338.1821453743746;3351.1789611545328;264560;267917;268036;268051;268629;31228.531572026437;54676.726616366665;54841;54896;55338.446615534136;31042.223944401117;33038.17404564905;33096.51776116801;33103.50344744831;33132;13458.52895148665;13649.225600672047;13651.522196723066;13656.385462654005;13677.452901884013;11464.94267528657;11541.249818761753;11554.422278886022;11559.537618495335;11565.653030409101;7701.961490192513;7852;7905.407094467823;7921.089074756362;7971.920280797234;3132.592762940849;3142.355817057498;3158.368326334696;3170.381775553791;3173;776.642744337611;778.0702061037119;819.4345901328064;855.0765173612483;859.7205908079774;25712.8714356427;28652.14043578696;30252.24369390739;31061.8446907764;31457.584408701663;895.1496078725107;916.8624706293975;925.796324808541;947.5593848860175;969.6606187834343;13906.30468476572;14393.568192954228;14518.564443066725;14880.404783808743;15054.473093441677;430034;433572;434243;434827;435035;51015.234771478456;51302;51351.743241283555;51394.48605513972;51395.229071563954;1056.2817284246787;1164.0396672745019;1186.039308160393;1187.940602969848;1235.7281398092405;27441.31396715018;29422.264443389085;29488.705112949454;30104.24739381533;30132.24669383283;42534.787326063175;43118;43150.13699726051;43160;43187.784061079496;335901;336715;338389;339927;340012;22416.887915560972;24498.63252051185;25417.61873571935;25531.617025745;25927;41133.76598702044;41837.79081104575;41953;41985;42581.36127958021;35150.82424587963;35664;35907.64092359043;35920.46119308265;35958.64041359658;35560.46659300164;35797.46303805393;35839.283214335046;35966.10084747902;35993.28013439769;27773.56230031933;31339.373212536077;35295.294094118486;38742.2251554973;38760.418593720555;27590.310242244104;29083.12750617485;29259.268518287212;29325.909723603443;29540;24701;25169.748302516742;25295.00426392738;25560.23319300424;26089 21:20:49 INFO - PROCESS | 16978 | |2;jquery.html;360.9855605775771;370.8590735520502;376.28318054106927;378.3076969146462;391.37966323377447;15801.446949356761;17194.914025429873;17323.91338043311;17400.73898891518;17414.738778918327;16102.919485402557;17235.74146387805;17248.56878578036;17362.305507779674;17376.304947802106;4854.2718592211095;5088.084144853925;5094.2103973884105;5095.133827249358;5097.923531147036;3674.2835147146316;3868.458415821787;5009.949900500985;5656.4626360495895;5875.441833025856;5899.941000590004;5901.144334071557;5905.852353691171;5906.497947674453;5915.201447804556;5721.256236689245;5968.67172305523;5980.641161530322;5991.370906054889;5993.999999999978;5056.140456122468;5246.7638956247065;5259.21111833228;5260.395054568736;5267;21053.157873685035;21401.67897481539;21407.67888481674;21431.14275428981;21618.243361482273;1769.6991511442957;1817.6819056665163;1828.5520047588425;1853.8887666739904;1881.4073566826405;13175.736485270241;13195.20828750278;13270.469181232738;13327.466901323935;14001.369938352798;1657.6436066245694;1667.591440097172;1672.4313733330725;1677.0776073159836;1769.78762548495;53909.73045134788;70457;70807.2919270811;72367.63816180939;74135.25864741337;8940.329475289249;9068.50123243228;9304.581293841793;9322.813543729224;9582.185514231369;12592.81110783339;12602.11785175029;12631.368431578385;12643.051771117203;12645.367731613382;7087.362137407658;7135.82160445993;7136.143662760398;7303.342699156995;7334.083239595051;6974.616396098263;6979.613360263516;6989.825254368681;7172.964135179291;7173.5337203081635;5926.851828704231;5931.9703401483575;5939.198208241918;5951.5238780897425;5982.341942386334;1546.891717579781;1580.7391780356343;1597.2892063032073;1685.2416412614373;1688.122176468254;776.8485145396594;795.5266616363255;810.959452027408;905.2893478619252;919.3151102428733;16277.186140692918;17106.230219639896;17269.827301727073;17356.826431735775;17972.55068623295;821.5290398068233;828.4532208742202;841.8021764885247;875.3478658399533;885.7342797160826;9535.713928582154;10113.848292275621;10174.440405777754;10210.693679189637;10214.336068155695;386165;393579;394280;400042;402868;68557;69127.65436172887;69832.6508367455;70760.29239707538;71376.64311678408;916.3036092569695;916.9403988740711;920.8848893888264;924.2282693950626;931.3573634192386;11662.358570278717;15368.308426121075;16637.833621663627;16760.66478670444;16873.325066997673;24457.633135503336;24712.25863224106;36419.63580364268;36498.452523212705;36552;49543.256851147984;50449.49550504448;55986.440135598124;57767.71116144393;58299;7409.8147546311775;7703.728884734181;7745.380369570308;7899.407544434305;7973.521588704696;9313.534323283673;11668.533258669559;11802.881971180179;11918.165728398928;12132.93933530362;9983.700488985342;9987;10176.287659863738;10283.2801703883;10553.841692374466;10280.537375817956;10543.736406589896;10593.09958653523;10699.251052426256;10725.517351719347;9984.151347135576;10030.749231269278;10060.748481288027;10122.493875306352;10283.485825708534;6702.564333318319;6919.827004324932;6928.272531384334;7306.96346518264;7583.924160758543;8830.955845220733;9711.805763884824;9717.222622190066;9778.853317200106;10536 21:20:49 INFO - PROCESS | 16978 | |3;mootools.html;1398.713263780925;1441.9639509012277;1497.5881632551054;1628.983710162898;1704.0094633917472;7695.461317707755;8054.476459030167;8056.91943080569;8062.435629505944;8085.2319029692235;6225.470834979033;7376.336129748322;7697.153313135551;7699.807504812383;8017.19828017198;2176.6843807647883;2204.151401710345;2217.922372716955;2221.0893533651206;2223.1440895255364;2191.7698641642637;2223.83321250906;2225.276785044859;2226.065052677879;2230.4200907764016;2189.058704756954;2190.9452263693374;2201.306588424641;2201.471646804763;2231.9665205021856;2148.0548558634227;2151.817095546865;2154.34292540775;2155.4395857077275;2156.892155392232;2139.732533433321;2184.7924447177493;2186.486175748698;2190.277208521184;2191.8684878907156;7880.80297992549;7978.122406535276;7981.241782030698;7993.280604745601;7997.840043199161;4558.703684260546;4689.273162659793;4703.106409782131;4706.10583989041;4716.339712440261;2688.2204160793344;2764.847933363664;2809.1432113205465;2810.901618443345;2811.0863969209886;860.7632900952226;889.8665200219954;905.3798148268457;907.1654078248027;933.9533023348805;10833.187510936632;10871.075958543533;10874.02133807961;10898;10932.945335273353;4071.4096456013713;4213.47331583552;4339.4792624884585;4391.890202744893;4391.978040109779;9071.863922041042;9077.364584479155;9083.18251357381;9092.136247056518;9113.498757568397;4584.33527138563;4594.885127871763;4629.8842528936375;4632.305154226893;4634.420697412824;3600.6579374959338;4054.39184122384;4152.896177595584;4157.8336866525;4220.282551966173;3642.399004164336;3643.6356364363883;3809.0096574890727;3811.6378944000276;3818.522684664417;1173.7711146326556;1220.6399112261727;1222.9021678265717;1226.3193927370396;1227.4415141110774;857.3484152044491;859.1408591408591;861.875028120919;877.1053525404052;880.5905254056767;5235.136202526541;5266.8419947401635;5382.085045542267;5383.8384848453825;5452.836414907559;423.9703220774578;427.63009996353554;431.6611460003882;431.7495852405597;441.23886296138903;3621.8913432597064;3673.3020726061964;3690;3690.9815450922574;3731.384321586908;9497.287703422271;9703.126718595218;9785.804283914282;9808.754781130387;9809.460479673686;9751.268654850914;9758.17055550272;9772.315937884421;9775.755606109904;9776.120149186607;7394.408447324201;7461.738839140713;7531.435142364454;7531.661075251517;7536.5101268417375;5010.273510341053;5167.173252279617;5259.55293800016;5299.841004769709;5462.235287059894;8693.261072808882;8927.732168034969;8969.327300452622;8974.730758077269;9121.042290559395;9006;9197.264218862609;9214.677486287948;9268.768280793034;9288.11762882525;5961.850953726018;6545.37818907203;6872.278410766798;6964.233934267125;6970.37266645984;7372.299631534996;7631.3894888407685;7641.541507509567;7641.617919103911;7650.6174691264105;6692.83267918306;6710.496712746466;6756.527043106936;6875;6893.069435626327;6589.242237142598;6760.222574404006;6803.591784492946;6826.044353790573;6928.7228510859;6129.724162412791;6349;6354.046892965907;6355.046742988404;6359.87280254383;4403;4440.600345968878;4771.284307353924;4788.329633851194;4864.294677271847;4591.265397536378;4729.196036673842;4729.219678753105;4762.78567464458;4905.533974272438 21:20:49 INFO - PROCESS | 16978 | |4;prototype.html;305.3374178033665;307.8630009645708;311.17538522914276;315.9636641786195;319.2927665221534;4804.399450068741;4850.660453768238;4854.854354369366;4862.416510018798;4866.902661946759;4552.772361381934;4674.480793742039;4687.109449204657;4774.188387974044;4818.51814818518;3804.182100848317;3833.884983450487;3835.7506762060375;3862.0731024554116;3867.284552357809;3829.253295607358;3829.48301979232;3842.5206295576263;3847.6537111659945;3850.07598176437;3613.620569840168;3668.798216098127;3770.8868733938025;3789.5073640426845;3808.1241314497684;3779.4897688811934;3793.6775374093236;3816.179521402898;3819.59894211108;3826.4834247376803;3398.5921689397182;3404.7957122572725;3406.2846802171575;3413.573303337083;3418.5555877735983;4512.278035514302;4745.034353529044;4746.647205546437;4749.430068391781;4754.66717329787;4233.661307095455;4262.25410553152;4264.957350426518;4268.9786551067355;4300.118475712471;5013.824516141917;5018.347614810088;5021.69869807809;5022.648414610979;5036.438704001771;3602.7117830573493;3606.5672119345845;3610.837512311952;3611.4763359313015;3627.6239063413504;5116.897662046738;5182.031781671181;5205.089109405839;5238.345206849106;5246.737663116867;3635.6727894489095;3678.393065144221;3680.687141592941;3689.3728066228805;3697.519322488113;3715.479832823407;3737.1591391936595;3744.513213282256;3748.2503499299705;3750.099976005739;4145.79271036447;4234.491860976641;4267.97866010668;4297.355396690521;4299.054208074219;4977.257959714071;4988.650794444354;5008.824691135793;5013.623978201649;5056.469070747518;1271.0848189303686;1290.5741105435184;1291.560869304441;1294.838145231846;1297.7022977022978;824.3158178711655;830.4892491118039;854.1586537260794;856.6402111113347;857.5412154497315;4132.615573782757;4348.304271316574;4381.430414046154;4476.194285028661;4478.2610869206865;5150.639455238097;5169.767360468788;5179.300794392709;5187.9883422732955;5198.81804136861;4476.664250181249;4483.349914262414;4508.45898492183;4512.593866551961;4542.500324964251;2619.5677713177492;2726.5637498000224;2729.7543221110195;2735.7264273572882;2745.68424631166;4810.687305325143;4845.020312507852;4845.394325709286;4852.126617208865;4857.813983707346;5239.947600524022;5251.553617942518;5275.815346462856;5327.520523152857;5327.653702509402;4707.552782485658;4862.416510018749;4867.1969125093965;4871.2206047032305;4893.584045356185;5219.817306394258;5224.477552244745;5225.947740522546;5226.137687281556;5236.136037553761;5114.948850511596;5122;5126.179811230185;5130.179171332569;5144.562712169358;4654.534546545454;4871.439784424695;4871.5128487151005;4887.682300650446;4905.632077594266;3654.8172591369794;3665.4868318434906;3665.7433979622238;3674.118211629136;3691.9261614767015;5233.659812112201;5242.449542797951;5273.499017593323;5278.89442211146;5283.682979021271;5002.424721157114;5011.824586139468;5025.3718285214345;5058.924116138334;5126.128558145049;5006.4242612098615;5007.674501157413;5017.924731128963;5023.598112151092;5044.924326135184;4965.751712414437;4974.8756281093265;5003.599712023102;5014.448410674896;5051.06555287273;4907.779149938333;5055.924161137659;5088.363954505687;5097.649122982487;5144.948550514447;4952.257161425815;4960.255961605788;4975.636572627973;4984.925226121539;4987.47631498702 21:20:49 INFO - PROCESS | 16978 | |5;yui.html;649.7628365646539;670.949678774092;671.6587696477505;734.3977938090768;757.8673732096884;12527.123101382907;13673.726525469485;13690.178589284626;13697;13737.381817818197;13102.737945241113;13247.801282980763;13249.536266230683;13254.734905301888;13267;618.730852079347;637.3881074168803;754.9773506794805;776.79026662801;781.1329424338979;768.69636493585;787.0869791042392;790.865552856013;794.9046114466272;797.5055465611329;818.2840014986887;825.6614787936948;827.4786884262905;827.5572568675759;827.71443851871;655.2922843329193;674.2515807453723;823.7693445835175;825.0347093900122;825.0553116681426;499.76261275894024;499.9300097986285;581.2792137749161;595.8748662780821;596.2755252368394;13825.723485530334;13834.169949803045;13841.30793460323;13855.307234638227;13860.168389896638;12063;12141.210821296676;12358.567450139202;12562;12771.872281277254;11750.412479375997;11772.175947683665;11793.764124717543;11918.284902905853;11986.580469683606;4199.0552125771765;4199.706020578561;4231.048014196812;4272.9145417091795;4289.120730250323;63595;64985;66287.35419551923;66731.33268667349;67523;9801.411915285105;9822.607095716234;9909.752256193653;10869.67390978272;11010.724731881766;20732.274370397266;21132.894335528224;21135.154593816074;21147.365579032652;21162.47093822636;6847.726090956306;6849.602639076383;6922.5154239202775;7209.531380460253;7227.638618069181;3480.7911525308564;3538.663826936437;3600.4959305697225;3601.9279614408088;3650.3064417760543;2700.027989923626;2773.7642300404286;2779.70743604221;2780.5134101532153;2784.8607569621436;480.9639277054235;489.59608323133557;489.6670264220293;490.298872612168;501.1755661936095;569.7521578113534;597.5846786483439;616.5098746496546;625.0838496817443;627.8650090230623;7638.427117966175;7970.521768693897;8040.35677145827;8114.350851931831;8143.633536490872;561.3768716724433;591.8609126855185;595.1103100864236;606.2361424604941;620.8572028433485;3508.526348942911;3771.6039815819495;3891.610838916086;3983.1237127831832;4058.2695114879607;96183;98358.50820745851;102068;104781;105190.47404762928;88726;90831.09168908358;90953;91376.54311728399;92254.53872730593;75713.24286757172;76078.61960690162;76605.61697191479;77140.61429692815;77645;7289.56262624223;7503.849923001619;7539.283768042027;7545.471816972759;7566.281203285899;45568.54431455643;46257.768711156226;46783.53216467928;48062.27906581334;48260;37462.8126859364;67045.66477167583;68263.97604036043;69375;69564;9951.701448956543;10432.634857779942;10600.1519878408;10831.729206769895;11015.669529914116;9802.117809397188;10562.31344962575;10887.455627218766;11353.886461135284;11468.4265786712;9060.30058011073;9213.309001824757;9251.93602735694;9367.812643746951;9559.569819358285;9807.607695692092;9961.452120133463;10009.449480278654;11860.347680877634;12058.216215945935;11567.76864462723;11718.70703232426;11809.468573914022;11976.712498451629;12395.62813115608;8110.959445202972;8421.19998600132;8473.703420380258;8914.687985920462;9110.180083792491;8812.603432845666;9559;10176.962403609965;10537.473126343806;10904.454777261264 21:20:49 INFO - PROCESS | 16978 | __end_tp_report 21:20:49 INFO - PROCESS | 16978 | __start_cc_report 21:20:49 INFO - PROCESS | 16978 | _x_x_mozilla_cycle_collect,1168 21:20:49 INFO - PROCESS | 16978 | __end_cc_report 21:20:49 INFO - PROCESS | 16978 | __startTimestamp1469593249223__endTimestamp 21:20:49 INFO - PROCESS | 16978 | 21:20:49 INFO - PROCESS | 16978 | ------- Summary: start ------- 21:20:49 INFO - PROCESS | 16978 | Number of tests: 6 21:20:49 INFO - PROCESS | 16978 | 21:20:49 INFO - PROCESS | 16978 | [#0] dojo.html Cycles:165 Average:4686.35 Median:4815.78 stddev:486.07 (10.1%) stddev-sans-first:459.96 21:20:49 INFO - PROCESS | 16978 | Values: 2627.9 2637.7 2667.6 2669.3 2679.3 4545.5 4665.0 4689.7 4774.3 4781.0 4702.9 4759.6 4782.8 4809.2 4865.8 4302.0 4335.0 4377.3 4398.8 4479.1 4350.8 4464.8 4479.6 4507.7 4555.7 4275.3 4428.1 4450.8 4498.3 4549.5 4294.7 4295.8 4341.8 4344.1 4420.7 4315.5 4326.3 4328.2 4359.5 4373.3 4744.1 4774.7 4804.0 4806.7 4815.8 4929.6 4995.9 5064.1 5065.2 5110.6 5083.6 5101.5 5104.6 5152.1 5171.7 4768.9 4770.9 4797.8 4819.5 4825.0 5098.2 5148.9 5153.7 5208.6 5292.4 4945.6 4996.1 5011.5 5047.5 5048.2 5076.0 5119.0 5121.9 5122.8 5125.0 4849.8 4927.3 5018.9 5031.4 5034.1 4898.5 4931.2 4932.1 4934.8 5014.0 4227.8 4248.1 4251.9 4259.7 4299.3 3725.9 3730.9 3731.0 3752.7 3755.6 4272.6 4356.6 4402.3 4410.0 4461.6 5146.6 5155.0 5160.9 5161.7 5198.4 4412.3 4415.1 4419.2 4451.0 4460.6 4470.6 4514.8 4545.6 4571.4 4620.8 4715.1 4723.1 4725.2 4768.9 4800.9 5074.8 5077.6 5080.2 5090.7 5090.8 4609.7 4697.1 4713.6 4739.1 4755.8 4945.9 4953.6 4957.9 4964.9 5054.9 5000.3 5002.4 5009.4 5010.7 5031.4 4748.8 4873.9 4909.0 4923.3 4944.6 4936.9 4961.9 4963.3 4964.7 4991.4 4865.3 4890.8 4929.0 4937.8 4951.8 4842.3 4905.1 4912.2 4930.8 5007.2 4848.6 4860.9 4870.9 4873.0 4916.2 21:20:49 INFO - PROCESS | 16978 | 21:20:49 INFO - PROCESS | 16978 | [#1] ext.html Cycles:180 Average:48594.91 Median:35939.55 stddev:93484.13 (260.1%) stddev-sans-first:93676.81 21:20:49 INFO - PROCESS | 16978 | Values: 562.1 569.9 570.0 589.1 591.1 27306.0 39232.8 40779.4 40866.0 45199.8 40883.4 41119.6 41140.2 41165.6 41215.8 7567.8 7729.3 7732.9 7738.5 7739.3 7708.9 7806.3 7807.4 7808.9 7815.1 7818.4 7882.2 7883.8 7893.4 7894.7 7799.4 7802.8 7849.6 7852.8 7860.6 7721.0 7792.2 7797.6 7800.4 7802.3 38810.2 39119.4 39159.2 39206.4 39211.2 2886.2 3025.7 3302.9 3331.3 3724.8 26785.6 28830.9 29373.0 29492.7 29493.3 3180.9 3193.0 3195.5 3338.2 3351.2 264560.0 267917.0 268036.0 268051.0 268629.0 31228.5 54676.7 54841.0 54896.0 55338.4 31042.2 33038.2 33096.5 33103.5 33132.0 13458.5 13649.2 13651.5 13656.4 13677.5 11464.9 11541.2 11554.4 11559.5 11565.7 7702.0 7852.0 7905.4 7921.1 7971.9 3132.6 3142.4 3158.4 3170.4 3173.0 776.6 778.1 819.4 855.1 859.7 25712.9 28652.1 30252.2 31061.8 31457.6 895.1 916.9 925.8 947.6 969.7 13906.3 14393.6 14518.6 14880.4 15054.5 430034.0 433572.0 434243.0 434827.0 435035.0 51015.2 51302.0 51351.7 51394.5 51395.2 1056.3 1164.0 1186.0 1187.9 1235.7 27441.3 29422.3 29488.7 30104.2 30132.2 42534.8 43118.0 43150.1 43160.0 43187.8 335901.0 336715.0 338389.0 339927.0 340012.0 22416.9 24498.6 25417.6 25531.6 25927.0 41133.8 41837.8 41953.0 41985.0 42581.4 35150.8 35664.0 35907.6 35920.5 35958.6 35560.5 35797.5 35839.3 35966.1 35993.3 27773.6 31339.4 35295.3 38742.2 38760.4 27590.3 29083.1 29259.3 29325.9 29540.0 24701.0 25169.7 25295.0 25560.2 26089.0 21:20:49 INFO - PROCESS | 16978 | 21:20:49 INFO - PROCESS | 16978 | [#2] jquery.html Cycles:180 Average:24312.80 Median:27729.72 stddev:65109.51 (234.8%) stddev-sans-first:65267.32 21:20:49 INFO - PROCESS | 16978 | Values: 361.0 370.9 376.3 378.3 391.4 15801.4 17194.9 17323.9 17400.7 17414.7 16102.9 17235.7 17248.6 17362.3 17376.3 4854.3 5088.1 5094.2 5095.1 5097.9 3674.3 3868.5 5009.9 5656.5 5875.4 5899.9 5901.1 5905.9 5906.5 5915.2 5721.3 5968.7 5980.6 5991.4 5994.0 5056.1 5246.8 5259.2 5260.4 5267.0 21053.2 21401.7 21407.7 21431.1 21618.2 1769.7 1817.7 1828.6 1853.9 1881.4 13175.7 13195.2 13270.5 13327.5 14001.4 1657.6 1667.6 1672.4 1677.1 1769.8 53909.7 70457.0 70807.3 72367.6 74135.3 8940.3 9068.5 9304.6 9322.8 9582.2 12592.8 12602.1 12631.4 12643.1 12645.4 7087.4 7135.8 7136.1 7303.3 7334.1 6974.6 6979.6 6989.8 7173.0 7173.5 5926.9 5932.0 5939.2 5951.5 5982.3 1546.9 1580.7 1597.3 1685.2 1688.1 776.8 795.5 811.0 905.3 919.3 16277.2 17106.2 17269.8 17356.8 17972.6 821.5 828.5 841.8 875.3 885.7 9535.7 10113.8 10174.4 10210.7 10214.3 386165.0 393579.0 394280.0 400042.0 402868.0 68557.0 69127.7 69832.7 70760.3 71376.6 916.3 916.9 920.9 924.2 931.4 11662.4 15368.3 16637.8 16760.7 16873.3 24457.6 24712.3 36419.6 36498.5 36552.0 49543.3 50449.5 55986.4 57767.7 58299.0 7409.8 7703.7 7745.4 7899.4 7973.5 9313.5 11668.5 11802.9 11918.2 12132.9 9983.7 9987.0 10176.3 10283.3 10553.8 10280.5 10543.7 10593.1 10699.3 10725.5 9984.2 10030.7 10060.7 10122.5 10283.5 6702.6 6919.8 6928.3 7307.0 7583.9 8831.0 9711.8 9717.2 9778.9 10536.0 21:20:49 INFO - PROCESS | 16978 | 21:20:49 INFO - PROCESS | 16978 | [#3] mootools.html Cycles:180 Average:5174.17 Median:5088.72 stddev:2929.51 (57.6%) stddev-sans-first:2923.99 21:20:49 INFO - PROCESS | 16978 | Values: 1398.7 1442.0 1497.6 1629.0 1704.0 7695.5 8054.5 8056.9 8062.4 8085.2 6225.5 7376.3 7697.2 7699.8 8017.2 2176.7 2204.2 2217.9 2221.1 2223.1 2191.8 2223.8 2225.3 2226.1 2230.4 2189.1 2190.9 2201.3 2201.5 2232.0 2148.1 2151.8 2154.3 2155.4 2156.9 2139.7 2184.8 2186.5 2190.3 2191.9 7880.8 7978.1 7981.2 7993.3 7997.8 4558.7 4689.3 4703.1 4706.1 4716.3 2688.2 2764.8 2809.1 2810.9 2811.1 860.8 889.9 905.4 907.2 934.0 10833.2 10871.1 10874.0 10898.0 10932.9 4071.4 4213.5 4339.5 4391.9 4392.0 9071.9 9077.4 9083.2 9092.1 9113.5 4584.3 4594.9 4629.9 4632.3 4634.4 3600.7 4054.4 4152.9 4157.8 4220.3 3642.4 3643.6 3809.0 3811.6 3818.5 1173.8 1220.6 1222.9 1226.3 1227.4 857.3 859.1 861.9 877.1 880.6 5235.1 5266.8 5382.1 5383.8 5452.8 424.0 427.6 431.7 431.7 441.2 3621.9 3673.3 3690.0 3691.0 3731.4 9497.3 9703.1 9785.8 9808.8 9809.5 9751.3 9758.2 9772.3 9775.8 9776.1 7394.4 7461.7 7531.4 7531.7 7536.5 5010.3 5167.2 5259.6 5299.8 5462.2 8693.3 8927.7 8969.3 8974.7 9121.0 9006.0 9197.3 9214.7 9268.8 9288.1 5961.9 6545.4 6872.3 6964.2 6970.4 7372.3 7631.4 7641.5 7641.6 7650.6 6692.8 6710.5 6756.5 6875.0 6893.1 6589.2 6760.2 6803.6 6826.0 6928.7 6129.7 6349.0 6354.0 6355.0 6359.9 4403.0 4440.6 4771.3 4788.3 4864.3 4591.3 4729.2 4729.2 4762.8 4905.5 21:20:49 INFO - PROCESS | 16978 | 21:20:49 INFO - PROCESS | 16978 | [#4] prototype.html Cycles:180 Average:4195.59 Median:4789.29 stddev:1212.79 (25.3%) stddev-sans-first:1180.52 21:20:49 INFO - PROCESS | 16978 | Values: 305.3 307.9 311.2 316.0 319.3 4804.4 4850.7 4854.9 4862.4 4866.9 4552.8 4674.5 4687.1 4774.2 4818.5 3804.2 3833.9 3835.8 3862.1 3867.3 3829.3 3829.5 3842.5 3847.7 3850.1 3613.6 3668.8 3770.9 3789.5 3808.1 3779.5 3793.7 3816.2 3819.6 3826.5 3398.6 3404.8 3406.3 3413.6 3418.6 4512.3 4745.0 4746.6 4749.4 4754.7 4233.7 4262.3 4265.0 4269.0 4300.1 5013.8 5018.3 5021.7 5022.6 5036.4 3602.7 3606.6 3610.8 3611.5 3627.6 5116.9 5182.0 5205.1 5238.3 5246.7 3635.7 3678.4 3680.7 3689.4 3697.5 3715.5 3737.2 3744.5 3748.3 3750.1 4145.8 4234.5 4268.0 4297.4 4299.1 4977.3 4988.7 5008.8 5013.6 5056.5 1271.1 1290.6 1291.6 1294.8 1297.7 824.3 830.5 854.2 856.6 857.5 4132.6 4348.3 4381.4 4476.2 4478.3 5150.6 5169.8 5179.3 5188.0 5198.8 4476.7 4483.3 4508.5 4512.6 4542.5 2619.6 2726.6 2729.8 2735.7 2745.7 4810.7 4845.0 4845.4 4852.1 4857.8 5239.9 5251.6 5275.8 5327.5 5327.7 4707.6 4862.4 4867.2 4871.2 4893.6 5219.8 5224.5 5225.9 5226.1 5236.1 5114.9 5122.0 5126.2 5130.2 5144.6 4654.5 4871.4 4871.5 4887.7 4905.6 3654.8 3665.5 3665.7 3674.1 3691.9 5233.7 5242.4 5273.5 5278.9 5283.7 5002.4 5011.8 5025.4 5058.9 5126.1 5006.4 5007.7 5017.9 5023.6 5044.9 4965.8 4974.9 5003.6 5014.4 5051.1 4907.8 5055.9 5088.4 5097.6 5144.9 4952.3 4960.3 4975.6 4984.9 4987.5 21:20:49 INFO - PROCESS | 16978 | 21:20:49 INFO - PROCESS | 16978 | [#5] yui.html Cycles:180 Average:18191.04 Median:593.49 stddev:26657.77 (4491.7%) stddev-sans-first:26700.02 21:20:49 INFO - PROCESS | 16978 | Values: 649.8 670.9 671.7 734.4 757.9 12527.1 13673.7 13690.2 13697.0 13737.4 13102.7 13247.8 13249.5 13254.7 13267.0 618.7 637.4 755.0 776.8 781.1 768.7 787.1 790.9 794.9 797.5 818.3 825.7 827.5 827.6 827.7 655.3 674.3 823.8 825.0 825.1 499.8 499.9 581.3 595.9 596.3 13825.7 13834.2 13841.3 13855.3 13860.2 12063.0 12141.2 12358.6 12562.0 12771.9 11750.4 11772.2 11793.8 11918.3 11986.6 4199.1 4199.7 4231.0 4272.9 4289.1 63595.0 64985.0 66287.4 66731.3 67523.0 9801.4 9822.6 9909.8 10869.7 11010.7 20732.3 21132.9 21135.2 21147.4 21162.5 6847.7 6849.6 6922.5 7209.5 7227.6 3480.8 3538.7 3600.5 3601.9 3650.3 2700.0 2773.8 2779.7 2780.5 2784.9 481.0 489.6 489.7 490.3 501.2 569.8 597.6 616.5 625.1 627.9 7638.4 7970.5 8040.4 8114.4 8143.6 561.4 591.9 595.1 606.2 620.9 3508.5 3771.6 3891.6 3983.1 4058.3 96183.0 98358.5 102068.0 104781.0 105190.5 88726.0 90831.1 90953.0 91376.5 92254.5 75713.2 76078.6 76605.6 77140.6 77645.0 7289.6 7503.8 7539.3 7545.5 7566.3 45568.5 46257.8 46783.5 48062.3 48260.0 37462.8 67045.7 68264.0 69375.0 69564.0 9951.7 10432.6 10600.2 10831.7 11015.7 9802.1 10562.3 10887.5 11353.9 11468.4 9060.3 9213.3 9251.9 9367.8 9559.6 9807.6 9961.5 10009.4 11860.3 12058.2 11567.8 11718.7 11809.5 11976.7 12395.6 8111.0 8421.2 8473.7 8914.7 9110.2 8812.6 9559.0 10177.0 10537.5 10904.5 21:20:49 INFO - PROCESS | 16978 | -------- Summary: end -------- 21:20:49 INFO - PROCESS | 16978 | 21:20:50 INFO - TEST-INFO | 16978: exit 0 21:20:50 INFO - TEST-OK | dromaeo_css | took 1118724ms 21:20:50 INFO - TEST-START | kraken 21:20:50 INFO - operating with platform_type : linux_ 21:20:50 INFO - Initialising browser for kraken test... 21:20:50 INFO - TEST-INFO | started process 17168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx9pCVY/profile http://localhost:35940/getInfo.html) 21:20:53 INFO - PROCESS | 17168 | JavaScript error: , line 0: uncaught exception: undefined 21:20:54 INFO - PROCESS | 17168 | __metrics Screen width/height:1600/1200 21:20:54 INFO - PROCESS | 17168 | colorDepth:24 21:20:54 INFO - PROCESS | 17168 | Browser inner width/height: 1024/697 21:20:54 INFO - PROCESS | 17168 | __metrics 21:20:54 INFO - PROCESS | 17168 | 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" 21:20:54 INFO - PROCESS | 17168 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:20:55 INFO - TEST-INFO | 17168: exit 0 21:20:55 INFO - Browser initialized. 21:20:55 INFO - Running cycle 1/1 for kraken test... 21:20:55 INFO - TEST-INFO | started process 17247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx9pCVY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:21:07 INFO - PROCESS | 17247 | RSS: Main: 158912512 21:21:07 INFO - PROCESS | 17247 | 21:21:10 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35940/tests/kraken/driver.html?testName=audio-beat-detection) 21:21:11 INFO - PROCESS | 17247 | RSS: Main: 215814144 21:21:11 INFO - PROCESS | 17247 | 21:21:14 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35940/tests/kraken/driver.html?testName=audio-dft) 21:21:14 INFO - PROCESS | 17247 | RSS: Main: 189390848 21:21:14 INFO - PROCESS | 17247 | 21:21:17 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35940/tests/kraken/driver.html?testName=audio-fft) 21:21:18 INFO - PROCESS | 17247 | RSS: Main: 273367040 21:21:18 INFO - PROCESS | 17247 | 21:21:20 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35940/tests/kraken/driver.html?testName=audio-oscillator) 21:21:21 INFO - PROCESS | 17247 | RSS: Main: 182423552 21:21:21 INFO - PROCESS | 17247 | 21:21:23 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35940/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:21:24 INFO - PROCESS | 17247 | RSS: Main: 187351040 21:21:24 INFO - PROCESS | 17247 | 21:21:27 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35940/tests/kraken/driver.html?testName=imaging-darkroom) 21:21:27 INFO - PROCESS | 17247 | RSS: Main: 223137792 21:21:27 INFO - PROCESS | 17247 | 21:21:31 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35940/tests/kraken/driver.html?testName=imaging-desaturate) 21:21:32 INFO - PROCESS | 17247 | RSS: Main: 216850432 21:21:32 INFO - PROCESS | 17247 | 21:21:34 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35940/tests/kraken/driver.html?testName=json-parse-financial) 21:21:35 INFO - PROCESS | 17247 | RSS: Main: 220360704 21:21:35 INFO - PROCESS | 17247 | 21:21:37 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35940/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:21:37 INFO - PROCESS | 17247 | RSS: Main: 186179584 21:21:37 INFO - PROCESS | 17247 | 21:21:39 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35940/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:21:40 INFO - PROCESS | 17247 | RSS: Main: 193163264 21:21:40 INFO - PROCESS | 17247 | 21:21:42 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35940/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:21:43 INFO - PROCESS | 17247 | RSS: Main: 196755456 21:21:43 INFO - PROCESS | 17247 | 21:21:45 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35940/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:21:46 INFO - PROCESS | 17247 | RSS: Main: 191791104 21:21:46 INFO - PROCESS | 17247 | 21:21:49 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35940/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:21:49 INFO - PROCESS | 17247 | RSS: Main: 194916352 21:21:49 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | Cycle 1(1): loaded http://localhost:35940/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35940/tests/kraken/driver.html?testName=ai-astar) 21:21:51 INFO - PROCESS | 17247 | RSS: Main: 245039104 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | __start_tp_report 21:21:51 INFO - PROCESS | 17247 | _x_x_mozilla_page_load 21:21:51 INFO - PROCESS | 17247 | _x_x_mozilla_page_load_details 21:21:51 INFO - PROCESS | 17247 | |i|pagename|runs| 21:21:51 INFO - PROCESS | 17247 | |0;ai-astar;104;113;113;113;111;112;112;112;112;114 21:21:51 INFO - PROCESS | 17247 | |1;audio-beat-detection;144;124;122;172;125;123;156;123;162;123 21:21:51 INFO - PROCESS | 17247 | |2;audio-dft;161;160;161;160;161;163;163;162;161;159 21:21:51 INFO - PROCESS | 17247 | |3;audio-fft;77;78;76;118;75;76;75;75;116;75 21:21:51 INFO - PROCESS | 17247 | |4;audio-oscillator;86;85;124;87;86;85;118;84;85;123 21:21:51 INFO - PROCESS | 17247 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;118;118;117 21:21:51 INFO - PROCESS | 17247 | |6;imaging-darkroom;147;146;146;148;148;146;147;148;147;146 21:21:51 INFO - PROCESS | 17247 | |7;imaging-desaturate;95;95;95;95;94;96;95;94;95;95 21:21:51 INFO - PROCESS | 17247 | |8;json-parse-financial;62;62;62;63;63;63;64;62;62;63 21:21:51 INFO - PROCESS | 17247 | |9;json-stringify-tinderbox;67;68;67;66;68;66;66;67;70;68 21:21:51 INFO - PROCESS | 17247 | |10;stanford-crypto-aes;89;89;78;77;89;78;80;79;78;82 21:21:51 INFO - PROCESS | 17247 | |11;stanford-crypto-ccm;128;132;131;130;133;127;128;127;177;137 21:21:51 INFO - PROCESS | 17247 | |12;stanford-crypto-pbkdf2;168;165;169;163;163;169;167;162;163;161 21:21:51 INFO - PROCESS | 17247 | |13;stanford-crypto-sha256-iterative;67;70;67;68;66;66;66;67;66;65 21:21:51 INFO - PROCESS | 17247 | __end_tp_report 21:21:51 INFO - PROCESS | 17247 | __start_cc_report 21:21:51 INFO - PROCESS | 17247 | _x_x_mozilla_cycle_collect,1929 21:21:51 INFO - PROCESS | 17247 | __end_cc_report 21:21:51 INFO - PROCESS | 17247 | __startTimestamp1469593311948__endTimestamp 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | ------- Summary: start ------- 21:21:51 INFO - PROCESS | 17247 | Number of tests: 14 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:2.80 (2.5%) stddev-sans-first:0.88 21:21:51 INFO - PROCESS | 17247 | Values: 104.0 113.0 113.0 113.0 111.0 112.0 112.0 112.0 112.0 114.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:134.50 stddev:19.39 (14.4%) stddev-sans-first:20.42 21:21:51 INFO - PROCESS | 17247 | Values: 144.0 124.0 122.0 172.0 125.0 123.0 156.0 123.0 162.0 123.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 21:21:51 INFO - PROCESS | 17247 | Values: 161.0 160.0 161.0 160.0 161.0 163.0 163.0 162.0 161.0 159.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#3] audio-fft Cycles:10 Average:84.10 Median:75.50 stddev:17.37 (23.0%) stddev-sans-first:18.24 21:21:51 INFO - PROCESS | 17247 | Values: 77.0 78.0 76.0 118.0 75.0 76.0 75.0 75.0 116.0 75.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:85.00 stddev:17.59 (20.7%) stddev-sans-first:18.26 21:21:51 INFO - PROCESS | 17247 | Values: 86.0 85.0 124.0 87.0 86.0 85.0 118.0 84.0 85.0 123.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 21:21:51 INFO - PROCESS | 17247 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 118.0 118.0 117.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 21:21:51 INFO - PROCESS | 17247 | Values: 147.0 146.0 146.0 148.0 148.0 146.0 147.0 148.0 147.0 146.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 21:21:51 INFO - PROCESS | 17247 | Values: 95.0 95.0 95.0 95.0 94.0 96.0 95.0 94.0 95.0 95.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 21:21:51 INFO - PROCESS | 17247 | Values: 62.0 62.0 62.0 63.0 63.0 63.0 64.0 62.0 62.0 63.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:67.50 stddev:1.25 (1.9%) stddev-sans-first:1.32 21:21:51 INFO - PROCESS | 17247 | Values: 67.0 68.0 67.0 66.0 68.0 66.0 66.0 67.0 70.0 68.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:81.00 stddev:5.09 (6.3%) stddev-sans-first:4.70 21:21:51 INFO - PROCESS | 17247 | Values: 89.0 89.0 78.0 77.0 89.0 78.0 80.0 79.0 78.0 82.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:131.50 stddev:15.08 (11.5%) stddev-sans-first:15.79 21:21:51 INFO - PROCESS | 17247 | Values: 128.0 132.0 131.0 130.0 133.0 127.0 128.0 127.0 177.0 137.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.00 stddev:3.02 (1.8%) stddev-sans-first:3.00 21:21:51 INFO - PROCESS | 17247 | Values: 168.0 165.0 169.0 163.0 163.0 169.0 167.0 162.0 163.0 161.0 21:21:51 INFO - PROCESS | 17247 | 21:21:51 INFO - PROCESS | 17247 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.40 (2.1%) stddev-sans-first:1.48 21:21:51 INFO - PROCESS | 17247 | Values: 67.0 70.0 67.0 68.0 66.0 66.0 66.0 67.0 66.0 65.0 21:21:51 INFO - PROCESS | 17247 | -------- Summary: end -------- 21:21:51 INFO - PROCESS | 17247 | 21:21:52 INFO - TEST-INFO | 17247: exit 0 21:21:53 INFO - TEST-OK | kraken | took 62940ms 21:21:53 INFO - SUITE-END | took 1181s 21:21:53 INFO - Completed test suite (00:19:41) 21:21:53 INFO - javascript benchmark 21:21:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2627.868606569671, 2637.749413805688, 2667.5731882898726, 2669.3193235724893, 2679.3435608275977, 4545.499995000548, 4665.0, 4689.701876777063, 4774.331593576902, 4780.976095119524, 4702.929456058162, 4759.619230461563, 4782.83260085897, 4809.230523116302, 4865.7810398532065, 4302.032042790378, 4334.97832510837, 4377.321515165138, 4398.7580683062415, 4479.148961697267, 4350.847720329789, 4464.776761161945, 4479.596836284733, 4507.6619253555955, 4555.681102322838, 4275.337322714984, 4428.114377124588, 4450.799714012877, 4498.325251212312, 4549.499555048942, 4294.720843145185, 4295.806688699016, 4341.804618792162, 4344.087741574273, 4420.668449866273, 4315.4605674290715, 4326.286162783137, 4328.177646247203, 4359.476862776487, 4373.2565463871215, 4744.07490539345, 4774.73738944358, 4804.039192161582, 4806.663533552653, 4815.783289751969, 4929.556339929423, 4995.925061124086, 5064.063148317573, 5065.214891691794, 5110.6422550421485, 5083.644144909821, 5101.489851014869, 5104.591632669378, 5152.124138896396, 5171.741412929338, 4768.904621907612, 4770.880727981794, 4797.760111994421, 4819.542143496362, 4824.95175048245, 5098.235264710249, 5148.871278218, 5153.742312884416, 5208.583313334924, 5292.364916210079, 4945.554900059012, 4996.125678006334, 5011.523905228997, 5047.470015648378, 5048.192289233705, 5076.035553244872, 5118.976204759063, 5121.948780512222, 5122.846314610568, 5124.9743751281, 4849.830255941025, 4927.310176575283, 5018.924716129261, 5031.396232452056, 5034.11902916988, 4898.485659005824, 4931.186354251508, 4932.087563800708, 4934.8026078957155, 5014.0, 4227.767472788964, 4248.129133527619, 4251.851185208565, 4259.680523960716, 4299.333603291495, 3725.9441108383394, 3730.8880733578035, 3731.048582611417, 3752.6810221130963, 3755.586885442599, 4272.636825869774, 4356.586124318184, 4402.3396490526675, 4409.96365854024, 4461.643068554573, 5146.614003949644, 5154.974225128925, 5160.922586161136, 5161.741912904415, 5198.428172900978, 4412.338149277634, 4415.09490554442, 4419.226635338803, 4451.020775429465, 4460.553944605578, 4470.619997300266, 4514.774261286988, 4545.5908968193025, 4571.38286331341, 4620.792064357112, 4715.0805592909055, 4723.149833030087, 4725.1967165581245, 4768.904621907612, 4800.855974320706, 5074.797008119782, 5077.568406685473, 5080.237964305384, 5090.7200103993155, 5090.796368145233, 4609.746463944515, 4697.0840686065885, 4713.646476514344, 4739.123262196505, 4755.83354582588, 4945.925811112764, 4953.578945789504, 4957.925631115464, 4964.851054468371, 5054.898902022012, 5000.349954505891, 5002.374703162105, 5009.373828271466, 5010.724410157476, 5031.4213865405945, 4748.810047598057, 4873.853784386474, 4908.950910490992, 4923.286123512143, 4944.554990050912, 4936.9259461107395, 4961.875953101201, 4963.329950456787, 4964.67729597575, 4991.401031876198, 4865.318855360323, 4890.80436782539, 4928.975355123202, 4937.802487900443, 4951.8266860659705, 4842.346283251716, 4905.1416002200185, 4912.1894887344615, 4930.827421040246, 5007.24891266313, 4848.636352273664, 4860.902781944412, 4870.853874383774, 4872.952315252238, 4916.213405855046], "value": 4655.570182875573, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.1174755633657, 569.8575356160959, 570.0138759945291, 589.0751520113421, 591.101525680965, 27306.0, 39232.80383598081, 40779.38830917539, 40865.99999999996, 45199.77400112995, 40883.38674919871, 41119.58880411195, 41140.17719645613, 41165.58834411655, 41215.793921030425, 7567.848643027136, 7729.265719756642, 7732.884006739903, 7738.535687858718, 7739.264769846855, 7708.884366734504, 7806.297433231008, 7807.375409967189, 7808.882866757003, 7815.140334563193, 7818.374530037583, 7882.172371900954, 7883.763487095368, 7893.407994400414, 7894.7236846710375, 7799.37604991605, 7802.765917022499, 7849.607519623996, 7852.842943141162, 7860.646270917822, 7720.961395192988, 7792.220777922163, 7797.610119494059, 7800.375969922393, 7802.297793198582, 38810.223795524216, 39119.41320880189, 39159.216815663814, 39206.41190382146, 39211.21577568433, 2886.2207204055, 3025.667176610571, 3302.9009129726146, 3331.283773988604, 3724.8137593120236, 26785.598216026774, 28830.855845720846, 29373.0, 29492.70507294921, 29493.262668433246, 3180.9343869803643, 3192.9680703192903, 3195.472746996743, 3338.1821453743746, 3351.1789611545328, 264560.0, 267917.0, 268036.0, 268051.0, 268629.0, 31228.531572026437, 54676.726616366665, 54841.0, 54896.0, 55338.446615534136, 31042.223944401117, 33038.17404564905, 33096.51776116801, 33103.50344744831, 33132.0, 13458.52895148665, 13649.225600672047, 13651.522196723066, 13656.385462654005, 13677.452901884013, 11464.94267528657, 11541.249818761753, 11554.422278886022, 11559.537618495335, 11565.653030409101, 7701.961490192513, 7852.0, 7905.407094467823, 7921.089074756362, 7971.920280797234, 3132.592762940849, 3142.355817057498, 3158.368326334696, 3170.381775553791, 3173.0, 776.642744337611, 778.0702061037119, 819.4345901328064, 855.0765173612483, 859.7205908079774, 25712.8714356427, 28652.14043578696, 30252.24369390739, 31061.8446907764, 31457.584408701663, 895.1496078725107, 916.8624706293975, 925.796324808541, 947.5593848860175, 969.6606187834343, 13906.30468476572, 14393.568192954228, 14518.564443066725, 14880.404783808743, 15054.473093441677, 430034.0, 433572.0, 434243.0, 434827.0, 435035.0, 51015.234771478456, 51302.0, 51351.743241283555, 51394.48605513972, 51395.229071563954, 1056.2817284246787, 1164.0396672745019, 1186.039308160393, 1187.940602969848, 1235.7281398092405, 27441.31396715018, 29422.264443389085, 29488.705112949454, 30104.24739381533, 30132.24669383283, 42534.787326063175, 43118.0, 43150.13699726051, 43160.0, 43187.784061079496, 335901.0, 336715.0, 338389.0, 339927.0, 340012.0, 22416.887915560972, 24498.63252051185, 25417.61873571935, 25531.617025745, 25927.0, 41133.76598702044, 41837.79081104575, 41953.0, 41985.0, 42581.36127958021, 35150.82424587963, 35664.0, 35907.64092359043, 35920.46119308265, 35958.64041359658, 35560.46659300164, 35797.46303805393, 35839.283214335046, 35966.10084747902, 35993.28013439769, 27773.56230031933, 31339.373212536077, 35295.294094118486, 38742.2251554973, 38760.418593720555, 27590.310242244104, 29083.12750617485, 29259.268518287212, 29325.909723603443, 29540.0, 24701.0, 25169.748302516742, 25295.00426392738, 25560.23319300424, 26089.0], "value": 16485.560079084564, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.9855605775771, 370.8590735520502, 376.28318054106927, 378.3076969146462, 391.37966323377447, 15801.446949356761, 17194.914025429873, 17323.91338043311, 17400.73898891518, 17414.738778918327, 16102.919485402557, 17235.74146387805, 17248.56878578036, 17362.305507779674, 17376.304947802106, 4854.2718592211095, 5088.084144853925, 5094.2103973884105, 5095.133827249358, 5097.923531147036, 3674.2835147146316, 3868.458415821787, 5009.949900500985, 5656.4626360495895, 5875.441833025856, 5899.941000590004, 5901.144334071557, 5905.852353691171, 5906.497947674453, 5915.201447804556, 5721.256236689245, 5968.67172305523, 5980.641161530322, 5991.370906054889, 5993.999999999978, 5056.140456122468, 5246.7638956247065, 5259.21111833228, 5260.395054568736, 5267.0, 21053.157873685035, 21401.67897481539, 21407.67888481674, 21431.14275428981, 21618.243361482273, 1769.6991511442957, 1817.6819056665163, 1828.5520047588425, 1853.8887666739904, 1881.4073566826405, 13175.736485270241, 13195.20828750278, 13270.469181232738, 13327.466901323935, 14001.369938352798, 1657.6436066245694, 1667.591440097172, 1672.4313733330725, 1677.0776073159836, 1769.78762548495, 53909.73045134788, 70457.0, 70807.2919270811, 72367.63816180939, 74135.25864741337, 8940.329475289249, 9068.50123243228, 9304.581293841793, 9322.813543729224, 9582.185514231369, 12592.81110783339, 12602.11785175029, 12631.368431578385, 12643.051771117203, 12645.367731613382, 7087.362137407658, 7135.82160445993, 7136.143662760398, 7303.342699156995, 7334.083239595051, 6974.616396098263, 6979.613360263516, 6989.825254368681, 7172.964135179291, 7173.5337203081635, 5926.851828704231, 5931.9703401483575, 5939.198208241918, 5951.5238780897425, 5982.341942386334, 1546.891717579781, 1580.7391780356343, 1597.2892063032073, 1685.2416412614373, 1688.122176468254, 776.8485145396594, 795.5266616363255, 810.959452027408, 905.2893478619252, 919.3151102428733, 16277.186140692918, 17106.230219639896, 17269.827301727073, 17356.826431735775, 17972.55068623295, 821.5290398068233, 828.4532208742202, 841.8021764885247, 875.3478658399533, 885.7342797160826, 9535.713928582154, 10113.848292275621, 10174.440405777754, 10210.693679189637, 10214.336068155695, 386165.0, 393579.0, 394280.0, 400042.0, 402868.0, 68557.0, 69127.65436172887, 69832.6508367455, 70760.29239707538, 71376.64311678408, 916.3036092569695, 916.9403988740711, 920.8848893888264, 924.2282693950626, 931.3573634192386, 11662.358570278717, 15368.308426121075, 16637.833621663627, 16760.66478670444, 16873.325066997673, 24457.633135503336, 24712.25863224106, 36419.63580364268, 36498.452523212705, 36552.0, 49543.256851147984, 50449.49550504448, 55986.440135598124, 57767.71116144393, 58299.0, 7409.8147546311775, 7703.728884734181, 7745.380369570308, 7899.407544434305, 7973.521588704696, 9313.534323283673, 11668.533258669559, 11802.881971180179, 11918.165728398928, 12132.93933530362, 9983.700488985342, 9987.0, 10176.287659863738, 10283.2801703883, 10553.841692374466, 10280.537375817956, 10543.736406589896, 10593.09958653523, 10699.251052426256, 10725.517351719347, 9984.151347135576, 10030.749231269278, 10060.748481288027, 10122.493875306352, 10283.485825708534, 6702.564333318319, 6919.827004324932, 6928.272531384334, 7306.96346518264, 7583.924160758543, 8830.955845220733, 9711.805763884824, 9717.222622190066, 9778.853317200106, 10536.0], "value": 8238.86565749261, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.713263780925, 1441.9639509012277, 1497.5881632551054, 1628.983710162898, 1704.0094633917472, 7695.461317707755, 8054.476459030167, 8056.91943080569, 8062.435629505944, 8085.2319029692235, 6225.470834979033, 7376.336129748322, 7697.153313135551, 7699.807504812383, 8017.19828017198, 2176.6843807647883, 2204.151401710345, 2217.922372716955, 2221.0893533651206, 2223.1440895255364, 2191.7698641642637, 2223.83321250906, 2225.276785044859, 2226.065052677879, 2230.4200907764016, 2189.058704756954, 2190.9452263693374, 2201.306588424641, 2201.471646804763, 2231.9665205021856, 2148.0548558634227, 2151.817095546865, 2154.34292540775, 2155.4395857077275, 2156.892155392232, 2139.732533433321, 2184.7924447177493, 2186.486175748698, 2190.277208521184, 2191.8684878907156, 7880.80297992549, 7978.122406535276, 7981.241782030698, 7993.280604745601, 7997.840043199161, 4558.703684260546, 4689.273162659793, 4703.106409782131, 4706.10583989041, 4716.339712440261, 2688.2204160793344, 2764.847933363664, 2809.1432113205465, 2810.901618443345, 2811.0863969209886, 860.7632900952226, 889.8665200219954, 905.3798148268457, 907.1654078248027, 933.9533023348805, 10833.187510936632, 10871.075958543533, 10874.02133807961, 10898.0, 10932.945335273353, 4071.4096456013713, 4213.47331583552, 4339.4792624884585, 4391.890202744893, 4391.978040109779, 9071.863922041042, 9077.364584479155, 9083.18251357381, 9092.136247056518, 9113.498757568397, 4584.33527138563, 4594.885127871763, 4629.8842528936375, 4632.305154226893, 4634.420697412824, 3600.6579374959338, 4054.39184122384, 4152.896177595584, 4157.8336866525, 4220.282551966173, 3642.399004164336, 3643.6356364363883, 3809.0096574890727, 3811.6378944000276, 3818.522684664417, 1173.7711146326556, 1220.6399112261727, 1222.9021678265717, 1226.3193927370396, 1227.4415141110774, 857.3484152044491, 859.1408591408591, 861.875028120919, 877.1053525404052, 880.5905254056767, 5235.136202526541, 5266.8419947401635, 5382.085045542267, 5383.8384848453825, 5452.836414907559, 423.9703220774578, 427.63009996353554, 431.6611460003882, 431.7495852405597, 441.23886296138903, 3621.8913432597064, 3673.3020726061964, 3690.0, 3690.9815450922574, 3731.384321586908, 9497.287703422271, 9703.126718595218, 9785.804283914282, 9808.754781130387, 9809.460479673686, 9751.268654850914, 9758.17055550272, 9772.315937884421, 9775.755606109904, 9776.120149186607, 7394.408447324201, 7461.738839140713, 7531.435142364454, 7531.661075251517, 7536.5101268417375, 5010.273510341053, 5167.173252279617, 5259.55293800016, 5299.841004769709, 5462.235287059894, 8693.261072808882, 8927.732168034969, 8969.327300452622, 8974.730758077269, 9121.042290559395, 9006.0, 9197.264218862609, 9214.677486287948, 9268.768280793034, 9288.11762882525, 5961.850953726018, 6545.37818907203, 6872.278410766798, 6964.233934267125, 6970.37266645984, 7372.299631534996, 7631.3894888407685, 7641.541507509567, 7641.617919103911, 7650.6174691264105, 6692.83267918306, 6710.496712746466, 6756.527043106936, 6875.0, 6893.069435626327, 6589.242237142598, 6760.222574404006, 6803.591784492946, 6826.044353790573, 6928.7228510859, 6129.724162412791, 6349.0, 6354.046892965907, 6355.046742988404, 6359.87280254383, 4403.0, 4440.600345968878, 4771.284307353924, 4788.329633851194, 4864.294677271847, 4591.265397536378, 4729.196036673842, 4729.219678753105, 4762.78567464458, 4905.533974272438], "value": 4097.590874603289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.3374178033665, 307.8630009645708, 311.17538522914276, 315.9636641786195, 319.2927665221534, 4804.399450068741, 4850.660453768238, 4854.854354369366, 4862.416510018798, 4866.902661946759, 4552.772361381934, 4674.480793742039, 4687.109449204657, 4774.188387974044, 4818.51814818518, 3804.182100848317, 3833.884983450487, 3835.7506762060375, 3862.0731024554116, 3867.284552357809, 3829.253295607358, 3829.48301979232, 3842.5206295576263, 3847.6537111659945, 3850.07598176437, 3613.620569840168, 3668.798216098127, 3770.8868733938025, 3789.5073640426845, 3808.1241314497684, 3779.4897688811934, 3793.6775374093236, 3816.179521402898, 3819.59894211108, 3826.4834247376803, 3398.5921689397182, 3404.7957122572725, 3406.2846802171575, 3413.573303337083, 3418.5555877735983, 4512.278035514302, 4745.034353529044, 4746.647205546437, 4749.430068391781, 4754.66717329787, 4233.661307095455, 4262.25410553152, 4264.957350426518, 4268.9786551067355, 4300.118475712471, 5013.824516141917, 5018.347614810088, 5021.69869807809, 5022.648414610979, 5036.438704001771, 3602.7117830573493, 3606.5672119345845, 3610.837512311952, 3611.4763359313015, 3627.6239063413504, 5116.897662046738, 5182.031781671181, 5205.089109405839, 5238.345206849106, 5246.737663116867, 3635.6727894489095, 3678.393065144221, 3680.687141592941, 3689.3728066228805, 3697.519322488113, 3715.479832823407, 3737.1591391936595, 3744.513213282256, 3748.2503499299705, 3750.099976005739, 4145.79271036447, 4234.491860976641, 4267.97866010668, 4297.355396690521, 4299.054208074219, 4977.257959714071, 4988.650794444354, 5008.824691135793, 5013.623978201649, 5056.469070747518, 1271.0848189303686, 1290.5741105435184, 1291.560869304441, 1294.838145231846, 1297.7022977022978, 824.3158178711655, 830.4892491118039, 854.1586537260794, 856.6402111113347, 857.5412154497315, 4132.615573782757, 4348.304271316574, 4381.430414046154, 4476.194285028661, 4478.2610869206865, 5150.639455238097, 5169.767360468788, 5179.300794392709, 5187.9883422732955, 5198.81804136861, 4476.664250181249, 4483.349914262414, 4508.45898492183, 4512.593866551961, 4542.500324964251, 2619.5677713177492, 2726.5637498000224, 2729.7543221110195, 2735.7264273572882, 2745.68424631166, 4810.687305325143, 4845.020312507852, 4845.394325709286, 4852.126617208865, 4857.813983707346, 5239.947600524022, 5251.553617942518, 5275.815346462856, 5327.520523152857, 5327.653702509402, 4707.552782485658, 4862.416510018749, 4867.1969125093965, 4871.2206047032305, 4893.584045356185, 5219.817306394258, 5224.477552244745, 5225.947740522546, 5226.137687281556, 5236.136037553761, 5114.948850511596, 5122.0, 5126.179811230185, 5130.179171332569, 5144.562712169358, 4654.534546545454, 4871.439784424695, 4871.5128487151005, 4887.682300650446, 4905.632077594266, 3654.8172591369794, 3665.4868318434906, 3665.7433979622238, 3674.118211629136, 3691.9261614767015, 5233.659812112201, 5242.449542797951, 5273.499017593323, 5278.89442211146, 5283.682979021271, 5002.424721157114, 5011.824586139468, 5025.3718285214345, 5058.924116138334, 5126.128558145049, 5006.4242612098615, 5007.674501157413, 5017.924731128963, 5023.598112151092, 5044.924326135184, 4965.751712414437, 4974.8756281093265, 5003.599712023102, 5014.448410674896, 5051.06555287273, 4907.779149938333, 5055.924161137659, 5088.363954505687, 5097.649122982487, 5144.948550514447, 4952.257161425815, 4960.255961605788, 4975.636572627973, 4984.925226121539, 4987.47631498702], "value": 3812.237193389698, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.7628365646539, 670.949678774092, 671.6587696477505, 734.3977938090768, 757.8673732096884, 12527.123101382907, 13673.726525469485, 13690.178589284626, 13697.0, 13737.381817818197, 13102.737945241113, 13247.801282980763, 13249.536266230683, 13254.734905301888, 13267.0, 618.730852079347, 637.3881074168803, 754.9773506794805, 776.79026662801, 781.1329424338979, 768.69636493585, 787.0869791042392, 790.865552856013, 794.9046114466272, 797.5055465611329, 818.2840014986887, 825.6614787936948, 827.4786884262905, 827.5572568675759, 827.71443851871, 655.2922843329193, 674.2515807453723, 823.7693445835175, 825.0347093900122, 825.0553116681426, 499.76261275894024, 499.9300097986285, 581.2792137749161, 595.8748662780821, 596.2755252368394, 13825.723485530334, 13834.169949803045, 13841.30793460323, 13855.307234638227, 13860.168389896638, 12063.0, 12141.210821296676, 12358.567450139202, 12562.0, 12771.872281277254, 11750.412479375997, 11772.175947683665, 11793.764124717543, 11918.284902905853, 11986.580469683606, 4199.0552125771765, 4199.706020578561, 4231.048014196812, 4272.9145417091795, 4289.120730250323, 63595.0, 64985.0, 66287.35419551923, 66731.33268667349, 67523.0, 9801.411915285105, 9822.607095716234, 9909.752256193653, 10869.67390978272, 11010.724731881766, 20732.274370397266, 21132.894335528224, 21135.154593816074, 21147.365579032652, 21162.47093822636, 6847.726090956306, 6849.602639076383, 6922.5154239202775, 7209.531380460253, 7227.638618069181, 3480.7911525308564, 3538.663826936437, 3600.4959305697225, 3601.9279614408088, 3650.3064417760543, 2700.027989923626, 2773.7642300404286, 2779.70743604221, 2780.5134101532153, 2784.8607569621436, 480.9639277054235, 489.59608323133557, 489.6670264220293, 490.298872612168, 501.1755661936095, 569.7521578113534, 597.5846786483439, 616.5098746496546, 625.0838496817443, 627.8650090230623, 7638.427117966175, 7970.521768693897, 8040.35677145827, 8114.350851931831, 8143.633536490872, 561.3768716724433, 591.8609126855185, 595.1103100864236, 606.2361424604941, 620.8572028433485, 3508.526348942911, 3771.6039815819495, 3891.610838916086, 3983.1237127831832, 4058.2695114879607, 96183.0, 98358.50820745851, 102068.0, 104781.0, 105190.47404762928, 88726.0, 90831.09168908358, 90953.0, 91376.54311728399, 92254.53872730593, 75713.24286757172, 76078.61960690162, 76605.61697191479, 77140.61429692815, 77645.0, 7289.56262624223, 7503.849923001619, 7539.283768042027, 7545.471816972759, 7566.281203285899, 45568.54431455643, 46257.768711156226, 46783.53216467928, 48062.27906581334, 48260.0, 37462.8126859364, 67045.66477167583, 68263.97604036043, 69375.0, 69564.0, 9951.701448956543, 10432.634857779942, 10600.1519878408, 10831.729206769895, 11015.669529914116, 9802.117809397188, 10562.31344962575, 10887.455627218766, 11353.886461135284, 11468.4265786712, 9060.30058011073, 9213.309001824757, 9251.93602735694, 9367.812643746951, 9559.569819358285, 9807.607695692092, 9961.452120133463, 10009.449480278654, 11860.347680877634, 12058.216215945935, 11567.76864462723, 11718.70703232426, 11809.468573914022, 11976.712498451629, 12395.62813115608, 8110.959445202972, 8421.19998600132, 8473.703420380258, 8914.687985920462, 9110.180083792491, 8812.603432845666, 9559.0, 10176.962403609965, 10537.473126343806, 10904.454777261264], "value": 6519.34680731847, "unit": "score"}], "name": "dromaeo_css", "value": 6331.170056560987, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 113.0, 113.0, 111.0, 112.0, 112.0, 112.0, 112.0, 114.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 124.0, 122.0, 172.0, 125.0, 123.0, 156.0, 123.0, 162.0, 123.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 161.0, 163.0, 163.0, 162.0, 161.0, 159.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 76.0, 118.0, 75.0, 76.0, 75.0, 75.0, 116.0, 75.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 124.0, 87.0, 86.0, 85.0, 118.0, 84.0, 85.0, 123.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 148.0, 148.0, 146.0, 147.0, 148.0, 147.0, 146.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 63.0, 63.0, 63.0, 64.0, 62.0, 62.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 68.0, 66.0, 66.0, 67.0, 70.0, 68.0], "value": 67.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 78.0, 77.0, 89.0, 78.0, 80.0, 79.0, 78.0, 82.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 132.0, 131.0, 130.0, 133.0, 127.0, 128.0, 127.0, 177.0, 137.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 169.0, 163.0, 163.0, 169.0, 167.0, 162.0, 163.0, 161.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 67.0, 68.0, 66.0, 66.0, 66.0, 67.0, 66.0, 65.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1528.2, "alertThreshold": 2.0}]} 21:21:53 INFO - Return code: 0 21:21:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:21:53 INFO - # TBPL SUCCESS # 21:21:53 INFO - Running post-action listener: _resource_record_post_action 21:21:53 INFO - [mozharness: 2016-07-27 04:21:53.236032Z] Finished run-tests step (success) 21:21:53 INFO - Running post-run listener: _resource_record_post_run 21:21:53 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 0; Write bytes: 536805376; Read time: 0; Write time: 446564 21:21:53 INFO - TinderboxPrint: CPU usage
13.0% 21:21:53 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:21:53 INFO - TinderboxPrint: I/O write bytes / time
536,805,376 / 446,564 21:21:53 INFO - TinderboxPrint: CPU idle
8,321.7 (87.0%) 21:21:53 INFO - TinderboxPrint: CPU user
1,197.9 (12.5%) 21:21:53 INFO - TinderboxPrint: Swap in / out
0 / 0 21:21:53 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247070720; Read time: 0; Write time: 263288 21:21:53 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 289734656; Read time: 0; Write time: 183276 21:21:53 INFO - Running post-run listener: _upload_blobber_files 21:21:53 INFO - Blob upload gear active. 21:21:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:21:53 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 21:21:53 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:21:53 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:21:54 INFO - (blobuploader) - INFO - Open directory for files ... 21:21:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:21:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:21:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:57 INFO - (blobuploader) - INFO - Done attempting. 21:21:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:21:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:21:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:58 INFO - (blobuploader) - INFO - Done attempting. 21:21:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:21:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:21:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:59 INFO - (blobuploader) - INFO - Done attempting. 21:21:59 INFO - (blobuploader) - INFO - Iteration through files over. 21:21:59 INFO - Return code: 0 21:21:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:21:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:21:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/97da137493fa9a84002c99caf99b39534a70ddcecc4879bbe4b5ac45ccf62a1d1f8fb52a03726c4f5c1d92cb3473d9e18cc62d5b6ede90c55b416c277b9dbe39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/24990086647a15dcb747b6c01afecfca39b49e3916bf82cd5a7e708d10ca7657c07d5ceba42465bedf9730cc979139efe542f5b8cbfc3442a373ad29aff244c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b93a777be244d407726a876332cedebaa7e80e55b86cc4cce2c39380cfa1b130b62ad73c34fc35bf9fcc3684f1067848de8fc6cd94e87fd9265d3f5824aba8d6"} 21:21:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:21:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:21:59 INFO - Contents: 21:21:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/97da137493fa9a84002c99caf99b39534a70ddcecc4879bbe4b5ac45ccf62a1d1f8fb52a03726c4f5c1d92cb3473d9e18cc62d5b6ede90c55b416c277b9dbe39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/24990086647a15dcb747b6c01afecfca39b49e3916bf82cd5a7e708d10ca7657c07d5ceba42465bedf9730cc979139efe542f5b8cbfc3442a373ad29aff244c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b93a777be244d407726a876332cedebaa7e80e55b86cc4cce2c39380cfa1b130b62ad73c34fc35bf9fcc3684f1067848de8fc6cd94e87fd9265d3f5824aba8d6"} 21:21:59 INFO - Running post-run listener: copy_logs_to_upload_dir 21:21:59 INFO - Copying logs to upload dir... 21:21:59 INFO - mkdir: /builds/slave/test/build/upload/logs 21:21:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.566920 ========= master_lag: 0.92 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-26 21:22:00.340709) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 21:22:00.347775) ========= 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=1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459 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-Non-PGO/sha512/97da137493fa9a84002c99caf99b39534a70ddcecc4879bbe4b5ac45ccf62a1d1f8fb52a03726c4f5c1d92cb3473d9e18cc62d5b6ede90c55b416c277b9dbe39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/24990086647a15dcb747b6c01afecfca39b49e3916bf82cd5a7e708d10ca7657c07d5ceba42465bedf9730cc979139efe542f5b8cbfc3442a373ad29aff244c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b93a777be244d407726a876332cedebaa7e80e55b86cc4cce2c39380cfa1b130b62ad73c34fc35bf9fcc3684f1067848de8fc6cd94e87fd9265d3f5824aba8d6"} build_url:https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010120 build_url: 'https://queue.taskcluster.net/v1/task/bGq6capUSaKOfmcWhT798Q/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-Non-PGO/sha512/97da137493fa9a84002c99caf99b39534a70ddcecc4879bbe4b5ac45ccf62a1d1f8fb52a03726c4f5c1d92cb3473d9e18cc62d5b6ede90c55b416c277b9dbe39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/24990086647a15dcb747b6c01afecfca39b49e3916bf82cd5a7e708d10ca7657c07d5ceba42465bedf9730cc979139efe542f5b8cbfc3442a373ad29aff244c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b93a777be244d407726a876332cedebaa7e80e55b86cc4cce2c39380cfa1b130b62ad73c34fc35bf9fcc3684f1067848de8fc6cd94e87fd9265d3f5824aba8d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 21:22:00.373721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:22:00.374059) ========= 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=1f5564757af27664ca02d71f000001b0-1469591007.95472-2039034459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:22:00.423155) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 21:22:00.426538) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 21:22:00.426864) ========= ========= Total master_lag: 1.06 =========