builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1469601596.75 results: success (0) buildid: 20160726200738 builduid: c1b0b353bf6a473bad7b78c67ff35501 revision: 722707a63810c1c284c4ba5f539002686f2861a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:56.754201) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:56.754642) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:56.754920) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:56.769786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:56.770095) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 23:39:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2016-07-26 23:39:56 (46.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077218 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:56.863386) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:56.863702) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014171 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:56.912452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 23:39:56.912759) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 722707a63810c1c284c4ba5f539002686f2861a6 --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 722707a63810c1c284c4ba5f539002686f2861a6 --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=516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 23:39:57,108 truncating revision to first 12 chars 2016-07-26 23:39:57,109 Setting DEBUG logging. 2016-07-26 23:39:57,109 attempt 1/10 2016-07-26 23:39:57,109 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/722707a63810?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 23:39:58,119 unpacking tar archive at: autoland-722707a63810/testing/mozharness/ program finished with exit code 0 elapsedTime=1.345422 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 23:39:58.271822) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:58.272154) ========= script_repo_revision: 722707a63810c1c284c4ba5f539002686f2861a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:58.272530) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:58.272795) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 23:39:58.281984) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-26 23:39:58.282248) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:39:58 INFO - MultiFileLogger online at 20160726 23:39:58 in /builds/slave/test 23:39:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 23:39:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:39:58 INFO - {'append_to_log': False, 23:39:58 INFO - 'base_work_dir': '/builds/slave/test', 23:39:58 INFO - 'blob_upload_branch': 'Autoland', 23:39:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:39:58 INFO - 'branch': 'Autoland', 23:39:58 INFO - 'buildbot_json_path': 'buildprops.json', 23:39:58 INFO - 'config_files': ('talos/linux_config.py',), 23:39:58 INFO - 'default_actions': ('clobber', 23:39:58 INFO - 'read-buildbot-config', 23:39:58 INFO - 'download-and-extract', 23:39:58 INFO - 'populate-webroot', 23:39:58 INFO - 'create-virtualenv', 23:39:58 INFO - 'install', 23:39:58 INFO - 'run-tests'), 23:39:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:39:58 INFO - 'download_minidump_stackwalk': True, 23:39:58 INFO - 'download_symbols': 'ondemand', 23:39:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:39:58 INFO - 'tooltool.py': '/tools/tooltool.py', 23:39:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:39:58 INFO - '/tools/misc-python/virtualenv.py')}, 23:39:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:39:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:39:58 INFO - 'installer_path': 'installer.exe', 23:39:58 INFO - 'log_level': 'info', 23:39:58 INFO - 'log_name': 'talos', 23:39:58 INFO - 'log_to_console': True, 23:39:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:39:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:39:58 INFO - 'opt_config_files': (), 23:39:58 INFO - 'pip_index': False, 23:39:58 INFO - 'sps_profile': False, 23:39:58 INFO - 'sps_profile_interval': 0, 23:39:58 INFO - 'suite': 'dromaeojs', 23:39:58 INFO - 'system_bits': '32', 23:39:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:39:58 INFO - 'title': 'talos-linux64-ix-061', 23:39:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:39:58 INFO - 'use_talos_json': True, 23:39:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:39:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:39:58 INFO - 'work_dir': 'build'} 23:39:58 INFO - [mozharness: 2016-07-27 06:39:58.576511Z] Running clobber step. 23:39:58 INFO - Running pre-action listener: _resource_record_pre_action 23:39:58 INFO - Running main action method: clobber 23:39:58 INFO - rmtree: /builds/slave/test/build 23:39:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:39:59 INFO - Running post-action listener: _resource_record_post_action 23:39:59 INFO - [mozharness: 2016-07-27 06:39:59.566681Z] Finished clobber step (success) 23:39:59 INFO - [mozharness: 2016-07-27 06:39:59.566781Z] Running read-buildbot-config step. 23:39:59 INFO - Running pre-action listener: _resource_record_pre_action 23:39:59 INFO - Running main action method: read_buildbot_config 23:39:59 INFO - Using buildbot properties: 23:39:59 INFO - { 23:39:59 INFO - "project": "", 23:39:59 INFO - "product": "firefox", 23:39:59 INFO - "script_repo_revision": "production", 23:39:59 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 23:39:59 INFO - "repository": "", 23:39:59 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 23:39:59 INFO - "buildid": "20160726200738", 23:39:59 INFO - "pgo_build": "True", 23:39:59 INFO - "basedir": "/builds/slave/test", 23:39:59 INFO - "buildnumber": 72, 23:39:59 INFO - "slavename": "talos-linux64-ix-061", 23:39:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:39:59 INFO - "platform": "ubuntu64_hw", 23:39:59 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 23:39:59 INFO - "branch": "autoland", 23:39:59 INFO - "repo_path": "integration/autoland", 23:39:59 INFO - "revision": "722707a63810c1c284c4ba5f539002686f2861a6", 23:39:59 INFO - "stage_platform": "linux64-pgo", 23:39:59 INFO - "builduid": "c1b0b353bf6a473bad7b78c67ff35501", 23:39:59 INFO - "slavebuilddir": "test" 23:39:59 INFO - } 23:39:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:39:59 INFO - Running post-action listener: _resource_record_post_action 23:39:59 INFO - [mozharness: 2016-07-27 06:39:59.569267Z] Finished read-buildbot-config step (success) 23:39:59 INFO - [mozharness: 2016-07-27 06:39:59.569357Z] Running download-and-extract step. 23:39:59 INFO - Running pre-action listener: _resource_record_pre_action 23:39:59 INFO - Running main action method: download_and_extract 23:39:59 INFO - mkdir: /builds/slave/test/build/tests 23:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:39:59 INFO - https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:39:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:39:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:39:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:39:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:00 INFO - Downloaded 1361 bytes. 23:40:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:40:00 INFO - Using the following test package requirements: 23:40:00 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:40:00 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:40:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:40:00 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:40:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:40:00 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:40:00 INFO - u'jsshell-linux-x86_64.zip'], 23:40:00 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:40:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:40:00 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:40:00 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:40:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:40:00 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:40:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:40:00 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:40:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:40:00 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:40:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:40:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:40:00 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')]} 23:40:00 INFO - https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:40:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:40:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:40:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:01 INFO - Downloaded 24311989 bytes. 23:40:01 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'] 23:40:01 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 23:40:02 INFO - Return code: 0 23:40:02 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 23:40:02 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')]} 23:40:02 INFO - https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:40:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:40:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:40:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:03 INFO - Downloaded 24311989 bytes. 23:40:03 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'] 23:40:03 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 23:40:04 INFO - Return code: 0 23:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:04 INFO - https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:40:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:40:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:40:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:05 INFO - Downloaded 14413725 bytes. 23:40:05 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'] 23:40:05 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 23:40:06 INFO - Return code: 0 23:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:06 INFO - https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:40:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:40:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:40:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OnBZffB9Skm42P7pUVqdjw/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 23:40:07 INFO - Downloaded 60893205 bytes. 23:40:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:40:07 INFO - mkdir: /builds/slave/test/properties 23:40:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:40:07 INFO - Writing to file /builds/slave/test/properties/build_url 23:40:07 INFO - Contents: 23:40:07 INFO - build_url:https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:40:07 INFO - Running post-action listener: _resource_record_post_action 23:40:07 INFO - Running post-action listener: set_extra_try_arguments 23:40:07 INFO - [mozharness: 2016-07-27 06:40:07.998635Z] Finished download-and-extract step (success) 23:40:07 INFO - [mozharness: 2016-07-27 06:40:07.998869Z] Running populate-webroot step. 23:40:07 INFO - Running pre-action listener: _resource_record_pre_action 23:40:07 INFO - Running main action method: populate_webroot 23:40:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:40:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:40:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:40:08 INFO - '--tppagecycles', 23:40:08 INFO - '7'], 23:40:08 INFO - 'tests': ['tsvgm']}}, 23:40:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:40:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:40:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:40:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:40:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:40:08 INFO - 'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:40:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:40:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:40:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:40:08 INFO - 'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['damp', 'tps']}, 23:40:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:40:08 INFO - 'tests': ['damp', 'tps']}, 23:40:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['dromaeo_dom']}, 23:40:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:40:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['basic_compositor_video']}, 23:40:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:40:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['a11yr', 23:40:08 INFO - 'ts_paint', 23:40:08 INFO - 'tpaint', 23:40:08 INFO - 'sessionrestore', 23:40:08 INFO - 'sessionrestore_no_auto_restore', 23:40:08 INFO - 'tabpaint']}, 23:40:08 INFO - 'other-e10s': {'tests': ['a11yr', 23:40:08 INFO - 'ts_paint', 23:40:08 INFO - 'tpaint', 23:40:08 INFO - 'sessionrestore', 23:40:08 INFO - 'sessionrestore_no_auto_restore', 23:40:08 INFO - 'tabpaint']}, 23:40:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:40:08 INFO - 'ts_paint', 23:40:08 INFO - 'tpaint', 23:40:08 INFO - 'sessionrestore', 23:40:08 INFO - 'sessionrestore_no_auto_restore', 23:40:08 INFO - 'tabpaint']}, 23:40:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:40:08 INFO - 'ts_paint', 23:40:08 INFO - 'tpaint', 23:40:08 INFO - 'sessionrestore', 23:40:08 INFO - 'sessionrestore_no_auto_restore', 23:40:08 INFO - 'tabpaint']}, 23:40:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['a11yr', 23:40:08 INFO - 'ts_paint', 23:40:08 INFO - 'tpaint', 23:40:08 INFO - 'sessionrestore', 23:40:08 INFO - 'sessionrestore_no_auto_restore', 23:40:08 INFO - 'tabpaint']}, 23:40:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['a11yr', 23:40:08 INFO - 'ts_paint', 23:40:08 INFO - 'tpaint', 23:40:08 INFO - 'sessionrestore', 23:40:08 INFO - 'sessionrestore_no_auto_restore', 23:40:08 INFO - 'tabpaint']}, 23:40:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['tsvgx', 23:40:08 INFO - 'tsvgr_opacity', 23:40:08 INFO - 'tart', 23:40:08 INFO - 'tscrollx', 23:40:08 INFO - 'cart']}, 23:40:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:40:08 INFO - 'tsvgr_opacity', 23:40:08 INFO - 'tart', 23:40:08 INFO - 'tscrollx', 23:40:08 INFO - 'cart']}, 23:40:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:40:08 INFO - 'talos_options': ['--disable-e10s'], 23:40:08 INFO - 'tests': ['tp5o']}, 23:40:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:40:08 INFO - 'tests': ['tp5o']}, 23:40:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:40:08 INFO - 'talos_options': ['--disable-e10s', 23:40:08 INFO - '--xperf_path', 23:40:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:40:08 INFO - 'tests': ['tp5n']}, 23:40:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:40:08 INFO - 'talos_options': ['--xperf_path', 23:40:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:40:08 INFO - 'tests': ['tp5n']}}, 23:40:08 INFO - 'talos.zip': {'path': '', 23:40:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:40:08 INFO - Running post-action listener: _resource_record_post_action 23:40:08 INFO - [mozharness: 2016-07-27 06:40:08.017038Z] Finished populate-webroot step (success) 23:40:08 INFO - [mozharness: 2016-07-27 06:40:08.017128Z] Running create-virtualenv step. 23:40:08 INFO - Running pre-action listener: _resource_record_pre_action 23:40:08 INFO - Running main action method: create_virtualenv 23:40:08 INFO - Creating virtualenv /builds/slave/test/build/venv 23:40:08 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 23:40:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:40:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:40:08 INFO - Using real prefix '/usr' 23:40:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:40:08 INFO - Installing distribute.............................................................................................................................................................................................done. 23:40:10 INFO - Installing pip.................done. 23:40:10 INFO - Return code: 0 23:40:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:40:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:40:10 INFO - Using env: {'DISPLAY': ':0', 23:40:10 INFO - 'HOME': '/home/cltbld', 23:40:10 INFO - 'LANG': 'en_US.UTF-8', 23:40:10 INFO - 'LANGUAGE': 'en_US:en', 23:40:10 INFO - 'LOGNAME': 'cltbld', 23:40:10 INFO - 'MAIL': '/var/mail/cltbld', 23:40:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:10 INFO - 'MOZ_NO_REMOTE': '1', 23:40:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:10 INFO - 'NO_EM_RESTART': '1', 23:40:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:10 INFO - 'PWD': '/builds/slave/test', 23:40:10 INFO - 'SHELL': '/bin/bash', 23:40:10 INFO - 'SHLVL': '1', 23:40:10 INFO - 'TERM': 'linux', 23:40:10 INFO - 'TMOUT': '86400', 23:40:10 INFO - 'USER': 'cltbld', 23:40:10 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:10 INFO - '_': '/tools/buildbot/bin/python'} 23:40:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:10 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 23:40:10 INFO - Cleaning up... 23:40:10 INFO - Return code: 0 23:40:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:40:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:40:10 INFO - Using env: {'DISPLAY': ':0', 23:40:10 INFO - 'HOME': '/home/cltbld', 23:40:10 INFO - 'LANG': 'en_US.UTF-8', 23:40:10 INFO - 'LANGUAGE': 'en_US:en', 23:40:10 INFO - 'LOGNAME': 'cltbld', 23:40:10 INFO - 'MAIL': '/var/mail/cltbld', 23:40:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:10 INFO - 'MOZ_NO_REMOTE': '1', 23:40:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:10 INFO - 'NO_EM_RESTART': '1', 23:40:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:10 INFO - 'PWD': '/builds/slave/test', 23:40:10 INFO - 'SHELL': '/bin/bash', 23:40:10 INFO - 'SHLVL': '1', 23:40:10 INFO - 'TERM': 'linux', 23:40:10 INFO - 'TMOUT': '86400', 23:40:10 INFO - 'USER': 'cltbld', 23:40:10 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:10 INFO - '_': '/tools/buildbot/bin/python'} 23:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:11 INFO - Downloading/unpacking psutil>=3.1.1 23:40:11 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 23:40:11 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 23:40:11 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 23:40:11 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 23:40:11 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 23:40:11 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 23:40:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:40:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:40:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:40:13 INFO - Installing collected packages: psutil 23:40:13 INFO - Running setup.py install for psutil 23:40:13 INFO - building 'psutil._psutil_linux' extension 23:40:13 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 23:40:13 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 23:40:13 INFO - building 'psutil._psutil_posix' extension 23:40:13 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 23:40:13 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 23:40:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:40:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:40:13 INFO - Successfully installed psutil 23:40:13 INFO - Cleaning up... 23:40:13 INFO - Return code: 0 23:40:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:40:13 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')]} 23:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:13 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')]} 23:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:13 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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:13 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 23:40:13 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 23:40:13 INFO - Using env: {'DISPLAY': ':0', 23:40:13 INFO - 'HOME': '/home/cltbld', 23:40:13 INFO - 'LANG': 'en_US.UTF-8', 23:40:13 INFO - 'LANGUAGE': 'en_US:en', 23:40:13 INFO - 'LOGNAME': 'cltbld', 23:40:13 INFO - 'MAIL': '/var/mail/cltbld', 23:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:13 INFO - 'MOZ_NO_REMOTE': '1', 23:40:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:13 INFO - 'NO_EM_RESTART': '1', 23:40:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:13 INFO - 'PWD': '/builds/slave/test', 23:40:13 INFO - 'SHELL': '/bin/bash', 23:40:13 INFO - 'SHLVL': '1', 23:40:13 INFO - 'TERM': 'linux', 23:40:13 INFO - 'TMOUT': '86400', 23:40:13 INFO - 'USER': 'cltbld', 23:40:13 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:13 INFO - '_': '/tools/buildbot/bin/python'} 23:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:40:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:40:15 INFO - Installing collected packages: mozsystemmonitor 23:40:15 INFO - Running setup.py install for mozsystemmonitor 23:40:15 INFO - Successfully installed mozsystemmonitor 23:40:15 INFO - Cleaning up... 23:40:15 INFO - Return code: 0 23:40:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:40:15 INFO - Using env: {'DISPLAY': ':0', 23:40:15 INFO - 'HOME': '/home/cltbld', 23:40:15 INFO - 'LANG': 'en_US.UTF-8', 23:40:15 INFO - 'LANGUAGE': 'en_US:en', 23:40:15 INFO - 'LOGNAME': 'cltbld', 23:40:15 INFO - 'MAIL': '/var/mail/cltbld', 23:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:15 INFO - 'MOZ_NO_REMOTE': '1', 23:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:15 INFO - 'NO_EM_RESTART': '1', 23:40:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:15 INFO - 'PWD': '/builds/slave/test', 23:40:15 INFO - 'SHELL': '/bin/bash', 23:40:15 INFO - 'SHLVL': '1', 23:40:15 INFO - 'TERM': 'linux', 23:40:15 INFO - 'TMOUT': '86400', 23:40:15 INFO - 'USER': 'cltbld', 23:40:15 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:15 INFO - '_': '/tools/buildbot/bin/python'} 23:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:16 INFO - Downloading/unpacking jsonschema==2.5.1 23:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:40:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:18 INFO - Downloading functools32-3.2.3-2.tar.gz 23:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:40:18 INFO - warning: no files found matching '*.txt' 23:40:18 INFO - no previously-included directories found matching 'build' 23:40:18 INFO - no previously-included directories found matching 'dist' 23:40:18 INFO - no previously-included directories found matching '.git*' 23:40:18 INFO - Installing collected packages: jsonschema, functools32 23:40:18 INFO - Running setup.py install for functools32 23:40:18 INFO - warning: no files found matching '*.txt' 23:40:18 INFO - no previously-included directories found matching 'build' 23:40:18 INFO - no previously-included directories found matching 'dist' 23:40:18 INFO - no previously-included directories found matching '.git*' 23:40:18 INFO - Successfully installed jsonschema functools32 23:40:18 INFO - Cleaning up... 23:40:18 INFO - Return code: 0 23:40:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:40:18 INFO - Using env: {'DISPLAY': ':0', 23:40:18 INFO - 'HOME': '/home/cltbld', 23:40:18 INFO - 'LANG': 'en_US.UTF-8', 23:40:18 INFO - 'LANGUAGE': 'en_US:en', 23:40:18 INFO - 'LOGNAME': 'cltbld', 23:40:18 INFO - 'MAIL': '/var/mail/cltbld', 23:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:18 INFO - 'MOZ_NO_REMOTE': '1', 23:40:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:18 INFO - 'NO_EM_RESTART': '1', 23:40:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:18 INFO - 'PWD': '/builds/slave/test', 23:40:18 INFO - 'SHELL': '/bin/bash', 23:40:18 INFO - 'SHLVL': '1', 23:40:18 INFO - 'TERM': 'linux', 23:40:18 INFO - 'TMOUT': '86400', 23:40:18 INFO - 'USER': 'cltbld', 23:40:18 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:18 INFO - '_': '/tools/buildbot/bin/python'} 23:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:40:18 INFO - Cleaning up... 23:40:18 INFO - Return code: 0 23:40:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:40:18 INFO - Using env: {'DISPLAY': ':0', 23:40:18 INFO - 'HOME': '/home/cltbld', 23:40:18 INFO - 'LANG': 'en_US.UTF-8', 23:40:18 INFO - 'LANGUAGE': 'en_US:en', 23:40:18 INFO - 'LOGNAME': 'cltbld', 23:40:18 INFO - 'MAIL': '/var/mail/cltbld', 23:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:18 INFO - 'MOZ_NO_REMOTE': '1', 23:40:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:18 INFO - 'NO_EM_RESTART': '1', 23:40:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:18 INFO - 'PWD': '/builds/slave/test', 23:40:18 INFO - 'SHELL': '/bin/bash', 23:40:18 INFO - 'SHLVL': '1', 23:40:18 INFO - 'TERM': 'linux', 23:40:18 INFO - 'TMOUT': '86400', 23:40:18 INFO - 'USER': 'cltbld', 23:40:18 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:18 INFO - '_': '/tools/buildbot/bin/python'} 23:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:19 INFO - Downloading/unpacking blobuploader==1.2.4 23:40:19 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 23:40:19 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 23:40:19 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 23:40:19 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 23:40:19 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 23:40:19 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 23:40:20 INFO - Downloading blobuploader-1.2.4.tar.gz 23:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:40:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:40:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:40:21 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 23:40:21 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 23:40:21 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 23:40:21 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 23:40:21 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 23:40:21 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 23:40:21 INFO - Downloading docopt-0.6.1.tar.gz 23:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:40:21 INFO - Installing collected packages: blobuploader, requests, docopt 23:40:21 INFO - Running setup.py install for blobuploader 23:40:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:40:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:40:21 INFO - Running setup.py install for requests 23:40:22 INFO - Running setup.py install for docopt 23:40:22 INFO - Successfully installed blobuploader requests docopt 23:40:22 INFO - Cleaning up... 23:40:22 INFO - Return code: 0 23:40:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:40:22 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')]} 23:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:22 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')]} 23:40:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:22 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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:22 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 23:40:22 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 23:40:22 INFO - Using env: {'DISPLAY': ':0', 23:40:22 INFO - 'HOME': '/home/cltbld', 23:40:22 INFO - 'LANG': 'en_US.UTF-8', 23:40:22 INFO - 'LANGUAGE': 'en_US:en', 23:40:22 INFO - 'LOGNAME': 'cltbld', 23:40:22 INFO - 'MAIL': '/var/mail/cltbld', 23:40:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:22 INFO - 'MOZ_NO_REMOTE': '1', 23:40:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:22 INFO - 'NO_EM_RESTART': '1', 23:40:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:22 INFO - 'PWD': '/builds/slave/test', 23:40:22 INFO - 'SHELL': '/bin/bash', 23:40:22 INFO - 'SHLVL': '1', 23:40:22 INFO - 'TERM': 'linux', 23:40:22 INFO - 'TMOUT': '86400', 23:40:22 INFO - 'USER': 'cltbld', 23:40:22 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:22 INFO - '_': '/tools/buildbot/bin/python'} 23:40:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:40:22 INFO - Running setup.py (path:/tmp/pip-uUILeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:40:22 INFO - Running setup.py (path:/tmp/pip-n8zJRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:40:22 INFO - Running setup.py (path:/tmp/pip-mEHobx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:40:22 INFO - Running setup.py (path:/tmp/pip-KGnboL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:40:22 INFO - Running setup.py (path:/tmp/pip-gvoUIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:40:22 INFO - Running setup.py (path:/tmp/pip-Zbfaiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:40:22 INFO - Running setup.py (path:/tmp/pip-je6fsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:40:23 INFO - Running setup.py (path:/tmp/pip-jQ83W8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:40:23 INFO - Running setup.py (path:/tmp/pip-mhKzxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:40:23 INFO - Running setup.py (path:/tmp/pip-z9aasv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:40:23 INFO - Running setup.py (path:/tmp/pip-mcLPxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:40:23 INFO - Running setup.py (path:/tmp/pip-zN6Zjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:40:23 INFO - Running setup.py (path:/tmp/pip-t0ja_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:40:23 INFO - Running setup.py (path:/tmp/pip-cKogYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:40:23 INFO - Running setup.py (path:/tmp/pip-Vl2OlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:40:23 INFO - Running setup.py (path:/tmp/pip-vrTRJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:40:23 INFO - Running setup.py (path:/tmp/pip-GGDCob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:40:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:40:23 INFO - Running setup.py install for manifestparser 23:40:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:40:23 INFO - Running setup.py install for mozcrash 23:40:23 INFO - Running setup.py install for mozdebug 23:40:24 INFO - Running setup.py install for mozdevice 23:40:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Running setup.py install for mozfile 23:40:24 INFO - Running setup.py install for mozhttpd 23:40:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Running setup.py install for mozinfo 23:40:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Running setup.py install for mozInstall 23:40:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Running setup.py install for mozleak 23:40:24 INFO - Running setup.py install for mozlog 23:40:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Running setup.py install for moznetwork 23:40:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:40:24 INFO - Running setup.py install for mozprocess 23:40:25 INFO - Running setup.py install for mozprofile 23:40:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:40:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:40:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:40:25 INFO - Running setup.py install for mozrunner 23:40:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:40:25 INFO - Running setup.py install for mozscreenshot 23:40:25 INFO - Running setup.py install for moztest 23:40:25 INFO - Running setup.py install for mozversion 23:40:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:40:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:40:25 INFO - Cleaning up... 23:40:25 INFO - Return code: 0 23:40:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:40: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')]} 23:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40: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')]} 23:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:25 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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:25 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 23:40:25 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 23:40:25 INFO - Using env: {'DISPLAY': ':0', 23:40:25 INFO - 'HOME': '/home/cltbld', 23:40:25 INFO - 'LANG': 'en_US.UTF-8', 23:40:25 INFO - 'LANGUAGE': 'en_US:en', 23:40:25 INFO - 'LOGNAME': 'cltbld', 23:40:25 INFO - 'MAIL': '/var/mail/cltbld', 23:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:25 INFO - 'MOZ_NO_REMOTE': '1', 23:40:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:25 INFO - 'NO_EM_RESTART': '1', 23:40:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:25 INFO - 'PWD': '/builds/slave/test', 23:40:25 INFO - 'SHELL': '/bin/bash', 23:40:25 INFO - 'SHLVL': '1', 23:40:25 INFO - 'TERM': 'linux', 23:40:25 INFO - 'TMOUT': '86400', 23:40:25 INFO - 'USER': 'cltbld', 23:40:25 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:25 INFO - '_': '/tools/buildbot/bin/python'} 23:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:40:25 INFO - Running setup.py (path:/tmp/pip-E2tqxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:40:25 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)) 23:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:40:25 INFO - Running setup.py (path:/tmp/pip-rCWzrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:40:25 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)) 23:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:40:25 INFO - Running setup.py (path:/tmp/pip-YM1wjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:40:25 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)) 23:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:40:26 INFO - Running setup.py (path:/tmp/pip-RoU8LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:40:26 INFO - Running setup.py (path:/tmp/pip-_alAmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:40:26 INFO - Running setup.py (path:/tmp/pip-Ly8Pm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:40:26 INFO - Running setup.py (path:/tmp/pip-gfHJtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:40:26 INFO - Running setup.py (path:/tmp/pip-iA6q36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:40:26 INFO - Running setup.py (path:/tmp/pip-HwwzjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:40:26 INFO - Running setup.py (path:/tmp/pip-C6VOaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:40:26 INFO - Running setup.py (path:/tmp/pip-9vidvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:40:26 INFO - Running setup.py (path:/tmp/pip-3R__ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:40:26 INFO - Running setup.py (path:/tmp/pip-CxicNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:40:26 INFO - Running setup.py (path:/tmp/pip-hayXxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:40:26 INFO - Running setup.py (path:/tmp/pip-MaspD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:40:26 INFO - Running setup.py (path:/tmp/pip-9lND9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:40:26 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)) 23:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:40:26 INFO - Running setup.py (path:/tmp/pip-_mHdDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:40:27 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)) 23:40:27 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)) 23:40:27 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)) 23:40:27 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)) 23:40:27 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)) 23:40:27 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)) 23:40:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:40:27 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 23:40:27 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 23:40:27 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 23:40:27 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 23:40:27 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 23:40:27 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 23:40:28 INFO - Downloading blessings-1.6.tar.gz 23:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:40:28 INFO - Installing collected packages: blessings 23:40:28 INFO - Running setup.py install for blessings 23:40:29 INFO - Successfully installed blessings 23:40:29 INFO - Cleaning up... 23:40:29 INFO - Return code: 0 23:40:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:40:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:40:29 INFO - Reading from file tmpfile_stdout 23:40:29 INFO - Current package versions: 23:40:29 INFO - argparse == 1.2.1 23:40:29 INFO - blessings == 1.6 23:40:29 INFO - blobuploader == 1.2.4 23:40:29 INFO - docopt == 0.6.1 23:40:29 INFO - functools32 == 3.2.3-2 23:40:29 INFO - jsonschema == 2.5.1 23:40:29 INFO - manifestparser == 1.1 23:40:29 INFO - mozInstall == 1.12 23:40:29 INFO - mozcrash == 0.17 23:40:29 INFO - mozdebug == 0.1 23:40:29 INFO - mozdevice == 0.48 23:40:29 INFO - mozfile == 1.2 23:40:29 INFO - mozhttpd == 0.7 23:40:29 INFO - mozinfo == 0.9 23:40:29 INFO - mozleak == 0.1 23:40:29 INFO - mozlog == 3.3 23:40:29 INFO - moznetwork == 0.27 23:40:29 INFO - mozprocess == 0.23 23:40:29 INFO - mozprofile == 0.28 23:40:29 INFO - mozrunner == 6.12 23:40:29 INFO - mozscreenshot == 0.1 23:40:29 INFO - mozsystemmonitor == 0.3 23:40:29 INFO - moztest == 0.7 23:40:29 INFO - mozversion == 1.4 23:40:29 INFO - psutil == 3.1.1 23:40:29 INFO - requests == 1.2.3 23:40:29 INFO - wsgiref == 0.1.2 23:40:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:40: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')]} 23:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40: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')]} 23:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:29 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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:29 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 23:40:29 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 23:40:29 INFO - Using env: {'DISPLAY': ':0', 23:40:29 INFO - 'HOME': '/home/cltbld', 23:40:29 INFO - 'LANG': 'en_US.UTF-8', 23:40:29 INFO - 'LANGUAGE': 'en_US:en', 23:40:29 INFO - 'LOGNAME': 'cltbld', 23:40:29 INFO - 'MAIL': '/var/mail/cltbld', 23:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:29 INFO - 'MOZ_NO_REMOTE': '1', 23:40:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:29 INFO - 'NO_EM_RESTART': '1', 23:40:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:29 INFO - 'PWD': '/builds/slave/test', 23:40:29 INFO - 'SHELL': '/bin/bash', 23:40:29 INFO - 'SHLVL': '1', 23:40:29 INFO - 'TERM': 'linux', 23:40:29 INFO - 'TMOUT': '86400', 23:40:29 INFO - 'USER': 'cltbld', 23:40:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:29 INFO - '_': '/tools/buildbot/bin/python'} 23:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 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)) 23:40:29 INFO - Cleaning up... 23:40:29 INFO - Return code: 0 23:40:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:40: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')]} 23:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40: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')]} 23:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:29 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 0x18e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', '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 23:40:29 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 23:40:29 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 23:40:29 INFO - Using env: {'DISPLAY': ':0', 23:40:29 INFO - 'HOME': '/home/cltbld', 23:40:29 INFO - 'LANG': 'en_US.UTF-8', 23:40:29 INFO - 'LANGUAGE': 'en_US:en', 23:40:29 INFO - 'LOGNAME': 'cltbld', 23:40:29 INFO - 'MAIL': '/var/mail/cltbld', 23:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:29 INFO - 'MOZ_NO_REMOTE': '1', 23:40:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:29 INFO - 'NO_EM_RESTART': '1', 23:40:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:29 INFO - 'PWD': '/builds/slave/test', 23:40:29 INFO - 'SHELL': '/bin/bash', 23:40:29 INFO - 'SHLVL': '1', 23:40:29 INFO - 'TERM': 'linux', 23:40:29 INFO - 'TMOUT': '86400', 23:40:29 INFO - 'USER': 'cltbld', 23:40:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:29 INFO - '_': '/tools/buildbot/bin/python'} 23:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:40:29 INFO - Cleaning up... 23:40:29 INFO - Return code: 0 23:40:29 INFO - Running post-action listener: _resource_record_post_action 23:40:29 INFO - Running post-action listener: _start_resource_monitoring 23:40:29 INFO - Starting resource monitoring. 23:40:29 INFO - [mozharness: 2016-07-27 06:40:29.849401Z] Finished create-virtualenv step (success) 23:40:29 INFO - [mozharness: 2016-07-27 06:40:29.849841Z] Running install step. 23:40:29 INFO - Running pre-action listener: _resource_record_pre_action 23:40:29 INFO - Running main action method: install 23:40:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:40:30 INFO - Reading from file tmpfile_stdout 23:40:30 INFO - Detecting whether we're running mozinstall >=1.0... 23:40:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:40:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:40:30 INFO - Reading from file tmpfile_stdout 23:40:30 INFO - Output received: 23:40:30 INFO - Usage: mozinstall [options] installer 23:40:30 INFO - Options: 23:40:30 INFO - -h, --help show this help message and exit 23:40:30 INFO - -d DEST, --destination=DEST 23:40:30 INFO - Directory to install application into. [default: 23:40:30 INFO - "/builds/slave/test"] 23:40:30 INFO - --app=APP Application being installed. [default: firefox] 23:40:30 INFO - mkdir: /builds/slave/test/build/application 23:40:30 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'] 23:40:30 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 23:40:47 INFO - Reading from file tmpfile_stdout 23:40:47 INFO - Output received: 23:40:47 INFO - /builds/slave/test/build/application/firefox/firefox 23:40:47 INFO - Running post-action listener: _resource_record_post_action 23:40:47 INFO - [mozharness: 2016-07-27 06:40:47.409095Z] Finished install step (success) 23:40:47 INFO - [mozharness: 2016-07-27 06:40:47.409371Z] Running run-tests step. 23:40:47 INFO - Running pre-action listener: _resource_record_pre_action 23:40:47 INFO - Running main action method: run_tests 23:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:40:47 INFO - Python 2.7.3 23:40:47 INFO - Return code: 0 23:40:47 INFO - grabbing minidump binary from tooltool 23:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:47 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 0x1b9f3b0>, '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 0x1ba02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:40:47 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 23:40:47 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 23:40:47 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 23:40:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:40:47 INFO - Return code: 0 23:40:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:40:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:40:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:40:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:40:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 23:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --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 23:40:47 INFO - Using env: {'DISPLAY': ':0', 23:40:47 INFO - 'HOME': '/home/cltbld', 23:40:47 INFO - 'LANG': 'en_US.UTF-8', 23:40:47 INFO - 'LANGUAGE': 'en_US:en', 23:40:47 INFO - 'LOGNAME': 'cltbld', 23:40:47 INFO - 'MAIL': '/var/mail/cltbld', 23:40:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:40:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:47 INFO - 'MOZ_NO_REMOTE': '1', 23:40:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:40:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:47 INFO - 'NO_EM_RESTART': '1', 23:40:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:47 INFO - 'PWD': '/builds/slave/test', 23:40:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:40:47 INFO - 'SHELL': '/bin/bash', 23:40:47 INFO - 'SHLVL': '1', 23:40:47 INFO - 'TERM': 'linux', 23:40:47 INFO - 'TMOUT': '86400', 23:40:47 INFO - 'USER': 'cltbld', 23:40:47 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266', 23:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:47 INFO - '_': '/tools/buildbot/bin/python'} 23:40:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 23:40:47 INFO - mozversion application_buildid: 20160726200738 23:40:47 INFO - mozversion application_changeset: 722707a63810c1c284c4ba5f539002686f2861a6 23:40:47 INFO - mozversion application_display_name: Nightly 23:40:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:40:47 INFO - mozversion application_name: Firefox 23:40:47 INFO - mozversion application_remotingname: firefox 23:40:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:40:47 INFO - mozversion application_vendor: Mozilla 23:40:47 INFO - mozversion application_version: 50.0a1 23:40:47 INFO - mozversion platform_buildid: 20160726200738 23:40:47 INFO - mozversion platform_changeset: 722707a63810c1c284c4ba5f539002686f2861a6 23:40:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:40:47 INFO - mozversion platform_version: 50.0a1 23:40:47 INFO - using testdate: 1469601647 23:40:47 INFO - actual date: 1469601647 23:40:47 INFO - starting webserver on 'localhost:36827' 23:40:47 INFO - SUITE-START | Running 2 tests 23:40:47 INFO - TEST-START | dromaeo_css 23:40:47 INFO - operating with platform_type : linux_ 23:40:47 INFO - Initialising browser for dromaeo_css test... 23:40:47 INFO - TEST-INFO | started process 9071 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphyKisU/profile http://localhost:36827/getInfo.html) 23:40:51 INFO - PROCESS | 9071 | JavaScript error: , line 0: uncaught exception: undefined 23:40:51 INFO - PROCESS | 9071 | __metrics Screen width/height:1600/1200 23:40:51 INFO - PROCESS | 9071 | colorDepth:24 23:40:51 INFO - PROCESS | 9071 | Browser inner width/height: 1024/697 23:40:51 INFO - PROCESS | 9071 | __metrics 23:40:52 INFO - PROCESS | 9071 | 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" 23:40:52 INFO - PROCESS | 9071 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:40:53 INFO - TEST-INFO | 9071: exit 0 23:40:53 INFO - Browser initialized. 23:40:53 INFO - Running cycle 1/1 for dromaeo_css test... 23:40:53 INFO - TEST-INFO | started process 9152 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphyKisU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:41:05 INFO - PROCESS | 9152 | RSS: Main: 157790208 23:41:05 INFO - PROCESS | 9152 | 23:41:56 INFO - PROCESS | 9152 | 1469601716898 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] 23:44:04 INFO - PROCESS | 9152 | Cycle 1(1): loaded http://localhost:36827/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36827/tests/dromaeo/cssquery-ext.html) 23:44:06 INFO - PROCESS | 9152 | RSS: Main: 238780416 23:44:06 INFO - PROCESS | 9152 | 23:47:08 INFO - PROCESS | 9152 | Cycle 1(1): loaded http://localhost:36827/tests/dromaeo/cssquery-ext.html (next: http://localhost:36827/tests/dromaeo/cssquery-jquery.html) 23:47:08 INFO - PROCESS | 9152 | RSS: Main: 237084672 23:47:08 INFO - PROCESS | 9152 | 23:50:10 INFO - PROCESS | 9152 | Cycle 1(1): loaded http://localhost:36827/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36827/tests/dromaeo/cssquery-mootools.html) 23:50:11 INFO - PROCESS | 9152 | RSS: Main: 226361344 23:50:11 INFO - PROCESS | 9152 | 23:53:13 INFO - PROCESS | 9152 | Cycle 1(1): loaded http://localhost:36827/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36827/tests/dromaeo/cssquery-prototype.html) 23:53:14 INFO - PROCESS | 9152 | RSS: Main: 184131584 23:53:14 INFO - PROCESS | 9152 | 23:56:16 INFO - PROCESS | 9152 | Cycle 1(1): loaded http://localhost:36827/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36827/tests/dromaeo/cssquery-yui.html) 23:56:17 INFO - PROCESS | 9152 | RSS: Main: 185540608 23:56:17 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | Cycle 1(1): loaded http://localhost:36827/tests/dromaeo/cssquery-yui.html (next: http://localhost:36827/tests/dromaeo/cssquery-dojo.html) 23:59:20 INFO - PROCESS | 9152 | RSS: Main: 277041152 23:59:20 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | __start_tp_report 23:59:20 INFO - PROCESS | 9152 | _x_x_mozilla_page_load 23:59:20 INFO - PROCESS | 9152 | _x_x_mozilla_page_load_details 23:59:20 INFO - PROCESS | 9152 | |i|pagename|runs| 23:59:20 INFO - PROCESS | 9152 | |0;dojo.html;3046.969530304697;3055.7249847513717;3085.25953771095;3088.2896933705233;3116.641586217584;5193.091209038422;5252.1859111837675;5304.443033481487;5306.044911915854;5335.386430560482;5275.129603615399;5306.204069389593;5327.866803329919;5373.731313434322;5383.650062745919;4781.880452988686;4815.181419158734;4849.175640141167;4872.6345524085655;4943.184374578191;4778.283257511366;4816.8554943351755;4893.804247830082;4900.093482705711;4942.5304596063315;4704.223803072506;4736.976315118437;4742.241241401393;4804.087223427555;4810.735409552473;4805;4873.512648735134;4886.975565122188;4949.158643030657;4952.628552858514;4865.9756701216265;4867.464578896319;4881.023795240966;4881.170201065792;4908.828191013297;5323.1749078892835;5348.893022139536;5353.304070470853;5378.757955891994;5391.32608423947;5450.672959622396;5487.917681234785;5493.093639549469;5499.1476321170285;5560.471755183251;5523.447655234485;5548.389677135512;5548.889022219573;5588.6646801192055;5597.216389705444;5231.921521177109;5236.450172731847;5238.60710446718;5240.554552863011;5242.397124330751;5727.828165155052;5810.21562089121;5866.882662346729;5871.119332100134;5947.434993675594;5655.349634791969;5667.1782591524825;5689.2319536862;5703.344115426696;5706.3152421709665;5740.54075673945;5750.396208398058;5753.568482363839;5753.88492230153;5805.622634528827;5494.725263736797;5588.497035266845;5621.8594535136135;5631.155326701028;5699.287589051369;5650.689212093293;5658.151277308354;5663.207150998864;5698.344690360579;5713.371529131792;4354.542773008852;4358.346248062816;4359.542248063971;4360.934585981213;4401.6698747594055;3857.0550215197454;3881.378979363288;3898.1813819098306;3909.0618251619408;3919.6080391961145;5398.4871437213405;5403.2165336026055;5410.91883621746;5450.236966824647;5459.3994660587305;5627.352854421794;5682.886342273131;5684.54523638108;5684.715764211773;5722.141678748221;5265.605079619044;5280.339957505312;5312.840614781563;5381.865453363697;5399.784008639689;4868.805247790119;4878.609711223094;4883.365162528919;4907.877303067452;4923.704577725348;5777.8555536111935;5786;5789.739461724148;5811.099279611667;5851.824445266649;5538.335399752055;5604.915926261191;5619.971900140473;5689.6586204827845;5729.627574207663;5991.550633702577;6028.33688294296;6031.035034394476;6041.124037014696;6044.153818465506;5622.522085622768;5667.971660141673;5672.262605861211;5672.801451949162;5673.602847800779;5517.972410138084;5774.855628609318;5778.451047150514;5783.306003279465;5785.710714464344;5464.071107911585;5509.531689806251;5511.448855114457;5540.612157148961;5588.916166257428;5525.254090697705;5534.944650553604;5535.667859928417;5537.5569954402745;5538.086215774352;5392.352917649907;5467.261919640957;5472.835814925558;5475.370332411823;5485.0949593318255;5443.972780136074;5476.479734425223;5478;5489.807856724996;5540.889182216414;5337.893242135058;5366.838994830161;5381.542568881689;5381.8923621526565;5389.784408623611 23:59:20 INFO - PROCESS | 9152 | |1;ext.html;560.0674876330909;575.9273707355133;601.1313651773188;636.3096040795737;657.6580178307278;42854.57145428545;44102.55897441025;44183;44221.115577688426;46724.766376168154;46090.7695461523;46440.53559464405;46514.53485465144;46572.76713616436;46586.53413465864;9073.863892041625;9193.632254709837;9203.40177888435;9206.171444569987;9210.907890921071;9047.095290470967;9126.634934602607;9130.26957843371;9130.497822619753;9135.817283654324;9143.497107659075;9161.679341223058;9167.679131230409;9176.449413035205;9183.586738596745;9104.13510716481;9111.27109831212;9126.498042607654;9138.17756401927;9141.95429022854;8854.513001784899;8919.643214271422;8938.68714594986;8941.64233430662;8945.731628051104;43838.34242486365;44218.77890610559;44225.33661995073;44271.557284427065;44290.77854610739;3585.480105384731;3618.981905090484;3632.128289210597;3757.6993840492696;4285.678574106954;33260.66739332624;33287.83356083228;33383;33521;33566.83216583926;4370.322599997005;4404.229259879508;4457.509673935864;4474.463064432256;4512.729236245836;236533;237135;237138;238865.99999999825;239737;57750.71124644434;58265;58273.708631456575;58293.12560311599;58313.4168658308;62286.0171186304;62305;62506.687466562376;62573.37426625766;62740.68629656822;16788.32846686119;16935.576610584587;16948.322067117177;16988.235529401205;16991.06549139809;12397.442115104843;12494.250344979331;12511.499540018298;12514.624561263177;12514.874851251552;15206;16764.91617541929;16905.91547042257;16975.830241697426;17248.741268880978;5310.415854256029;6224.221972253468;6290.087937249074;6428.132202152649;6562.179727534059;992.7220378293945;1040.0966231795796;1059.4649701900491;1065.3767545985531;1107.186218129674;31661.841690791858;32527.86152484699;32554.511682324784;32656.836715816746;33158.66841331604;1076.7146706122883;1087.2932593814085;1118.0105606538273;1136;1162.8430161928031;16669.416570419977;17151.741169178065;17166.57083572896;17175.914120429567;17241;494082;499207;499295;500285;508331;58309.12536311959;58575.41424585785;58583.70708146432;58610;58620.70689646524;1200.2618389690278;1283.6020833541631;1307.3593938969836;1339.9933000334936;1357.307773035739;31462.370752585277;32661.673383265865;33276.334473309915;33485.497717534745;33826.15434614154;51126.488735113664;51825.48174518206;51826.740866295426;51865.22202167046;51894.48105518897;407900;408263;409417;410544;411578;27243.182704518895;28339.57490637601;28375;28833.56749648715;28887;48385.27422088601;49058.75470622767;49169.83716709694;50046.499535004186;50763.23855142102;40560.1887962245;40592.79703601463;41425.37861932134;42558.787206063775;42823.3576496359;41249.79375103225;41927.49761374444;42073.36889946592;42680.78659606682;42774.14451710886;40438.75467592718;40440.3933940997;40596.3910541348;40802.183956320114;41228.38157427701;34406.31187376288;36527.81736091409;36838.26323473462;36904.26191476209;37398.439023415216;26851.597226042017;26993.73006269912;27144.72855271422;27293.181204563894;27699.86150069237 23:59:20 INFO - PROCESS | 9152 | |2;jquery.html;362.38394728960776;366.46998780093105;391.3054328566794;392.9685625149988;398.13405842293025;15681.607959801011;17867.10664466778;18011.54971125724;19651.31220407286;20000.49998750032;18342.541436464096;18998.14508347124;19006.904965475154;19219.71170432441;19445.805541944577;5625;5673.744681489333;5912.7930522431725;5915.112733090049;5915.792947246847;5677.517411020068;5707.172459993299;5737.5696822738255;5740.540756739471;5758.337791154029;5586.748596313176;5595.888082238353;5701.6864072476;5716;5718.342390625069;5730.312362516483;5754.3094828620415;5776.884462310752;5778.508826749731;5790.68151251681;5005.374328208974;5125.897482050375;5177.37871455424;5178.922316165261;5197;19352.70970935437;19456.805431945784;19605.509862253417;19706.60586788256;19708;2133.3386650138505;2320.5358928214255;2321.9319113207944;2617.764401203901;2645.259327388315;9918.702438926844;10337.431441270726;10456.63401780934;10791.298565593212;10863.130949524018;1884.8869067855971;1891.4514790710678;1929.9710504342447;1976.7459186156784;2036.8370530357683;75926;79470;79551.60224198842;79607;80034.59982700108;10371.636992705218;10421.957804219517;10544.578216871392;10578.677665291838;10857.45712714361;14149.646258843406;14164.645883852987;14176.929115354356;14182.219977901108;14197.290135493184;7898.763037108896;7913;7964.283214510722;7972.880406793903;8279.337652987868;7551.697932082655;7559.508631938907;7584.582847943306;7799.142094369615;7820.257075577811;4248.702590818613;4329.220740266782;4351.151525452507;4434.091011342732;4486.3494793254795;1814.564504518906;1886.2360743898469;1913.808619138075;1933.787283398825;1943.9416817495498;888.2272422992038;1012.8025035118228;1014.6854475112739;1048.5858086055964;1053.5785685725618;19828.306009289896;20194.798052019585;20232.494187645425;20264.898675506527;20594;937.7843096087802;947.7914858731068;954.61815273891;975.1029053271008;1007.6926537406015;8987.685431009882;10516.526756295985;11266.605668801552;11273.549058037586;11275.774484510264;494069;495173;506449;509639;514929;58278.41721582814;82979.58510207411;95265;96108.03891961131;98116;971.2763990826738;984.6110786239539;991.3655260633201;1048.7483004079118;1055.2402270365324;17648;18075.36736214226;18772.530686732942;18808.341708040152;18838.34065807745;44327.11345773131;44550.77724611356;44593.33110003417;45717.54282457133;46752.29871551861;58163.4183658158;60151.39848601458;60332.396676034434;61724.382756171864;63155.684221578595;10794.08250298702;11561.88438115608;12071.637850864488;12151.270923744603;12432.813507797571;13671.17972921628;13732.29630808399;13831.723365532835;13935.860641393861;14377.28113594337;11219.943900280772;11424;11481.483333250191;11501.424928753362;11565.710857228636;11327.546898123983;11652.766944661229;11709.473073711873;11859.288442693465;11932.642020739391;11776.646700598996;12053.276803391474;12078.818817717565;12140.453679584616;12212;12011.63965081049;12154.939225303817;12176.57381991626;12683.23900565969;12892.677683057624;7697.846043078995;8099.068607110258;8224.259816616533;8288.50268983863;8323.542205178774 23:59:20 INFO - PROCESS | 9152 | |3;mootools.html;1340.6124660975893;1446.6744982378966;1507.494989178625;1604.1257514654512;1729.3082766893233;8004.079530853954;8337.958310208449;8408.579571021455;8554;8565.785855353619;8392.91607083929;8471.703490377839;8494.277986371166;8496.277816385615;8504.319654427647;2234.4748983988748;2255.357223191391;2257.3679369776487;2257.7516473187934;2258.1644791427193;2228.3092241405134;2284.588774020676;2299.1263319938485;2301.2290882554366;2302.0561569756405;2220.8334374921865;2226.743924448685;2246.33733048751;2255.244493094807;2268.3761965459466;2200.0759680933966;2208.5803697297624;2236.541508990653;2244.1360076370634;2244.955100897981;2268.6823844661762;2293.552757212344;2294.655801629752;2296.632538793802;2296.7358753743365;8324.70863519774;8399.5380254086;8433.578321083922;8450.154984501562;8466.068732439428;4844.709317440965;4991.725455099968;4993.151164302041;5001.9249711254;5029.19532874742;3087.969120308813;3183.984080079608;3226.2257058306072;3256.7068963793135;3263.1189578813824;1105.745678493943;1116.107114308558;1122.3714719756963;1122.9775404491945;1141.1726498288758;11411.600593979254;11416.885831141666;11433.42832858362;11527.827082593767;11585.884141158649;3997.400389941474;4144.212599606126;4212.410262563244;4216.451861258017;4222.59885310895;9090.27277817773;9127.90872091284;9185.632574696938;9187.173154416134;9192.63229470827;4869.172240719086;4895.069936712013;4897.730624815669;4906.018796240694;4909.435414927258;3980.3233450313514;4382.8904277392685;4604.953950460519;4605.631549476034;4647.209974304309;3822.120912190156;3887.8056097195595;4000.7999600019884;4022.316206244887;4027.617376349242;1222.584321330752;1244.0731654916963;1246.9064820138526;1256.6983923858209;1264.968375790594;843.6625349859983;876.7939534209455;925.1812146250483;967.583938906277;991.2218908157062;5856.736446859902;5977.22296101504;6074.301455332605;6096.69516524172;6276.497880169575;947.5404428852002;957.3872721458272;969.4038166527534;969.8399764038995;994.6220436234184;4045.5752146024834;4189.371594260824;4198.181354635878;4248.915021699549;4280.101178752497;9975.900240997496;10369.9481502592;10384.532696028697;10394.428306443067;10394.896051039394;9692.563834627459;10117.190624750003;10120.342177758423;10341.121004714683;10343.172546196287;7443.739469118555;7568.356689681439;7602.315791578674;7613.885791713129;7627.046619172604;5842.298924129132;5921.881562368814;5986.4013598639785;6013.849653758516;6287.842803929939;8934.73195804127;9666.420014799134;9676.548517722398;9685.806283874143;9756.951215243878;9438.48088355147;9531;9619.759506012406;9620.326577139813;9638.518074096408;6833.41915937131;7203.063601731741;7279.308465695751;7426.14599321085;7440.330370266702;7945.324647405036;8099.635516401659;8183.099859015384;8189;8191.631376588187;7282.563046217032;7486.401087913063;7508.511946723445;7535.849283014419;7693.192214817587;7381;7390.422523953578;7409.851802963803;7533.096028476618;7593.1647518774;6563.835904102245;6571.737130514726;6609.339066093352;6615.834604134935;6626.536142470173;4918.557329840189;4936.111499930047;4953.331300274417;5036.722980235975;5124.692518448755;4932.45742968266;5181.53366197044;5192.818251361185;5230.790768369223;5277.815276465305 23:59:20 INFO - PROCESS | 9152 | |4;prototype.html;310.32968787419173;310.5931230088584;316.35305799639735;322.1993346533863;324.17012448132766;5250.186221135726;5266.683998960065;5273.472652734729;5299.28459657946;5301.893962120765;5217.452167522412;5240.423553409122;5245.239440281157;5250.894982100355;5265.473452654733;3874.1476875087506;3993.560708322082;4006.83972641094;4015.337469317559;4016.5983401659746;3611.981940090309;4017.4131218168804;4034.677225821942;4038.111615444612;4046.5953404659444;3988.24223397555;3992.2414741199223;3993.6206060424215;4007.338789099795;4034.1326614777818;3920.4315374270855;4046.1705350403095;4050.7442692765044;4075.9388609170737;4079.5308539518037;3468.236987862666;3536.257385948954;3542.71658267338;3559.4126969050076;3572.4462708280257;5024.517767258668;5053.242013697938;5056.558880719014;5059.316992206079;5151.459096794858;4625.537446255381;4627.3521706961055;4636.698614590041;4655.3947986761505;4655.743934083658;5140.460251673558;5364.865878353034;5376.709589698483;5388.602072077927;5391.299131112969;3835.846566137351;3845.4231865220163;3847.1920896611746;3855.7661548304554;3871.825767840454;5382.381026182;5474.808381706661;5609.663420194802;5613.354464236624;5619.438056194389;3896.3245804303856;3906.648401643809;3907.628775266345;3910.8631197907935;3927.116398810245;3935.704822138351;3969.126792105732;3975.2248311578965;3978.562358140602;4006.5392479864604;4454.7749368923805;4584;4586.724796512153;4589.380433641482;4602.194615942197;5424.674519528841;5452.700101494456;5455.536279416294;5495.697736624524;5497.697626630494;1361.7616917039677;1370.0683535195963;1380.9378577963814;1381.9170849749048;1384.0588399888172;843.6751850537464;882.2060145868642;903.6927444668844;911.2573252798948;911.8996910339857;4650.86047418578;4720.173969555314;4761.381020467386;4770.904581908342;4802.759862006886;4918.754062296943;4968.100041467431;5001.274815151855;5024.271480635361;5044.461439261061;4695.882602934954;4701.412323459567;4715.24556071027;4773.665843390999;4946.9010619787405;2931.51629981055;2948.277671970381;2969.9804133189205;2970.5247160454223;2977.865996030184;5767.961766882002;5775;5783.797567085132;5794.8989691958395;5833.4749872511675;5658.943410565842;5680.97159514208;5694.145878118233;5751.827445176651;5771.249737534177;5757.733298674285;5760.30071129018;5786.276715410574;5809.2738407699035;5835.416458354301;5446.591505637014;5600.551955843604;5603.2909962461845;5634.718264086698;5688.886222275614;5529.087700529529;5535.142052981794;5535.833924982257;5538.141588053697;5539.556835453234;5533.806316778893;5677.602567820213;5690.800821971211;5812.157237200667;5841.18223448709;3909.354956432157;3945.3095708251517;3970.404439334123;3974.960250397459;3989.7805620691142;5584.441555844383;5633.4084921082695;5634.943650563442;5639.182318563868;5641.520470759867;5303.5757139429525;5340.679559226459;5396.4333744957785;5437.673739575638;5475.275288284178;5380.381256155436;5405.702686352289;5426.077566813572;5441.183822426668;5446.291982042309;5386.407495175449;5405.702686352289;5417.485338892799;5425.348958125051;5436.6194366393975;5210.791568337224;5458.4814442627885;5498.670079795065;5510.752016159202;5515.703809604659;5378.2251856887915;5393.784248630011;5394.464601637224;5409.621326507264;5453.209284653623 23:59:20 INFO - PROCESS | 9152 | |5;yui.html;692.4425837201053;731.3308322884561;747.4879707400431;779.2752739951845;826.3843436639704;14454.78317825232;15116.546503604883;15163.696726065486;15201.315940782664;15226.619334516643;14864.479743208989;15088.47190348338;15101.848981510182;15109.093454392743;15115.319810608522;651.4527796650813;765.4106338119644;785.0892964161574;795.1134485049187;800.8958835351397;772.9845403091934;782.0732432067989;787.0122995640463;787.0122995640492;787.2403130978604;839.5046922315832;839.9034111077212;840.306746933782;841.398400143897;841.5077179849795;675.0717763075771;801.1787917384669;827.8923739913832;832.8625776746829;832.9916700833005;613.772904025509;613.9017757158833;613.9017757158833;614.112607282477;614.9262088549402;13575;13586;13612.183269003892;13622.659433514142;13657.658558536017;13243.735125297537;13821.654458638513;14109.71780564383;14144.575662730134;14414.92792536041;12427;12476.251424914533;12499.937500312531;12570.245785252913;13022.47910083605;4340.65274778017;4351.173277077345;4378.934315985262;4532.750698711569;4581.266997280419;69391;69583.30416695819;70297;72136.27863721349;72269.63865180693;10945.890541094646;10952;11044.779104417867;11208.719782005515;11260.774784504429;18037.909810450863;18097.276108955495;18149.455516334267;18188.727169092475;18212.08939553017;7955.323797477279;8113.7565873023905;8149.266566009088;8300.2114799094;8687.131286871263;4657.930131048037;4730.526947305242;4810.6151507879285;4841.467438581753;4905.950940490621;3346.748993825473;3466.3587236361354;3468.739844511672;3468.8092154931214;3472.1493234157792;569.7436153730838;575.2492996639426;575.3010092737344;580.1761498671896;581.7905554000556;552.353746117044;594.551113908996;595.0716881664617;602.8523011862122;644.9516286278548;8659.956700216584;8964.514630283657;9139.405938613969;9883.752906177404;9998.500074996222;658.851758354376;662.867426514699;711.1412123264448;732.6886073418882;756.3495393961189;4669.101912032556;4744.4543877454535;4809.955604993646;4883.291922671264;4981.153203955337;64064.67967660225;68166.31833681698;105542;114529;116119.41940290414;89075.55462222647;95393;97782;100170;100586.4970675142;80689;82061;88167.55916220378;90865.54567227254;92154;9018.729438116867;9295.395799273025;9404.670836520689;10122.949385253321;10276.743081423025;51229.74385128199;52503;53253.73373133109;53650.195247070544;55917.72041139931;71372;75080.99028116443;81443.04126545957;82197.58901205455;82668.3466330682;12995.545155919497;13224.206547607175;13330.943561556553;13407.32963351848;13436.529721459701;14204.786928196292;14305.928470357581;14339.713205736036;14818.25908704539;15145.924270378577;14180.574582762534;14201.715965680834;14214.644633884236;14372.712545748816;15102.773458397913;14115.364808583434;14319.427222910967;14337.713245734818;14611.488597899022;15361.923190383977;14056.578302650936;14329.713405732036;14373.496927607484;14389.928050359682;14533;10536.262461627612;10579.312344697877;10716.624918127829;10950.890491095306;11280.830787538029;11903.76192476163;11961.940190298992;11988.64034078979;12213.267203967791;12372 23:59:20 INFO - PROCESS | 9152 | __end_tp_report 23:59:20 INFO - PROCESS | 9152 | __start_cc_report 23:59:20 INFO - PROCESS | 9152 | _x_x_mozilla_cycle_collect,1056 23:59:20 INFO - PROCESS | 9152 | __end_cc_report 23:59:20 INFO - PROCESS | 9152 | __startTimestamp1469602760629__endTimestamp 23:59:20 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | ------- Summary: start ------- 23:59:20 INFO - PROCESS | 9152 | Number of tests: 6 23:59:20 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | [#0] dojo.html Cycles:165 Average:5272.39 Median:5459.40 stddev:593.49 (10.9%) stddev-sans-first:569.06 23:59:20 INFO - PROCESS | 9152 | Values: 3047.0 3055.7 3085.3 3088.3 3116.6 5193.1 5252.2 5304.4 5306.0 5335.4 5275.1 5306.2 5327.9 5373.7 5383.7 4781.9 4815.2 4849.2 4872.6 4943.2 4778.3 4816.9 4893.8 4900.1 4942.5 4704.2 4737.0 4742.2 4804.1 4810.7 4805.0 4873.5 4887.0 4949.2 4952.6 4866.0 4867.5 4881.0 4881.2 4908.8 5323.2 5348.9 5353.3 5378.8 5391.3 5450.7 5487.9 5493.1 5499.1 5560.5 5523.4 5548.4 5548.9 5588.7 5597.2 5231.9 5236.5 5238.6 5240.6 5242.4 5727.8 5810.2 5866.9 5871.1 5947.4 5655.3 5667.2 5689.2 5703.3 5706.3 5740.5 5750.4 5753.6 5753.9 5805.6 5494.7 5588.5 5621.9 5631.2 5699.3 5650.7 5658.2 5663.2 5698.3 5713.4 4354.5 4358.3 4359.5 4360.9 4401.7 3857.1 3881.4 3898.2 3909.1 3919.6 5398.5 5403.2 5410.9 5450.2 5459.4 5627.4 5682.9 5684.5 5684.7 5722.1 5265.6 5280.3 5312.8 5381.9 5399.8 4868.8 4878.6 4883.4 4907.9 4923.7 5777.9 5786.0 5789.7 5811.1 5851.8 5538.3 5604.9 5620.0 5689.7 5729.6 5991.6 6028.3 6031.0 6041.1 6044.2 5622.5 5668.0 5672.3 5672.8 5673.6 5518.0 5774.9 5778.5 5783.3 5785.7 5464.1 5509.5 5511.4 5540.6 5588.9 5525.3 5534.9 5535.7 5537.6 5538.1 5392.4 5467.3 5472.8 5475.4 5485.1 5444.0 5476.5 5478.0 5489.8 5540.9 5337.9 5366.8 5381.5 5381.9 5389.8 23:59:20 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | [#1] ext.html Cycles:180 Average:55705.55 Median:42727.47 stddev:105383.00 (246.6%) stddev-sans-first:105597.29 23:59:20 INFO - PROCESS | 9152 | Values: 560.1 575.9 601.1 636.3 657.7 42854.6 44102.6 44183.0 44221.1 46724.8 46090.8 46440.5 46514.5 46572.8 46586.5 9073.9 9193.6 9203.4 9206.2 9210.9 9047.1 9126.6 9130.3 9130.5 9135.8 9143.5 9161.7 9167.7 9176.4 9183.6 9104.1 9111.3 9126.5 9138.2 9142.0 8854.5 8919.6 8938.7 8941.6 8945.7 43838.3 44218.8 44225.3 44271.6 44290.8 3585.5 3619.0 3632.1 3757.7 4285.7 33260.7 33287.8 33383.0 33521.0 33566.8 4370.3 4404.2 4457.5 4474.5 4512.7 236533.0 237135.0 237138.0 238866.0 239737.0 57750.7 58265.0 58273.7 58293.1 58313.4 62286.0 62305.0 62506.7 62573.4 62740.7 16788.3 16935.6 16948.3 16988.2 16991.1 12397.4 12494.3 12511.5 12514.6 12514.9 15206.0 16764.9 16905.9 16975.8 17248.7 5310.4 6224.2 6290.1 6428.1 6562.2 992.7 1040.1 1059.5 1065.4 1107.2 31661.8 32527.9 32554.5 32656.8 33158.7 1076.7 1087.3 1118.0 1136.0 1162.8 16669.4 17151.7 17166.6 17175.9 17241.0 494082.0 499207.0 499295.0 500285.0 508331.0 58309.1 58575.4 58583.7 58610.0 58620.7 1200.3 1283.6 1307.4 1340.0 1357.3 31462.4 32661.7 33276.3 33485.5 33826.2 51126.5 51825.5 51826.7 51865.2 51894.5 407900.0 408263.0 409417.0 410544.0 411578.0 27243.2 28339.6 28375.0 28833.6 28887.0 48385.3 49058.8 49169.8 50046.5 50763.2 40560.2 40592.8 41425.4 42558.8 42823.4 41249.8 41927.5 42073.4 42680.8 42774.1 40438.8 40440.4 40596.4 40802.2 41228.4 34406.3 36527.8 36838.3 36904.3 37398.4 26851.6 26993.7 27144.7 27293.2 27699.9 23:59:20 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | [#2] jquery.html Cycles:180 Average:29319.45 Median:2226.94 stddev:82968.75 (3725.7%) stddev-sans-first:83173.01 23:59:20 INFO - PROCESS | 9152 | Values: 362.4 366.5 391.3 393.0 398.1 15681.6 17867.1 18011.5 19651.3 20000.5 18342.5 18998.1 19006.9 19219.7 19445.8 5625.0 5673.7 5912.8 5915.1 5915.8 5677.5 5707.2 5737.6 5740.5 5758.3 5586.7 5595.9 5701.7 5716.0 5718.3 5730.3 5754.3 5776.9 5778.5 5790.7 5005.4 5125.9 5177.4 5178.9 5197.0 19352.7 19456.8 19605.5 19706.6 19708.0 2133.3 2320.5 2321.9 2617.8 2645.3 9918.7 10337.4 10456.6 10791.3 10863.1 1884.9 1891.5 1930.0 1976.7 2036.8 75926.0 79470.0 79551.6 79607.0 80034.6 10371.6 10422.0 10544.6 10578.7 10857.5 14149.6 14164.6 14176.9 14182.2 14197.3 7898.8 7913.0 7964.3 7972.9 8279.3 7551.7 7559.5 7584.6 7799.1 7820.3 4248.7 4329.2 4351.2 4434.1 4486.3 1814.6 1886.2 1913.8 1933.8 1943.9 888.2 1012.8 1014.7 1048.6 1053.6 19828.3 20194.8 20232.5 20264.9 20594.0 937.8 947.8 954.6 975.1 1007.7 8987.7 10516.5 11266.6 11273.5 11275.8 494069.0 495173.0 506449.0 509639.0 514929.0 58278.4 82979.6 95265.0 96108.0 98116.0 971.3 984.6 991.4 1048.7 1055.2 17648.0 18075.4 18772.5 18808.3 18838.3 44327.1 44550.8 44593.3 45717.5 46752.3 58163.4 60151.4 60332.4 61724.4 63155.7 10794.1 11561.9 12071.6 12151.3 12432.8 13671.2 13732.3 13831.7 13935.9 14377.3 11219.9 11424.0 11481.5 11501.4 11565.7 11327.5 11652.8 11709.5 11859.3 11932.6 11776.6 12053.3 12078.8 12140.5 12212.0 12011.6 12154.9 12176.6 12683.2 12892.7 7697.8 8099.1 8224.3 8288.5 8323.5 23:59:20 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | [#3] mootools.html Cycles:180 Average:5529.00 Median:5015.56 stddev:3066.70 (61.1%) stddev-sans-first:3059.15 23:59:20 INFO - PROCESS | 9152 | Values: 1340.6 1446.7 1507.5 1604.1 1729.3 8004.1 8338.0 8408.6 8554.0 8565.8 8392.9 8471.7 8494.3 8496.3 8504.3 2234.5 2255.4 2257.4 2257.8 2258.2 2228.3 2284.6 2299.1 2301.2 2302.1 2220.8 2226.7 2246.3 2255.2 2268.4 2200.1 2208.6 2236.5 2244.1 2245.0 2268.7 2293.6 2294.7 2296.6 2296.7 8324.7 8399.5 8433.6 8450.2 8466.1 4844.7 4991.7 4993.2 5001.9 5029.2 3088.0 3184.0 3226.2 3256.7 3263.1 1105.7 1116.1 1122.4 1123.0 1141.2 11411.6 11416.9 11433.4 11527.8 11585.9 3997.4 4144.2 4212.4 4216.5 4222.6 9090.3 9127.9 9185.6 9187.2 9192.6 4869.2 4895.1 4897.7 4906.0 4909.4 3980.3 4382.9 4605.0 4605.6 4647.2 3822.1 3887.8 4000.8 4022.3 4027.6 1222.6 1244.1 1246.9 1256.7 1265.0 843.7 876.8 925.2 967.6 991.2 5856.7 5977.2 6074.3 6096.7 6276.5 947.5 957.4 969.4 969.8 994.6 4045.6 4189.4 4198.2 4248.9 4280.1 9975.9 10369.9 10384.5 10394.4 10394.9 9692.6 10117.2 10120.3 10341.1 10343.2 7443.7 7568.4 7602.3 7613.9 7627.0 5842.3 5921.9 5986.4 6013.8 6287.8 8934.7 9666.4 9676.5 9685.8 9757.0 9438.5 9531.0 9619.8 9620.3 9638.5 6833.4 7203.1 7279.3 7426.1 7440.3 7945.3 8099.6 8183.1 8189.0 8191.6 7282.6 7486.4 7508.5 7535.8 7693.2 7381.0 7390.4 7409.9 7533.1 7593.2 6563.8 6571.7 6609.3 6615.8 6626.5 4918.6 4936.1 4953.3 5036.7 5124.7 4932.5 5181.5 5192.8 5230.8 5277.8 23:59:20 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | [#4] prototype.html Cycles:180 Average:4533.36 Median:5181.13 stddev:1343.43 (25.9%) stddev-sans-first:1309.28 23:59:20 INFO - PROCESS | 9152 | Values: 310.3 310.6 316.4 322.2 324.2 5250.2 5266.7 5273.5 5299.3 5301.9 5217.5 5240.4 5245.2 5250.9 5265.5 3874.1 3993.6 4006.8 4015.3 4016.6 3612.0 4017.4 4034.7 4038.1 4046.6 3988.2 3992.2 3993.6 4007.3 4034.1 3920.4 4046.2 4050.7 4075.9 4079.5 3468.2 3536.3 3542.7 3559.4 3572.4 5024.5 5053.2 5056.6 5059.3 5151.5 4625.5 4627.4 4636.7 4655.4 4655.7 5140.5 5364.9 5376.7 5388.6 5391.3 3835.8 3845.4 3847.2 3855.8 3871.8 5382.4 5474.8 5609.7 5613.4 5619.4 3896.3 3906.6 3907.6 3910.9 3927.1 3935.7 3969.1 3975.2 3978.6 4006.5 4454.8 4584.0 4586.7 4589.4 4602.2 5424.7 5452.7 5455.5 5495.7 5497.7 1361.8 1370.1 1380.9 1381.9 1384.1 843.7 882.2 903.7 911.3 911.9 4650.9 4720.2 4761.4 4770.9 4802.8 4918.8 4968.1 5001.3 5024.3 5044.5 4695.9 4701.4 4715.2 4773.7 4946.9 2931.5 2948.3 2970.0 2970.5 2977.9 5768.0 5775.0 5783.8 5794.9 5833.5 5658.9 5681.0 5694.1 5751.8 5771.2 5757.7 5760.3 5786.3 5809.3 5835.4 5446.6 5600.6 5603.3 5634.7 5688.9 5529.1 5535.1 5535.8 5538.1 5539.6 5533.8 5677.6 5690.8 5812.2 5841.2 3909.4 3945.3 3970.4 3975.0 3989.8 5584.4 5633.4 5634.9 5639.2 5641.5 5303.6 5340.7 5396.4 5437.7 5475.3 5380.4 5405.7 5426.1 5441.2 5446.3 5386.4 5405.7 5417.5 5425.3 5436.6 5210.8 5458.5 5498.7 5510.8 5515.7 5378.2 5393.8 5394.5 5409.6 5453.2 23:59:20 INFO - PROCESS | 9152 | 23:59:20 INFO - PROCESS | 9152 | [#5] yui.html Cycles:180 Average:20119.10 Median:4777.20 stddev:28323.86 (592.9%) stddev-sans-first:28365.75 23:59:20 INFO - PROCESS | 9152 | Values: 692.4 731.3 747.5 779.3 826.4 14454.8 15116.5 15163.7 15201.3 15226.6 14864.5 15088.5 15101.8 15109.1 15115.3 651.5 765.4 785.1 795.1 800.9 773.0 782.1 787.0 787.0 787.2 839.5 839.9 840.3 841.4 841.5 675.1 801.2 827.9 832.9 833.0 613.8 613.9 613.9 614.1 614.9 13575.0 13586.0 13612.2 13622.7 13657.7 13243.7 13821.7 14109.7 14144.6 14414.9 12427.0 12476.3 12499.9 12570.2 13022.5 4340.7 4351.2 4378.9 4532.8 4581.3 69391.0 69583.3 70297.0 72136.3 72269.6 10945.9 10952.0 11044.8 11208.7 11260.8 18037.9 18097.3 18149.5 18188.7 18212.1 7955.3 8113.8 8149.3 8300.2 8687.1 4657.9 4730.5 4810.6 4841.5 4906.0 3346.7 3466.4 3468.7 3468.8 3472.1 569.7 575.2 575.3 580.2 581.8 552.4 594.6 595.1 602.9 645.0 8660.0 8964.5 9139.4 9883.8 9998.5 658.9 662.9 711.1 732.7 756.3 4669.1 4744.5 4810.0 4883.3 4981.2 64064.7 68166.3 105542.0 114529.0 116119.4 89075.6 95393.0 97782.0 100170.0 100586.5 80689.0 82061.0 88167.6 90865.5 92154.0 9018.7 9295.4 9404.7 10122.9 10276.7 51229.7 52503.0 53253.7 53650.2 55917.7 71372.0 75081.0 81443.0 82197.6 82668.3 12995.5 13224.2 13330.9 13407.3 13436.5 14204.8 14305.9 14339.7 14818.3 15145.9 14180.6 14201.7 14214.6 14372.7 15102.8 14115.4 14319.4 14337.7 14611.5 15361.9 14056.6 14329.7 14373.5 14389.9 14533.0 10536.3 10579.3 10716.6 10950.9 11280.8 11903.8 11961.9 11988.6 12213.3 12372.0 23:59:20 INFO - PROCESS | 9152 | -------- Summary: end -------- 23:59:20 INFO - PROCESS | 9152 | 23:59:21 INFO - TEST-INFO | 9152: exit 0 23:59:21 INFO - TEST-OK | dromaeo_css | took 1113781ms 23:59:21 INFO - TEST-START | kraken 23:59:21 INFO - operating with platform_type : linux_ 23:59:21 INFO - Initialising browser for kraken test... 23:59:21 INFO - TEST-INFO | started process 9342 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVeC6ng/profile http://localhost:36827/getInfo.html) 23:59:24 INFO - PROCESS | 9342 | JavaScript error: , line 0: uncaught exception: undefined 23:59:25 INFO - PROCESS | 9342 | __metrics Screen width/height:1600/1200 23:59:25 INFO - PROCESS | 9342 | colorDepth:24 23:59:25 INFO - PROCESS | 9342 | Browser inner width/height: 1024/697 23:59:25 INFO - PROCESS | 9342 | __metrics 23:59:26 INFO - PROCESS | 9342 | 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" 23:59:26 INFO - PROCESS | 9342 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:59:27 INFO - TEST-INFO | 9342: exit 0 23:59:27 INFO - Browser initialized. 23:59:27 INFO - Running cycle 1/1 for kraken test... 23:59:27 INFO - TEST-INFO | started process 9424 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVeC6ng/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:59:39 INFO - PROCESS | 9424 | RSS: Main: 157446144 23:59:39 INFO - PROCESS | 9424 | 23:59:41 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36827/tests/kraken/driver.html?testName=audio-beat-detection) 23:59:42 INFO - PROCESS | 9424 | RSS: Main: 214560768 23:59:42 INFO - PROCESS | 9424 | 23:59:45 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36827/tests/kraken/driver.html?testName=audio-dft) 23:59:45 INFO - PROCESS | 9424 | RSS: Main: 188260352 23:59:45 INFO - PROCESS | 9424 | 23:59:49 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36827/tests/kraken/driver.html?testName=audio-fft) 23:59:49 INFO - PROCESS | 9424 | RSS: Main: 271675392 23:59:49 INFO - PROCESS | 9424 | 23:59:51 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36827/tests/kraken/driver.html?testName=audio-oscillator) 23:59:52 INFO - PROCESS | 9424 | RSS: Main: 181116928 23:59:52 INFO - PROCESS | 9424 | 23:59:54 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36827/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:59:55 INFO - PROCESS | 9424 | RSS: Main: 185294848 23:59:55 INFO - PROCESS | 9424 | 23:59:58 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36827/tests/kraken/driver.html?testName=imaging-darkroom) 23:59:58 INFO - PROCESS | 9424 | RSS: Main: 220106752 23:59:58 INFO - PROCESS | 9424 | 00:00:02 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36827/tests/kraken/driver.html?testName=imaging-desaturate) 00:00:02 INFO - PROCESS | 9424 | RSS: Main: 220909568 00:00:02 INFO - PROCESS | 9424 | 00:00:05 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36827/tests/kraken/driver.html?testName=json-parse-financial) 00:00:06 INFO - PROCESS | 9424 | RSS: Main: 220426240 00:00:06 INFO - PROCESS | 9424 | 00:00:07 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36827/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:00:08 INFO - PROCESS | 9424 | RSS: Main: 184885248 00:00:08 INFO - PROCESS | 9424 | 00:00:09 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36827/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:00:10 INFO - PROCESS | 9424 | RSS: Main: 189739008 00:00:10 INFO - PROCESS | 9424 | 00:00:12 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36827/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:00:13 INFO - PROCESS | 9424 | RSS: Main: 195293184 00:00:13 INFO - PROCESS | 9424 | 00:00:15 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:00:16 INFO - PROCESS | 9424 | RSS: Main: 188755968 00:00:16 INFO - PROCESS | 9424 | 00:00:19 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:00:19 INFO - PROCESS | 9424 | RSS: Main: 193368064 00:00:19 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | Cycle 1(1): loaded http://localhost:36827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36827/tests/kraken/driver.html?testName=ai-astar) 00:00:21 INFO - PROCESS | 9424 | RSS: Main: 244473856 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | __start_tp_report 00:00:21 INFO - PROCESS | 9424 | _x_x_mozilla_page_load 00:00:21 INFO - PROCESS | 9424 | _x_x_mozilla_page_load_details 00:00:21 INFO - PROCESS | 9424 | |i|pagename|runs| 00:00:21 INFO - PROCESS | 9424 | |0;ai-astar;103;111;111;111;110;109;113;111;111;112 00:00:21 INFO - PROCESS | 9424 | |1;audio-beat-detection;149;116;122;174;138;123;144;120;176;117 00:00:21 INFO - PROCESS | 9424 | |2;audio-dft;160;160;160;159;162;161;161;162;163;161 00:00:21 INFO - PROCESS | 9424 | |3;audio-fft;76;77;75;107;75;76;75;75;122;74 00:00:21 INFO - PROCESS | 9424 | |4;audio-oscillator;82;83;110;84;84;84;124;83;83;123 00:00:21 INFO - PROCESS | 9424 | |5;imaging-gaussian-blur;116;117;117;116;116;116;117;117;116;117 00:00:21 INFO - PROCESS | 9424 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;147;146 00:00:21 INFO - PROCESS | 9424 | |7;imaging-desaturate;95;96;96;96;95;96;96;95;95;95 00:00:21 INFO - PROCESS | 9424 | |8;json-parse-financial;53;52;51;51;52;51;52;52;52;52 00:00:21 INFO - PROCESS | 9424 | |9;json-stringify-tinderbox;62;63;63;63;64;63;63;63;63;63 00:00:21 INFO - PROCESS | 9424 | |10;stanford-crypto-aes;80;76;75;74;75;76;76;76;84;77 00:00:21 INFO - PROCESS | 9424 | |11;stanford-crypto-ccm;120;120;123;127;127;124;127;130;172;134 00:00:21 INFO - PROCESS | 9424 | |12;stanford-crypto-pbkdf2;154;155;156;158;156;154;152;157;151;151 00:00:21 INFO - PROCESS | 9424 | |13;stanford-crypto-sha256-iterative;68;62;64;61;61;62;60;61;61;61 00:00:21 INFO - PROCESS | 9424 | __end_tp_report 00:00:21 INFO - PROCESS | 9424 | __start_cc_report 00:00:21 INFO - PROCESS | 9424 | _x_x_mozilla_cycle_collect,1694 00:00:21 INFO - PROCESS | 9424 | __end_cc_report 00:00:21 INFO - PROCESS | 9424 | __startTimestamp1469602821565__endTimestamp 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | ------- Summary: start ------- 00:00:21 INFO - PROCESS | 9424 | Number of tests: 14 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.00 stddev:2.74 (2.5%) stddev-sans-first:1.12 00:00:21 INFO - PROCESS | 9424 | Values: 103.0 111.0 111.0 111.0 110.0 109.0 113.0 111.0 111.0 112.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:141.00 stddev:22.67 (16.1%) stddev-sans-first:23.69 00:00:21 INFO - PROCESS | 9424 | Values: 149.0 116.0 122.0 174.0 138.0 123.0 144.0 120.0 176.0 117.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 00:00:21 INFO - PROCESS | 9424 | Values: 160.0 160.0 160.0 159.0 162.0 161.0 161.0 162.0 163.0 161.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:16.89 (22.5%) stddev-sans-first:17.71 00:00:21 INFO - PROCESS | 9424 | Values: 76.0 77.0 75.0 107.0 75.0 76.0 75.0 75.0 122.0 74.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:83.00 stddev:17.65 (21.3%) stddev-sans-first:18.18 00:00:21 INFO - PROCESS | 9424 | Values: 82.0 83.0 110.0 84.0 84.0 84.0 124.0 83.0 83.0 123.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 00:00:21 INFO - PROCESS | 9424 | Values: 116.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 00:00:21 INFO - PROCESS | 9424 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 00:00:21 INFO - PROCESS | 9424 | Values: 95.0 96.0 96.0 96.0 95.0 96.0 96.0 95.0 95.0 95.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:0.63 (1.2%) stddev-sans-first:0.50 00:00:21 INFO - PROCESS | 9424 | Values: 53.0 52.0 51.0 51.0 52.0 51.0 52.0 52.0 52.0 52.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:0.47 (0.7%) stddev-sans-first:0.33 00:00:21 INFO - PROCESS | 9424 | Values: 62.0 63.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 63.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.00 stddev:2.96 (3.9%) stddev-sans-first:2.92 00:00:21 INFO - PROCESS | 9424 | Values: 80.0 76.0 75.0 74.0 75.0 76.0 76.0 76.0 84.0 77.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:127.00 stddev:15.24 (12.0%) stddev-sans-first:15.69 00:00:21 INFO - PROCESS | 9424 | Values: 120.0 120.0 123.0 127.0 127.0 124.0 127.0 130.0 172.0 134.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.40 Median:155.50 stddev:2.46 (1.6%) stddev-sans-first:2.60 00:00:21 INFO - PROCESS | 9424 | Values: 154.0 155.0 156.0 158.0 156.0 154.0 152.0 157.0 151.0 151.0 00:00:21 INFO - PROCESS | 9424 | 00:00:21 INFO - PROCESS | 9424 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:61.50 stddev:2.33 (3.8%) stddev-sans-first:1.13 00:00:21 INFO - PROCESS | 9424 | Values: 68.0 62.0 64.0 61.0 61.0 62.0 60.0 61.0 61.0 61.0 00:00:21 INFO - PROCESS | 9424 | -------- Summary: end -------- 00:00:21 INFO - PROCESS | 9424 | 00:00:22 INFO - TEST-INFO | 9424: exit 0 00:00:22 INFO - TEST-OK | kraken | took 61148ms 00:00:22 INFO - SUITE-END | took 1174s 00:00:22 INFO - Completed test suite (00:19:35) 00:00:22 INFO - javascript benchmark 00:00:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3046.969530304697, 3055.7249847513717, 3085.25953771095, 3088.2896933705233, 3116.641586217584, 5193.091209038422, 5252.1859111837675, 5304.443033481487, 5306.044911915854, 5335.386430560482, 5275.129603615399, 5306.204069389593, 5327.866803329919, 5373.731313434322, 5383.650062745919, 4781.880452988686, 4815.181419158734, 4849.175640141167, 4872.6345524085655, 4943.184374578191, 4778.283257511366, 4816.8554943351755, 4893.804247830082, 4900.093482705711, 4942.5304596063315, 4704.223803072506, 4736.976315118437, 4742.241241401393, 4804.087223427555, 4810.735409552473, 4805.0, 4873.512648735134, 4886.975565122188, 4949.158643030657, 4952.628552858514, 4865.9756701216265, 4867.464578896319, 4881.023795240966, 4881.170201065792, 4908.828191013297, 5323.1749078892835, 5348.893022139536, 5353.304070470853, 5378.757955891994, 5391.32608423947, 5450.672959622396, 5487.917681234785, 5493.093639549469, 5499.1476321170285, 5560.471755183251, 5523.447655234485, 5548.389677135512, 5548.889022219573, 5588.6646801192055, 5597.216389705444, 5231.921521177109, 5236.450172731847, 5238.60710446718, 5240.554552863011, 5242.397124330751, 5727.828165155052, 5810.21562089121, 5866.882662346729, 5871.119332100134, 5947.434993675594, 5655.349634791969, 5667.1782591524825, 5689.2319536862, 5703.344115426696, 5706.3152421709665, 5740.54075673945, 5750.396208398058, 5753.568482363839, 5753.88492230153, 5805.622634528827, 5494.725263736797, 5588.497035266845, 5621.8594535136135, 5631.155326701028, 5699.287589051369, 5650.689212093293, 5658.151277308354, 5663.207150998864, 5698.344690360579, 5713.371529131792, 4354.542773008852, 4358.346248062816, 4359.542248063971, 4360.934585981213, 4401.6698747594055, 3857.0550215197454, 3881.378979363288, 3898.1813819098306, 3909.0618251619408, 3919.6080391961145, 5398.4871437213405, 5403.2165336026055, 5410.91883621746, 5450.236966824647, 5459.3994660587305, 5627.352854421794, 5682.886342273131, 5684.54523638108, 5684.715764211773, 5722.141678748221, 5265.605079619044, 5280.339957505312, 5312.840614781563, 5381.865453363697, 5399.784008639689, 4868.805247790119, 4878.609711223094, 4883.365162528919, 4907.877303067452, 4923.704577725348, 5777.8555536111935, 5786.0, 5789.739461724148, 5811.099279611667, 5851.824445266649, 5538.335399752055, 5604.915926261191, 5619.971900140473, 5689.6586204827845, 5729.627574207663, 5991.550633702577, 6028.33688294296, 6031.035034394476, 6041.124037014696, 6044.153818465506, 5622.522085622768, 5667.971660141673, 5672.262605861211, 5672.801451949162, 5673.602847800779, 5517.972410138084, 5774.855628609318, 5778.451047150514, 5783.306003279465, 5785.710714464344, 5464.071107911585, 5509.531689806251, 5511.448855114457, 5540.612157148961, 5588.916166257428, 5525.254090697705, 5534.944650553604, 5535.667859928417, 5537.5569954402745, 5538.086215774352, 5392.352917649907, 5467.261919640957, 5472.835814925558, 5475.370332411823, 5485.0949593318255, 5443.972780136074, 5476.479734425223, 5478.0, 5489.807856724996, 5540.889182216414, 5337.893242135058, 5366.838994830161, 5381.542568881689, 5381.8923621526565, 5389.784408623611], "value": 5232.664492583139, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.0674876330909, 575.9273707355133, 601.1313651773188, 636.3096040795737, 657.6580178307278, 42854.57145428545, 44102.55897441025, 44183.0, 44221.115577688426, 46724.766376168154, 46090.7695461523, 46440.53559464405, 46514.53485465144, 46572.76713616436, 46586.53413465864, 9073.863892041625, 9193.632254709837, 9203.40177888435, 9206.171444569987, 9210.907890921071, 9047.095290470967, 9126.634934602607, 9130.26957843371, 9130.497822619753, 9135.817283654324, 9143.497107659075, 9161.679341223058, 9167.679131230409, 9176.449413035205, 9183.586738596745, 9104.13510716481, 9111.27109831212, 9126.498042607654, 9138.17756401927, 9141.95429022854, 8854.513001784899, 8919.643214271422, 8938.68714594986, 8941.64233430662, 8945.731628051104, 43838.34242486365, 44218.77890610559, 44225.33661995073, 44271.557284427065, 44290.77854610739, 3585.480105384731, 3618.981905090484, 3632.128289210597, 3757.6993840492696, 4285.678574106954, 33260.66739332624, 33287.83356083228, 33383.0, 33521.0, 33566.83216583926, 4370.322599997005, 4404.229259879508, 4457.509673935864, 4474.463064432256, 4512.729236245836, 236533.0, 237135.0, 237138.0, 238865.99999999825, 239737.0, 57750.71124644434, 58265.0, 58273.708631456575, 58293.12560311599, 58313.4168658308, 62286.0171186304, 62305.0, 62506.687466562376, 62573.37426625766, 62740.68629656822, 16788.32846686119, 16935.576610584587, 16948.322067117177, 16988.235529401205, 16991.06549139809, 12397.442115104843, 12494.250344979331, 12511.499540018298, 12514.624561263177, 12514.874851251552, 15206.0, 16764.91617541929, 16905.91547042257, 16975.830241697426, 17248.741268880978, 5310.415854256029, 6224.221972253468, 6290.087937249074, 6428.132202152649, 6562.179727534059, 992.7220378293945, 1040.0966231795796, 1059.4649701900491, 1065.3767545985531, 1107.186218129674, 31661.841690791858, 32527.86152484699, 32554.511682324784, 32656.836715816746, 33158.66841331604, 1076.7146706122883, 1087.2932593814085, 1118.0105606538273, 1136.0, 1162.8430161928031, 16669.416570419977, 17151.741169178065, 17166.57083572896, 17175.914120429567, 17241.0, 494082.0, 499207.0, 499295.0, 500285.0, 508331.0, 58309.12536311959, 58575.41424585785, 58583.70708146432, 58610.0, 58620.70689646524, 1200.2618389690278, 1283.6020833541631, 1307.3593938969836, 1339.9933000334936, 1357.307773035739, 31462.370752585277, 32661.673383265865, 33276.334473309915, 33485.497717534745, 33826.15434614154, 51126.488735113664, 51825.48174518206, 51826.740866295426, 51865.22202167046, 51894.48105518897, 407900.0, 408263.0, 409417.0, 410544.0, 411578.0, 27243.182704518895, 28339.57490637601, 28375.0, 28833.56749648715, 28887.0, 48385.27422088601, 49058.75470622767, 49169.83716709694, 50046.499535004186, 50763.23855142102, 40560.1887962245, 40592.79703601463, 41425.37861932134, 42558.787206063775, 42823.3576496359, 41249.79375103225, 41927.49761374444, 42073.36889946592, 42680.78659606682, 42774.14451710886, 40438.75467592718, 40440.3933940997, 40596.3910541348, 40802.183956320114, 41228.38157427701, 34406.31187376288, 36527.81736091409, 36838.26323473462, 36904.26191476209, 37398.439023415216, 26851.597226042017, 26993.73006269912, 27144.72855271422, 27293.181204563894, 27699.86150069237], "value": 19945.42267411578, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.38394728960776, 366.46998780093105, 391.3054328566794, 392.9685625149988, 398.13405842293025, 15681.607959801011, 17867.10664466778, 18011.54971125724, 19651.31220407286, 20000.49998750032, 18342.541436464096, 18998.14508347124, 19006.904965475154, 19219.71170432441, 19445.805541944577, 5625.0, 5673.744681489333, 5912.7930522431725, 5915.112733090049, 5915.792947246847, 5677.517411020068, 5707.172459993299, 5737.5696822738255, 5740.540756739471, 5758.337791154029, 5586.748596313176, 5595.888082238353, 5701.6864072476, 5716.0, 5718.342390625069, 5730.312362516483, 5754.3094828620415, 5776.884462310752, 5778.508826749731, 5790.68151251681, 5005.374328208974, 5125.897482050375, 5177.37871455424, 5178.922316165261, 5197.0, 19352.70970935437, 19456.805431945784, 19605.509862253417, 19706.60586788256, 19708.0, 2133.3386650138505, 2320.5358928214255, 2321.9319113207944, 2617.764401203901, 2645.259327388315, 9918.702438926844, 10337.431441270726, 10456.63401780934, 10791.298565593212, 10863.130949524018, 1884.8869067855971, 1891.4514790710678, 1929.9710504342447, 1976.7459186156784, 2036.8370530357683, 75926.0, 79470.0, 79551.60224198842, 79607.0, 80034.59982700108, 10371.636992705218, 10421.957804219517, 10544.578216871392, 10578.677665291838, 10857.45712714361, 14149.646258843406, 14164.645883852987, 14176.929115354356, 14182.219977901108, 14197.290135493184, 7898.763037108896, 7913.0, 7964.283214510722, 7972.880406793903, 8279.337652987868, 7551.697932082655, 7559.508631938907, 7584.582847943306, 7799.142094369615, 7820.257075577811, 4248.702590818613, 4329.220740266782, 4351.151525452507, 4434.091011342732, 4486.3494793254795, 1814.564504518906, 1886.2360743898469, 1913.808619138075, 1933.787283398825, 1943.9416817495498, 888.2272422992038, 1012.8025035118228, 1014.6854475112739, 1048.5858086055964, 1053.5785685725618, 19828.306009289896, 20194.798052019585, 20232.494187645425, 20264.898675506527, 20594.0, 937.7843096087802, 947.7914858731068, 954.61815273891, 975.1029053271008, 1007.6926537406015, 8987.685431009882, 10516.526756295985, 11266.605668801552, 11273.549058037586, 11275.774484510264, 494069.0, 495173.0, 506449.0, 509639.0, 514929.0, 58278.41721582814, 82979.58510207411, 95265.0, 96108.03891961131, 98116.0, 971.2763990826738, 984.6110786239539, 991.3655260633201, 1048.7483004079118, 1055.2402270365324, 17648.0, 18075.36736214226, 18772.530686732942, 18808.341708040152, 18838.34065807745, 44327.11345773131, 44550.77724611356, 44593.33110003417, 45717.54282457133, 46752.29871551861, 58163.4183658158, 60151.39848601458, 60332.396676034434, 61724.382756171864, 63155.684221578595, 10794.08250298702, 11561.88438115608, 12071.637850864488, 12151.270923744603, 12432.813507797571, 13671.17972921628, 13732.29630808399, 13831.723365532835, 13935.860641393861, 14377.28113594337, 11219.943900280772, 11424.0, 11481.483333250191, 11501.424928753362, 11565.710857228636, 11327.546898123983, 11652.766944661229, 11709.473073711873, 11859.288442693465, 11932.642020739391, 11776.646700598996, 12053.276803391474, 12078.818817717565, 12140.453679584616, 12212.0, 12011.63965081049, 12154.939225303817, 12176.57381991626, 12683.23900565969, 12892.677683057624, 7697.846043078995, 8099.068607110258, 8224.259816616533, 8288.50268983863, 8323.542205178774], "value": 9215.718457273355, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.6124660975893, 1446.6744982378966, 1507.494989178625, 1604.1257514654512, 1729.3082766893233, 8004.079530853954, 8337.958310208449, 8408.579571021455, 8554.0, 8565.785855353619, 8392.91607083929, 8471.703490377839, 8494.277986371166, 8496.277816385615, 8504.319654427647, 2234.4748983988748, 2255.357223191391, 2257.3679369776487, 2257.7516473187934, 2258.1644791427193, 2228.3092241405134, 2284.588774020676, 2299.1263319938485, 2301.2290882554366, 2302.0561569756405, 2220.8334374921865, 2226.743924448685, 2246.33733048751, 2255.244493094807, 2268.3761965459466, 2200.0759680933966, 2208.5803697297624, 2236.541508990653, 2244.1360076370634, 2244.955100897981, 2268.6823844661762, 2293.552757212344, 2294.655801629752, 2296.632538793802, 2296.7358753743365, 8324.70863519774, 8399.5380254086, 8433.578321083922, 8450.154984501562, 8466.068732439428, 4844.709317440965, 4991.725455099968, 4993.151164302041, 5001.9249711254, 5029.19532874742, 3087.969120308813, 3183.984080079608, 3226.2257058306072, 3256.7068963793135, 3263.1189578813824, 1105.745678493943, 1116.107114308558, 1122.3714719756963, 1122.9775404491945, 1141.1726498288758, 11411.600593979254, 11416.885831141666, 11433.42832858362, 11527.827082593767, 11585.884141158649, 3997.400389941474, 4144.212599606126, 4212.410262563244, 4216.451861258017, 4222.59885310895, 9090.27277817773, 9127.90872091284, 9185.632574696938, 9187.173154416134, 9192.63229470827, 4869.172240719086, 4895.069936712013, 4897.730624815669, 4906.018796240694, 4909.435414927258, 3980.3233450313514, 4382.8904277392685, 4604.953950460519, 4605.631549476034, 4647.209974304309, 3822.120912190156, 3887.8056097195595, 4000.7999600019884, 4022.316206244887, 4027.617376349242, 1222.584321330752, 1244.0731654916963, 1246.9064820138526, 1256.6983923858209, 1264.968375790594, 843.6625349859983, 876.7939534209455, 925.1812146250483, 967.583938906277, 991.2218908157062, 5856.736446859902, 5977.22296101504, 6074.301455332605, 6096.69516524172, 6276.497880169575, 947.5404428852002, 957.3872721458272, 969.4038166527534, 969.8399764038995, 994.6220436234184, 4045.5752146024834, 4189.371594260824, 4198.181354635878, 4248.915021699549, 4280.101178752497, 9975.900240997496, 10369.9481502592, 10384.532696028697, 10394.428306443067, 10394.896051039394, 9692.563834627459, 10117.190624750003, 10120.342177758423, 10341.121004714683, 10343.172546196287, 7443.739469118555, 7568.356689681439, 7602.315791578674, 7613.885791713129, 7627.046619172604, 5842.298924129132, 5921.881562368814, 5986.4013598639785, 6013.849653758516, 6287.842803929939, 8934.73195804127, 9666.420014799134, 9676.548517722398, 9685.806283874143, 9756.951215243878, 9438.48088355147, 9531.0, 9619.759506012406, 9620.326577139813, 9638.518074096408, 6833.41915937131, 7203.063601731741, 7279.308465695751, 7426.14599321085, 7440.330370266702, 7945.324647405036, 8099.635516401659, 8183.099859015384, 8189.0, 8191.631376588187, 7282.563046217032, 7486.401087913063, 7508.511946723445, 7535.849283014419, 7693.192214817587, 7381.0, 7390.422523953578, 7409.851802963803, 7533.096028476618, 7593.1647518774, 6563.835904102245, 6571.737130514726, 6609.339066093352, 6615.834604134935, 6626.536142470173, 4918.557329840189, 4936.111499930047, 4953.331300274417, 5036.722980235975, 5124.692518448755, 4932.45742968266, 5181.53366197044, 5192.818251361185, 5230.790768369223, 5277.815276465305], "value": 4464.723710642734, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.32968787419173, 310.5931230088584, 316.35305799639735, 322.1993346533863, 324.17012448132766, 5250.186221135726, 5266.683998960065, 5273.472652734729, 5299.28459657946, 5301.893962120765, 5217.452167522412, 5240.423553409122, 5245.239440281157, 5250.894982100355, 5265.473452654733, 3874.1476875087506, 3993.560708322082, 4006.83972641094, 4015.337469317559, 4016.5983401659746, 3611.981940090309, 4017.4131218168804, 4034.677225821942, 4038.111615444612, 4046.5953404659444, 3988.24223397555, 3992.2414741199223, 3993.6206060424215, 4007.338789099795, 4034.1326614777818, 3920.4315374270855, 4046.1705350403095, 4050.7442692765044, 4075.9388609170737, 4079.5308539518037, 3468.236987862666, 3536.257385948954, 3542.71658267338, 3559.4126969050076, 3572.4462708280257, 5024.517767258668, 5053.242013697938, 5056.558880719014, 5059.316992206079, 5151.459096794858, 4625.537446255381, 4627.3521706961055, 4636.698614590041, 4655.3947986761505, 4655.743934083658, 5140.460251673558, 5364.865878353034, 5376.709589698483, 5388.602072077927, 5391.299131112969, 3835.846566137351, 3845.4231865220163, 3847.1920896611746, 3855.7661548304554, 3871.825767840454, 5382.381026182, 5474.808381706661, 5609.663420194802, 5613.354464236624, 5619.438056194389, 3896.3245804303856, 3906.648401643809, 3907.628775266345, 3910.8631197907935, 3927.116398810245, 3935.704822138351, 3969.126792105732, 3975.2248311578965, 3978.562358140602, 4006.5392479864604, 4454.7749368923805, 4584.0, 4586.724796512153, 4589.380433641482, 4602.194615942197, 5424.674519528841, 5452.700101494456, 5455.536279416294, 5495.697736624524, 5497.697626630494, 1361.7616917039677, 1370.0683535195963, 1380.9378577963814, 1381.9170849749048, 1384.0588399888172, 843.6751850537464, 882.2060145868642, 903.6927444668844, 911.2573252798948, 911.8996910339857, 4650.86047418578, 4720.173969555314, 4761.381020467386, 4770.904581908342, 4802.759862006886, 4918.754062296943, 4968.100041467431, 5001.274815151855, 5024.271480635361, 5044.461439261061, 4695.882602934954, 4701.412323459567, 4715.24556071027, 4773.665843390999, 4946.9010619787405, 2931.51629981055, 2948.277671970381, 2969.9804133189205, 2970.5247160454223, 2977.865996030184, 5767.961766882002, 5775.0, 5783.797567085132, 5794.8989691958395, 5833.4749872511675, 5658.943410565842, 5680.97159514208, 5694.145878118233, 5751.827445176651, 5771.249737534177, 5757.733298674285, 5760.30071129018, 5786.276715410574, 5809.2738407699035, 5835.416458354301, 5446.591505637014, 5600.551955843604, 5603.2909962461845, 5634.718264086698, 5688.886222275614, 5529.087700529529, 5535.142052981794, 5535.833924982257, 5538.141588053697, 5539.556835453234, 5533.806316778893, 5677.602567820213, 5690.800821971211, 5812.157237200667, 5841.18223448709, 3909.354956432157, 3945.3095708251517, 3970.404439334123, 3974.960250397459, 3989.7805620691142, 5584.441555844383, 5633.4084921082695, 5634.943650563442, 5639.182318563868, 5641.520470759867, 5303.5757139429525, 5340.679559226459, 5396.4333744957785, 5437.673739575638, 5475.275288284178, 5380.381256155436, 5405.702686352289, 5426.077566813572, 5441.183822426668, 5446.291982042309, 5386.407495175449, 5405.702686352289, 5417.485338892799, 5425.348958125051, 5436.6194366393975, 5210.791568337224, 5458.4814442627885, 5498.670079795065, 5510.752016159202, 5515.703809604659, 5378.2251856887915, 5393.784248630011, 5394.464601637224, 5409.621326507264, 5453.209284653623], "value": 4102.572123938326, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.4425837201053, 731.3308322884561, 747.4879707400431, 779.2752739951845, 826.3843436639704, 14454.78317825232, 15116.546503604883, 15163.696726065486, 15201.315940782664, 15226.619334516643, 14864.479743208989, 15088.47190348338, 15101.848981510182, 15109.093454392743, 15115.319810608522, 651.4527796650813, 765.4106338119644, 785.0892964161574, 795.1134485049187, 800.8958835351397, 772.9845403091934, 782.0732432067989, 787.0122995640463, 787.0122995640492, 787.2403130978604, 839.5046922315832, 839.9034111077212, 840.306746933782, 841.398400143897, 841.5077179849795, 675.0717763075771, 801.1787917384669, 827.8923739913832, 832.8625776746829, 832.9916700833005, 613.772904025509, 613.9017757158833, 613.9017757158833, 614.112607282477, 614.9262088549402, 13575.0, 13586.0, 13612.183269003892, 13622.659433514142, 13657.658558536017, 13243.735125297537, 13821.654458638513, 14109.71780564383, 14144.575662730134, 14414.92792536041, 12427.0, 12476.251424914533, 12499.937500312531, 12570.245785252913, 13022.47910083605, 4340.65274778017, 4351.173277077345, 4378.934315985262, 4532.750698711569, 4581.266997280419, 69391.0, 69583.30416695819, 70297.0, 72136.27863721349, 72269.63865180693, 10945.890541094646, 10952.0, 11044.779104417867, 11208.719782005515, 11260.774784504429, 18037.909810450863, 18097.276108955495, 18149.455516334267, 18188.727169092475, 18212.08939553017, 7955.323797477279, 8113.7565873023905, 8149.266566009088, 8300.2114799094, 8687.131286871263, 4657.930131048037, 4730.526947305242, 4810.6151507879285, 4841.467438581753, 4905.950940490621, 3346.748993825473, 3466.3587236361354, 3468.739844511672, 3468.8092154931214, 3472.1493234157792, 569.7436153730838, 575.2492996639426, 575.3010092737344, 580.1761498671896, 581.7905554000556, 552.353746117044, 594.551113908996, 595.0716881664617, 602.8523011862122, 644.9516286278548, 8659.956700216584, 8964.514630283657, 9139.405938613969, 9883.752906177404, 9998.500074996222, 658.851758354376, 662.867426514699, 711.1412123264448, 732.6886073418882, 756.3495393961189, 4669.101912032556, 4744.4543877454535, 4809.955604993646, 4883.291922671264, 4981.153203955337, 64064.67967660225, 68166.31833681698, 105542.0, 114529.0, 116119.41940290414, 89075.55462222647, 95393.0, 97782.0, 100170.0, 100586.4970675142, 80689.0, 82061.0, 88167.55916220378, 90865.54567227254, 92154.0, 9018.729438116867, 9295.395799273025, 9404.670836520689, 10122.949385253321, 10276.743081423025, 51229.74385128199, 52503.0, 53253.73373133109, 53650.195247070544, 55917.72041139931, 71372.0, 75080.99028116443, 81443.04126545957, 82197.58901205455, 82668.3466330682, 12995.545155919497, 13224.206547607175, 13330.943561556553, 13407.32963351848, 13436.529721459701, 14204.786928196292, 14305.928470357581, 14339.713205736036, 14818.25908704539, 15145.924270378577, 14180.574582762534, 14201.715965680834, 14214.644633884236, 14372.712545748816, 15102.773458397913, 14115.364808583434, 14319.427222910967, 14337.713245734818, 14611.488597899022, 15361.923190383977, 14056.578302650936, 14329.713405732036, 14373.496927607484, 14389.928050359682, 14533.0, 10536.262461627612, 10579.312344697877, 10716.624918127829, 10950.890491095306, 11280.830787538029, 11903.76192476163, 11961.940190298992, 11988.64034078979, 12213.267203967791, 12372.0], "value": 7430.026387486044, "unit": "score"}], "name": "dromaeo_css", "value": 7125.752176360757, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 111.0, 111.0, 110.0, 109.0, 113.0, 111.0, 111.0, 112.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 122.0, 174.0, 138.0, 123.0, 144.0, 120.0, 176.0, 117.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 159.0, 162.0, 161.0, 161.0, 162.0, 163.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 107.0, 75.0, 76.0, 75.0, 75.0, 122.0, 74.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 110.0, 84.0, 84.0, 84.0, 124.0, 83.0, 83.0, 123.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 51.0, 51.0, 52.0, 51.0, 52.0, 52.0, 52.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 75.0, 74.0, 75.0, 76.0, 76.0, 76.0, 84.0, 77.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 120.0, 123.0, 127.0, 127.0, 124.0, 127.0, 130.0, 172.0, 134.0], "value": 130.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 155.0, 156.0, 158.0, 156.0, 154.0, 152.0, 157.0, 151.0, 151.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 62.0, 64.0, 61.0, 61.0, 62.0, 60.0, 61.0, 61.0, 61.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1482.9, "alertThreshold": 2.0}]} 00:00:22 INFO - Return code: 0 00:00:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:00:22 INFO - # TBPL SUCCESS # 00:00:22 INFO - Running post-action listener: _resource_record_post_action 00:00:22 INFO - [mozharness: 2016-07-27 07:00:22.886827Z] Finished run-tests step (success) 00:00:22 INFO - Running post-run listener: _resource_record_post_run 00:00:22 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 561938432; Read time: 136; Write time: 467324 00:00:22 INFO - TinderboxPrint: CPU usage
13.0% 00:00:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 136 00:00:22 INFO - TinderboxPrint: I/O write bytes / time
561,938,432 / 467,324 00:00:22 INFO - TinderboxPrint: CPU idle
8,273.8 (87.0%) 00:00:22 INFO - TinderboxPrint: CPU user
1,193.9 (12.5%) 00:00:22 INFO - TinderboxPrint: Swap in / out
0 / 0 00:00:22 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 267689984; Read time: 0; Write time: 272244 00:00:22 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294019072; Read time: 136; Write time: 195040 00:00:23 INFO - Running post-run listener: _upload_blobber_files 00:00:23 INFO - Blob upload gear active. 00:00:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:00:23 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 00:00:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:00:23 INFO - (blobuploader) - INFO - Open directory for files ... 00:00:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:00:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:00:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:00:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:00:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:00:24 INFO - (blobuploader) - INFO - Done attempting. 00:00:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:00:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:00:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:00:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:00:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:00:35 INFO - (blobuploader) - INFO - Done attempting. 00:00:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:00:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:00:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:00:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:00:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:00:37 INFO - (blobuploader) - INFO - Done attempting. 00:00:37 INFO - (blobuploader) - INFO - Iteration through files over. 00:00:37 INFO - Return code: 0 00:00:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:00:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:00:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7e3fb9edfcb16d0a1c3ba467b8fa94e740cbb9ab1c88dac4da73910ec7e86f96eb015669f218261f6be75206687d848f0e66f5be94c945ef6d0de836962c8cfa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0a97d70a83abc0958238703e81fcd260a7590ae23806b5b78235dfe916a7a96972758922895d5a2fe9665dadf9b9c7a3243bab74fc00b13d54e77e12d1dd900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1dfefc7c98eb0ae7ccaf5be54907b38d238136d81bc995fb422784feba69210501ff76a6530cb329e9ba4a291cbc64d311495b003f15c3244b82faa9b8a0d5bc"} 00:00:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:00:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:00:37 INFO - Contents: 00:00:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7e3fb9edfcb16d0a1c3ba467b8fa94e740cbb9ab1c88dac4da73910ec7e86f96eb015669f218261f6be75206687d848f0e66f5be94c945ef6d0de836962c8cfa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0a97d70a83abc0958238703e81fcd260a7590ae23806b5b78235dfe916a7a96972758922895d5a2fe9665dadf9b9c7a3243bab74fc00b13d54e77e12d1dd900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1dfefc7c98eb0ae7ccaf5be54907b38d238136d81bc995fb422784feba69210501ff76a6530cb329e9ba4a291cbc64d311495b003f15c3244b82faa9b8a0d5bc"} 00:00:37 INFO - Running post-run listener: copy_logs_to_upload_dir 00:00:37 INFO - Copying logs to upload dir... 00:00:37 INFO - mkdir: /builds/slave/test/build/upload/logs 00:00:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.317716 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-27 00:00:37.603959) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 00:00:37.607326) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7e3fb9edfcb16d0a1c3ba467b8fa94e740cbb9ab1c88dac4da73910ec7e86f96eb015669f218261f6be75206687d848f0e66f5be94c945ef6d0de836962c8cfa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0a97d70a83abc0958238703e81fcd260a7590ae23806b5b78235dfe916a7a96972758922895d5a2fe9665dadf9b9c7a3243bab74fc00b13d54e77e12d1dd900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1dfefc7c98eb0ae7ccaf5be54907b38d238136d81bc995fb422784feba69210501ff76a6530cb329e9ba4a291cbc64d311495b003f15c3244b82faa9b8a0d5bc"} build_url:https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/OnBZffB9Skm42P7pUVqdjw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7e3fb9edfcb16d0a1c3ba467b8fa94e740cbb9ab1c88dac4da73910ec7e86f96eb015669f218261f6be75206687d848f0e66f5be94c945ef6d0de836962c8cfa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0a97d70a83abc0958238703e81fcd260a7590ae23806b5b78235dfe916a7a96972758922895d5a2fe9665dadf9b9c7a3243bab74fc00b13d54e77e12d1dd900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1dfefc7c98eb0ae7ccaf5be54907b38d238136d81bc995fb422784feba69210501ff76a6530cb329e9ba4a291cbc64d311495b003f15c3244b82faa9b8a0d5bc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 00:00:37.632642) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 00:00:37.632978) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469601592.992376-1562421266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 00:00:37.716498) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 00:00:37.716803) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 00:00:37.717088) ========= ========= Total master_lag: 0.16 =========