builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1470092397.84 results: success (0) buildid: 20160801144049 builduid: db7da76c67cc46dba5d4b85b45f4946e revision: 6cc02bae02af28b83eaec57151ae72a52023af0f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:57.839670) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:57.840081) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:57.840355) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:57.866407) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:57.866707) ========= 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=4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 15:59:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2016-08-01 15:59:57 (18.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091438 ========= master_lag: 0.24 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:58.202241) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:58.202566) ========= 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=4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020786 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:58.242296) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:58.242604) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 6cc02bae02af28b83eaec57151ae72a52023af0f --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 6cc02bae02af28b83eaec57151ae72a52023af0f --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=4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 15:59:58,328 truncating revision to first 12 chars 2016-08-01 15:59:58,328 Setting DEBUG logging. 2016-08-01 15:59:58,328 attempt 1/10 2016-08-01 15:59:58,328 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/6cc02bae02af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 15:59:58,672 unpacking tar archive at: autoland-6cc02bae02af/testing/mozharness/ program finished with exit code 0 elapsedTime=0.789888 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:59.051795) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:59.052470) ========= script_repo_revision: 6cc02bae02af28b83eaec57151ae72a52023af0f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:59.053372) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:59.054052) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 15:59:59.067302) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-01 15:59:59.067563) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:59:59 INFO - MultiFileLogger online at 20160801 15:59:59 in /builds/slave/test 15:59:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 15:59:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:59:59 INFO - {'append_to_log': False, 15:59:59 INFO - 'base_work_dir': '/builds/slave/test', 15:59:59 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 15:59:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:59:59 INFO - 'branch': 'Autoland-Non-PGO', 15:59:59 INFO - 'buildbot_json_path': 'buildprops.json', 15:59:59 INFO - 'config_files': ('talos/linux_config.py',), 15:59:59 INFO - 'default_actions': ('clobber', 15:59:59 INFO - 'read-buildbot-config', 15:59:59 INFO - 'download-and-extract', 15:59:59 INFO - 'populate-webroot', 15:59:59 INFO - 'create-virtualenv', 15:59:59 INFO - 'install', 15:59:59 INFO - 'run-tests'), 15:59:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:59:59 INFO - 'download_minidump_stackwalk': True, 15:59:59 INFO - 'download_symbols': 'ondemand', 15:59:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:59:59 INFO - 'tooltool.py': '/tools/tooltool.py', 15:59:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:59:59 INFO - '/tools/misc-python/virtualenv.py')}, 15:59:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:59:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:59:59 INFO - 'installer_path': 'installer.exe', 15:59:59 INFO - 'log_level': 'info', 15:59:59 INFO - 'log_name': 'talos', 15:59:59 INFO - 'log_to_console': True, 15:59:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:59:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:59:59 INFO - 'opt_config_files': (), 15:59:59 INFO - 'pip_index': False, 15:59:59 INFO - 'sps_profile': False, 15:59:59 INFO - 'sps_profile_interval': 0, 15:59:59 INFO - 'suite': 'dromaeojs', 15:59:59 INFO - 'system_bits': '32', 15:59:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:59:59 INFO - 'title': 'talos-linux64-ix-030', 15:59:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:59:59 INFO - 'use_talos_json': True, 15:59:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:59:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:59:59 INFO - 'work_dir': 'build'} 15:59:59 INFO - [mozharness: 2016-08-01 22:59:59.243286Z] Running clobber step. 15:59:59 INFO - Running pre-action listener: _resource_record_pre_action 15:59:59 INFO - Running main action method: clobber 15:59:59 INFO - rmtree: /builds/slave/test/build 15:59:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:00:00 INFO - Running post-action listener: _resource_record_post_action 16:00:00 INFO - [mozharness: 2016-08-01 23:00:00.219399Z] Finished clobber step (success) 16:00:00 INFO - [mozharness: 2016-08-01 23:00:00.219500Z] Running read-buildbot-config step. 16:00:00 INFO - Running pre-action listener: _resource_record_pre_action 16:00:00 INFO - Running main action method: read_buildbot_config 16:00:00 INFO - Using buildbot properties: 16:00:00 INFO - { 16:00:00 INFO - "project": "", 16:00:00 INFO - "product": "firefox", 16:00:00 INFO - "script_repo_revision": "production", 16:00:00 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 16:00:00 INFO - "repository": "", 16:00:00 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 16:00:00 INFO - "buildid": "20160801144049", 16:00:00 INFO - "pgo_build": "False", 16:00:00 INFO - "basedir": "/builds/slave/test", 16:00:00 INFO - "buildnumber": 282, 16:00:00 INFO - "slavename": "talos-linux64-ix-030", 16:00:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:00:00 INFO - "platform": "ubuntu64_hw", 16:00:00 INFO - "scheduler": "tests-autoland-linux64-talos", 16:00:00 INFO - "branch": "autoland", 16:00:00 INFO - "repo_path": "integration/autoland", 16:00:00 INFO - "revision": "6cc02bae02af28b83eaec57151ae72a52023af0f", 16:00:00 INFO - "stage_platform": "linux64", 16:00:00 INFO - "builduid": "db7da76c67cc46dba5d4b85b45f4946e", 16:00:00 INFO - "slavebuilddir": "test" 16:00:00 INFO - } 16:00:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:00:00 INFO - Running post-action listener: _resource_record_post_action 16:00:00 INFO - [mozharness: 2016-08-01 23:00:00.221930Z] Finished read-buildbot-config step (success) 16:00:00 INFO - [mozharness: 2016-08-01 23:00:00.222020Z] Running download-and-extract step. 16:00:00 INFO - Running pre-action listener: _resource_record_pre_action 16:00:00 INFO - Running main action method: download_and_extract 16:00:00 INFO - mkdir: /builds/slave/test/build/tests 16:00: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')]} 16:00:00 INFO - https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:00:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:00:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:00:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:01 INFO - Downloaded 1361 bytes. 16:00:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:00:01 INFO - Using the following test package requirements: 16:00:01 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:00:01 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:00:01 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:00:01 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'jsshell-linux-x86_64.zip'], 16:00:01 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:00:01 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:00:01 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:00:01 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:00:01 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:00:01 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:00:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:00:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:01 INFO - https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:00:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:00:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:00:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:02 INFO - Downloaded 20237696 bytes. 16:00:02 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'] 16:00:02 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 16:00:03 INFO - Return code: 0 16:00:03 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 16:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:03 INFO - https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:00:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:00:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:00:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:06 INFO - Downloaded 20237696 bytes. 16:00:06 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'] 16:00:06 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 16:00:08 INFO - Return code: 0 16:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:08 INFO - https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:00:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:00:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:00:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:09 INFO - Downloaded 14413723 bytes. 16:00:09 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'] 16:00:09 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 16:00:10 INFO - Return code: 0 16:00: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')]} 16:00:10 INFO - https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:00:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:00:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:00:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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 16:00:11 INFO - Downloaded 55673678 bytes. 16:00:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:00:11 INFO - mkdir: /builds/slave/test/properties 16:00:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:00:11 INFO - Writing to file /builds/slave/test/properties/build_url 16:00:11 INFO - Contents: 16:00:11 INFO - build_url:https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:00:12 INFO - Running post-action listener: _resource_record_post_action 16:00:12 INFO - Running post-action listener: set_extra_try_arguments 16:00:12 INFO - [mozharness: 2016-08-01 23:00:12.266452Z] Finished download-and-extract step (success) 16:00:12 INFO - [mozharness: 2016-08-01 23:00:12.266687Z] Running populate-webroot step. 16:00:12 INFO - Running pre-action listener: _resource_record_pre_action 16:00:12 INFO - Running main action method: populate_webroot 16:00:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:00:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:00:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:00:12 INFO - '--tppagecycles', 16:00:12 INFO - '7'], 16:00:12 INFO - 'tests': ['tsvgm']}}, 16:00:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:00:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:00:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:00:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:00:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['damp', 'tps']}, 16:00:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'tests': ['damp', 'tps']}, 16:00:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['dromaeo_dom']}, 16:00:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:00:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['basic_compositor_video']}, 16:00:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:00:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other-e10s': {'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['a11yr', 16:00:12 INFO - 'ts_paint', 16:00:12 INFO - 'tpaint', 16:00:12 INFO - 'sessionrestore', 16:00:12 INFO - 'sessionrestore_no_auto_restore', 16:00:12 INFO - 'tabpaint']}, 16:00:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['tsvgx', 16:00:12 INFO - 'tsvgr_opacity', 16:00:12 INFO - 'tart', 16:00:12 INFO - 'tscrollx', 16:00:12 INFO - 'cart']}, 16:00:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:00:12 INFO - 'tsvgr_opacity', 16:00:12 INFO - 'tart', 16:00:12 INFO - 'tscrollx', 16:00:12 INFO - 'cart']}, 16:00:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--disable-e10s'], 16:00:12 INFO - 'tests': ['tp5o']}, 16:00:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'tests': ['tp5o']}, 16:00:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--disable-e10s', 16:00:12 INFO - '--xperf_path', 16:00:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:12 INFO - 'tests': ['tp5n']}, 16:00:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:12 INFO - 'talos_options': ['--xperf_path', 16:00:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:12 INFO - 'tests': ['tp5n']}}, 16:00:12 INFO - 'talos.zip': {'path': '', 16:00:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:00:12 INFO - Running post-action listener: _resource_record_post_action 16:00:12 INFO - [mozharness: 2016-08-01 23:00:12.284812Z] Finished populate-webroot step (success) 16:00:12 INFO - [mozharness: 2016-08-01 23:00:12.284901Z] Running create-virtualenv step. 16:00:12 INFO - Running pre-action listener: _resource_record_pre_action 16:00:12 INFO - Running main action method: create_virtualenv 16:00:12 INFO - Creating virtualenv /builds/slave/test/build/venv 16:00:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:00:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:00:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:00:12 INFO - Using real prefix '/usr' 16:00:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:00:12 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:14 INFO - Installing pip.................done. 16:00:14 INFO - Return code: 0 16:00:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:00:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:00:14 INFO - Using env: {'DISPLAY': ':0', 16:00:14 INFO - 'HOME': '/home/cltbld', 16:00:14 INFO - 'LANG': 'en_US.UTF-8', 16:00:14 INFO - 'LANGUAGE': 'en_US:en', 16:00:14 INFO - 'LOGNAME': 'cltbld', 16:00:14 INFO - 'MAIL': '/var/mail/cltbld', 16:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:14 INFO - 'MOZ_NO_REMOTE': '1', 16:00:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:14 INFO - 'NO_EM_RESTART': '1', 16:00:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:14 INFO - 'PWD': '/builds/slave/test', 16:00:14 INFO - 'SHELL': '/bin/bash', 16:00:14 INFO - 'SHLVL': '1', 16:00:14 INFO - 'TERM': 'linux', 16:00:14 INFO - 'TMOUT': '86400', 16:00:14 INFO - 'USER': 'cltbld', 16:00:14 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:14 INFO - '_': '/tools/buildbot/bin/python'} 16:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:00:15 INFO - Cleaning up... 16:00:15 INFO - Return code: 0 16:00:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:00:15 INFO - Using env: {'DISPLAY': ':0', 16:00:15 INFO - 'HOME': '/home/cltbld', 16:00:15 INFO - 'LANG': 'en_US.UTF-8', 16:00:15 INFO - 'LANGUAGE': 'en_US:en', 16:00:15 INFO - 'LOGNAME': 'cltbld', 16:00:15 INFO - 'MAIL': '/var/mail/cltbld', 16:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:15 INFO - 'MOZ_NO_REMOTE': '1', 16:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:15 INFO - 'NO_EM_RESTART': '1', 16:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:15 INFO - 'PWD': '/builds/slave/test', 16:00:15 INFO - 'SHELL': '/bin/bash', 16:00:15 INFO - 'SHLVL': '1', 16:00:15 INFO - 'TERM': 'linux', 16:00:15 INFO - 'TMOUT': '86400', 16:00:15 INFO - 'USER': 'cltbld', 16:00:15 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:15 INFO - '_': '/tools/buildbot/bin/python'} 16:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:15 INFO - Downloading/unpacking psutil>=3.1.1 16:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:17 INFO - Installing collected packages: psutil 16:00:17 INFO - Running setup.py install for psutil 16:00:17 INFO - building 'psutil._psutil_linux' extension 16:00:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:00:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:00:17 INFO - building 'psutil._psutil_posix' extension 16:00:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:00:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:00:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:17 INFO - Successfully installed psutil 16:00:17 INFO - Cleaning up... 16:00:17 INFO - Return code: 0 16:00:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:17 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 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:00:17 INFO - Using env: {'DISPLAY': ':0', 16:00:17 INFO - 'HOME': '/home/cltbld', 16:00:17 INFO - 'LANG': 'en_US.UTF-8', 16:00:17 INFO - 'LANGUAGE': 'en_US:en', 16:00:17 INFO - 'LOGNAME': 'cltbld', 16:00:17 INFO - 'MAIL': '/var/mail/cltbld', 16:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:17 INFO - 'MOZ_NO_REMOTE': '1', 16:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:17 INFO - 'NO_EM_RESTART': '1', 16:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:17 INFO - 'PWD': '/builds/slave/test', 16:00:17 INFO - 'SHELL': '/bin/bash', 16:00:17 INFO - 'SHLVL': '1', 16:00:17 INFO - 'TERM': 'linux', 16:00:17 INFO - 'TMOUT': '86400', 16:00:17 INFO - 'USER': 'cltbld', 16:00:17 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:17 INFO - '_': '/tools/buildbot/bin/python'} 16:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:00:19 INFO - Installing collected packages: mozsystemmonitor 16:00:19 INFO - Running setup.py install for mozsystemmonitor 16:00:20 INFO - Successfully installed mozsystemmonitor 16:00:20 INFO - Cleaning up... 16:00:20 INFO - Return code: 0 16:00:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:00:20 INFO - Using env: {'DISPLAY': ':0', 16:00:20 INFO - 'HOME': '/home/cltbld', 16:00:20 INFO - 'LANG': 'en_US.UTF-8', 16:00:20 INFO - 'LANGUAGE': 'en_US:en', 16:00:20 INFO - 'LOGNAME': 'cltbld', 16:00:20 INFO - 'MAIL': '/var/mail/cltbld', 16:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:20 INFO - 'MOZ_NO_REMOTE': '1', 16:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:20 INFO - 'NO_EM_RESTART': '1', 16:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:20 INFO - 'PWD': '/builds/slave/test', 16:00:20 INFO - 'SHELL': '/bin/bash', 16:00:20 INFO - 'SHLVL': '1', 16:00:20 INFO - 'TERM': 'linux', 16:00:20 INFO - 'TMOUT': '86400', 16:00:20 INFO - 'USER': 'cltbld', 16:00:20 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:20 INFO - '_': '/tools/buildbot/bin/python'} 16:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:20 INFO - Downloading/unpacking jsonschema==2.5.1 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00: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 16:00:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:00:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:22 INFO - Downloading functools32-3.2.3-2.tar.gz 16:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:00:22 INFO - warning: no files found matching '*.txt' 16:00:22 INFO - no previously-included directories found matching 'build' 16:00:22 INFO - no previously-included directories found matching 'dist' 16:00:22 INFO - no previously-included directories found matching '.git*' 16:00:22 INFO - Installing collected packages: jsonschema, functools32 16:00:22 INFO - Running setup.py install for functools32 16:00:22 INFO - warning: no files found matching '*.txt' 16:00:22 INFO - no previously-included directories found matching 'build' 16:00:22 INFO - no previously-included directories found matching 'dist' 16:00:22 INFO - no previously-included directories found matching '.git*' 16:00:22 INFO - Successfully installed jsonschema functools32 16:00:22 INFO - Cleaning up... 16:00:22 INFO - Return code: 0 16:00:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:22 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 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:22 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 16:00:22 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 16:00:22 INFO - Using env: {'DISPLAY': ':0', 16:00:22 INFO - 'HOME': '/home/cltbld', 16:00:22 INFO - 'LANG': 'en_US.UTF-8', 16:00:22 INFO - 'LANGUAGE': 'en_US:en', 16:00:22 INFO - 'LOGNAME': 'cltbld', 16:00:22 INFO - 'MAIL': '/var/mail/cltbld', 16:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:22 INFO - 'MOZ_NO_REMOTE': '1', 16:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:22 INFO - 'NO_EM_RESTART': '1', 16:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:22 INFO - 'PWD': '/builds/slave/test', 16:00:22 INFO - 'SHELL': '/bin/bash', 16:00:22 INFO - 'SHLVL': '1', 16:00:22 INFO - 'TERM': 'linux', 16:00:22 INFO - 'TMOUT': '86400', 16:00:22 INFO - 'USER': 'cltbld', 16:00:22 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:22 INFO - '_': '/tools/buildbot/bin/python'} 16:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:00:22 INFO - Cleaning up... 16:00:22 INFO - Return code: 0 16:00:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:22 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 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:00:22 INFO - Using env: {'DISPLAY': ':0', 16:00:22 INFO - 'HOME': '/home/cltbld', 16:00:22 INFO - 'LANG': 'en_US.UTF-8', 16:00:22 INFO - 'LANGUAGE': 'en_US:en', 16:00:22 INFO - 'LOGNAME': 'cltbld', 16:00:22 INFO - 'MAIL': '/var/mail/cltbld', 16:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:22 INFO - 'MOZ_NO_REMOTE': '1', 16:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:22 INFO - 'NO_EM_RESTART': '1', 16:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:22 INFO - 'PWD': '/builds/slave/test', 16:00:22 INFO - 'SHELL': '/bin/bash', 16:00:22 INFO - 'SHLVL': '1', 16:00:22 INFO - 'TERM': 'linux', 16:00:22 INFO - 'TMOUT': '86400', 16:00:22 INFO - 'USER': 'cltbld', 16:00:22 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:22 INFO - '_': '/tools/buildbot/bin/python'} 16:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:23 INFO - Downloading/unpacking blobuploader==1.2.4 16:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:24 INFO - Downloading blobuploader-1.2.4.tar.gz 16:00:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:00:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:00:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:25 INFO - Downloading docopt-0.6.1.tar.gz 16:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:00:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:00:25 INFO - Running setup.py install for blobuploader 16:00:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:00:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:00:25 INFO - Running setup.py install for requests 16:00:26 INFO - Running setup.py install for docopt 16:00:26 INFO - Successfully installed blobuploader requests docopt 16:00:26 INFO - Cleaning up... 16:00:26 INFO - Return code: 0 16:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:26 INFO - Using env: {'DISPLAY': ':0', 16:00:26 INFO - 'HOME': '/home/cltbld', 16:00:26 INFO - 'LANG': 'en_US.UTF-8', 16:00:26 INFO - 'LANGUAGE': 'en_US:en', 16:00:26 INFO - 'LOGNAME': 'cltbld', 16:00:26 INFO - 'MAIL': '/var/mail/cltbld', 16:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:26 INFO - 'MOZ_NO_REMOTE': '1', 16:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:26 INFO - 'NO_EM_RESTART': '1', 16:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:26 INFO - 'PWD': '/builds/slave/test', 16:00:26 INFO - 'SHELL': '/bin/bash', 16:00:26 INFO - 'SHLVL': '1', 16:00:26 INFO - 'TERM': 'linux', 16:00:26 INFO - 'TMOUT': '86400', 16:00:26 INFO - 'USER': 'cltbld', 16:00:26 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:26 INFO - '_': '/tools/buildbot/bin/python'} 16:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:26 INFO - Running setup.py (path:/tmp/pip-q4MYtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:26 INFO - Running setup.py (path:/tmp/pip-iByOAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:26 INFO - Running setup.py (path:/tmp/pip-zMUXvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:26 INFO - Running setup.py (path:/tmp/pip-BpDiWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:26 INFO - Running setup.py (path:/tmp/pip-stsyUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:26 INFO - Running setup.py (path:/tmp/pip-uV9DAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:26 INFO - Running setup.py (path:/tmp/pip-zNP3nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:27 INFO - Running setup.py (path:/tmp/pip-q3YU_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:27 INFO - Running setup.py (path:/tmp/pip-vvwThQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:27 INFO - Running setup.py (path:/tmp/pip-YAqPc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:27 INFO - Running setup.py (path:/tmp/pip-F2LdSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:27 INFO - Running setup.py (path:/tmp/pip-mwBooU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:27 INFO - Running setup.py (path:/tmp/pip-6kloNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:27 INFO - Running setup.py (path:/tmp/pip-wCiHAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:27 INFO - Running setup.py (path:/tmp/pip-pPjcY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:27 INFO - Running setup.py (path:/tmp/pip-lA7Ljv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:27 INFO - Running setup.py (path:/tmp/pip-GL0n_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:00:27 INFO - Running setup.py install for manifestparser 16:00:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:00:27 INFO - Running setup.py install for mozcrash 16:00:27 INFO - Running setup.py install for mozdebug 16:00:28 INFO - Running setup.py install for mozdevice 16:00:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Running setup.py install for mozfile 16:00:28 INFO - Running setup.py install for mozhttpd 16:00:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Running setup.py install for mozinfo 16:00:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Running setup.py install for mozInstall 16:00:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Running setup.py install for mozleak 16:00:28 INFO - Running setup.py install for mozlog 16:00:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Running setup.py install for moznetwork 16:00:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:00:28 INFO - Running setup.py install for mozprocess 16:00:29 INFO - Running setup.py install for mozprofile 16:00:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:00:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:00:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:00:29 INFO - Running setup.py install for mozrunner 16:00:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:00:29 INFO - Running setup.py install for mozscreenshot 16:00:29 INFO - Running setup.py install for moztest 16:00:29 INFO - Running setup.py install for mozversion 16:00:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:00:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:00:29 INFO - Cleaning up... 16:00:29 INFO - Return code: 0 16:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:29 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 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:29 INFO - Using env: {'DISPLAY': ':0', 16:00:29 INFO - 'HOME': '/home/cltbld', 16:00:29 INFO - 'LANG': 'en_US.UTF-8', 16:00:29 INFO - 'LANGUAGE': 'en_US:en', 16:00:29 INFO - 'LOGNAME': 'cltbld', 16:00:29 INFO - 'MAIL': '/var/mail/cltbld', 16:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:29 INFO - 'MOZ_NO_REMOTE': '1', 16:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:29 INFO - 'NO_EM_RESTART': '1', 16:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:29 INFO - 'PWD': '/builds/slave/test', 16:00:29 INFO - 'SHELL': '/bin/bash', 16:00:29 INFO - 'SHLVL': '1', 16:00:29 INFO - 'TERM': 'linux', 16:00:29 INFO - 'TMOUT': '86400', 16:00:29 INFO - 'USER': 'cltbld', 16:00:29 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:29 INFO - '_': '/tools/buildbot/bin/python'} 16:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:29 INFO - Running setup.py (path:/tmp/pip-yHfw1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:29 INFO - Running setup.py (path:/tmp/pip-9dWjHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:29 INFO - Running setup.py (path:/tmp/pip-VUbEmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:30 INFO - Running setup.py (path:/tmp/pip-BTVpSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:30 INFO - Running setup.py (path:/tmp/pip-e8Rm1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:30 INFO - Running setup.py (path:/tmp/pip-QOLNUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:30 INFO - Running setup.py (path:/tmp/pip-RUFJBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:30 INFO - Running setup.py (path:/tmp/pip-3t_APA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:30 INFO - Running setup.py (path:/tmp/pip-1ctd9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:30 INFO - Running setup.py (path:/tmp/pip-Y16HRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:30 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)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:30 INFO - Running setup.py (path:/tmp/pip-akPsxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:30 INFO - Running setup.py (path:/tmp/pip-3AN8IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:30 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)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:30 INFO - Running setup.py (path:/tmp/pip-mxogsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:30 INFO - Running setup.py (path:/tmp/pip-mCf1Yl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:30 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)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:30 INFO - Running setup.py (path:/tmp/pip-O9GMdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:30 INFO - Running setup.py (path:/tmp/pip-1GuZeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:30 INFO - Running setup.py (path:/tmp/pip-6Fz2z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:00:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:32 INFO - Downloading blessings-1.6.tar.gz 16:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:32 INFO - Installing collected packages: blessings 16:00:32 INFO - Running setup.py install for blessings 16:00:33 INFO - Successfully installed blessings 16:00:33 INFO - Cleaning up... 16:00:33 INFO - Return code: 0 16:00:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:00:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:33 INFO - Reading from file tmpfile_stdout 16:00:33 INFO - Current package versions: 16:00:33 INFO - argparse == 1.2.1 16:00:33 INFO - blessings == 1.6 16:00:33 INFO - blobuploader == 1.2.4 16:00:33 INFO - docopt == 0.6.1 16:00:33 INFO - functools32 == 3.2.3-2 16:00:33 INFO - jsonschema == 2.5.1 16:00:33 INFO - manifestparser == 1.1 16:00:33 INFO - mozInstall == 1.12 16:00:33 INFO - mozcrash == 0.17 16:00:33 INFO - mozdebug == 0.1 16:00:33 INFO - mozdevice == 0.48 16:00:33 INFO - mozfile == 1.2 16:00:33 INFO - mozhttpd == 0.7 16:00:33 INFO - mozinfo == 0.9 16:00:33 INFO - mozleak == 0.1 16:00:33 INFO - mozlog == 3.3 16:00:33 INFO - moznetwork == 0.27 16:00:33 INFO - mozprocess == 0.23 16:00:33 INFO - mozprofile == 0.28 16:00:33 INFO - mozrunner == 6.12 16:00:33 INFO - mozscreenshot == 0.1 16:00:33 INFO - mozsystemmonitor == 0.3 16:00:33 INFO - moztest == 0.7 16:00:33 INFO - mozversion == 1.4 16:00:33 INFO - psutil == 3.1.1 16:00:33 INFO - requests == 1.2.3 16:00:33 INFO - wsgiref == 0.1.2 16:00:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:33 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 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:33 INFO - Using env: {'DISPLAY': ':0', 16:00:33 INFO - 'HOME': '/home/cltbld', 16:00:33 INFO - 'LANG': 'en_US.UTF-8', 16:00:33 INFO - 'LANGUAGE': 'en_US:en', 16:00:33 INFO - 'LOGNAME': 'cltbld', 16:00:33 INFO - 'MAIL': '/var/mail/cltbld', 16:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:33 INFO - 'MOZ_NO_REMOTE': '1', 16:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:33 INFO - 'NO_EM_RESTART': '1', 16:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:33 INFO - 'PWD': '/builds/slave/test', 16:00:33 INFO - 'SHELL': '/bin/bash', 16:00:33 INFO - 'SHLVL': '1', 16:00:33 INFO - 'TERM': 'linux', 16:00:33 INFO - 'TMOUT': '86400', 16:00:33 INFO - 'USER': 'cltbld', 16:00:33 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:33 INFO - '_': '/tools/buildbot/bin/python'} 16:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:00:33 INFO - Cleaning up... 16:00:33 INFO - Return code: 0 16:00:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:33 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 0x145e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x171f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:00:33 INFO - Using env: {'DISPLAY': ':0', 16:00:33 INFO - 'HOME': '/home/cltbld', 16:00:33 INFO - 'LANG': 'en_US.UTF-8', 16:00:33 INFO - 'LANGUAGE': 'en_US:en', 16:00:33 INFO - 'LOGNAME': 'cltbld', 16:00:33 INFO - 'MAIL': '/var/mail/cltbld', 16:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:33 INFO - 'MOZ_NO_REMOTE': '1', 16:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:33 INFO - 'NO_EM_RESTART': '1', 16:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:33 INFO - 'PWD': '/builds/slave/test', 16:00:33 INFO - 'SHELL': '/bin/bash', 16:00:33 INFO - 'SHLVL': '1', 16:00:33 INFO - 'TERM': 'linux', 16:00:33 INFO - 'TMOUT': '86400', 16:00:33 INFO - 'USER': 'cltbld', 16:00:33 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:33 INFO - '_': '/tools/buildbot/bin/python'} 16:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:00:33 INFO - Cleaning up... 16:00:33 INFO - Return code: 0 16:00:33 INFO - Running post-action listener: _resource_record_post_action 16:00:33 INFO - Running post-action listener: _start_resource_monitoring 16:00:33 INFO - Starting resource monitoring. 16:00:33 INFO - [mozharness: 2016-08-01 23:00:33.863090Z] Finished create-virtualenv step (success) 16:00:33 INFO - [mozharness: 2016-08-01 23:00:33.863530Z] Running install step. 16:00:33 INFO - Running pre-action listener: _resource_record_pre_action 16:00:33 INFO - Running main action method: install 16:00:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:34 INFO - Reading from file tmpfile_stdout 16:00:34 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:00:34 INFO - Reading from file tmpfile_stdout 16:00:34 INFO - Output received: 16:00:34 INFO - Usage: mozinstall [options] installer 16:00:34 INFO - Options: 16:00:34 INFO - -h, --help show this help message and exit 16:00:34 INFO - -d DEST, --destination=DEST 16:00:34 INFO - Directory to install application into. [default: 16:00:34 INFO - "/builds/slave/test"] 16:00:34 INFO - --app=APP Application being installed. [default: firefox] 16:00:34 INFO - mkdir: /builds/slave/test/build/application 16:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:00:50 INFO - Reading from file tmpfile_stdout 16:00:50 INFO - Output received: 16:00:50 INFO - /builds/slave/test/build/application/firefox/firefox 16:00:50 INFO - Running post-action listener: _resource_record_post_action 16:00:50 INFO - [mozharness: 2016-08-01 23:00:50.203104Z] Finished install step (success) 16:00:50 INFO - [mozharness: 2016-08-01 23:00:50.203382Z] Running run-tests step. 16:00:50 INFO - Running pre-action listener: _resource_record_pre_action 16:00:50 INFO - Running main action method: run_tests 16:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:00:50 INFO - Python 2.7.3 16:00:50 INFO - Return code: 0 16:00:50 INFO - grabbing minidump binary from tooltool 16:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:50 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 0x171d3b0>, '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 0x171e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x171e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:00:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:00:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:00:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:00:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:50 INFO - Return code: 0 16:00:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:00:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:00:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:00:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:00:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:00:50 INFO - Using env: {'DISPLAY': ':0', 16:00:50 INFO - 'HOME': '/home/cltbld', 16:00:50 INFO - 'LANG': 'en_US.UTF-8', 16:00:50 INFO - 'LANGUAGE': 'en_US:en', 16:00:50 INFO - 'LOGNAME': 'cltbld', 16:00:50 INFO - 'MAIL': '/var/mail/cltbld', 16:00:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:00:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:50 INFO - 'MOZ_NO_REMOTE': '1', 16:00:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:50 INFO - 'NO_EM_RESTART': '1', 16:00:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:50 INFO - 'PWD': '/builds/slave/test', 16:00:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:00:50 INFO - 'SHELL': '/bin/bash', 16:00:50 INFO - 'SHLVL': '1', 16:00:50 INFO - 'TERM': 'linux', 16:00:50 INFO - 'TMOUT': '86400', 16:00:50 INFO - 'USER': 'cltbld', 16:00:50 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225', 16:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:50 INFO - '_': '/tools/buildbot/bin/python'} 16:00:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:00:50 INFO - mozversion application_buildid: 20160801144049 16:00:50 INFO - mozversion application_changeset: 6cc02bae02af28b83eaec57151ae72a52023af0f 16:00:50 INFO - mozversion application_display_name: Nightly 16:00:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:00:50 INFO - mozversion application_name: Firefox 16:00:50 INFO - mozversion application_remotingname: firefox 16:00:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:00:50 INFO - mozversion application_vendor: Mozilla 16:00:50 INFO - mozversion application_version: 50.0a1 16:00:50 INFO - mozversion platform_buildid: 20160801144049 16:00:50 INFO - mozversion platform_changeset: 6cc02bae02af28b83eaec57151ae72a52023af0f 16:00:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:00:50 INFO - mozversion platform_version: 50.0a1 16:00:50 INFO - using testdate: 1470092450 16:00:50 INFO - actual date: 1470092450 16:00:50 INFO - starting webserver on 'localhost:41078' 16:00:50 INFO - SUITE-START | Running 2 tests 16:00:50 INFO - TEST-START | dromaeo_css 16:00:50 INFO - operating with platform_type : linux_ 16:00:50 INFO - Initialising browser for dromaeo_css test... 16:00:50 INFO - TEST-INFO | started process 23541 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo0JZDe/profile http://localhost:41078/getInfo.html) 16:00:54 INFO - PROCESS | 23541 | __metrics Screen width/height:1600/1200 16:00:54 INFO - PROCESS | 23541 | colorDepth:24 16:00:54 INFO - PROCESS | 23541 | Browser inner width/height: 1024/697 16:00:54 INFO - PROCESS | 23541 | __metrics 16:00:55 INFO - PROCESS | 23541 | 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" 16:00:55 INFO - PROCESS | 23541 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:00:56 INFO - TEST-INFO | 23541: exit 0 16:00:56 INFO - Browser initialized. 16:00:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:00:56 INFO - TEST-INFO | started process 23618 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo0JZDe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:08 INFO - PROCESS | 23618 | RSS: Main: 157749248 16:01:08 INFO - PROCESS | 23618 | 16:01:59 INFO - PROCESS | 23618 | 1470092519803 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:04:12 INFO - PROCESS | 23618 | Cycle 1(1): loaded http://localhost:41078/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41078/tests/dromaeo/cssquery-ext.html) 16:04:13 INFO - PROCESS | 23618 | RSS: Main: 275435520 16:04:13 INFO - PROCESS | 23618 | 16:07:16 INFO - PROCESS | 23618 | Cycle 1(1): loaded http://localhost:41078/tests/dromaeo/cssquery-ext.html (next: http://localhost:41078/tests/dromaeo/cssquery-jquery.html) 16:07:16 INFO - PROCESS | 23618 | RSS: Main: 244666368 16:07:16 INFO - PROCESS | 23618 | 16:10:19 INFO - PROCESS | 23618 | Cycle 1(1): loaded http://localhost:41078/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41078/tests/dromaeo/cssquery-mootools.html) 16:10:19 INFO - PROCESS | 23618 | RSS: Main: 187981824 16:10:19 INFO - PROCESS | 23618 | 16:13:21 INFO - PROCESS | 23618 | Cycle 1(1): loaded http://localhost:41078/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41078/tests/dromaeo/cssquery-prototype.html) 16:13:22 INFO - PROCESS | 23618 | RSS: Main: 189968384 16:13:22 INFO - PROCESS | 23618 | 16:16:25 INFO - PROCESS | 23618 | Cycle 1(1): loaded http://localhost:41078/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41078/tests/dromaeo/cssquery-yui.html) 16:16:25 INFO - PROCESS | 23618 | RSS: Main: 189997056 16:16:25 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | Cycle 1(1): loaded http://localhost:41078/tests/dromaeo/cssquery-yui.html (next: http://localhost:41078/tests/dromaeo/cssquery-dojo.html) 16:19:29 INFO - PROCESS | 23618 | RSS: Main: 256774144 16:19:29 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | __start_tp_report 16:19:29 INFO - PROCESS | 23618 | _x_x_mozilla_page_load 16:19:29 INFO - PROCESS | 23618 | _x_x_mozilla_page_load_details 16:19:29 INFO - PROCESS | 23618 | |i|pagename|runs| 16:19:29 INFO - PROCESS | 23618 | |0;dojo.html;2608.1914606471996;2620.72482389349;2646.3119588906884;2660.720624334445;2666.7599916007543;4574.130915126132;4649.349091127245;4657.7205367677925;4705.317728929307;4715.5048719884435;4618.376519169911;4627.560381763736;4670.439547254335;4670.649701272401;4704;4249.46881639795;4290.935635965463;4312.654987600984;4373.475182978047;4377.080813029268;4280.807363668642;4350.32569951658;4393.978030109845;4430.401895744081;4434.423524941753;4186.330187170069;4224.66202703783;4241.49102107746;4356.89107772305;4390.429244198233;4264.211120942636;4286.721363111388;4287.785610719451;4288.785560721983;4294.978525107385;4184.03767133561;4220.683448741357;4255.340422234559;4263.658907287409;4270.615644592001;4615.976920115378;4650.581447669692;4667.556582124693;4697.436307643105;4702.6002789762915;5021.397432308147;5036.7733451994745;5085.516875896821;5090.898182036375;5096.031753966735;4899.755012249373;5009.649324547283;5059.392872855281;5092.821751238689;5113.284140220372;4759.381280433521;4776.09254241693;4803.18345881201;4813.4945830687275;4817.397825271841;5040;5262.289590905255;5264.078786212397;5269;5272.736363181825;4725.385699858996;4859.635527335464;4911.238757992517;4946.975265123651;5009;5072.416672082684;5115.820946266863;5122.92315615266;5126.128558145124;5127.564157046618;5030.949690503121;5033.874153146201;5038.992201559703;5042.319286896295;5068.619853510928;4854.296127061627;4938.802447902115;4973.1297023020825;4997.400311962588;5064.1897296433135;4348.586884245991;4360.302351623725;4360.803763830571;4363.694541382073;4409.272470042407;3790.753601015925;3792.924141517154;3809.4285857121654;3815.389537673959;3829.4064420014383;4218.6835987300465;4259.97870010648;4326.2212801696;4361.694681372273;4362.585554372329;5093.159628661304;5093.337866077461;5099;5118.155504341742;5141.53726164647;4389.912201755947;4394.209042372339;4394.516603173648;4427.092446048552;4445.355423463579;4437.400950871655;4566.474855391606;4597.655175861823;4604.470485894098;4635.165670179333;4778.498257682893;4797.712137271775;4807.903841923072;4844.273358996108;4849.660523763303;5106.412762532356;5110.463401342806;5115.948840511547;5116.565091967224;5117.2835802986865;4540.2054640438455;4592.793324300348;4711.175544279806;4746.335513028248;4807.495213002724;5053.949460505348;5055.418626857811;5057.519535644108;5074.797008119634;5077.339945807022;5108.055009823194;5124.538791508633;5124.7950081996305;5126.23106534008;5169.095408303607;4820.71075735457;4899.632527560376;4940.876478087933;4966.428860681068;4979.701217926936;4957.405111386656;4975.353204083446;4976.8755781105765;5009.7996080157845;5024.698518088926;4870.610351171968;4930.926036109389;4932.728699921395;4934.01319736047;4937.284093806306;4769.3084502746215;4904.460509343898;4940.6788558743565;4977.776000079933;4988.401391833003;4848.5636292733825;4851.272309153655;4856.077345304381;4859.684120532154;4884.48712885142 16:19:29 INFO - PROCESS | 23618 | |1;ext.html;560.5907687388207;569.4703925349423;573.9942600573994;576.9163471296662;581.255992329818;28659.140225793235;38559.61440385595;39838.80080599593;39894.20211595766;43887.12225755483;40138.7993060035;40344.193116137656;40585.39121913166;40587.99999999993;40623.59376406235;7648.120466146394;7712.807179820521;7766.766996990071;7789.805254868617;7793.376529877596;7330.780076597684;9746.756331091708;9932.801343973117;9946.950265248664;10353.948230258877;10019.499025048755;10082.39505629661;10115.44365059925;10136;10141.746456338577;9915.157211637019;9981.500924953796;9982.550785214682;9986.800263994752;9988.101070903616;9830.508474576243;9897.109260166548;9902.059304366147;9902.60389584423;9910.504474776304;37880.242395151945;38160;38189.80905095484;38218.617813822064;38266.61733382686;2867.899623513167;3015;3027.1826606816035;3051.5727798108283;3154.984225078883;28741.85629071862;28860.711392886224;29427.11718648444;29449;30095.69904300951;3421.760476766627;3481.1993241554333;3487.494313324554;3543.114221444639;3637.018005138598;248443;250064;251457;251489;251813.740931296;52566.73716631392;53032;53146.73426632919;53236.46763532393;53239.20141197885;32534.674653253165;33218.83390583032;33330;35589.288214235574;35634.82182589071;15220.619484512976;15532.689346213012;15555.844441555666;15561.14413707257;15565.688686226213;9560.76098097553;10035.448050357301;10145.492725363702;10156.441395723159;10438.947805261078;17258.913705431394;17931.103444827706;17936.39922211272;17984.37054703079;18255.361062362754;6749.291324410964;6752.5948443093575;6780.355866192704;6942.444604431634;6956.339147780953;808.401782680812;835.3609488741118;889.573004957611;900.7928176519438;921.3642586615214;28153.24881695388;28488.572671409944;29186.416271674872;29205.85397073001;29391.55912661312;929.4284015330658;935.9017303183203;946.7491114854569;1007.2546315726456;1061.9256652034437;14560.708785824225;14989.550313490612;15268.236588170546;15276.9905432417;15410.229488525529;400297;404117;405574;406323;406494;48958.26562601564;49575;49627.75186124046;49663.75168124209;49790.751046244535;1083.257968291707;1091.8198497248025;1161.1523587780966;1163.4299193395345;1200.351810022578;27514.312142196606;28799.136025919255;28963.710362896945;29066.12801615955;29452.852735736185;41251.1749764997;41786.58213417827;41827.58172418237;41828.58171418247;41918;293632.5730684163;293771;294490;294854.1543753691;295663;24945.875270623532;25361.873190633927;25746.485070298862;25765.227043188734;27141;41482;41737.37393939032;41971.37042944419;42250;43033;35530.644693552735;35631.82184089063;35736;35928.64071359253;36472.452913205794;35462.29075418529;35902.820485897406;36289.27421451609;36443.817780910926;36809.81595092007;35004.47493287654;35060.64939350574;35220.47169292411;35371.82314088413;35544;31095.378092438477;32158.87443939451;32373.676263237066;32679.673203267663;32706.67293327036;24976.875115624305;25108.74891251064;25321.366965825266;25419.36451588725;25841.353966150997 16:19:29 INFO - PROCESS | 23618 | |2;jquery.html;346.48892882997563;366.4027634955023;371.11118870305614;375.5737238234604;393.4039929506796;12771.744565108693;16145.75781363279;16565.08892010939;16649.750253746202;17531.82468175318;16660.58348541287;17152;17184.05487698176;17347.39284125056;17416;5011.373578302712;5029.673071250366;5189.870253243661;5209.453007434222;5216.713080780555;3791.090138366786;5152.871178220537;5174.146265866128;5888.911666325009;6206.627602343851;6237.563370564062;6271.15339429178;6273.529485288599;6276.717547710364;6282.7172777224905;5979.46184843364;6088.391160883921;6110.6944652767625;6112.113743507211;6121.908171377433;5205;5391.973040134813;5396.568274538066;5397.811076612339;5404.5946554008215;20506.5898682027;20733.8963305184;20831.16675333;20854.47863803402;20866;2014.305064752658;2103.8001389867936;2110.5989861926187;2244.7867452591977;2513.522430738154;13186.472541098345;13366;13409.798853017213;13703.314834258248;13793.172409655454;1699.490152954109;1700.0649642696467;1711.5977745229861;1772.929082836685;1815.2829632295302;67725.32274677289;68332.658336708;68700.31299687039;69341;71257.64371178162;9423.6701715441;9564.378315409476;9677.467739274267;9813.705588832347;9894.703158905244;13185.60443186706;13218.140820846615;13225.338733063309;13229.206247625174;13251;7359.080114985627;7470.103587569418;7540.849183016309;7569.962150189324;7572.621368931532;7208.207097219301;7233;7233.204347521769;7393.186749457555;7418.146913104954;5989.850253743604;6122.204113465221;6159.692015399212;6185.257769067651;6265.310815810257;1724.5947202407424;1741.1033317841068;1747.4146161035942;1760.3662681434673;1771.654527367151;696.947728920333;831.3183189784319;862.9913700863036;916.3814425262921;949.392388871123;16993.00664343878;17343.65312693739;17877.821221787617;18196.363127290482;18266.452006439562;800.371708209053;831.1107115386478;850.6724910909345;868.2533021601417;878.5299864572423;9466.337356384987;9563.426194428357;9878;9881.357711748713;9987.300888937705;390575;391432;392496;399647;410421;51618.483815162115;58594.41405585975;80966;82972.58513707393;83084;927.0868194828089;954.1508057828538;966.1739212972807;971.4462756229022;982.0130768577534;15237.3905043797;15303.010608620809;15713.68572628518;16022.759658604897;16120;36381.63618363888;37796.62203378041;38109;38203.617963820005;38450;53217.20174197468;54099;54556.454435456726;54638.18042729442;54874.451255486936;7194.640267986475;7505.699772009277;7515.3473023925735;7551.207123251979;7887.211278872067;11047.613333533287;11092.279001864854;11196;11365.943170284096;11574.421278936188;9426.434413935185;9464.810703785748;9524.666636667684;9959.701208963743;10122.240831937781;9798.706038818847;9864.260180486348;10001.899981000097;10015.799684006424;10064.295499314978;7674.424418168548;7790.415718820997;10112.89887101149;10137.64518241858;10366.896331036593;10547.472626368804;11084.501197445972;11169.273997190157;11261.49323280438;11472.827907581226;9092.499912504876;9957.850632240377;10400.375977441377;10759.246852720235;11383.601573944872 16:19:29 INFO - PROCESS | 23618 | |3;mootools.html;1293.9223646581206;1327.1307293722612;1402.4740722229164;1640.09794612963;1654.7766051583033;7040.225575186725;7607.467477276593;7613.923860761391;7614.923850761491;7620.961895190523;6149.907751383722;6329.272133704625;6330.96834515828;6454.451371633417;7459.254074592538;1792.3905872003484;2104.736907886514;2173.3588591365506;2175.9564808703817;2178.3900507857825;2143.699882016519;2149.0758973641327;2151.8493705440624;2153.6446486329814;2158.7301587301586;2091.7489901211798;2175.2386664667397;2181.2801775414155;2182.2580322690283;2183.552371763785;2107.2519255664324;2126.362091372582;2127.489402543394;2127.914883404662;2135.0819147766456;2096.727425434699;2098.412444515538;2102.1591363454586;2104.8000439958178;2109.177420805887;7507.4369422293;7569.394448444111;7618.314351708317;7664.923350766477;7670.0795904491815;4219.852305169305;4337.913241735179;4339.132173565299;4350.58669426404;4360.084382279725;2823.898679514991;2951.940961180764;2998.205475548981;3001.4597372472945;3012.3071693510615;963.4363897120123;973.5667627905585;974.4543055888724;990.2177279948831;990.3958585262984;10024.749381265454;10033.49832508379;10045.598176072948;10051.597936082548;10093.495325233709;4078.1028173801715;4159.542450330461;4228.154369126187;4229.809658565372;4305.332673435622;8065.6370463329295;8141.552214628134;8190.262876341158;8191.83616327682;8195.46729462583;4330.891727706832;4333.414988976511;4341.739495630272;4343.847965321199;4344.630706389929;3908.8631897884006;3978.1844721831953;3982.900427489278;3988.66096381805;4021.8391264349098;3076.6923307669504;3207.935841283161;3374.6962773350515;3422.0418282880833;3422.281320922634;1030.4847576211894;1039.417925961464;1041.5365162502615;1049.2026060194307;1059.9523021464054;751.1924680967982;762.904636920385;777.7705576854842;792.5522080024883;794.8171920458327;4926.3842019747535;5034.194528875362;5038.823641172541;5075.87310317245;5312.256284120226;801.5831767480877;805.5126648377765;848.8533513785081;854.9529775862387;855.8288342331558;3409.4885767134633;3417.3165366926714;3445.242046749711;3480.704140148065;3493.9650603493637;8674.78313042179;9039.63841446348;9138.13187747163;9154.542272886329;9185.219256363283;9081.54592270384;9105.908940910505;9122.498262595489;9124;9138.908610913939;6968.756093536702;6972.09362782835;6991.6853741580735;7013.92986070143;7070.328318809705;4959.876003099951;5045.318881951036;5062.670926389773;5119.667221630582;5163.845084647466;8380.455270407405;8406.075331713384;8456.069832318562;8479.36404769657;8535.573221338784;8214.794630134294;8455.830883382421;8647.697330593399;8683.913160868311;8707.651693932172;6162.137300777805;6258.342873998165;6258.405451482102;6272.278687950944;6272.6236425814595;6062.060380641008;6794.626295553792;6964.408025317702;7067.081279433641;7161.820954475971;6465.676716164267;6479.157709497735;6508.967455162694;6518.413342799171;6566.146400967844;6344.1118243445035;6487.935120648733;6547.443467305332;6623.503237257129;6677.3656502632175;5782.681952492653;5924.200232968494;5960.642361458326;5978.402159784161;6036.094585812163;4398.450193725785;4453.064856380196;4499.257622492252;4536.6143877770755;4564.977175114103;4434.689571729948;4582.19811532987;4590.77046147698;4595.333676616939;4623.88440288982 16:19:29 INFO - PROCESS | 23618 | |4;prototype.html;293.3414484482337;295.71758970183464;300.7293435907683;302.7608189530271;304.4884593882277;4917.2378281366355;4918.926216106757;4930.975345123269;4942.703437793735;4943.382077240345;4884;4890.877728056791;4891.804327826882;4896.338994235777;4896.922677011052;3647.398179300419;3760.417135344026;3779.225258821935;3780.621937806225;3809.8095095245267;3745.3632882410056;3801.3157631626295;3847.3074846527616;3880.553736320331;3919.176972835708;3835.271298453299;3839.1170030892913;3851.557070936836;3868.516435445569;3883.8834834954855;3719.888403347904;3772.622737726233;3776.2447510497736;3835.1562656215588;3854.807259637035;3375.2405708715587;3378.847951842173;3389.322135572895;3398.898033059012;3411.1301618087227;4754.215554433515;4758.3576217210575;4778.952210477885;4795.928061079087;4800.447948485934;4401.823927042915;4412.669049821244;4413.404190434282;4415.977920110379;4438.578335058164;4881.877953051202;5083.135866902636;5089.54194122527;5097.643164978453;5120.180771076647;3726.105734623698;3733.21602463483;3735.9066023349633;3748.4377343398437;3752.624737526253;5388.434214407509;5388.811391601275;5393.325834270716;5397.595180361449;5401.70290634015;3535.116220944764;3741.214344987583;3756.4553139794575;3759.6240375962184;3834.175652234783;3780.243951209769;3786.6213378662464;3794.734368594227;3797.4873392091713;3798.2593394287846;4306.246406878784;4308.375285583573;4340.893072266615;4356.780101571565;4358.259095953696;4916.778744956486;5092;5156.613254005964;5157.484251574888;5160.690358578498;1275.67470295076;1293.3921057103146;1294.152330223705;1298.7077907470743;1300.1938797945145;864.1833467373272;865.1521508921293;882.6910581296495;886.5965985476722;891.7012800711834;4329.523752387235;4406.633943477532;4534.909301813946;4538.954610453853;4548.363229147856;5185.403678576937;5223.2948551945765;5251.474852514718;5264.236685680555;5284.7886084556185;4479.955200448019;4501.752403617833;4514;4517.638588912879;4523.819047238139;2656.6015097735494;2777.250142461524;2781.805273630867;2783;2783.7077106903484;4951.702897826142;4958.206686930074;4958.504149585013;4981.775820088104;4992.726854652113;5202.739863006835;5391.191321301758;5401.540869026177;5406.702631355313;5472.713912230623;4933.827316043921;4941.604671626261;4988.476209997898;4989.875253118701;5030.308784800114;5279.656822306538;5287.180487024518;5303.337082864642;5310.256564081109;5316.548093412103;4877.9024419512125;5077.847664570068;5143.459936706592;5187;5193.714345711022;4910.238912968495;4919.655624106278;4924.729139897197;4939.333190019302;4939.753012349296;3730.328540862562;3731.160488889978;3754.831032603485;3759.8496060157286;3778.905527361838;5021.84934451967;5301.655392399482;5309;5309.469053094814;5316.723986243196;5128.358895169065;5138.280640710229;5147.433782284021;5158.484151584811;5158.561522270649;5090.363704536933;5145.331106956071;5166.984875898327;5173.603127155722;5180.896382072413;4835.492273311252;5036.823711170092;5091.821786237464;5112.74436278192;5123.436421993652;4886.560209581013;5015.22264049073;5130.281760553451;5137.640365174402;5160.845174644766;5036.949630503648;5038.9010758384065;5039.807683657621;5046.59627229828;5065.606958086556 16:19:29 INFO - PROCESS | 23618 | |5;yui.html;664.5846346033729;673.0711617967205;690.8687349403613;726.9491135620506;746.078592937722;12035.277883327006;13287.202767833936;13317.334133293345;13432.66418339542;13437.865621343784;13339.53311634093;13389.732205355887;13454.394552245149;13463.12489688172;13463.93268033661;616.4975544930867;744.5793126883307;748.2928632442345;762.9122650895135;764.3503022430945;760.3499008347866;763.522798251093;767.597011568928;767.6545554500497;767.8502691975067;817.5748610722421;818.0102076487427;818.5661599352323;818.9426740128164;819.1726356380069;648.0376640688573;657.7007461605006;813.0081300813029;813.4671789977574;813.5118928642826;488.6945658963148;488.91199584074855;574.8620331120501;579.3164066401645;588.5438784941662;11468.598599048993;11518.481668324861;11519.30884146954;11532.538698452136;11566.768664626661;11753.35356555389;12635.241885486808;12667.556635517805;13001.479940802357;13402.477442729654;11712.824307635392;11748.118891083117;11762.88237117635;11928.10539209554;11975.820362694567;3917.5690674025836;4025.1547175093265;4030.738002029888;4037.3338399164104;4168.937465938014;60812.08781868231;61197.38802611962;61496.38503614996;62130;62263.37736622621;9685.612575496902;9790.118889299854;9983.900160998443;10062.245331600014;10210.387376757419;17853.642927141384;17866.91066544659;17869.28522859071;17884.91057544704;17887.552811179823;6681;6714.630695311707;6756.391924726799;6835.4189893859575;6992.720291188397;3635.345637785171;3642.1440961374055;3688;3694.9630503695157;3742.0083677825396;2647.457271259387;2728.9181324560295;2738.3701748597537;2738.3975525384385;2743.478739039576;481.5689957488029;493.89628178082285;496.68212344099805;504.8006037615195;510.4436164580625;441.82768720199147;447.7805875121167;522.7621432248318;526.3341872531252;560.3499940069581;7017.7192912282935;7303.3792127668685;7445;7527.661255243527;7549.50928189666;469.5867636479876;511.28847668732806;536.9543588794996;545.5962587685084;571.0492030768806;3602.2975519773395;3651.470536772153;3723.906902327409;3732.496113024761;3784.6404591563755;57345.426545734845;58178.41821581815;100762.48856267161;102926.48536757418;107056;74062;85211.57394213113;86916.13083869207;87016.56491717501;88756;81018;81300.59349703214;83697;84053.59464053668;84109;7012.123484564429;7032;7082.220955694973;7310.671019804228;7344.742933997284;38317.2336553273;46115.30827037664;47056.52943470521;47936.7603161982;49029;60271.39728602658;65134.81219284161;65988;66489;68988;9476.715698529055;9507.286953478379;9679.177269931854;9740.61037558518;10152.949235253776;10226.948865255627;10297.330673506498;10518;10533.473326333806;10816.459177040959;9646.228301735704;9800.9708980556;9905.851412228967;9946.701598952042;10141.543630536791;9989.203023153528;10061.899381006097;11538.48076836561;11674.416279186176;12476.563320283747;11238.438078095898;11496.770064598493;11862.051035917279;11927.880721192678;11979.101567382655;9330.813383732424;10133;11040.392778397265;11195.10439164881;11215.71960700989;8058.153893841297;8178.141295163922;9521.476318802532;9701.854472183064;9797.265205109788 16:19:29 INFO - PROCESS | 23618 | __end_tp_report 16:19:29 INFO - PROCESS | 23618 | __start_cc_report 16:19:29 INFO - PROCESS | 23618 | _x_x_mozilla_cycle_collect,1148 16:19:29 INFO - PROCESS | 23618 | __end_cc_report 16:19:29 INFO - PROCESS | 23618 | __startTimestamp1470093569267__endTimestamp 16:19:29 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | ------- Summary: start ------- 16:19:29 INFO - PROCESS | 23618 | Number of tests: 6 16:19:29 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | [#0] dojo.html Cycles:165 Average:4673.83 Median:4807.90 stddev:497.58 (10.3%) stddev-sans-first:471.98 16:19:29 INFO - PROCESS | 23618 | Values: 2608.2 2620.7 2646.3 2660.7 2666.8 4574.1 4649.3 4657.7 4705.3 4715.5 4618.4 4627.6 4670.4 4670.6 4704.0 4249.5 4290.9 4312.7 4373.5 4377.1 4280.8 4350.3 4394.0 4430.4 4434.4 4186.3 4224.7 4241.5 4356.9 4390.4 4264.2 4286.7 4287.8 4288.8 4295.0 4184.0 4220.7 4255.3 4263.7 4270.6 4616.0 4650.6 4667.6 4697.4 4702.6 5021.4 5036.8 5085.5 5090.9 5096.0 4899.8 5009.6 5059.4 5092.8 5113.3 4759.4 4776.1 4803.2 4813.5 4817.4 5040.0 5262.3 5264.1 5269.0 5272.7 4725.4 4859.6 4911.2 4947.0 5009.0 5072.4 5115.8 5122.9 5126.1 5127.6 5030.9 5033.9 5039.0 5042.3 5068.6 4854.3 4938.8 4973.1 4997.4 5064.2 4348.6 4360.3 4360.8 4363.7 4409.3 3790.8 3792.9 3809.4 3815.4 3829.4 4218.7 4260.0 4326.2 4361.7 4362.6 5093.2 5093.3 5099.0 5118.2 5141.5 4389.9 4394.2 4394.5 4427.1 4445.4 4437.4 4566.5 4597.7 4604.5 4635.2 4778.5 4797.7 4807.9 4844.3 4849.7 5106.4 5110.5 5115.9 5116.6 5117.3 4540.2 4592.8 4711.2 4746.3 4807.5 5053.9 5055.4 5057.5 5074.8 5077.3 5108.1 5124.5 5124.8 5126.2 5169.1 4820.7 4899.6 4940.9 4966.4 4979.7 4957.4 4975.4 4976.9 5009.8 5024.7 4870.6 4930.9 4932.7 4934.0 4937.3 4769.3 4904.5 4940.7 4977.8 4988.4 4848.6 4851.3 4856.1 4859.7 4884.5 16:19:29 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | [#1] ext.html Cycles:180 Average:46864.00 Median:35537.32 stddev:85022.07 (239.2%) stddev-sans-first:85189.50 16:19:29 INFO - PROCESS | 23618 | Values: 560.6 569.5 574.0 576.9 581.3 28659.1 38559.6 39838.8 39894.2 43887.1 40138.8 40344.2 40585.4 40588.0 40623.6 7648.1 7712.8 7766.8 7789.8 7793.4 7330.8 9746.8 9932.8 9947.0 10353.9 10019.5 10082.4 10115.4 10136.0 10141.7 9915.2 9981.5 9982.6 9986.8 9988.1 9830.5 9897.1 9902.1 9902.6 9910.5 37880.2 38160.0 38189.8 38218.6 38266.6 2867.9 3015.0 3027.2 3051.6 3155.0 28741.9 28860.7 29427.1 29449.0 30095.7 3421.8 3481.2 3487.5 3543.1 3637.0 248443.0 250064.0 251457.0 251489.0 251813.7 52566.7 53032.0 53146.7 53236.5 53239.2 32534.7 33218.8 33330.0 35589.3 35634.8 15220.6 15532.7 15555.8 15561.1 15565.7 9560.8 10035.4 10145.5 10156.4 10438.9 17258.9 17931.1 17936.4 17984.4 18255.4 6749.3 6752.6 6780.4 6942.4 6956.3 808.4 835.4 889.6 900.8 921.4 28153.2 28488.6 29186.4 29205.9 29391.6 929.4 935.9 946.7 1007.3 1061.9 14560.7 14989.6 15268.2 15277.0 15410.2 400297.0 404117.0 405574.0 406323.0 406494.0 48958.3 49575.0 49627.8 49663.8 49790.8 1083.3 1091.8 1161.2 1163.4 1200.4 27514.3 28799.1 28963.7 29066.1 29452.9 41251.2 41786.6 41827.6 41828.6 41918.0 293632.6 293771.0 294490.0 294854.2 295663.0 24945.9 25361.9 25746.5 25765.2 27141.0 41482.0 41737.4 41971.4 42250.0 43033.0 35530.6 35631.8 35736.0 35928.6 36472.5 35462.3 35902.8 36289.3 36443.8 36809.8 35004.5 35060.6 35220.5 35371.8 35544.0 31095.4 32158.9 32373.7 32679.7 32706.7 24976.9 25108.7 25321.4 25419.4 25841.4 16:19:29 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | [#2] jquery.html Cycles:180 Average:24714.42 Median:5182.01 stddev:65404.92 (1262.2%) stddev-sans-first:65562.80 16:19:29 INFO - PROCESS | 23618 | Values: 346.5 366.4 371.1 375.6 393.4 12771.7 16145.8 16565.1 16649.8 17531.8 16660.6 17152.0 17184.1 17347.4 17416.0 5011.4 5029.7 5189.9 5209.5 5216.7 3791.1 5152.9 5174.1 5888.9 6206.6 6237.6 6271.2 6273.5 6276.7 6282.7 5979.5 6088.4 6110.7 6112.1 6121.9 5205.0 5392.0 5396.6 5397.8 5404.6 20506.6 20733.9 20831.2 20854.5 20866.0 2014.3 2103.8 2110.6 2244.8 2513.5 13186.5 13366.0 13409.8 13703.3 13793.2 1699.5 1700.1 1711.6 1772.9 1815.3 67725.3 68332.7 68700.3 69341.0 71257.6 9423.7 9564.4 9677.5 9813.7 9894.7 13185.6 13218.1 13225.3 13229.2 13251.0 7359.1 7470.1 7540.8 7570.0 7572.6 7208.2 7233.0 7233.2 7393.2 7418.1 5989.9 6122.2 6159.7 6185.3 6265.3 1724.6 1741.1 1747.4 1760.4 1771.7 696.9 831.3 863.0 916.4 949.4 16993.0 17343.7 17877.8 18196.4 18266.5 800.4 831.1 850.7 868.3 878.5 9466.3 9563.4 9878.0 9881.4 9987.3 390575.0 391432.0 392496.0 399647.0 410421.0 51618.5 58594.4 80966.0 82972.6 83084.0 927.1 954.2 966.2 971.4 982.0 15237.4 15303.0 15713.7 16022.8 16120.0 36381.6 37796.6 38109.0 38203.6 38450.0 53217.2 54099.0 54556.5 54638.2 54874.5 7194.6 7505.7 7515.3 7551.2 7887.2 11047.6 11092.3 11196.0 11365.9 11574.4 9426.4 9464.8 9524.7 9959.7 10122.2 9798.7 9864.3 10001.9 10015.8 10064.3 7674.4 7790.4 10112.9 10137.6 10366.9 10547.5 11084.5 11169.3 11261.5 11472.8 9092.5 9957.9 10400.4 10759.2 11383.6 16:19:29 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | [#3] mootools.html Cycles:180 Average:4875.32 Median:5069.27 stddev:2676.13 (52.8%) stddev-sans-first:2670.10 16:19:29 INFO - PROCESS | 23618 | Values: 1293.9 1327.1 1402.5 1640.1 1654.8 7040.2 7607.5 7613.9 7614.9 7621.0 6149.9 6329.3 6331.0 6454.5 7459.3 1792.4 2104.7 2173.4 2176.0 2178.4 2143.7 2149.1 2151.8 2153.6 2158.7 2091.7 2175.2 2181.3 2182.3 2183.6 2107.3 2126.4 2127.5 2127.9 2135.1 2096.7 2098.4 2102.2 2104.8 2109.2 7507.4 7569.4 7618.3 7664.9 7670.1 4219.9 4337.9 4339.1 4350.6 4360.1 2823.9 2951.9 2998.2 3001.5 3012.3 963.4 973.6 974.5 990.2 990.4 10024.7 10033.5 10045.6 10051.6 10093.5 4078.1 4159.5 4228.2 4229.8 4305.3 8065.6 8141.6 8190.3 8191.8 8195.5 4330.9 4333.4 4341.7 4343.8 4344.6 3908.9 3978.2 3982.9 3988.7 4021.8 3076.7 3207.9 3374.7 3422.0 3422.3 1030.5 1039.4 1041.5 1049.2 1060.0 751.2 762.9 777.8 792.6 794.8 4926.4 5034.2 5038.8 5075.9 5312.3 801.6 805.5 848.9 855.0 855.8 3409.5 3417.3 3445.2 3480.7 3494.0 8674.8 9039.6 9138.1 9154.5 9185.2 9081.5 9105.9 9122.5 9124.0 9138.9 6968.8 6972.1 6991.7 7013.9 7070.3 4959.9 5045.3 5062.7 5119.7 5163.8 8380.5 8406.1 8456.1 8479.4 8535.6 8214.8 8455.8 8647.7 8683.9 8707.7 6162.1 6258.3 6258.4 6272.3 6272.6 6062.1 6794.6 6964.4 7067.1 7161.8 6465.7 6479.2 6509.0 6518.4 6566.1 6344.1 6487.9 6547.4 6623.5 6677.4 5782.7 5924.2 5960.6 5978.4 6036.1 4398.5 4453.1 4499.3 4536.6 4565.0 4434.7 4582.2 4590.8 4595.3 4623.9 16:19:29 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | [#4] prototype.html Cycles:180 Average:4259.07 Median:4891.34 stddev:1235.68 (25.3%) stddev-sans-first:1202.76 16:19:29 INFO - PROCESS | 23618 | Values: 293.3 295.7 300.7 302.8 304.5 4917.2 4918.9 4931.0 4942.7 4943.4 4884.0 4890.9 4891.8 4896.3 4896.9 3647.4 3760.4 3779.2 3780.6 3809.8 3745.4 3801.3 3847.3 3880.6 3919.2 3835.3 3839.1 3851.6 3868.5 3883.9 3719.9 3772.6 3776.2 3835.2 3854.8 3375.2 3378.8 3389.3 3398.9 3411.1 4754.2 4758.4 4779.0 4795.9 4800.4 4401.8 4412.7 4413.4 4416.0 4438.6 4881.9 5083.1 5089.5 5097.6 5120.2 3726.1 3733.2 3735.9 3748.4 3752.6 5388.4 5388.8 5393.3 5397.6 5401.7 3535.1 3741.2 3756.5 3759.6 3834.2 3780.2 3786.6 3794.7 3797.5 3798.3 4306.2 4308.4 4340.9 4356.8 4358.3 4916.8 5092.0 5156.6 5157.5 5160.7 1275.7 1293.4 1294.2 1298.7 1300.2 864.2 865.2 882.7 886.6 891.7 4329.5 4406.6 4534.9 4539.0 4548.4 5185.4 5223.3 5251.5 5264.2 5284.8 4480.0 4501.8 4514.0 4517.6 4523.8 2656.6 2777.3 2781.8 2783.0 2783.7 4951.7 4958.2 4958.5 4981.8 4992.7 5202.7 5391.2 5401.5 5406.7 5472.7 4933.8 4941.6 4988.5 4989.9 5030.3 5279.7 5287.2 5303.3 5310.3 5316.5 4877.9 5077.8 5143.5 5187.0 5193.7 4910.2 4919.7 4924.7 4939.3 4939.8 3730.3 3731.2 3754.8 3759.8 3778.9 5021.8 5301.7 5309.0 5309.5 5316.7 5128.4 5138.3 5147.4 5158.5 5158.6 5090.4 5145.3 5167.0 5173.6 5180.9 4835.5 5036.8 5091.8 5112.7 5123.4 4886.6 5015.2 5130.3 5137.6 5160.8 5036.9 5038.9 5039.8 5046.6 5065.6 16:19:29 INFO - PROCESS | 23618 | 16:19:29 INFO - PROCESS | 23618 | [#5] yui.html Cycles:180 Average:17482.08 Median:517.03 stddev:25420.69 (4916.7%) stddev-sans-first:25460.64 16:19:29 INFO - PROCESS | 23618 | Values: 664.6 673.1 690.9 726.9 746.1 12035.3 13287.2 13317.3 13432.7 13437.9 13339.5 13389.7 13454.4 13463.1 13463.9 616.5 744.6 748.3 762.9 764.4 760.3 763.5 767.6 767.7 767.9 817.6 818.0 818.6 818.9 819.2 648.0 657.7 813.0 813.5 813.5 488.7 488.9 574.9 579.3 588.5 11468.6 11518.5 11519.3 11532.5 11566.8 11753.4 12635.2 12667.6 13001.5 13402.5 11712.8 11748.1 11762.9 11928.1 11975.8 3917.6 4025.2 4030.7 4037.3 4168.9 60812.1 61197.4 61496.4 62130.0 62263.4 9685.6 9790.1 9983.9 10062.2 10210.4 17853.6 17866.9 17869.3 17884.9 17887.6 6681.0 6714.6 6756.4 6835.4 6992.7 3635.3 3642.1 3688.0 3695.0 3742.0 2647.5 2728.9 2738.4 2738.4 2743.5 481.6 493.9 496.7 504.8 510.4 441.8 447.8 522.8 526.3 560.3 7017.7 7303.4 7445.0 7527.7 7549.5 469.6 511.3 537.0 545.6 571.0 3602.3 3651.5 3723.9 3732.5 3784.6 57345.4 58178.4 100762.5 102926.5 107056.0 74062.0 85211.6 86916.1 87016.6 88756.0 81018.0 81300.6 83697.0 84053.6 84109.0 7012.1 7032.0 7082.2 7310.7 7344.7 38317.2 46115.3 47056.5 47936.8 49029.0 60271.4 65134.8 65988.0 66489.0 68988.0 9476.7 9507.3 9679.2 9740.6 10152.9 10226.9 10297.3 10518.0 10533.5 10816.5 9646.2 9801.0 9905.9 9946.7 10141.5 9989.2 10061.9 11538.5 11674.4 12476.6 11238.4 11496.8 11862.1 11927.9 11979.1 9330.8 10133.0 11040.4 11195.1 11215.7 8058.2 8178.1 9521.5 9701.9 9797.3 16:19:29 INFO - PROCESS | 23618 | -------- Summary: end -------- 16:19:29 INFO - PROCESS | 23618 | 16:19:30 INFO - TEST-INFO | 23618: exit 0 16:19:30 INFO - TEST-OK | dromaeo_css | took 1119668ms 16:19:30 INFO - TEST-START | kraken 16:19:30 INFO - operating with platform_type : linux_ 16:19:30 INFO - Initialising browser for kraken test... 16:19:30 INFO - TEST-INFO | started process 23808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIrWVP8/profile http://localhost:41078/getInfo.html) 16:19:34 INFO - PROCESS | 23808 | __metrics Screen width/height:1600/1200 16:19:34 INFO - PROCESS | 23808 | colorDepth:24 16:19:34 INFO - PROCESS | 23808 | Browser inner width/height: 1024/697 16:19:34 INFO - PROCESS | 23808 | __metrics 16:19:35 INFO - PROCESS | 23808 | 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" 16:19:35 INFO - PROCESS | 23808 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:19:36 INFO - TEST-INFO | 23808: exit 0 16:19:36 INFO - Browser initialized. 16:19:36 INFO - Running cycle 1/1 for kraken test... 16:19:36 INFO - TEST-INFO | started process 23886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIrWVP8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:48 INFO - PROCESS | 23886 | RSS: Main: 157184000 16:19:48 INFO - PROCESS | 23886 | 16:19:50 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41078/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:51 INFO - PROCESS | 23886 | RSS: Main: 214192128 16:19:51 INFO - PROCESS | 23886 | 16:19:54 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41078/tests/kraken/driver.html?testName=audio-dft) 16:19:55 INFO - PROCESS | 23886 | RSS: Main: 192663552 16:19:55 INFO - PROCESS | 23886 | 16:19:58 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41078/tests/kraken/driver.html?testName=audio-fft) 16:19:58 INFO - PROCESS | 23886 | RSS: Main: 272277504 16:19:58 INFO - PROCESS | 23886 | 16:20:01 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41078/tests/kraken/driver.html?testName=audio-oscillator) 16:20:01 INFO - PROCESS | 23886 | RSS: Main: 180944896 16:20:01 INFO - PROCESS | 23886 | 16:20:03 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41078/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:20:04 INFO - PROCESS | 23886 | RSS: Main: 184991744 16:20:04 INFO - PROCESS | 23886 | 16:20:07 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41078/tests/kraken/driver.html?testName=imaging-darkroom) 16:20:08 INFO - PROCESS | 23886 | RSS: Main: 221675520 16:20:08 INFO - PROCESS | 23886 | 16:20:11 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41078/tests/kraken/driver.html?testName=imaging-desaturate) 16:20:12 INFO - PROCESS | 23886 | RSS: Main: 221679616 16:20:12 INFO - PROCESS | 23886 | 16:20:15 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41078/tests/kraken/driver.html?testName=json-parse-financial) 16:20:15 INFO - PROCESS | 23886 | RSS: Main: 221167616 16:20:15 INFO - PROCESS | 23886 | 16:20:17 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41078/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:20:18 INFO - PROCESS | 23886 | RSS: Main: 185839616 16:20:18 INFO - PROCESS | 23886 | 16:20:20 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41078/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:20:20 INFO - PROCESS | 23886 | RSS: Main: 191676416 16:20:20 INFO - PROCESS | 23886 | 16:20:22 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41078/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:20:23 INFO - PROCESS | 23886 | RSS: Main: 195629056 16:20:23 INFO - PROCESS | 23886 | 16:20:26 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41078/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:20:26 INFO - PROCESS | 23886 | RSS: Main: 190148608 16:20:26 INFO - PROCESS | 23886 | 16:20:29 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41078/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:20:30 INFO - PROCESS | 23886 | RSS: Main: 193126400 16:20:30 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | Cycle 1(1): loaded http://localhost:41078/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41078/tests/kraken/driver.html?testName=ai-astar) 16:20:32 INFO - PROCESS | 23886 | RSS: Main: 243527680 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | __start_tp_report 16:20:32 INFO - PROCESS | 23886 | _x_x_mozilla_page_load 16:20:32 INFO - PROCESS | 23886 | _x_x_mozilla_page_load_details 16:20:32 INFO - PROCESS | 23886 | |i|pagename|runs| 16:20:32 INFO - PROCESS | 23886 | |0;ai-astar;104;113;115;115;113;113;112;113;112;112 16:20:32 INFO - PROCESS | 23886 | |1;audio-beat-detection;144;124;125;185;125;134;150;121;184;122 16:20:32 INFO - PROCESS | 23886 | |2;audio-dft;162;161;162;162;160;162;162;162;162;161 16:20:32 INFO - PROCESS | 23886 | |3;audio-fft;75;75;76;118;77;76;75;75;100;76 16:20:32 INFO - PROCESS | 23886 | |4;audio-oscillator;84;85;124;84;85;85;130;84;85;128 16:20:32 INFO - PROCESS | 23886 | |5;imaging-gaussian-blur;117;117;118;116;117;117;117;117;117;118 16:20:32 INFO - PROCESS | 23886 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;146;146 16:20:32 INFO - PROCESS | 23886 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;95;94 16:20:32 INFO - PROCESS | 23886 | |8;json-parse-financial;62;61;61;61;63;62;61;61;62;63 16:20:32 INFO - PROCESS | 23886 | |9;json-stringify-tinderbox;64;66;65;65;66;65;65;65;67;66 16:20:32 INFO - PROCESS | 23886 | |10;stanford-crypto-aes;82;81;80;80;80;81;81;86;80;85 16:20:32 INFO - PROCESS | 23886 | |11;stanford-crypto-ccm;138;135;141;129;129;127;128;128;176;135 16:20:32 INFO - PROCESS | 23886 | |12;stanford-crypto-pbkdf2;171;171;167;167;166;168;167;168;167;168 16:20:32 INFO - PROCESS | 23886 | |13;stanford-crypto-sha256-iterative;68;68;68;66;69;67;69;67;66;68 16:20:32 INFO - PROCESS | 23886 | __end_tp_report 16:20:32 INFO - PROCESS | 23886 | __start_cc_report 16:20:32 INFO - PROCESS | 23886 | _x_x_mozilla_cycle_collect,1936 16:20:32 INFO - PROCESS | 23886 | __end_cc_report 16:20:32 INFO - PROCESS | 23886 | __startTimestamp1470093632421__endTimestamp 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | ------- Summary: start ------- 16:20:32 INFO - PROCESS | 23886 | Number of tests: 14 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:3.08 (2.7%) stddev-sans-first:1.17 16:20:32 INFO - PROCESS | 23886 | Values: 104.0 113.0 115.0 115.0 113.0 113.0 112.0 113.0 112.0 112.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:139.00 stddev:24.69 (17.8%) stddev-sans-first:26.17 16:20:32 INFO - PROCESS | 23886 | Values: 144.0 124.0 125.0 185.0 125.0 134.0 150.0 121.0 184.0 122.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 16:20:32 INFO - PROCESS | 23886 | Values: 162.0 161.0 162.0 162.0 160.0 162.0 162.0 162.0 162.0 161.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#3] audio-fft Cycles:10 Average:82.30 Median:75.50 stddev:14.71 (19.5%) stddev-sans-first:15.37 16:20:32 INFO - PROCESS | 23886 | Values: 75.0 75.0 76.0 118.0 77.0 76.0 75.0 75.0 100.0 76.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:84.50 stddev:20.71 (24.5%) stddev-sans-first:21.39 16:20:32 INFO - PROCESS | 23886 | Values: 84.0 85.0 124.0 84.0 85.0 85.0 130.0 84.0 85.0 128.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 16:20:32 INFO - PROCESS | 23886 | Values: 117.0 117.0 118.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 16:20:32 INFO - PROCESS | 23886 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:20:32 INFO - PROCESS | 23886 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:62.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 16:20:32 INFO - PROCESS | 23886 | Values: 62.0 61.0 61.0 61.0 63.0 62.0 61.0 61.0 62.0 63.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.84 (1.3%) stddev-sans-first:0.73 16:20:32 INFO - PROCESS | 23886 | Values: 64.0 66.0 65.0 65.0 66.0 65.0 65.0 65.0 67.0 66.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.00 stddev:2.17 (2.7%) stddev-sans-first:2.30 16:20:32 INFO - PROCESS | 23886 | Values: 82.0 81.0 80.0 80.0 80.0 81.0 81.0 86.0 80.0 85.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:135.00 stddev:14.66 (10.9%) stddev-sans-first:15.54 16:20:32 INFO - PROCESS | 23886 | Values: 138.0 135.0 141.0 129.0 129.0 127.0 128.0 128.0 176.0 135.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:1.70 (1.0%) stddev-sans-first:1.41 16:20:32 INFO - PROCESS | 23886 | Values: 171.0 171.0 167.0 167.0 166.0 168.0 167.0 168.0 167.0 168.0 16:20:32 INFO - PROCESS | 23886 | 16:20:32 INFO - PROCESS | 23886 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.07 (1.6%) stddev-sans-first:1.13 16:20:32 INFO - PROCESS | 23886 | Values: 68.0 68.0 68.0 66.0 69.0 67.0 69.0 67.0 66.0 68.0 16:20:32 INFO - PROCESS | 23886 | -------- Summary: end -------- 16:20:32 INFO - PROCESS | 23886 | 16:20:33 INFO - TEST-INFO | 23886: exit 0 16:20:33 INFO - TEST-OK | kraken | took 63408ms 16:20:33 INFO - SUITE-END | took 1183s 16:20:33 INFO - Completed test suite (00:19:43) 16:20:33 INFO - javascript benchmark 16:20:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2608.1914606471996, 2620.72482389349, 2646.3119588906884, 2660.720624334445, 2666.7599916007543, 4574.130915126132, 4649.349091127245, 4657.7205367677925, 4705.317728929307, 4715.5048719884435, 4618.376519169911, 4627.560381763736, 4670.439547254335, 4670.649701272401, 4704.0, 4249.46881639795, 4290.935635965463, 4312.654987600984, 4373.475182978047, 4377.080813029268, 4280.807363668642, 4350.32569951658, 4393.978030109845, 4430.401895744081, 4434.423524941753, 4186.330187170069, 4224.66202703783, 4241.49102107746, 4356.89107772305, 4390.429244198233, 4264.211120942636, 4286.721363111388, 4287.785610719451, 4288.785560721983, 4294.978525107385, 4184.03767133561, 4220.683448741357, 4255.340422234559, 4263.658907287409, 4270.615644592001, 4615.976920115378, 4650.581447669692, 4667.556582124693, 4697.436307643105, 4702.6002789762915, 5021.397432308147, 5036.7733451994745, 5085.516875896821, 5090.898182036375, 5096.031753966735, 4899.755012249373, 5009.649324547283, 5059.392872855281, 5092.821751238689, 5113.284140220372, 4759.381280433521, 4776.09254241693, 4803.18345881201, 4813.4945830687275, 4817.397825271841, 5040.0, 5262.289590905255, 5264.078786212397, 5269.0, 5272.736363181825, 4725.385699858996, 4859.635527335464, 4911.238757992517, 4946.975265123651, 5009.0, 5072.416672082684, 5115.820946266863, 5122.92315615266, 5126.128558145124, 5127.564157046618, 5030.949690503121, 5033.874153146201, 5038.992201559703, 5042.319286896295, 5068.619853510928, 4854.296127061627, 4938.802447902115, 4973.1297023020825, 4997.400311962588, 5064.1897296433135, 4348.586884245991, 4360.302351623725, 4360.803763830571, 4363.694541382073, 4409.272470042407, 3790.753601015925, 3792.924141517154, 3809.4285857121654, 3815.389537673959, 3829.4064420014383, 4218.6835987300465, 4259.97870010648, 4326.2212801696, 4361.694681372273, 4362.585554372329, 5093.159628661304, 5093.337866077461, 5099.0, 5118.155504341742, 5141.53726164647, 4389.912201755947, 4394.209042372339, 4394.516603173648, 4427.092446048552, 4445.355423463579, 4437.400950871655, 4566.474855391606, 4597.655175861823, 4604.470485894098, 4635.165670179333, 4778.498257682893, 4797.712137271775, 4807.903841923072, 4844.273358996108, 4849.660523763303, 5106.412762532356, 5110.463401342806, 5115.948840511547, 5116.565091967224, 5117.2835802986865, 4540.2054640438455, 4592.793324300348, 4711.175544279806, 4746.335513028248, 4807.495213002724, 5053.949460505348, 5055.418626857811, 5057.519535644108, 5074.797008119634, 5077.339945807022, 5108.055009823194, 5124.538791508633, 5124.7950081996305, 5126.23106534008, 5169.095408303607, 4820.71075735457, 4899.632527560376, 4940.876478087933, 4966.428860681068, 4979.701217926936, 4957.405111386656, 4975.353204083446, 4976.8755781105765, 5009.7996080157845, 5024.698518088926, 4870.610351171968, 4930.926036109389, 4932.728699921395, 4934.01319736047, 4937.284093806306, 4769.3084502746215, 4904.460509343898, 4940.6788558743565, 4977.776000079933, 4988.401391833003, 4848.5636292733825, 4851.272309153655, 4856.077345304381, 4859.684120532154, 4884.48712885142], "value": 4641.867233635178, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.5907687388207, 569.4703925349423, 573.9942600573994, 576.9163471296662, 581.255992329818, 28659.140225793235, 38559.61440385595, 39838.80080599593, 39894.20211595766, 43887.12225755483, 40138.7993060035, 40344.193116137656, 40585.39121913166, 40587.99999999993, 40623.59376406235, 7648.120466146394, 7712.807179820521, 7766.766996990071, 7789.805254868617, 7793.376529877596, 7330.780076597684, 9746.756331091708, 9932.801343973117, 9946.950265248664, 10353.948230258877, 10019.499025048755, 10082.39505629661, 10115.44365059925, 10136.0, 10141.746456338577, 9915.157211637019, 9981.500924953796, 9982.550785214682, 9986.800263994752, 9988.101070903616, 9830.508474576243, 9897.109260166548, 9902.059304366147, 9902.60389584423, 9910.504474776304, 37880.242395151945, 38160.0, 38189.80905095484, 38218.617813822064, 38266.61733382686, 2867.899623513167, 3015.0, 3027.1826606816035, 3051.5727798108283, 3154.984225078883, 28741.85629071862, 28860.711392886224, 29427.11718648444, 29449.0, 30095.69904300951, 3421.760476766627, 3481.1993241554333, 3487.494313324554, 3543.114221444639, 3637.018005138598, 248443.0, 250064.0, 251457.0, 251489.0, 251813.740931296, 52566.73716631392, 53032.0, 53146.73426632919, 53236.46763532393, 53239.20141197885, 32534.674653253165, 33218.83390583032, 33330.0, 35589.288214235574, 35634.82182589071, 15220.619484512976, 15532.689346213012, 15555.844441555666, 15561.14413707257, 15565.688686226213, 9560.76098097553, 10035.448050357301, 10145.492725363702, 10156.441395723159, 10438.947805261078, 17258.913705431394, 17931.103444827706, 17936.39922211272, 17984.37054703079, 18255.361062362754, 6749.291324410964, 6752.5948443093575, 6780.355866192704, 6942.444604431634, 6956.339147780953, 808.401782680812, 835.3609488741118, 889.573004957611, 900.7928176519438, 921.3642586615214, 28153.24881695388, 28488.572671409944, 29186.416271674872, 29205.85397073001, 29391.55912661312, 929.4284015330658, 935.9017303183203, 946.7491114854569, 1007.2546315726456, 1061.9256652034437, 14560.708785824225, 14989.550313490612, 15268.236588170546, 15276.9905432417, 15410.229488525529, 400297.0, 404117.0, 405574.0, 406323.0, 406494.0, 48958.26562601564, 49575.0, 49627.75186124046, 49663.75168124209, 49790.751046244535, 1083.257968291707, 1091.8198497248025, 1161.1523587780966, 1163.4299193395345, 1200.351810022578, 27514.312142196606, 28799.136025919255, 28963.710362896945, 29066.12801615955, 29452.852735736185, 41251.1749764997, 41786.58213417827, 41827.58172418237, 41828.58171418247, 41918.0, 293632.5730684163, 293771.0, 294490.0, 294854.1543753691, 295663.0, 24945.875270623532, 25361.873190633927, 25746.485070298862, 25765.227043188734, 27141.0, 41482.0, 41737.37393939032, 41971.37042944419, 42250.0, 43033.0, 35530.644693552735, 35631.82184089063, 35736.0, 35928.64071359253, 36472.452913205794, 35462.29075418529, 35902.820485897406, 36289.27421451609, 36443.817780910926, 36809.81595092007, 35004.47493287654, 35060.64939350574, 35220.47169292411, 35371.82314088413, 35544.0, 31095.378092438477, 32158.87443939451, 32373.676263237066, 32679.673203267663, 32706.67293327036, 24976.875115624305, 25108.74891251064, 25321.366965825266, 25419.36451588725, 25841.353966150997], "value": 17676.0446617119, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.48892882997563, 366.4027634955023, 371.11118870305614, 375.5737238234604, 393.4039929506796, 12771.744565108693, 16145.75781363279, 16565.08892010939, 16649.750253746202, 17531.82468175318, 16660.58348541287, 17152.0, 17184.05487698176, 17347.39284125056, 17416.0, 5011.373578302712, 5029.673071250366, 5189.870253243661, 5209.453007434222, 5216.713080780555, 3791.090138366786, 5152.871178220537, 5174.146265866128, 5888.911666325009, 6206.627602343851, 6237.563370564062, 6271.15339429178, 6273.529485288599, 6276.717547710364, 6282.7172777224905, 5979.46184843364, 6088.391160883921, 6110.6944652767625, 6112.113743507211, 6121.908171377433, 5205.0, 5391.973040134813, 5396.568274538066, 5397.811076612339, 5404.5946554008215, 20506.5898682027, 20733.8963305184, 20831.16675333, 20854.47863803402, 20866.0, 2014.305064752658, 2103.8001389867936, 2110.5989861926187, 2244.7867452591977, 2513.522430738154, 13186.472541098345, 13366.0, 13409.798853017213, 13703.314834258248, 13793.172409655454, 1699.490152954109, 1700.0649642696467, 1711.5977745229861, 1772.929082836685, 1815.2829632295302, 67725.32274677289, 68332.658336708, 68700.31299687039, 69341.0, 71257.64371178162, 9423.6701715441, 9564.378315409476, 9677.467739274267, 9813.705588832347, 9894.703158905244, 13185.60443186706, 13218.140820846615, 13225.338733063309, 13229.206247625174, 13251.0, 7359.080114985627, 7470.103587569418, 7540.849183016309, 7569.962150189324, 7572.621368931532, 7208.207097219301, 7233.0, 7233.204347521769, 7393.186749457555, 7418.146913104954, 5989.850253743604, 6122.204113465221, 6159.692015399212, 6185.257769067651, 6265.310815810257, 1724.5947202407424, 1741.1033317841068, 1747.4146161035942, 1760.3662681434673, 1771.654527367151, 696.947728920333, 831.3183189784319, 862.9913700863036, 916.3814425262921, 949.392388871123, 16993.00664343878, 17343.65312693739, 17877.821221787617, 18196.363127290482, 18266.452006439562, 800.371708209053, 831.1107115386478, 850.6724910909345, 868.2533021601417, 878.5299864572423, 9466.337356384987, 9563.426194428357, 9878.0, 9881.357711748713, 9987.300888937705, 390575.0, 391432.0, 392496.0, 399647.0, 410421.0, 51618.483815162115, 58594.41405585975, 80966.0, 82972.58513707393, 83084.0, 927.0868194828089, 954.1508057828538, 966.1739212972807, 971.4462756229022, 982.0130768577534, 15237.3905043797, 15303.010608620809, 15713.68572628518, 16022.759658604897, 16120.0, 36381.63618363888, 37796.62203378041, 38109.0, 38203.617963820005, 38450.0, 53217.20174197468, 54099.0, 54556.454435456726, 54638.18042729442, 54874.451255486936, 7194.640267986475, 7505.699772009277, 7515.3473023925735, 7551.207123251979, 7887.211278872067, 11047.613333533287, 11092.279001864854, 11196.0, 11365.943170284096, 11574.421278936188, 9426.434413935185, 9464.810703785748, 9524.666636667684, 9959.701208963743, 10122.240831937781, 9798.706038818847, 9864.260180486348, 10001.899981000097, 10015.799684006424, 10064.295499314978, 7674.424418168548, 7790.415718820997, 10112.89887101149, 10137.64518241858, 10366.896331036593, 10547.472626368804, 11084.501197445972, 11169.273997190157, 11261.49323280438, 11472.827907581226, 9092.499912504876, 9957.850632240377, 10400.375977441377, 10759.246852720235, 11383.601573944872], "value": 8504.980310816221, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.9223646581206, 1327.1307293722612, 1402.4740722229164, 1640.09794612963, 1654.7766051583033, 7040.225575186725, 7607.467477276593, 7613.923860761391, 7614.923850761491, 7620.961895190523, 6149.907751383722, 6329.272133704625, 6330.96834515828, 6454.451371633417, 7459.254074592538, 1792.3905872003484, 2104.736907886514, 2173.3588591365506, 2175.9564808703817, 2178.3900507857825, 2143.699882016519, 2149.0758973641327, 2151.8493705440624, 2153.6446486329814, 2158.7301587301586, 2091.7489901211798, 2175.2386664667397, 2181.2801775414155, 2182.2580322690283, 2183.552371763785, 2107.2519255664324, 2126.362091372582, 2127.489402543394, 2127.914883404662, 2135.0819147766456, 2096.727425434699, 2098.412444515538, 2102.1591363454586, 2104.8000439958178, 2109.177420805887, 7507.4369422293, 7569.394448444111, 7618.314351708317, 7664.923350766477, 7670.0795904491815, 4219.852305169305, 4337.913241735179, 4339.132173565299, 4350.58669426404, 4360.084382279725, 2823.898679514991, 2951.940961180764, 2998.205475548981, 3001.4597372472945, 3012.3071693510615, 963.4363897120123, 973.5667627905585, 974.4543055888724, 990.2177279948831, 990.3958585262984, 10024.749381265454, 10033.49832508379, 10045.598176072948, 10051.597936082548, 10093.495325233709, 4078.1028173801715, 4159.542450330461, 4228.154369126187, 4229.809658565372, 4305.332673435622, 8065.6370463329295, 8141.552214628134, 8190.262876341158, 8191.83616327682, 8195.46729462583, 4330.891727706832, 4333.414988976511, 4341.739495630272, 4343.847965321199, 4344.630706389929, 3908.8631897884006, 3978.1844721831953, 3982.900427489278, 3988.66096381805, 4021.8391264349098, 3076.6923307669504, 3207.935841283161, 3374.6962773350515, 3422.0418282880833, 3422.281320922634, 1030.4847576211894, 1039.417925961464, 1041.5365162502615, 1049.2026060194307, 1059.9523021464054, 751.1924680967982, 762.904636920385, 777.7705576854842, 792.5522080024883, 794.8171920458327, 4926.3842019747535, 5034.194528875362, 5038.823641172541, 5075.87310317245, 5312.256284120226, 801.5831767480877, 805.5126648377765, 848.8533513785081, 854.9529775862387, 855.8288342331558, 3409.4885767134633, 3417.3165366926714, 3445.242046749711, 3480.704140148065, 3493.9650603493637, 8674.78313042179, 9039.63841446348, 9138.13187747163, 9154.542272886329, 9185.219256363283, 9081.54592270384, 9105.908940910505, 9122.498262595489, 9124.0, 9138.908610913939, 6968.756093536702, 6972.09362782835, 6991.6853741580735, 7013.92986070143, 7070.328318809705, 4959.876003099951, 5045.318881951036, 5062.670926389773, 5119.667221630582, 5163.845084647466, 8380.455270407405, 8406.075331713384, 8456.069832318562, 8479.36404769657, 8535.573221338784, 8214.794630134294, 8455.830883382421, 8647.697330593399, 8683.913160868311, 8707.651693932172, 6162.137300777805, 6258.342873998165, 6258.405451482102, 6272.278687950944, 6272.6236425814595, 6062.060380641008, 6794.626295553792, 6964.408025317702, 7067.081279433641, 7161.820954475971, 6465.676716164267, 6479.157709497735, 6508.967455162694, 6518.413342799171, 6566.146400967844, 6344.1118243445035, 6487.935120648733, 6547.443467305332, 6623.503237257129, 6677.3656502632175, 5782.681952492653, 5924.200232968494, 5960.642361458326, 5978.402159784161, 6036.094585812163, 4398.450193725785, 4453.064856380196, 4499.257622492252, 4536.6143877770755, 4564.977175114103, 4434.689571729948, 4582.19811532987, 4590.77046147698, 4595.333676616939, 4623.88440288982], "value": 3957.0392968869382, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.3414484482337, 295.71758970183464, 300.7293435907683, 302.7608189530271, 304.4884593882277, 4917.2378281366355, 4918.926216106757, 4930.975345123269, 4942.703437793735, 4943.382077240345, 4884.0, 4890.877728056791, 4891.804327826882, 4896.338994235777, 4896.922677011052, 3647.398179300419, 3760.417135344026, 3779.225258821935, 3780.621937806225, 3809.8095095245267, 3745.3632882410056, 3801.3157631626295, 3847.3074846527616, 3880.553736320331, 3919.176972835708, 3835.271298453299, 3839.1170030892913, 3851.557070936836, 3868.516435445569, 3883.8834834954855, 3719.888403347904, 3772.622737726233, 3776.2447510497736, 3835.1562656215588, 3854.807259637035, 3375.2405708715587, 3378.847951842173, 3389.322135572895, 3398.898033059012, 3411.1301618087227, 4754.215554433515, 4758.3576217210575, 4778.952210477885, 4795.928061079087, 4800.447948485934, 4401.823927042915, 4412.669049821244, 4413.404190434282, 4415.977920110379, 4438.578335058164, 4881.877953051202, 5083.135866902636, 5089.54194122527, 5097.643164978453, 5120.180771076647, 3726.105734623698, 3733.21602463483, 3735.9066023349633, 3748.4377343398437, 3752.624737526253, 5388.434214407509, 5388.811391601275, 5393.325834270716, 5397.595180361449, 5401.70290634015, 3535.116220944764, 3741.214344987583, 3756.4553139794575, 3759.6240375962184, 3834.175652234783, 3780.243951209769, 3786.6213378662464, 3794.734368594227, 3797.4873392091713, 3798.2593394287846, 4306.246406878784, 4308.375285583573, 4340.893072266615, 4356.780101571565, 4358.259095953696, 4916.778744956486, 5092.0, 5156.613254005964, 5157.484251574888, 5160.690358578498, 1275.67470295076, 1293.3921057103146, 1294.152330223705, 1298.7077907470743, 1300.1938797945145, 864.1833467373272, 865.1521508921293, 882.6910581296495, 886.5965985476722, 891.7012800711834, 4329.523752387235, 4406.633943477532, 4534.909301813946, 4538.954610453853, 4548.363229147856, 5185.403678576937, 5223.2948551945765, 5251.474852514718, 5264.236685680555, 5284.7886084556185, 4479.955200448019, 4501.752403617833, 4514.0, 4517.638588912879, 4523.819047238139, 2656.6015097735494, 2777.250142461524, 2781.805273630867, 2783.0, 2783.7077106903484, 4951.702897826142, 4958.206686930074, 4958.504149585013, 4981.775820088104, 4992.726854652113, 5202.739863006835, 5391.191321301758, 5401.540869026177, 5406.702631355313, 5472.713912230623, 4933.827316043921, 4941.604671626261, 4988.476209997898, 4989.875253118701, 5030.308784800114, 5279.656822306538, 5287.180487024518, 5303.337082864642, 5310.256564081109, 5316.548093412103, 4877.9024419512125, 5077.847664570068, 5143.459936706592, 5187.0, 5193.714345711022, 4910.238912968495, 4919.655624106278, 4924.729139897197, 4939.333190019302, 4939.753012349296, 3730.328540862562, 3731.160488889978, 3754.831032603485, 3759.8496060157286, 3778.905527361838, 5021.84934451967, 5301.655392399482, 5309.0, 5309.469053094814, 5316.723986243196, 5128.358895169065, 5138.280640710229, 5147.433782284021, 5158.484151584811, 5158.561522270649, 5090.363704536933, 5145.331106956071, 5166.984875898327, 5173.603127155722, 5180.896382072413, 4835.492273311252, 5036.823711170092, 5091.821786237464, 5112.74436278192, 5123.436421993652, 4886.560209581013, 5015.22264049073, 5130.281760553451, 5137.640365174402, 5160.845174644766, 5036.949630503648, 5038.9010758384065, 5039.807683657621, 5046.59627229828, 5065.606958086556], "value": 3864.5687948776526, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.5846346033729, 673.0711617967205, 690.8687349403613, 726.9491135620506, 746.078592937722, 12035.277883327006, 13287.202767833936, 13317.334133293345, 13432.66418339542, 13437.865621343784, 13339.53311634093, 13389.732205355887, 13454.394552245149, 13463.12489688172, 13463.93268033661, 616.4975544930867, 744.5793126883307, 748.2928632442345, 762.9122650895135, 764.3503022430945, 760.3499008347866, 763.522798251093, 767.597011568928, 767.6545554500497, 767.8502691975067, 817.5748610722421, 818.0102076487427, 818.5661599352323, 818.9426740128164, 819.1726356380069, 648.0376640688573, 657.7007461605006, 813.0081300813029, 813.4671789977574, 813.5118928642826, 488.6945658963148, 488.91199584074855, 574.8620331120501, 579.3164066401645, 588.5438784941662, 11468.598599048993, 11518.481668324861, 11519.30884146954, 11532.538698452136, 11566.768664626661, 11753.35356555389, 12635.241885486808, 12667.556635517805, 13001.479940802357, 13402.477442729654, 11712.824307635392, 11748.118891083117, 11762.88237117635, 11928.10539209554, 11975.820362694567, 3917.5690674025836, 4025.1547175093265, 4030.738002029888, 4037.3338399164104, 4168.937465938014, 60812.08781868231, 61197.38802611962, 61496.38503614996, 62130.0, 62263.37736622621, 9685.612575496902, 9790.118889299854, 9983.900160998443, 10062.245331600014, 10210.387376757419, 17853.642927141384, 17866.91066544659, 17869.28522859071, 17884.91057544704, 17887.552811179823, 6681.0, 6714.630695311707, 6756.391924726799, 6835.4189893859575, 6992.720291188397, 3635.345637785171, 3642.1440961374055, 3688.0, 3694.9630503695157, 3742.0083677825396, 2647.457271259387, 2728.9181324560295, 2738.3701748597537, 2738.3975525384385, 2743.478739039576, 481.5689957488029, 493.89628178082285, 496.68212344099805, 504.8006037615195, 510.4436164580625, 441.82768720199147, 447.7805875121167, 522.7621432248318, 526.3341872531252, 560.3499940069581, 7017.7192912282935, 7303.3792127668685, 7445.0, 7527.661255243527, 7549.50928189666, 469.5867636479876, 511.28847668732806, 536.9543588794996, 545.5962587685084, 571.0492030768806, 3602.2975519773395, 3651.470536772153, 3723.906902327409, 3732.496113024761, 3784.6404591563755, 57345.426545734845, 58178.41821581815, 100762.48856267161, 102926.48536757418, 107056.0, 74062.0, 85211.57394213113, 86916.13083869207, 87016.56491717501, 88756.0, 81018.0, 81300.59349703214, 83697.0, 84053.59464053668, 84109.0, 7012.123484564429, 7032.0, 7082.220955694973, 7310.671019804228, 7344.742933997284, 38317.2336553273, 46115.30827037664, 47056.52943470521, 47936.7603161982, 49029.0, 60271.39728602658, 65134.81219284161, 65988.0, 66489.0, 68988.0, 9476.715698529055, 9507.286953478379, 9679.177269931854, 9740.61037558518, 10152.949235253776, 10226.948865255627, 10297.330673506498, 10518.0, 10533.473326333806, 10816.459177040959, 9646.228301735704, 9800.9708980556, 9905.851412228967, 9946.701598952042, 10141.543630536791, 9989.203023153528, 10061.899381006097, 11538.48076836561, 11674.416279186176, 12476.563320283747, 11238.438078095898, 11496.770064598493, 11862.051035917279, 11927.880721192678, 11979.101567382655, 9330.813383732424, 10133.0, 11040.392778397265, 11195.10439164881, 11215.71960700989, 8058.153893841297, 8178.141295163922, 9521.476318802532, 9701.854472183064, 9797.265205109788], "value": 6324.686183682349, "unit": "score"}], "name": "dromaeo_css", "value": 6380.952606763913, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 115.0, 115.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 124.0, 125.0, 185.0, 125.0, 134.0, 150.0, 121.0, 184.0, 122.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 160.0, 162.0, 162.0, 162.0, 162.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 118.0, 77.0, 76.0, 75.0, 75.0, 100.0, 76.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 124.0, 84.0, 85.0, 85.0, 130.0, 84.0, 85.0, 128.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.1, "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, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 61.0, 63.0, 62.0, 61.0, 61.0, 62.0, 63.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 67.0, 66.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 80.0, 80.0, 81.0, 81.0, 86.0, 80.0, 85.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 141.0, 129.0, 129.0, 127.0, 128.0, 128.0, 176.0, 135.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 167.0, 167.0, 166.0, 168.0, 167.0, 168.0, 167.0, 168.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 69.0, 67.0, 69.0, 67.0, 66.0, 68.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1533.6999999999998, "alertThreshold": 2.0}]} 16:20:33 INFO - Return code: 0 16:20:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:20:33 INFO - # TBPL SUCCESS # 16:20:33 INFO - Running post-action listener: _resource_record_post_action 16:20:33 INFO - [mozharness: 2016-08-01 23:20:33.724925Z] Finished run-tests step (success) 16:20:33 INFO - Running post-run listener: _resource_record_post_run 16:20:33 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 535334912; Read time: 12; Write time: 528688 16:20:33 INFO - TinderboxPrint: CPU usage
13.0% 16:20:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:20:33 INFO - TinderboxPrint: I/O write bytes / time
535,334,912 / 528,688 16:20:33 INFO - TinderboxPrint: CPU idle
8,325.9 (87.0%) 16:20:33 INFO - TinderboxPrint: CPU user
1,199.8 (12.5%) 16:20:33 INFO - TinderboxPrint: Swap in / out
0 / 0 16:20:33 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 253669376; Read time: 0; Write time: 271844 16:20:33 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 281665536; Read time: 0; Write time: 256844 16:20:34 INFO - Running post-run listener: _upload_blobber_files 16:20:34 INFO - Blob upload gear active. 16:20:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:20:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:20:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:20:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:20:34 INFO - (blobuploader) - INFO - Open directory for files ... 16:20:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:20:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:20:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:36 INFO - (blobuploader) - INFO - Done attempting. 16:20:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:20:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:20:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:36 INFO - (blobuploader) - INFO - Done attempting. 16:20:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:20:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:20:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:40 INFO - (blobuploader) - INFO - Done attempting. 16:20:40 INFO - (blobuploader) - INFO - Iteration through files over. 16:20:40 INFO - Return code: 0 16:20:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:20:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:20:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9ea517d165383f8dc1df88a396e09705befbbf531859804f9ffd23e8ba9df26bc4b79db8d1241b86f074a619bfafda20a007b285a8b974874b7d9e6cd6ef4dd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dc25a2ab1f5b6d90e0293ae1b8360198db5c127e267d8471d9b839d484cadd2332a56be29824b33ae2062a3405978d8d9d1d6f86129074dcab78f6db0be56e46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/972a6cfbec45b663751741170ea2c6775810fe08a2412525716735725617559a8f6af30f61a042745d1001431de0993659bdaa622a490de0dd5d63bd679ab182"} 16:20:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:20:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:20:40 INFO - Contents: 16:20:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9ea517d165383f8dc1df88a396e09705befbbf531859804f9ffd23e8ba9df26bc4b79db8d1241b86f074a619bfafda20a007b285a8b974874b7d9e6cd6ef4dd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dc25a2ab1f5b6d90e0293ae1b8360198db5c127e267d8471d9b839d484cadd2332a56be29824b33ae2062a3405978d8d9d1d6f86129074dcab78f6db0be56e46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/972a6cfbec45b663751741170ea2c6775810fe08a2412525716735725617559a8f6af30f61a042745d1001431de0993659bdaa622a490de0dd5d63bd679ab182"} 16:20:40 INFO - Running post-run listener: copy_logs_to_upload_dir 16:20:40 INFO - Copying logs to upload dir... 16:20:40 INFO - mkdir: /builds/slave/test/build/upload/logs 16:20:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.328482 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-01 16:20:40.426938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 16:20:40.430346) ========= 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=4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9ea517d165383f8dc1df88a396e09705befbbf531859804f9ffd23e8ba9df26bc4b79db8d1241b86f074a619bfafda20a007b285a8b974874b7d9e6cd6ef4dd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dc25a2ab1f5b6d90e0293ae1b8360198db5c127e267d8471d9b839d484cadd2332a56be29824b33ae2062a3405978d8d9d1d6f86129074dcab78f6db0be56e46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/972a6cfbec45b663751741170ea2c6775810fe08a2412525716735725617559a8f6af30f61a042745d1001431de0993659bdaa622a490de0dd5d63bd679ab182"} build_url:https://queue.taskcluster.net/v1/task/DWGYgxCURkmU-HKm9Yc6XA/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/DWGYgxCURkmU-HKm9Yc6XA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9ea517d165383f8dc1df88a396e09705befbbf531859804f9ffd23e8ba9df26bc4b79db8d1241b86f074a619bfafda20a007b285a8b974874b7d9e6cd6ef4dd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dc25a2ab1f5b6d90e0293ae1b8360198db5c127e267d8471d9b839d484cadd2332a56be29824b33ae2062a3405978d8d9d1d6f86129074dcab78f6db0be56e46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/972a6cfbec45b663751741170ea2c6775810fe08a2412525716735725617559a8f6af30f61a042745d1001431de0993659bdaa622a490de0dd5d63bd679ab182"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 16:20:40.458160) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:20:40.458816) ========= 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=4ac8d263c7293b36a1be1aed000001be-1470092219.96777-103325225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:20:40.544161) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 16:20:40.544702) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 16:20:40.545319) ========= ========= Total master_lag: 0.41 =========