builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1469772532.77 results: success (0) buildid: 20160728203011 builduid: c5832e779f274d1b83bfb2af852eeff3 revision: 7fd2a709bd6cd1714d8e6639dce3fda9f7b7d899 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:52.771885) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:52.772361) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:52.772665) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:52.815421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:52.815830) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-28 23:08:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 103M=0s 2016-07-28 23:08:53 (103 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109148 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:52.947717) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:52.948074) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021973 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:53.004135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:53.004514) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7fd2a709bd6cd1714d8e6639dce3fda9f7b7d899 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 7fd2a709bd6cd1714d8e6639dce3fda9f7b7d899 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-28 23:08:53,180 truncating revision to first 12 chars 2016-07-28 23:08:53,180 Setting DEBUG logging. 2016-07-28 23:08:53,180 attempt 1/10 2016-07-28 23:08:53,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7fd2a709bd6c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-28 23:08:53,523 unpacking tar archive at: fx-team-7fd2a709bd6c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.720489 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:53.741693) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:53.742204) ========= script_repo_revision: 7fd2a709bd6cd1714d8e6639dce3fda9f7b7d899 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:53.742874) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:53.743348) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 23:08:53.755851) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-28 23:08:53.756487) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:08:54 INFO - MultiFileLogger online at 20160728 23:08:54 in /builds/slave/test 23:08:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:08:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:08:54 INFO - {'append_to_log': False, 23:08:54 INFO - 'base_work_dir': '/builds/slave/test', 23:08:54 INFO - 'blob_upload_branch': 'Fx-Team', 23:08:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:08:54 INFO - 'branch': 'Fx-Team', 23:08:54 INFO - 'buildbot_json_path': 'buildprops.json', 23:08:54 INFO - 'config_files': ('talos/linux_config.py',), 23:08:54 INFO - 'default_actions': ('clobber', 23:08:54 INFO - 'read-buildbot-config', 23:08:54 INFO - 'download-and-extract', 23:08:54 INFO - 'populate-webroot', 23:08:54 INFO - 'create-virtualenv', 23:08:54 INFO - 'install', 23:08:54 INFO - 'run-tests'), 23:08:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:08:54 INFO - 'download_minidump_stackwalk': True, 23:08:54 INFO - 'download_symbols': 'ondemand', 23:08:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:08:54 INFO - 'tooltool.py': '/tools/tooltool.py', 23:08:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:08:54 INFO - '/tools/misc-python/virtualenv.py')}, 23:08:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:08:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:08:54 INFO - 'installer_path': 'installer.exe', 23:08:54 INFO - 'log_level': 'info', 23:08:54 INFO - 'log_name': 'talos', 23:08:54 INFO - 'log_to_console': True, 23:08:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:08:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:08:54 INFO - 'opt_config_files': (), 23:08:54 INFO - 'pip_index': False, 23:08:54 INFO - 'sps_profile': False, 23:08:54 INFO - 'sps_profile_interval': 0, 23:08:54 INFO - 'suite': 'dromaeojs', 23:08:54 INFO - 'system_bits': '32', 23:08:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:08:54 INFO - 'title': 'talos-linux64-ix-027', 23:08:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:08:54 INFO - 'use_talos_json': True, 23:08:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:08:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:08:54 INFO - 'work_dir': 'build'} 23:08:54 INFO - [mozharness: 2016-07-29 06:08:54.022894Z] Running clobber step. 23:08:54 INFO - Running pre-action listener: _resource_record_pre_action 23:08:54 INFO - Running main action method: clobber 23:08:54 INFO - rmtree: /builds/slave/test/build 23:08:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:08:54 INFO - Running post-action listener: _resource_record_post_action 23:08:54 INFO - [mozharness: 2016-07-29 06:08:54.492604Z] Finished clobber step (success) 23:08:54 INFO - [mozharness: 2016-07-29 06:08:54.492706Z] Running read-buildbot-config step. 23:08:54 INFO - Running pre-action listener: _resource_record_pre_action 23:08:54 INFO - Running main action method: read_buildbot_config 23:08:54 INFO - Using buildbot properties: 23:08:54 INFO - { 23:08:54 INFO - "project": "", 23:08:54 INFO - "product": "firefox", 23:08:54 INFO - "script_repo_revision": "production", 23:08:54 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:08:54 INFO - "repository": "", 23:08:54 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:08:54 INFO - "buildid": "20160728203011", 23:08:54 INFO - "pgo_build": "True", 23:08:54 INFO - "basedir": "/builds/slave/test", 23:08:54 INFO - "buildnumber": 1386, 23:08:54 INFO - "slavename": "talos-linux64-ix-027", 23:08:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:08:54 INFO - "platform": "ubuntu64_hw", 23:08:54 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:08:54 INFO - "branch": "fx-team", 23:08:54 INFO - "repo_path": "integration/fx-team", 23:08:54 INFO - "revision": "7fd2a709bd6cd1714d8e6639dce3fda9f7b7d899", 23:08:54 INFO - "stage_platform": "linux64-pgo", 23:08:54 INFO - "builduid": "c5832e779f274d1b83bfb2af852eeff3", 23:08:54 INFO - "slavebuilddir": "test" 23:08:54 INFO - } 23:08:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:08:54 INFO - Running post-action listener: _resource_record_post_action 23:08:54 INFO - [mozharness: 2016-07-29 06:08:54.495466Z] Finished read-buildbot-config step (success) 23:08:54 INFO - [mozharness: 2016-07-29 06:08:54.495555Z] Running download-and-extract step. 23:08:54 INFO - Running pre-action listener: _resource_record_pre_action 23:08:54 INFO - Running main action method: download_and_extract 23:08:54 INFO - mkdir: /builds/slave/test/build/tests 23:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:54 INFO - https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:08:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:08:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:08:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:08:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:08:55 INFO - Downloaded 1361 bytes. 23:08:55 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:08:55 INFO - Using the following test package requirements: 23:08:55 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:08:55 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:55 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:08:55 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:55 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:08:55 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:55 INFO - u'jsshell-linux-x86_64.zip'], 23:08:55 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:55 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:08:55 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:08:55 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:55 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:08:55 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:55 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:08:55 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:55 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:08:55 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:55 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:08:55 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:55 INFO - https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:08:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:08:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:08:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:08:59 INFO - Downloaded 24321954 bytes. 23:08:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:08:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:09:00 INFO - Return code: 0 23:09:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:00 INFO - https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:09:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:09:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:09:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:09:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:09:04 INFO - Downloaded 24321954 bytes. 23:09:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:09:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:09:05 INFO - Return code: 0 23:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:05 INFO - https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:09:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:09:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:09:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:09:07 INFO - Downloaded 14413725 bytes. 23:09:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:09:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:09:07 INFO - Return code: 0 23:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:07 INFO - https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:09:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:09:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:09:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:09:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:09:12 INFO - Downloaded 61018196 bytes. 23:09:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:09:12 INFO - mkdir: /builds/slave/test/properties 23:09:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:09:12 INFO - Writing to file /builds/slave/test/properties/build_url 23:09:12 INFO - Contents: 23:09:12 INFO - build_url:https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:09:13 INFO - Running post-action listener: _resource_record_post_action 23:09:13 INFO - Running post-action listener: set_extra_try_arguments 23:09:13 INFO - [mozharness: 2016-07-29 06:09:13.233348Z] Finished download-and-extract step (success) 23:09:13 INFO - [mozharness: 2016-07-29 06:09:13.233587Z] Running populate-webroot step. 23:09:13 INFO - Running pre-action listener: _resource_record_pre_action 23:09:13 INFO - Running main action method: populate_webroot 23:09:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:09:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:09:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:09:13 INFO - '--tppagecycles', 23:09:13 INFO - '7'], 23:09:13 INFO - 'tests': ['tsvgm']}}, 23:09:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:09:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:09:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:09:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:09:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:13 INFO - 'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:09:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:09:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:13 INFO - 'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['damp', 'tps']}, 23:09:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:13 INFO - 'tests': ['damp', 'tps']}, 23:09:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['dromaeo_dom']}, 23:09:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:09:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['basic_compositor_video']}, 23:09:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:09:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['a11yr', 23:09:13 INFO - 'ts_paint', 23:09:13 INFO - 'tpaint', 23:09:13 INFO - 'sessionrestore', 23:09:13 INFO - 'sessionrestore_no_auto_restore', 23:09:13 INFO - 'tabpaint']}, 23:09:13 INFO - 'other-e10s': {'tests': ['a11yr', 23:09:13 INFO - 'ts_paint', 23:09:13 INFO - 'tpaint', 23:09:13 INFO - 'sessionrestore', 23:09:13 INFO - 'sessionrestore_no_auto_restore', 23:09:13 INFO - 'tabpaint']}, 23:09:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:09:13 INFO - 'ts_paint', 23:09:13 INFO - 'tpaint', 23:09:13 INFO - 'sessionrestore', 23:09:13 INFO - 'sessionrestore_no_auto_restore', 23:09:13 INFO - 'tabpaint']}, 23:09:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:09:13 INFO - 'ts_paint', 23:09:13 INFO - 'tpaint', 23:09:13 INFO - 'sessionrestore', 23:09:13 INFO - 'sessionrestore_no_auto_restore', 23:09:13 INFO - 'tabpaint']}, 23:09:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['a11yr', 23:09:13 INFO - 'ts_paint', 23:09:13 INFO - 'tpaint', 23:09:13 INFO - 'sessionrestore', 23:09:13 INFO - 'sessionrestore_no_auto_restore', 23:09:13 INFO - 'tabpaint']}, 23:09:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['a11yr', 23:09:13 INFO - 'ts_paint', 23:09:13 INFO - 'tpaint', 23:09:13 INFO - 'sessionrestore', 23:09:13 INFO - 'sessionrestore_no_auto_restore', 23:09:13 INFO - 'tabpaint']}, 23:09:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['tsvgx', 23:09:13 INFO - 'tsvgr_opacity', 23:09:13 INFO - 'tart', 23:09:13 INFO - 'tscrollx', 23:09:13 INFO - 'cart']}, 23:09:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:09:13 INFO - 'tsvgr_opacity', 23:09:13 INFO - 'tart', 23:09:13 INFO - 'tscrollx', 23:09:13 INFO - 'cart']}, 23:09:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:13 INFO - 'talos_options': ['--disable-e10s'], 23:09:13 INFO - 'tests': ['tp5o']}, 23:09:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:13 INFO - 'tests': ['tp5o']}, 23:09:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:13 INFO - 'talos_options': ['--disable-e10s', 23:09:13 INFO - '--xperf_path', 23:09:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:09:13 INFO - 'tests': ['tp5n']}, 23:09:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:13 INFO - 'talos_options': ['--xperf_path', 23:09:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:09:13 INFO - 'tests': ['tp5n']}}, 23:09:13 INFO - 'talos.zip': {'path': '', 23:09:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:09:13 INFO - Running post-action listener: _resource_record_post_action 23:09:13 INFO - [mozharness: 2016-07-29 06:09:13.254314Z] Finished populate-webroot step (success) 23:09:13 INFO - [mozharness: 2016-07-29 06:09:13.254404Z] Running create-virtualenv step. 23:09:13 INFO - Running pre-action listener: _resource_record_pre_action 23:09:13 INFO - Running main action method: create_virtualenv 23:09:13 INFO - Creating virtualenv /builds/slave/test/build/venv 23:09:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:09:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:09:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:09:13 INFO - Using real prefix '/usr' 23:09:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:09:13 INFO - Installing distribute.............................................................................................................................................................................................done. 23:09:15 INFO - Installing pip.................done. 23:09:15 INFO - Return code: 0 23:09:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:09:15 INFO - Using env: {'DISPLAY': ':0', 23:09:15 INFO - 'HOME': '/home/cltbld', 23:09:15 INFO - 'LANG': 'en_US.UTF-8', 23:09:15 INFO - 'LANGUAGE': 'en_US:en', 23:09:15 INFO - 'LOGNAME': 'cltbld', 23:09:15 INFO - 'MAIL': '/var/mail/cltbld', 23:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:15 INFO - 'MOZ_NO_REMOTE': '1', 23:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:15 INFO - 'NO_EM_RESTART': '1', 23:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:15 INFO - 'PWD': '/builds/slave/test', 23:09:15 INFO - 'SHELL': '/bin/bash', 23:09:15 INFO - 'SHLVL': '1', 23:09:15 INFO - 'TERM': 'linux', 23:09:15 INFO - 'TMOUT': '86400', 23:09:15 INFO - 'USER': 'cltbld', 23:09:15 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:15 INFO - '_': '/tools/buildbot/bin/python'} 23:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:09:16 INFO - Cleaning up... 23:09:16 INFO - Return code: 0 23:09:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:16 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 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:09:16 INFO - Using env: {'DISPLAY': ':0', 23:09:16 INFO - 'HOME': '/home/cltbld', 23:09:16 INFO - 'LANG': 'en_US.UTF-8', 23:09:16 INFO - 'LANGUAGE': 'en_US:en', 23:09:16 INFO - 'LOGNAME': 'cltbld', 23:09:16 INFO - 'MAIL': '/var/mail/cltbld', 23:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:16 INFO - 'MOZ_NO_REMOTE': '1', 23:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:16 INFO - 'NO_EM_RESTART': '1', 23:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:16 INFO - 'PWD': '/builds/slave/test', 23:09:16 INFO - 'SHELL': '/bin/bash', 23:09:16 INFO - 'SHLVL': '1', 23:09:16 INFO - 'TERM': 'linux', 23:09:16 INFO - 'TMOUT': '86400', 23:09:16 INFO - 'USER': 'cltbld', 23:09:16 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:16 INFO - '_': '/tools/buildbot/bin/python'} 23:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:16 INFO - Downloading/unpacking psutil>=3.1.1 23:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:09:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:09:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:09:18 INFO - Installing collected packages: psutil 23:09:18 INFO - Running setup.py install for psutil 23:09:18 INFO - building 'psutil._psutil_linux' extension 23:09:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 23:09:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:09:18 INFO - building 'psutil._psutil_posix' extension 23:09:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:09:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:09:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:09:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:09:18 INFO - Successfully installed psutil 23:09:18 INFO - Cleaning up... 23:09:18 INFO - Return code: 0 23:09:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:09:18 INFO - Using env: {'DISPLAY': ':0', 23:09:18 INFO - 'HOME': '/home/cltbld', 23:09:18 INFO - 'LANG': 'en_US.UTF-8', 23:09:18 INFO - 'LANGUAGE': 'en_US:en', 23:09:18 INFO - 'LOGNAME': 'cltbld', 23:09:18 INFO - 'MAIL': '/var/mail/cltbld', 23:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:18 INFO - 'MOZ_NO_REMOTE': '1', 23:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:18 INFO - 'NO_EM_RESTART': '1', 23:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:18 INFO - 'PWD': '/builds/slave/test', 23:09:18 INFO - 'SHELL': '/bin/bash', 23:09:18 INFO - 'SHLVL': '1', 23:09:18 INFO - 'TERM': 'linux', 23:09:18 INFO - 'TMOUT': '86400', 23:09:18 INFO - 'USER': 'cltbld', 23:09:18 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:18 INFO - '_': '/tools/buildbot/bin/python'} 23:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:09:20 INFO - Installing collected packages: mozsystemmonitor 23:09:20 INFO - Running setup.py install for mozsystemmonitor 23:09:21 INFO - Successfully installed mozsystemmonitor 23:09:21 INFO - Cleaning up... 23:09:21 INFO - Return code: 0 23:09:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:21 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 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:09:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:09:21 INFO - Using env: {'DISPLAY': ':0', 23:09:21 INFO - 'HOME': '/home/cltbld', 23:09:21 INFO - 'LANG': 'en_US.UTF-8', 23:09:21 INFO - 'LANGUAGE': 'en_US:en', 23:09:21 INFO - 'LOGNAME': 'cltbld', 23:09:21 INFO - 'MAIL': '/var/mail/cltbld', 23:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:21 INFO - 'MOZ_NO_REMOTE': '1', 23:09:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:21 INFO - 'NO_EM_RESTART': '1', 23:09:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:21 INFO - 'PWD': '/builds/slave/test', 23:09:21 INFO - 'SHELL': '/bin/bash', 23:09:21 INFO - 'SHLVL': '1', 23:09:21 INFO - 'TERM': 'linux', 23:09:21 INFO - 'TMOUT': '86400', 23:09:21 INFO - 'USER': 'cltbld', 23:09:21 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:21 INFO - '_': '/tools/buildbot/bin/python'} 23:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:21 INFO - Downloading/unpacking jsonschema==2.5.1 23:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:09:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:09: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 23:09: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 23:09: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 23:09: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 23:09: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 23:09: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 23:09:23 INFO - Downloading functools32-3.2.3-2.tar.gz 23:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:09:23 INFO - warning: no files found matching '*.txt' 23:09:23 INFO - no previously-included directories found matching 'build' 23:09:23 INFO - no previously-included directories found matching 'dist' 23:09:23 INFO - no previously-included directories found matching '.git*' 23:09:23 INFO - Installing collected packages: jsonschema, functools32 23:09:23 INFO - Running setup.py install for functools32 23:09:23 INFO - warning: no files found matching '*.txt' 23:09:23 INFO - no previously-included directories found matching 'build' 23:09:23 INFO - no previously-included directories found matching 'dist' 23:09:23 INFO - no previously-included directories found matching '.git*' 23:09:23 INFO - Successfully installed jsonschema functools32 23:09:23 INFO - Cleaning up... 23:09:23 INFO - Return code: 0 23:09:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:09:23 INFO - Using env: {'DISPLAY': ':0', 23:09:23 INFO - 'HOME': '/home/cltbld', 23:09:23 INFO - 'LANG': 'en_US.UTF-8', 23:09:23 INFO - 'LANGUAGE': 'en_US:en', 23:09:23 INFO - 'LOGNAME': 'cltbld', 23:09:23 INFO - 'MAIL': '/var/mail/cltbld', 23:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:23 INFO - 'MOZ_NO_REMOTE': '1', 23:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:23 INFO - 'NO_EM_RESTART': '1', 23:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:23 INFO - 'PWD': '/builds/slave/test', 23:09:23 INFO - 'SHELL': '/bin/bash', 23:09:23 INFO - 'SHLVL': '1', 23:09:23 INFO - 'TERM': 'linux', 23:09:23 INFO - 'TMOUT': '86400', 23:09:23 INFO - 'USER': 'cltbld', 23:09:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:23 INFO - '_': '/tools/buildbot/bin/python'} 23:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:09:23 INFO - Cleaning up... 23:09:23 INFO - Return code: 0 23:09:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:09:23 INFO - Using env: {'DISPLAY': ':0', 23:09:23 INFO - 'HOME': '/home/cltbld', 23:09:23 INFO - 'LANG': 'en_US.UTF-8', 23:09:23 INFO - 'LANGUAGE': 'en_US:en', 23:09:23 INFO - 'LOGNAME': 'cltbld', 23:09:23 INFO - 'MAIL': '/var/mail/cltbld', 23:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:23 INFO - 'MOZ_NO_REMOTE': '1', 23:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:23 INFO - 'NO_EM_RESTART': '1', 23:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:23 INFO - 'PWD': '/builds/slave/test', 23:09:23 INFO - 'SHELL': '/bin/bash', 23:09:23 INFO - 'SHLVL': '1', 23:09:23 INFO - 'TERM': 'linux', 23:09:23 INFO - 'TMOUT': '86400', 23:09:23 INFO - 'USER': 'cltbld', 23:09:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:23 INFO - '_': '/tools/buildbot/bin/python'} 23:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:24 INFO - Downloading/unpacking blobuploader==1.2.4 23:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:25 INFO - Downloading blobuploader-1.2.4.tar.gz 23:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:09:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:09:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:26 INFO - Downloading docopt-0.6.1.tar.gz 23:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:09:26 INFO - Installing collected packages: blobuploader, requests, docopt 23:09:26 INFO - Running setup.py install for blobuploader 23:09:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:09:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:09:26 INFO - Running setup.py install for requests 23:09:27 INFO - Running setup.py install for docopt 23:09:27 INFO - Successfully installed blobuploader requests docopt 23:09:27 INFO - Cleaning up... 23:09:27 INFO - Return code: 0 23:09:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:27 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 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:09:27 INFO - Using env: {'DISPLAY': ':0', 23:09:27 INFO - 'HOME': '/home/cltbld', 23:09:27 INFO - 'LANG': 'en_US.UTF-8', 23:09:27 INFO - 'LANGUAGE': 'en_US:en', 23:09:27 INFO - 'LOGNAME': 'cltbld', 23:09:27 INFO - 'MAIL': '/var/mail/cltbld', 23:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:27 INFO - 'MOZ_NO_REMOTE': '1', 23:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:27 INFO - 'NO_EM_RESTART': '1', 23:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:27 INFO - 'PWD': '/builds/slave/test', 23:09:27 INFO - 'SHELL': '/bin/bash', 23:09:27 INFO - 'SHLVL': '1', 23:09:27 INFO - 'TERM': 'linux', 23:09:27 INFO - 'TMOUT': '86400', 23:09:27 INFO - 'USER': 'cltbld', 23:09:27 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:27 INFO - '_': '/tools/buildbot/bin/python'} 23:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:09:27 INFO - Running setup.py (path:/tmp/pip-J2NJ3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:09:27 INFO - Running setup.py (path:/tmp/pip-LmV1Mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:09:27 INFO - Running setup.py (path:/tmp/pip-ga7dPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:09:27 INFO - Running setup.py (path:/tmp/pip-s6GqEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:09:27 INFO - Running setup.py (path:/tmp/pip-INpJum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:09:27 INFO - Running setup.py (path:/tmp/pip-sfByUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:09:28 INFO - Running setup.py (path:/tmp/pip-ovZpPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:09:28 INFO - Running setup.py (path:/tmp/pip-ooofiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:09:28 INFO - Running setup.py (path:/tmp/pip-RhGbP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:09:28 INFO - Running setup.py (path:/tmp/pip-Rjnn_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:09:28 INFO - Running setup.py (path:/tmp/pip-OABbaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:09:28 INFO - Running setup.py (path:/tmp/pip-QCArpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:09:28 INFO - Running setup.py (path:/tmp/pip-doIkPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:09:28 INFO - Running setup.py (path:/tmp/pip-uDVjGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:28 INFO - Running setup.py (path:/tmp/pip-YBiyGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:09:28 INFO - Running setup.py (path:/tmp/pip-d_znQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:09:28 INFO - Running setup.py (path:/tmp/pip-PzGDiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:09:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:09:28 INFO - Running setup.py install for manifestparser 23:09:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:09:28 INFO - Running setup.py install for mozcrash 23:09:29 INFO - Running setup.py install for mozdebug 23:09:29 INFO - Running setup.py install for mozdevice 23:09:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Running setup.py install for mozfile 23:09:29 INFO - Running setup.py install for mozhttpd 23:09:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Running setup.py install for mozinfo 23:09:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Running setup.py install for mozInstall 23:09:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Running setup.py install for mozleak 23:09:29 INFO - Running setup.py install for mozlog 23:09:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Running setup.py install for moznetwork 23:09:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:09:29 INFO - Running setup.py install for mozprocess 23:09:30 INFO - Running setup.py install for mozprofile 23:09:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:09:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:09:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:09:30 INFO - Running setup.py install for mozrunner 23:09:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:09:30 INFO - Running setup.py install for mozscreenshot 23:09:30 INFO - Running setup.py install for moztest 23:09:30 INFO - Running setup.py install for mozversion 23:09:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:09:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:09:30 INFO - Cleaning up... 23:09:30 INFO - Return code: 0 23:09:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:09:30 INFO - Using env: {'DISPLAY': ':0', 23:09:30 INFO - 'HOME': '/home/cltbld', 23:09:30 INFO - 'LANG': 'en_US.UTF-8', 23:09:30 INFO - 'LANGUAGE': 'en_US:en', 23:09:30 INFO - 'LOGNAME': 'cltbld', 23:09:30 INFO - 'MAIL': '/var/mail/cltbld', 23:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:30 INFO - 'MOZ_NO_REMOTE': '1', 23:09:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:30 INFO - 'NO_EM_RESTART': '1', 23:09:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:30 INFO - 'PWD': '/builds/slave/test', 23:09:30 INFO - 'SHELL': '/bin/bash', 23:09:30 INFO - 'SHLVL': '1', 23:09:30 INFO - 'TERM': 'linux', 23:09:30 INFO - 'TMOUT': '86400', 23:09:30 INFO - 'USER': 'cltbld', 23:09:30 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:30 INFO - '_': '/tools/buildbot/bin/python'} 23:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:09:30 INFO - Running setup.py (path:/tmp/pip-D2wHTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:09:30 INFO - Running setup.py (path:/tmp/pip-Qx9o9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:09:30 INFO - Running setup.py (path:/tmp/pip-ZdEBSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:09:31 INFO - Running setup.py (path:/tmp/pip-CKdxwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:09:31 INFO - Running setup.py (path:/tmp/pip-jJZ26b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:09:31 INFO - Running setup.py (path:/tmp/pip-3Vrlkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:09:31 INFO - Running setup.py (path:/tmp/pip-N5XHca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:09:31 INFO - Running setup.py (path:/tmp/pip-Ea5lMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:09:31 INFO - Running setup.py (path:/tmp/pip-NvlhCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:09:31 INFO - Running setup.py (path:/tmp/pip-vJctWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:09:31 INFO - Running setup.py (path:/tmp/pip-Y6NyTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:09:31 INFO - Running setup.py (path:/tmp/pip-lERZVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:09:31 INFO - Running setup.py (path:/tmp/pip-fx5Y_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:09:31 INFO - Running setup.py (path:/tmp/pip-bLVakD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:31 INFO - Running setup.py (path:/tmp/pip-fiXKx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:09:31 INFO - Running setup.py (path:/tmp/pip-tRWC5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:09:32 INFO - Running setup.py (path:/tmp/pip-JS0hng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:09:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:33 INFO - Downloading blessings-1.6.tar.gz 23:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:09:34 INFO - Installing collected packages: blessings 23:09:34 INFO - Running setup.py install for blessings 23:09:34 INFO - Successfully installed blessings 23:09:34 INFO - Cleaning up... 23:09:34 INFO - Return code: 0 23:09:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:09:34 INFO - Reading from file tmpfile_stdout 23:09:34 INFO - Current package versions: 23:09:34 INFO - argparse == 1.2.1 23:09:34 INFO - blessings == 1.6 23:09:34 INFO - blobuploader == 1.2.4 23:09:34 INFO - docopt == 0.6.1 23:09:34 INFO - functools32 == 3.2.3-2 23:09:34 INFO - jsonschema == 2.5.1 23:09:34 INFO - manifestparser == 1.1 23:09:34 INFO - mozInstall == 1.12 23:09:34 INFO - mozcrash == 0.17 23:09:34 INFO - mozdebug == 0.1 23:09:34 INFO - mozdevice == 0.48 23:09:34 INFO - mozfile == 1.2 23:09:34 INFO - mozhttpd == 0.7 23:09:34 INFO - mozinfo == 0.9 23:09:34 INFO - mozleak == 0.1 23:09:34 INFO - mozlog == 3.3 23:09:34 INFO - moznetwork == 0.27 23:09:34 INFO - mozprocess == 0.23 23:09:34 INFO - mozprofile == 0.28 23:09:34 INFO - mozrunner == 6.12 23:09:34 INFO - mozscreenshot == 0.1 23:09:34 INFO - mozsystemmonitor == 0.3 23:09:34 INFO - moztest == 0.7 23:09:34 INFO - mozversion == 1.4 23:09:34 INFO - psutil == 3.1.1 23:09:34 INFO - requests == 1.2.3 23:09:34 INFO - wsgiref == 0.1.2 23:09:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:09:34 INFO - Using env: {'DISPLAY': ':0', 23:09:34 INFO - 'HOME': '/home/cltbld', 23:09:34 INFO - 'LANG': 'en_US.UTF-8', 23:09:34 INFO - 'LANGUAGE': 'en_US:en', 23:09:34 INFO - 'LOGNAME': 'cltbld', 23:09:34 INFO - 'MAIL': '/var/mail/cltbld', 23:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:34 INFO - 'MOZ_NO_REMOTE': '1', 23:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:34 INFO - 'NO_EM_RESTART': '1', 23:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:34 INFO - 'PWD': '/builds/slave/test', 23:09:34 INFO - 'SHELL': '/bin/bash', 23:09:34 INFO - 'SHLVL': '1', 23:09:34 INFO - 'TERM': 'linux', 23:09:34 INFO - 'TMOUT': '86400', 23:09:34 INFO - 'USER': 'cltbld', 23:09:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:34 INFO - '_': '/tools/buildbot/bin/python'} 23:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:09:34 INFO - Cleaning up... 23:09:34 INFO - Return code: 0 23:09:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:09:34 INFO - Using env: {'DISPLAY': ':0', 23:09:34 INFO - 'HOME': '/home/cltbld', 23:09:34 INFO - 'LANG': 'en_US.UTF-8', 23:09:34 INFO - 'LANGUAGE': 'en_US:en', 23:09:34 INFO - 'LOGNAME': 'cltbld', 23:09:34 INFO - 'MAIL': '/var/mail/cltbld', 23:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:34 INFO - 'MOZ_NO_REMOTE': '1', 23:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:34 INFO - 'NO_EM_RESTART': '1', 23:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:34 INFO - 'PWD': '/builds/slave/test', 23:09:34 INFO - 'SHELL': '/bin/bash', 23:09:34 INFO - 'SHLVL': '1', 23:09:34 INFO - 'TERM': 'linux', 23:09:34 INFO - 'TMOUT': '86400', 23:09:34 INFO - 'USER': 'cltbld', 23:09:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:34 INFO - '_': '/tools/buildbot/bin/python'} 23:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:09:34 INFO - Cleaning up... 23:09:34 INFO - Return code: 0 23:09:34 INFO - Running post-action listener: _resource_record_post_action 23:09:34 INFO - Running post-action listener: _start_resource_monitoring 23:09:34 INFO - Starting resource monitoring. 23:09:34 INFO - [mozharness: 2016-07-29 06:09:34.944833Z] Finished create-virtualenv step (success) 23:09:34 INFO - [mozharness: 2016-07-29 06:09:34.945375Z] Running install step. 23:09:34 INFO - Running pre-action listener: _resource_record_pre_action 23:09:34 INFO - Running main action method: install 23:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:09:35 INFO - Reading from file tmpfile_stdout 23:09:35 INFO - Detecting whether we're running mozinstall >=1.0... 23:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:09:35 INFO - Reading from file tmpfile_stdout 23:09:35 INFO - Output received: 23:09:35 INFO - Usage: mozinstall [options] installer 23:09:35 INFO - Options: 23:09:35 INFO - -h, --help show this help message and exit 23:09:35 INFO - -d DEST, --destination=DEST 23:09:35 INFO - Directory to install application into. [default: 23:09:35 INFO - "/builds/slave/test"] 23:09:35 INFO - --app=APP Application being installed. [default: firefox] 23:09:35 INFO - mkdir: /builds/slave/test/build/application 23:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:09:52 INFO - Reading from file tmpfile_stdout 23:09:52 INFO - Output received: 23:09:52 INFO - /builds/slave/test/build/application/firefox/firefox 23:09:52 INFO - Running post-action listener: _resource_record_post_action 23:09:52 INFO - [mozharness: 2016-07-29 06:09:52.511092Z] Finished install step (success) 23:09:52 INFO - [mozharness: 2016-07-29 06:09:52.511430Z] Running run-tests step. 23:09:52 INFO - Running pre-action listener: _resource_record_pre_action 23:09:52 INFO - Running main action method: run_tests 23:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:09:52 INFO - Python 2.7.3 23:09:52 INFO - Return code: 0 23:09:52 INFO - grabbing minidump binary from tooltool 23:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:52 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a73b0>, '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 0x27a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27a8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:09:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:09:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 23:09:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:09:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:09:52 INFO - Return code: 0 23:09:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:09:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:09:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:09:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:09:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:09:52 INFO - Using env: {'DISPLAY': ':0', 23:09:52 INFO - 'HOME': '/home/cltbld', 23:09:52 INFO - 'LANG': 'en_US.UTF-8', 23:09:52 INFO - 'LANGUAGE': 'en_US:en', 23:09:52 INFO - 'LOGNAME': 'cltbld', 23:09:52 INFO - 'MAIL': '/var/mail/cltbld', 23:09:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:09:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:52 INFO - 'MOZ_NO_REMOTE': '1', 23:09:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:52 INFO - 'NO_EM_RESTART': '1', 23:09:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:52 INFO - 'PWD': '/builds/slave/test', 23:09:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:09:52 INFO - 'SHELL': '/bin/bash', 23:09:52 INFO - 'SHLVL': '1', 23:09:52 INFO - 'TERM': 'linux', 23:09:52 INFO - 'TMOUT': '86400', 23:09:52 INFO - 'USER': 'cltbld', 23:09:52 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608', 23:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:52 INFO - '_': '/tools/buildbot/bin/python'} 23:09:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:09:52 INFO - mozversion application_buildid: 20160728203011 23:09:52 INFO - mozversion application_changeset: 7fd2a709bd6cd1714d8e6639dce3fda9f7b7d899 23:09:52 INFO - mozversion application_display_name: Nightly 23:09:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:09:52 INFO - mozversion application_name: Firefox 23:09:52 INFO - mozversion application_remotingname: firefox 23:09:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:09:52 INFO - mozversion application_vendor: Mozilla 23:09:52 INFO - mozversion application_version: 50.0a1 23:09:52 INFO - mozversion platform_buildid: 20160728203011 23:09:52 INFO - mozversion platform_changeset: 7fd2a709bd6cd1714d8e6639dce3fda9f7b7d899 23:09:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:09:52 INFO - mozversion platform_version: 50.0a1 23:09:52 INFO - using testdate: 1469772592 23:09:52 INFO - actual date: 1469772592 23:09:52 INFO - starting webserver on 'localhost:44428' 23:09:52 INFO - SUITE-START | Running 2 tests 23:09:52 INFO - TEST-START | dromaeo_css 23:09:52 INFO - operating with platform_type : linux_ 23:09:52 INFO - Initialising browser for dromaeo_css test... 23:09:52 INFO - TEST-INFO | started process 4030 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf5YUjX/profile http://localhost:44428/getInfo.html) 23:09:55 INFO - PROCESS | 4030 | JavaScript error: , line 0: uncaught exception: undefined 23:09:56 INFO - PROCESS | 4030 | __metrics Screen width/height:1600/1200 23:09:56 INFO - PROCESS | 4030 | colorDepth:24 23:09:56 INFO - PROCESS | 4030 | Browser inner width/height: 1024/697 23:09:56 INFO - PROCESS | 4030 | __metrics 23:09:57 INFO - PROCESS | 4030 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:09:57 INFO - PROCESS | 4030 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:09:58 INFO - TEST-INFO | 4030: exit 0 23:09:58 INFO - Browser initialized. 23:09:58 INFO - Running cycle 1/1 for dromaeo_css test... 23:09:58 INFO - TEST-INFO | started process 4114 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf5YUjX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:10:10 INFO - PROCESS | 4114 | RSS: Main: 158240768 23:10:10 INFO - PROCESS | 4114 | 23:11:00 INFO - PROCESS | 4114 | 1469772660981 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:13:09 INFO - PROCESS | 4114 | Cycle 1(1): loaded http://localhost:44428/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44428/tests/dromaeo/cssquery-ext.html) 23:13:11 INFO - PROCESS | 4114 | RSS: Main: 279126016 23:13:11 INFO - PROCESS | 4114 | 23:16:13 INFO - PROCESS | 4114 | Cycle 1(1): loaded http://localhost:44428/tests/dromaeo/cssquery-ext.html (next: http://localhost:44428/tests/dromaeo/cssquery-jquery.html) 23:16:14 INFO - PROCESS | 4114 | RSS: Main: 248696832 23:16:14 INFO - PROCESS | 4114 | 23:19:16 INFO - PROCESS | 4114 | Cycle 1(1): loaded http://localhost:44428/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44428/tests/dromaeo/cssquery-mootools.html) 23:19:16 INFO - PROCESS | 4114 | RSS: Main: 229597184 23:19:16 INFO - PROCESS | 4114 | 23:22:18 INFO - PROCESS | 4114 | Cycle 1(1): loaded http://localhost:44428/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44428/tests/dromaeo/cssquery-prototype.html) 23:22:19 INFO - PROCESS | 4114 | RSS: Main: 216948736 23:22:19 INFO - PROCESS | 4114 | 23:25:22 INFO - PROCESS | 4114 | Cycle 1(1): loaded http://localhost:44428/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44428/tests/dromaeo/cssquery-yui.html) 23:25:22 INFO - PROCESS | 4114 | RSS: Main: 189960192 23:25:22 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | Cycle 1(1): loaded http://localhost:44428/tests/dromaeo/cssquery-yui.html (next: http://localhost:44428/tests/dromaeo/cssquery-dojo.html) 23:28:25 INFO - PROCESS | 4114 | RSS: Main: 276230144 23:28:25 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | __start_tp_report 23:28:25 INFO - PROCESS | 4114 | _x_x_mozilla_page_load 23:28:25 INFO - PROCESS | 4114 | _x_x_mozilla_page_load_details 23:28:25 INFO - PROCESS | 4114 | |i|pagename|runs| 23:28:25 INFO - PROCESS | 4114 | |0;dojo.html;3038.058201957395;3042.8782848686055;3080.2915329474226;3080.5071188609827;3093.690630936905;5210.270562121296;5273.18265668821;5294.417614062455;5371.060064488718;5398.6490878092945;5245.26566280721;5261.500157485042;5310.575153987681;5327.067763141445;5369.812056578021;4725.645576581753;4769.260764581495;4792.137415265251;4804.951950480486;4952.653314268003;4889.584385327234;4909.337239472679;4912.410510738699;4924.778384972685;4930.482299358569;4783.354247176621;4791.544803243686;4828.637852161102;4839.685420447659;4883.853484395474;4795.448523419782;4799.7360145192;4819.879503012417;4884.975575122137;4904.926426103611;4818;4825.396825396825;4859.757012149414;4883.267509873512;4898.975505122452;5115.104856650109;5247.160454327328;5387.784488620489;5406.8918621627745;5429.104197807357;5623.80316688918;5745.540356771448;5745.741441635137;5747.4252574742195;5751.309842818847;5738.454846789548;5793.971030144864;5807.041838096709;5810.215620891167;5813.767449302023;5197.948020519822;5295.629305948547;5338.813141540105;5383.300170977787;5383.596230282745;5859.326177489558;5867.20792692981;5883.735231914574;5915.024021036481;5916.3787802281;5619.044762390404;5676.914846277309;5683.26117604717;5684.857878553069;5693.914591281134;5530.2810634618045;5669.31968163823;5739.799107031317;5773.711314434261;5778.479936805707;5740.024195886709;5747.166660834156;5748;5776.046952252831;5782.45066718661;5710.371859095496;5739.7704091836695;5744.425557444306;5749.798757043567;5763;4228.9788551057045;4320.956790432118;4322.546132656089;4417.955820441819;4462.3752674625575;3892.5328960524917;3915.2169566086895;3919.7844118573753;3921.8627348042683;3946.644801967837;5379.381371142291;5385.380681221632;5386.48828361305;5387.946120538744;5390.8921821563345;5685.374608793029;5708.029634962067;5736.254286942671;5773.336066352339;5798.217240672455;5364.436734142937;5397.190421436738;5407.756650950717;5410.810621628224;5418.4039755626845;4986.426560945538;4989.25161225819;4993.051320249141;5001.924971125436;5036;5873.676947767828;5897.911531327033;5913.172155898178;5915;6003.039513677791;5705.743241554057;5745.2818397700285;5746.827595172068;5747.5401967841635;5751.654900705971;5951.464368206882;5967.9701601491715;5969;5973.940260597512;5976.551758618208;5415.729213539228;5567.721613919369;5603.8879222416135;5607.934492446422;5676.17695434168;5735.8566035849435;5738.282714660668;5742.942570574241;5745.166950792028;5748.48263656273;5605.831825045255;5606.271184745957;5609.80365687199;5613.663180209201;5618.6628802272;5434.918476222939;5559.0827513461445;5596.804111856066;5618.7190640467;5700.914486282792;5511.311086114236;5548.944510554843;5561.249231353715;5572.275604171432;5576.275084239185;5645.520130788929;5656.095024796013;5704.030314846403;5710.057840456278;5711.857203569778;5296.920546191887;5418.83743487696;5442.210879422382;5504.2018907257425;5587.0502014656795 23:28:25 INFO - PROCESS | 4114 | |1;ext.html;597.8893904627644;608.8964875971085;624.2134910013383;637.4677144584272;643.8905386084367;31563.368732625364;44170.116597668064;45147.09705805886;45166.32250516245;45179;45946;46432;46450;46544.30183547241;46552.06895862081;8954.059823718513;9036.096390360977;9038.954805225965;9093.272538196929;9095.95452022739;8904.465732056098;9009;9009.324300677443;9013;9016.639334426614;9008.0541543138;9047.140521650466;9061.32040096992;9067.909320906805;9082.500462474562;9001.684941027099;9022.593983270735;9057.09429057089;9066;9069.410488318272;8864.512451815148;8917.910820891773;8930.330225233134;8938.240249578794;8946.731598052003;44445.55554444446;44589.10821783546;44621.77689111566;44730.77634611806;44959.32561011588;3012.608360913089;3039.7264246217724;3091.381723655278;3139.576157218769;3332.6334103248623;32984.47889139207;33672.15819604505;34097.488537671954;34220;34309;4361.062371590124;4430.71200371978;4431.446069241345;4448.5773851484055;4532.297493888453;386289;387203.9999999972;388725.05637471913;389469;390452;60815.39184608186;61010.38989610136;61033.69483152556;61044.69477652583;61202;63920;63979.36020639734;64037.67981160065;64068.679656601416;64091.35908640947;17115.315387384613;17382;17388.565285867702;17395.73906391405;17398;13286.667833304246;13414.329283535784;13417;13421.194728316332;13422.597322080353;15637.765433518507;17551.385701500643;17640.294388224327;17643.73534396985;17666.5583360417;6257.2804127525915;6361.236651601745;6450.612963222222;6490.8052758417325;6630.83422914431;781.316348195329;832.7793134819278;922.2115091596722;1109.944502774858;1114.6098865397046;33606.66393336084;33644.83177584096;33651.63942605144;33774;33904;942.1708896171463;969.2439896880445;1048.4652827058103;1080.783843231357;1095.802755504001;16748.916255418644;17116.486505404857;17170;17314.220860061327;17369;584625;586416;586487;589100;590420;60749.39250607526;60900.390996089474;61164.694176528836;61335.38664613297;61419.07871381933;1206.2943178240828;1262.8547717012477;1274.3182397417338;1284.6146156153304;1358.2461733737673;30219.848900755354;33184;34484;34549.62822496092;34873.82563087168;52191.73904130455;52738.20892686689;52752.47247527629;52795;52841.20738189007;416253;419143;420241;421549;507951.3638908804;41031.79484102561;42492;42820.357694635226;45677.39502386768;45801.312980304654;48472;49774;50367.74816125896;50522.49477505178;50732.74633626808;42167.15665686731;42693.78653106839;43234.78382608067;43350.34974475322;44561;42030.36954445747;42357.5764242366;42852.57147428486;43797.562024379346;44181;40942.79528602338;41473.79263103786;42788;43010;43055.1388972225;34477.655223447444;37648;37848.62151378561;37991;38636.420453693776;28163.71836281611;28647.283817904718;28733;28836;28997.13008609745 23:28:25 INFO - PROCESS | 4114 | |2;jquery.html;366.6718287133015;370.32785494327794;375.3693794425365;380.36858814368156;391.2761391425861;17561.912190439045;18815.811841881576;19207.903960480176;19397.903010484944;19489.610207795835;11428.371439570821;12056.81914771277;12063.638090857288;12065.75868482632;12071.818922716166;4904.4605093439695;5131.435542090367;5160.793568257265;5210.947890521104;5216.217567364888;5113.6931784092885;5155.865709543894;5468.26178465908;5694.288213973254;5750.05124154514;5710.343310519281;5720.570957178207;5801.883962320751;5807.448292412235;5807.651540907559;5696.145578163268;5759.251297331362;5805.535557155417;5811.564132690065;5818.796342128047;4960.131976904027;5206.0368831766245;5237.371515418136;5293.788248470095;5295.947040529622;12990.935045324713;13215.933920330433;13424.93287533556;13648.863511364958;13661.453541858315;2135.359392182339;2202.405350555341;2206.2498750424934;2318.5131122464304;2597.6363309136736;9288.396254243453;9293.21007714345;9398.483083430408;9765.804683906354;9976.401415915068;1781.6347648731978;1833.532449225439;1853.2864847033989;1867.5891303913254;1909.4844392014218;79435;82658;82700;82960.58519707424;83708;9608.90391096094;9751.17115045215;9936.751581210528;9955.25335599833;10273.897261027443;13293.734125317633;13301.534446294527;13307.866921330857;13323.533676321282;13364.39860206293;7546;7651.135831092935;7674.232576742393;7840.764777056697;7854.3716502679645;7436.553806771611;7719.884201736979;7738.226177382329;7753.573553454614;8071.838563228703;4042.43405923171;4189.308764053958;4270.12462445198;4317.762523061262;4486.913585182906;1735.6875762362642;1776.3338747969512;1795.9820401795814;1816.5912669649224;1834.715619079045;910.1308250620641;910.5083255042204;972.1785091597588;990.3414229537422;1034.534459493216;18086.13253234847;19245.903770481058;19349;19509;19988.001199879895;941.6986564299358;966.5119114846957;984.975375615601;992.836182029957;1052.7473406382567;10404.635837745795;10889.945550272356;10914.126869850394;10928.562857485611;10941.28881622692;395097;401188;402330;526238;533997;89954.75113122225;97499;98292;103958;107870.46064769627;436.3236982676838;450.8782628690235;455.2306601842875;456.0309342646875;458.01298202373937;10271.794564108826;10947.412625169465;11408.315501069963;16502.25739841734;18074.186661599997;34067.65932340744;36007.459888102225;41389.379159313234;43412.04645790884;45644.31533526933;54069.72965135149;56528.717356412955;56644.43355566391;57835.421645784685;58249.417505826095;7936.523808571505;7948.920510794818;7952.403569732409;8056.274935255855;8443.535605541509;11420.543178272776;12083.939580302042;12098.637040888787;12171.330576818005;12466.688332791753;10469.110125639101;10487.318324308895;10602.628907988183;10644.308119972176;11042.723931901766;10554.419506927192;10643.893561064291;10667.733306667395;10715.678529644123;10855.945720271347;10505.949405059433;10622.681319560426;10867.13062954946;11035.779284414106;11170.553177873118;7896.170902055202;8074.919250807417;8111.918880811117;8118.715844945398;8137.918620813716;9898.257630677585;10323.122534584645;10414.739631509274;10809.35143891369;11263.549458021915 23:28:25 INFO - PROCESS | 4114 | |3;mootools.html;1273.4651446392513;1330.5143622577762;1425.5723283015093;1488.9776653350202;1786.2140658110438;8501.957490212548;8731.825363492726;8876.112388761128;9095;9109.089091090887;8974.641014359418;9054.230390416806;9060.456372617631;9064.773380665487;9070.86393704095;2343.5664402085627;2360.5160942006933;2369.869657168855;2371.0397289097855;2373.027058905849;2309.3764683535437;2345.57779599672;2373.786359227669;2381.047580967618;2381.357033600935;2289.9771002290013;2297.793198612125;2313.0747700919596;2315.166540045582;2323.604987152188;2301.171578231835;2306.3081075677314;2335.6613291072867;2336.8597884127007;2345.4253707841517;2263.1400067974228;2264.4791697909573;2268.353519247023;2270.5572413379396;2274.579202847462;8677.869831952527;8691.26124279437;8725.952885653762;8744.300455963508;8797.692080777206;4996.225585034289;5139.743012849342;5166.3800343958965;5181.611379146579;5188.351456067991;3252.1056709404866;3378.4087784637836;3454.5509083819193;3464.0473869685784;3464.428369319059;1157.213095095335;1175.5767923547517;1202.3026644546142;1211.113749703159;1226.3929354969348;12442.502299907992;12532.937335313356;12581.74836503274;12606.936965315115;12621.368931553385;4176.164767046542;4309.956900431018;4457.1308594823695;4460.843870464518;4470.217711900469;9889.851652225223;9897.356671816453;9903.851442228372;9905;9907.950460247652;5173.586113110942;5181.5077567631015;5182.766775495112;5187.610929180327;5188;4206.957930420718;4547.954520454753;4682.953170468251;4715.269133284346;4848.3454733610515;3658.231771328051;3719.367707489733;3773.0378753418254;3805.923881522354;3807.505024346817;1100.6532942123206;1150.4017910686396;1150.7525881935424;1158.4786845919202;1165.2076587920296;875.4834647557844;875.7372788163525;925.3984909808678;935.9298052645943;969.471637957315;5504.174373843875;5608.074667679869;5667.319921609351;5697.344805347355;5921.970390148022;439.2313451459945;445.4876891574715;445.8751549566127;446.5824454135394;449.93925820014533;3771.905702357463;3831;3840.8847734568008;3842.615738426135;3937.42907278443;10441.269111162297;10911.727206819893;10985.890141098647;11053.115750739922;11094.833577496343;11019.338839669645;11042.779144417227;11055.391953442477;11055.834162487568;11064.17018723599;7593.31660150578;7802.258785415376;7855.685772569033;7995.440319177718;8020.879686804703;5687.687177205294;5699.01605797356;5760.654360738369;5825.330087040044;5891.734871930859;10029.297949143782;10132.240081994027;10136.543855526666;10142.442165680959;10257.230707696803;9961.551730172305;10150.593976240867;10152.441615711206;10199.490025498548;10280.434576098387;7001.194862590867;7586.317231449196;7759.883601745865;7912.683492660229;8005.919940800517;8648.654053837776;8661.956690216508;8663.6101375437;8663.6101375437;8706.651733930572;7511.586862722603;7754.418418618513;7844.4901081429525;7905.486143400661;7932.6430310635005;7646.464747467624;7658.310752032344;7720.729774457867;7775.883361749465;7832.647530861011;7056.788296351117;7182.964085179749;7188.424926006011;7188.568685878864;7195.856082878208;4771.976140119277;4860.805567777391;4912.22498982821;5043.167877300204;5096.617753668564;4810.759462026815;4960.826371076995;5085.669431486942;5092.0579692756955;5133 23:28:25 INFO - PROCESS | 4114 | |4;prototype.html;310.2290807343751;314.7324773942148;318.0585467017627;324.50998991522795;328.033940046563;5064.898702025957;5159.71621560814;5223.216517522369;5247.009204753287;5255.343082114736;5141.485851414866;5168.32811734474;5168.48315168483;5186.222066689998;5217.973910130444;4109.630133288004;4112.198121366335;4128.938065929014;4142.337226043834;4143.834246630131;3977.34373828318;4087.57080506547;4112.342025275956;4114.835406583733;4133.276676581601;4021.8592349267783;4046.5953404659444;4055.4119652650347;4056.716029877895;4057.8376864925367;4032.1734044520804;4038.434619153321;4042.524478565317;4049.129437171008;4053.1691003344245;3565.358235517606;3579.284143171376;3581.5164952731307;3583.8566457341676;3585.480105384731;4910.1898186799135;4965.3048573199785;4968.552830245259;5006.5494105530315;5036.219385995177;4596.126735920165;4598.977005114986;4607.9078418431445;4608.5621865922685;4627.293033575363;5370.812021579226;5387.138057910715;5393.7303134843105;5397.73011349431;5403.540699040586;3795.3737633290475;3866.5553461352024;3870.574236833946;3892.6691231245377;3898.5516665583255;5515.420880807537;5580.637258578179;5611.242482264924;5611.7474713637985;5623.971880140655;3958.8812335629977;3965.5637879833193;3971.6822654187595;3972.4041393790585;3975.8807235782974;3924.2740093082302;3986.5814089520763;4010.2380547695843;4015.297322968469;4026.550441840935;4595.264757638829;4597.3793537872625;4603.355530225771;4607.378003969488;4618.745968971739;5390.245365648812;5396.973015134978;5421.810236641699;5426.565874730091;5448.782048718086;1282.3588205897051;1324.754920339721;1372.7185926884965;1373.3476598615778;1374.9106308089943;933.9673111441067;953.3564843730454;954.2223088183108;955.2644463762864;962.2782912815388;5148.4851514848215;5152.536271735562;5153.510416510426;5154.5103215194495;5170.767315470813;5224.582033437316;5422.53908417781;5439.728013599304;5441.673499590037;5453.15476101205;5101.846944591668;5105.489451054939;5123.257127716461;5130.538251557303;5133.255677926754;2523.899044038218;2572.4212052288085;2593.9351516212246;2624.4619852930105;2627.52858399297;4859.65982381237;4994.375703037121;5052.873678158076;5056.494350564914;5073.797048118107;4912.656114072052;4933.260010998307;4944.283078953532;4963.230699241623;4964.677295975822;4955.628327875424;4963.776630051656;4993.706670928827;5003.924941125813;5037.773300201499;4543.409356783597;4786.497417771084;4817.686850354716;4825.372701548777;4825.606071954604;4757.7621118943225;4775.785089671042;4795.872969852152;4796.304535842353;4825.662203645851;4938.654294199371;4965;4994.750262486788;5020;5031.345925029869;3596.388613935585;3643.508126402902;3656.4880916671154;3724.348239058208;3774.3772277573894;5065.822696205615;5533.446655334435;5549.1676248561425;5557.610967232416;5557.861053473695;5398.2442458056685;5401.243825864303;5411.891762164813;5422.15956526739;5425.348958124892;5303.9204411934625;5370.167624018283;5416.620836541404;5416.83749487516;5437.205722111615;5343.839684809462;5348.411674715856;5386.299781028441;5408.486193811582;5418.864528386822;5418.945810542002;5420.674759514442;5464.3442786865835;5469.659933316363;5490.3411590609385;5197.350331208599;5306.036766396458;5335.22568264508;5342.171963345688;5346.732663366894 23:28:25 INFO - PROCESS | 4114 | |5;yui.html;637.4549759955238;675.7837492002556;726.3825748114103;817.1338381315805;857.99571002145;14660;16165.43420980266;16890.94295646285;16946.74579881301;17089.999999999985;15227.619309517268;15310.234488275597;15442.15068171253;15469.226538673049;15476.458323958635;776.0648418655535;790.9723159689412;791.2008871040262;791.651673263765;791.9485233459835;646.2342124582361;760.5626764610344;786.2177133751925;788.4283894176733;788.6056971514242;838.9874151887697;841.8442588121187;841.9284360829337;841.949483031017;841.9747407577782;825.6119623776815;826.2604968553142;826.5123577089486;827.9213474719952;829.7303376402693;498.1531396625751;586.633354153654;600.5645906717639;600.942910423509;613.438703586218;15362.308696108701;15393.46007908705;15407.152606606633;15407.922960385238;15411.691766164726;13866.65333366674;14009.929950350284;14379.065360751621;14477.203753793538;14618.561443156723;12476.937615311956;12637.988960883107;12824.743505129845;12846.16499927511;12851.742965140646;4412.183746006966;4428.31361139024;4454.532274111204;4479;4639.8144074237325;74290.62854685746;75436.62281688556;75507.24492755056;75688;76100.61949690216;10856.511456984455;10912.50893709785;10915.454227288605;11078.778424431628;11161.944190279159;20795.480112996993;20849.68725469119;20926.47683807917;20937.476563086046;21015.579688406146;8224.300934420642;8469.491830490191;8474.872876906851;8733.56332183401;8767;4218.6625069994325;4323.956760432418;4386.232409328355;4434.733915965053;4570.108828778356;3254.4792833146585;3314.685104915029;3323.3519463704347;3333.633300336961;3365.3942290387963;586.234963372794;589.864331203817;590.347665829261;592.1354821959901;600.5675913342386;648.322502984373;706.7279097547481;717.6913927011435;719.1478098453349;753.6344872736719;9226.53867306632;9678.95160524193;9733.756656083655;9976.45129517869;10044.799104017879;680.119245576979;686.7733647896182;707.2417571471201;712.8609921065446;759.9848003040019;4651.558101980307;4738.928916066262;4764.071006153767;4766.609778735885;4927.827526036644;59557.10664340038;69939;113564;120538.39730801465;121493.39253303854;56062.71968640131;57164;86064.56967715247;94503.52748236214;94877;56559.717201414554;61430.0785488218;87010;88117;91170.54414727884;8895.377323587287;9107.134822191882;9127.900582865845;9164.725058248263;9291.349605527548;53099.46900530945;53490;54567.72716136394;54630;57406.425935741776;71063;73534;74051;76678;78664.60667696624;11727.179097463095;11945.16383853122;13620.93189534046;13661.248631325372;14087.436502540193;14317.284135792961;15283.923580382027;16051.640346256634;16141.59646008859;16149.596260093122;13644.317784110954;13802.65493362674;13898.51355202563;13966;14350.928245358706;12660.366981651065;13768.227549372748;13898;13933.58199254024;14410.855891440951;14075.436982520994;14177;14439.78340324875;14646.926765366532;15143.848561514684;11095.50070246857;11374.43127843621;11400.372979486208;11521.308721476738;11651;10014.596496840852;10238.846417303597;11192.593180645405;11205.60780372683;11280.943595281971 23:28:25 INFO - PROCESS | 4114 | __end_tp_report 23:28:25 INFO - PROCESS | 4114 | __start_cc_report 23:28:25 INFO - PROCESS | 4114 | _x_x_mozilla_cycle_collect,987 23:28:25 INFO - PROCESS | 4114 | __end_cc_report 23:28:25 INFO - PROCESS | 4114 | __startTimestamp1469773705552__endTimestamp 23:28:25 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | ------- Summary: start ------- 23:28:25 INFO - PROCESS | 4114 | Number of tests: 6 23:28:25 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | [#0] dojo.html Cycles:165 Average:5316.96 Median:5530.28 stddev:614.17 (11.1%) stddev-sans-first:589.46 23:28:25 INFO - PROCESS | 4114 | Values: 3038.1 3042.9 3080.3 3080.5 3093.7 5210.3 5273.2 5294.4 5371.1 5398.6 5245.3 5261.5 5310.6 5327.1 5369.8 4725.6 4769.3 4792.1 4805.0 4952.7 4889.6 4909.3 4912.4 4924.8 4930.5 4783.4 4791.5 4828.6 4839.7 4883.9 4795.4 4799.7 4819.9 4885.0 4904.9 4818.0 4825.4 4859.8 4883.3 4899.0 5115.1 5247.2 5387.8 5406.9 5429.1 5623.8 5745.5 5745.7 5747.4 5751.3 5738.5 5794.0 5807.0 5810.2 5813.8 5197.9 5295.6 5338.8 5383.3 5383.6 5859.3 5867.2 5883.7 5915.0 5916.4 5619.0 5676.9 5683.3 5684.9 5693.9 5530.3 5669.3 5739.8 5773.7 5778.5 5740.0 5747.2 5748.0 5776.0 5782.5 5710.4 5739.8 5744.4 5749.8 5763.0 4229.0 4321.0 4322.5 4418.0 4462.4 3892.5 3915.2 3919.8 3921.9 3946.6 5379.4 5385.4 5386.5 5387.9 5390.9 5685.4 5708.0 5736.3 5773.3 5798.2 5364.4 5397.2 5407.8 5410.8 5418.4 4986.4 4989.3 4993.1 5001.9 5036.0 5873.7 5897.9 5913.2 5915.0 6003.0 5705.7 5745.3 5746.8 5747.5 5751.7 5951.5 5968.0 5969.0 5973.9 5976.6 5415.7 5567.7 5603.9 5607.9 5676.2 5735.9 5738.3 5742.9 5745.2 5748.5 5605.8 5606.3 5609.8 5613.7 5618.7 5434.9 5559.1 5596.8 5618.7 5700.9 5511.3 5548.9 5561.2 5572.3 5576.3 5645.5 5656.1 5704.0 5710.1 5711.9 5296.9 5418.8 5442.2 5504.2 5587.1 23:28:25 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | [#1] ext.html Cycles:180 Average:63946.74 Median:24438.50 stddev:127102.22 (520.1%) stddev-sans-first:127369.79 23:28:25 INFO - PROCESS | 4114 | Values: 597.9 608.9 624.2 637.5 643.9 31563.4 44170.1 45147.1 45166.3 45179.0 45946.0 46432.0 46450.0 46544.3 46552.1 8954.1 9036.1 9039.0 9093.3 9096.0 8904.5 9009.0 9009.3 9013.0 9016.6 9008.1 9047.1 9061.3 9067.9 9082.5 9001.7 9022.6 9057.1 9066.0 9069.4 8864.5 8917.9 8930.3 8938.2 8946.7 44445.6 44589.1 44621.8 44730.8 44959.3 3012.6 3039.7 3091.4 3139.6 3332.6 32984.5 33672.2 34097.5 34220.0 34309.0 4361.1 4430.7 4431.4 4448.6 4532.3 386289.0 387204.0 388725.1 389469.0 390452.0 60815.4 61010.4 61033.7 61044.7 61202.0 63920.0 63979.4 64037.7 64068.7 64091.4 17115.3 17382.0 17388.6 17395.7 17398.0 13286.7 13414.3 13417.0 13421.2 13422.6 15637.8 17551.4 17640.3 17643.7 17666.6 6257.3 6361.2 6450.6 6490.8 6630.8 781.3 832.8 922.2 1109.9 1114.6 33606.7 33644.8 33651.6 33774.0 33904.0 942.2 969.2 1048.5 1080.8 1095.8 16748.9 17116.5 17170.0 17314.2 17369.0 584625.0 586416.0 586487.0 589100.0 590420.0 60749.4 60900.4 61164.7 61335.4 61419.1 1206.3 1262.9 1274.3 1284.6 1358.2 30219.8 33184.0 34484.0 34549.6 34873.8 52191.7 52738.2 52752.5 52795.0 52841.2 416253.0 419143.0 420241.0 421549.0 507951.4 41031.8 42492.0 42820.4 45677.4 45801.3 48472.0 49774.0 50367.7 50522.5 50732.7 42167.2 42693.8 43234.8 43350.3 44561.0 42030.4 42357.6 42852.6 43797.6 44181.0 40942.8 41473.8 42788.0 43010.0 43055.1 34477.7 37648.0 37848.6 37991.0 38636.4 28163.7 28647.3 28733.0 28836.0 28997.1 23:28:25 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | [#2] jquery.html Cycles:180 Average:27151.90 Median:22789.34 stddev:75851.55 (332.8%) stddev-sans-first:76037.67 23:28:25 INFO - PROCESS | 4114 | Values: 366.7 370.3 375.4 380.4 391.3 17561.9 18815.8 19207.9 19397.9 19489.6 11428.4 12056.8 12063.6 12065.8 12071.8 4904.5 5131.4 5160.8 5210.9 5216.2 5113.7 5155.9 5468.3 5694.3 5750.1 5710.3 5720.6 5801.9 5807.4 5807.7 5696.1 5759.3 5805.5 5811.6 5818.8 4960.1 5206.0 5237.4 5293.8 5295.9 12990.9 13215.9 13424.9 13648.9 13661.5 2135.4 2202.4 2206.2 2318.5 2597.6 9288.4 9293.2 9398.5 9765.8 9976.4 1781.6 1833.5 1853.3 1867.6 1909.5 79435.0 82658.0 82700.0 82960.6 83708.0 9608.9 9751.2 9936.8 9955.3 10273.9 13293.7 13301.5 13307.9 13323.5 13364.4 7546.0 7651.1 7674.2 7840.8 7854.4 7436.6 7719.9 7738.2 7753.6 8071.8 4042.4 4189.3 4270.1 4317.8 4486.9 1735.7 1776.3 1796.0 1816.6 1834.7 910.1 910.5 972.2 990.3 1034.5 18086.1 19245.9 19349.0 19509.0 19988.0 941.7 966.5 985.0 992.8 1052.7 10404.6 10889.9 10914.1 10928.6 10941.3 395097.0 401188.0 402330.0 526238.0 533997.0 89954.8 97499.0 98292.0 103958.0 107870.5 436.3 450.9 455.2 456.0 458.0 10271.8 10947.4 11408.3 16502.3 18074.2 34067.7 36007.5 41389.4 43412.0 45644.3 54069.7 56528.7 56644.4 57835.4 58249.4 7936.5 7948.9 7952.4 8056.3 8443.5 11420.5 12083.9 12098.6 12171.3 12466.7 10469.1 10487.3 10602.6 10644.3 11042.7 10554.4 10643.9 10667.7 10715.7 10855.9 10505.9 10622.7 10867.1 11035.8 11170.6 7896.2 8074.9 8111.9 8118.7 8137.9 9898.3 10323.1 10414.7 10809.4 11263.5 23:28:25 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | [#3] mootools.html Cycles:180 Average:5740.70 Median:4615.45 stddev:3351.14 (72.6%) stddev-sans-first:3343.72 23:28:25 INFO - PROCESS | 4114 | Values: 1273.5 1330.5 1425.6 1489.0 1786.2 8502.0 8731.8 8876.1 9095.0 9109.1 8974.6 9054.2 9060.5 9064.8 9070.9 2343.6 2360.5 2369.9 2371.0 2373.0 2309.4 2345.6 2373.8 2381.0 2381.4 2290.0 2297.8 2313.1 2315.2 2323.6 2301.2 2306.3 2335.7 2336.9 2345.4 2263.1 2264.5 2268.4 2270.6 2274.6 8677.9 8691.3 8726.0 8744.3 8797.7 4996.2 5139.7 5166.4 5181.6 5188.4 3252.1 3378.4 3454.6 3464.0 3464.4 1157.2 1175.6 1202.3 1211.1 1226.4 12442.5 12532.9 12581.7 12606.9 12621.4 4176.2 4310.0 4457.1 4460.8 4470.2 9889.9 9897.4 9903.9 9905.0 9908.0 5173.6 5181.5 5182.8 5187.6 5188.0 4207.0 4548.0 4683.0 4715.3 4848.3 3658.2 3719.4 3773.0 3805.9 3807.5 1100.7 1150.4 1150.8 1158.5 1165.2 875.5 875.7 925.4 935.9 969.5 5504.2 5608.1 5667.3 5697.3 5922.0 439.2 445.5 445.9 446.6 449.9 3771.9 3831.0 3840.9 3842.6 3937.4 10441.3 10911.7 10985.9 11053.1 11094.8 11019.3 11042.8 11055.4 11055.8 11064.2 7593.3 7802.3 7855.7 7995.4 8020.9 5687.7 5699.0 5760.7 5825.3 5891.7 10029.3 10132.2 10136.5 10142.4 10257.2 9961.6 10150.6 10152.4 10199.5 10280.4 7001.2 7586.3 7759.9 7912.7 8005.9 8648.7 8662.0 8663.6 8663.6 8706.7 7511.6 7754.4 7844.5 7905.5 7932.6 7646.5 7658.3 7720.7 7775.9 7832.6 7056.8 7183.0 7188.4 7188.6 7195.9 4772.0 4860.8 4912.2 5043.2 5096.6 4810.8 4960.8 5085.7 5092.1 5133.0 23:28:25 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | [#4] prototype.html Cycles:180 Average:4419.19 Median:4965.15 stddev:1276.06 (25.7%) stddev-sans-first:1241.81 23:28:25 INFO - PROCESS | 4114 | Values: 310.2 314.7 318.1 324.5 328.0 5064.9 5159.7 5223.2 5247.0 5255.3 5141.5 5168.3 5168.5 5186.2 5218.0 4109.6 4112.2 4128.9 4142.3 4143.8 3977.3 4087.6 4112.3 4114.8 4133.3 4021.9 4046.6 4055.4 4056.7 4057.8 4032.2 4038.4 4042.5 4049.1 4053.2 3565.4 3579.3 3581.5 3583.9 3585.5 4910.2 4965.3 4968.6 5006.5 5036.2 4596.1 4599.0 4607.9 4608.6 4627.3 5370.8 5387.1 5393.7 5397.7 5403.5 3795.4 3866.6 3870.6 3892.7 3898.6 5515.4 5580.6 5611.2 5611.7 5624.0 3958.9 3965.6 3971.7 3972.4 3975.9 3924.3 3986.6 4010.2 4015.3 4026.6 4595.3 4597.4 4603.4 4607.4 4618.7 5390.2 5397.0 5421.8 5426.6 5448.8 1282.4 1324.8 1372.7 1373.3 1374.9 934.0 953.4 954.2 955.3 962.3 5148.5 5152.5 5153.5 5154.5 5170.8 5224.6 5422.5 5439.7 5441.7 5453.2 5101.8 5105.5 5123.3 5130.5 5133.3 2523.9 2572.4 2593.9 2624.5 2627.5 4859.7 4994.4 5052.9 5056.5 5073.8 4912.7 4933.3 4944.3 4963.2 4964.7 4955.6 4963.8 4993.7 5003.9 5037.8 4543.4 4786.5 4817.7 4825.4 4825.6 4757.8 4775.8 4795.9 4796.3 4825.7 4938.7 4965.0 4994.8 5020.0 5031.3 3596.4 3643.5 3656.5 3724.3 3774.4 5065.8 5533.4 5549.2 5557.6 5557.9 5398.2 5401.2 5411.9 5422.2 5425.3 5303.9 5370.2 5416.6 5416.8 5437.2 5343.8 5348.4 5386.3 5408.5 5418.9 5418.9 5420.7 5464.3 5469.7 5490.3 5197.4 5306.0 5335.2 5342.2 5346.7 23:28:25 INFO - PROCESS | 4114 | 23:28:25 INFO - PROCESS | 4114 | [#5] yui.html Cycles:180 Average:19619.79 Median:4751.50 stddev:27086.22 (570.1%) stddev-sans-first:27124.70 23:28:25 INFO - PROCESS | 4114 | Values: 637.5 675.8 726.4 817.1 858.0 14660.0 16165.4 16890.9 16946.7 17090.0 15227.6 15310.2 15442.2 15469.2 15476.5 776.1 791.0 791.2 791.7 791.9 646.2 760.6 786.2 788.4 788.6 839.0 841.8 841.9 841.9 842.0 825.6 826.3 826.5 827.9 829.7 498.2 586.6 600.6 600.9 613.4 15362.3 15393.5 15407.2 15407.9 15411.7 13866.7 14009.9 14379.1 14477.2 14618.6 12476.9 12638.0 12824.7 12846.2 12851.7 4412.2 4428.3 4454.5 4479.0 4639.8 74290.6 75436.6 75507.2 75688.0 76100.6 10856.5 10912.5 10915.5 11078.8 11161.9 20795.5 20849.7 20926.5 20937.5 21015.6 8224.3 8469.5 8474.9 8733.6 8767.0 4218.7 4324.0 4386.2 4434.7 4570.1 3254.5 3314.7 3323.4 3333.6 3365.4 586.2 589.9 590.3 592.1 600.6 648.3 706.7 717.7 719.1 753.6 9226.5 9679.0 9733.8 9976.5 10044.8 680.1 686.8 707.2 712.9 760.0 4651.6 4738.9 4764.1 4766.6 4927.8 59557.1 69939.0 113564.0 120538.4 121493.4 56062.7 57164.0 86064.6 94503.5 94877.0 56559.7 61430.1 87010.0 88117.0 91170.5 8895.4 9107.1 9127.9 9164.7 9291.3 53099.5 53490.0 54567.7 54630.0 57406.4 71063.0 73534.0 74051.0 76678.0 78664.6 11727.2 11945.2 13620.9 13661.2 14087.4 14317.3 15283.9 16051.6 16141.6 16149.6 13644.3 13802.7 13898.5 13966.0 14350.9 12660.4 13768.2 13898.0 13933.6 14410.9 14075.4 14177.0 14439.8 14646.9 15143.8 11095.5 11374.4 11400.4 11521.3 11651.0 10014.6 10238.8 11192.6 11205.6 11280.9 23:28:25 INFO - PROCESS | 4114 | -------- Summary: end -------- 23:28:25 INFO - PROCESS | 4114 | 23:28:26 INFO - TEST-INFO | 4114: exit 0 23:28:26 INFO - TEST-OK | dromaeo_css | took 1113424ms 23:28:26 INFO - TEST-START | kraken 23:28:26 INFO - operating with platform_type : linux_ 23:28:26 INFO - Initialising browser for kraken test... 23:28:26 INFO - TEST-INFO | started process 4310 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFa5UOJ/profile http://localhost:44428/getInfo.html) 23:28:29 INFO - PROCESS | 4310 | JavaScript error: , line 0: uncaught exception: undefined 23:28:29 INFO - PROCESS | 4310 | __metrics Screen width/height:1600/1200 23:28:29 INFO - PROCESS | 4310 | colorDepth:24 23:28:29 INFO - PROCESS | 4310 | Browser inner width/height: 1024/697 23:28:29 INFO - PROCESS | 4310 | __metrics 23:28:30 INFO - PROCESS | 4310 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:28:30 INFO - PROCESS | 4310 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:28:31 INFO - TEST-INFO | 4310: exit 0 23:28:31 INFO - Browser initialized. 23:28:31 INFO - Running cycle 1/1 for kraken test... 23:28:31 INFO - TEST-INFO | started process 4388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFa5UOJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:28:43 INFO - PROCESS | 4388 | RSS: Main: 158011392 23:28:43 INFO - PROCESS | 4388 | 23:28:45 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44428/tests/kraken/driver.html?testName=audio-beat-detection) 23:28:46 INFO - PROCESS | 4388 | RSS: Main: 214327296 23:28:46 INFO - PROCESS | 4388 | 23:28:49 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44428/tests/kraken/driver.html?testName=audio-dft) 23:28:50 INFO - PROCESS | 4388 | RSS: Main: 193282048 23:28:50 INFO - PROCESS | 4388 | 23:28:53 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44428/tests/kraken/driver.html?testName=audio-fft) 23:28:53 INFO - PROCESS | 4388 | RSS: Main: 270905344 23:28:53 INFO - PROCESS | 4388 | 23:28:55 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44428/tests/kraken/driver.html?testName=audio-oscillator) 23:28:56 INFO - PROCESS | 4388 | RSS: Main: 180854784 23:28:56 INFO - PROCESS | 4388 | 23:28:58 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44428/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:28:59 INFO - PROCESS | 4388 | RSS: Main: 184664064 23:28:59 INFO - PROCESS | 4388 | 23:29:02 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44428/tests/kraken/driver.html?testName=imaging-darkroom) 23:29:02 INFO - PROCESS | 4388 | RSS: Main: 220065792 23:29:02 INFO - PROCESS | 4388 | 23:29:06 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44428/tests/kraken/driver.html?testName=imaging-desaturate) 23:29:06 INFO - PROCESS | 4388 | RSS: Main: 220254208 23:29:06 INFO - PROCESS | 4388 | 23:29:09 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44428/tests/kraken/driver.html?testName=json-parse-financial) 23:29:10 INFO - PROCESS | 4388 | RSS: Main: 220975104 23:29:10 INFO - PROCESS | 4388 | 23:29:11 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44428/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:29:12 INFO - PROCESS | 4388 | RSS: Main: 185036800 23:29:12 INFO - PROCESS | 4388 | 23:29:14 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44428/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:29:14 INFO - PROCESS | 4388 | RSS: Main: 191586304 23:29:14 INFO - PROCESS | 4388 | 23:29:16 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44428/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:29:17 INFO - PROCESS | 4388 | RSS: Main: 195399680 23:29:17 INFO - PROCESS | 4388 | 23:29:19 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44428/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:29:20 INFO - PROCESS | 4388 | RSS: Main: 191086592 23:29:20 INFO - PROCESS | 4388 | 23:29:23 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44428/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:29:23 INFO - PROCESS | 4388 | RSS: Main: 193548288 23:29:23 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | Cycle 1(1): loaded http://localhost:44428/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44428/tests/kraken/driver.html?testName=ai-astar) 23:29:25 INFO - PROCESS | 4388 | RSS: Main: 244084736 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | __start_tp_report 23:29:25 INFO - PROCESS | 4388 | _x_x_mozilla_page_load 23:29:25 INFO - PROCESS | 4388 | _x_x_mozilla_page_load_details 23:29:25 INFO - PROCESS | 4388 | |i|pagename|runs| 23:29:25 INFO - PROCESS | 4388 | |0;ai-astar;101;109;110;109;110;110;110;109;110;109 23:29:25 INFO - PROCESS | 4388 | |1;audio-beat-detection;154;118;117;176;119;120;151;123;152;117 23:29:25 INFO - PROCESS | 4388 | |2;audio-dft;161;162;161;161;160;162;159;161;162;162 23:29:25 INFO - PROCESS | 4388 | |3;audio-fft;75;76;74;136;77;76;74;75;106;74 23:29:25 INFO - PROCESS | 4388 | |4;audio-oscillator;84;84;119;84;84;85;124;83;84;129 23:29:25 INFO - PROCESS | 4388 | |5;imaging-gaussian-blur;117;116;116;117;116;116;116;117;118;117 23:29:25 INFO - PROCESS | 4388 | |6;imaging-darkroom;146;147;146;146;145;146;145;147;145;147 23:29:25 INFO - PROCESS | 4388 | |7;imaging-desaturate;95;96;95;95;95;95;96;96;96;95 23:29:25 INFO - PROCESS | 4388 | |8;json-parse-financial;53;52;52;51;52;53;55;53;52;52 23:29:25 INFO - PROCESS | 4388 | |9;json-stringify-tinderbox;64;65;63;64;64;63;64;63;65;64 23:29:25 INFO - PROCESS | 4388 | |10;stanford-crypto-aes;76;76;76;76;75;75;76;76;75;76 23:29:25 INFO - PROCESS | 4388 | |11;stanford-crypto-ccm;123;118;119;120;118;127;125;119;157;119 23:29:25 INFO - PROCESS | 4388 | |12;stanford-crypto-pbkdf2;157;158;164;162;159;161;156;166;156;157 23:29:25 INFO - PROCESS | 4388 | |13;stanford-crypto-sha256-iterative;67;65;63;63;63;62;63;65;62;61 23:29:25 INFO - PROCESS | 4388 | __end_tp_report 23:29:25 INFO - PROCESS | 4388 | __start_cc_report 23:29:25 INFO - PROCESS | 4388 | _x_x_mozilla_cycle_collect,1710 23:29:25 INFO - PROCESS | 4388 | __end_cc_report 23:29:25 INFO - PROCESS | 4388 | __startTimestamp1469773765683__endTimestamp 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | ------- Summary: start ------- 23:29:25 INFO - PROCESS | 4388 | Number of tests: 14 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#0] ai-astar Cycles:10 Average:108.70 Median:110.00 stddev:2.75 (2.5%) stddev-sans-first:0.53 23:29:25 INFO - PROCESS | 4388 | Values: 101.0 109.0 110.0 109.0 110.0 110.0 110.0 109.0 110.0 109.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:137.00 stddev:21.47 (15.7%) stddev-sans-first:21.61 23:29:25 INFO - PROCESS | 4388 | Values: 154.0 118.0 117.0 176.0 119.0 120.0 151.0 123.0 152.0 117.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:0.99 (0.6%) stddev-sans-first:1.05 23:29:25 INFO - PROCESS | 4388 | Values: 161.0 162.0 161.0 161.0 160.0 162.0 159.0 161.0 162.0 162.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#3] audio-fft Cycles:10 Average:84.30 Median:75.00 stddev:20.62 (27.5%) stddev-sans-first:21.59 23:29:25 INFO - PROCESS | 4388 | Values: 75.0 76.0 74.0 136.0 77.0 76.0 74.0 75.0 106.0 74.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:84.00 stddev:19.47 (23.2%) stddev-sans-first:20.16 23:29:25 INFO - PROCESS | 4388 | Values: 84.0 84.0 119.0 84.0 84.0 85.0 124.0 83.0 84.0 129.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 23:29:25 INFO - PROCESS | 4388 | Values: 117.0 116.0 116.0 117.0 116.0 116.0 116.0 117.0 118.0 117.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 23:29:25 INFO - PROCESS | 4388 | Values: 146.0 147.0 146.0 146.0 145.0 146.0 145.0 147.0 145.0 147.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:29:25 INFO - PROCESS | 4388 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 95.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.08 (2.1%) stddev-sans-first:1.13 23:29:25 INFO - PROCESS | 4388 | Values: 53.0 52.0 52.0 51.0 52.0 53.0 55.0 53.0 52.0 52.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 23:29:25 INFO - PROCESS | 4388 | Values: 64.0 65.0 63.0 64.0 64.0 63.0 64.0 63.0 65.0 64.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:76.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 23:29:25 INFO - PROCESS | 4388 | Values: 76.0 76.0 76.0 76.0 75.0 75.0 76.0 76.0 75.0 76.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#11] stanford-crypto-ccm Cycles:10 Average:124.50 Median:121.50 stddev:11.83 (9.7%) stddev-sans-first:12.54 23:29:25 INFO - PROCESS | 4388 | Values: 123.0 118.0 119.0 120.0 118.0 127.0 125.0 119.0 157.0 119.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.60 Median:160.00 stddev:3.50 (2.2%) stddev-sans-first:3.59 23:29:25 INFO - PROCESS | 4388 | Values: 157.0 158.0 164.0 162.0 159.0 161.0 156.0 166.0 156.0 157.0 23:29:25 INFO - PROCESS | 4388 | 23:29:25 INFO - PROCESS | 4388 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.00 stddev:1.78 (2.8%) stddev-sans-first:1.32 23:29:25 INFO - PROCESS | 4388 | Values: 67.0 65.0 63.0 63.0 63.0 62.0 63.0 65.0 62.0 61.0 23:29:25 INFO - PROCESS | 4388 | -------- Summary: end -------- 23:29:25 INFO - PROCESS | 4388 | 23:29:26 INFO - TEST-INFO | 4388: exit 0 23:29:26 INFO - TEST-OK | kraken | took 60479ms 23:29:26 INFO - SUITE-END | took 1173s 23:29:26 INFO - Completed test suite (00:19:34) 23:29:26 INFO - javascript benchmark 23:29:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3038.058201957395, 3042.8782848686055, 3080.2915329474226, 3080.5071188609827, 3093.690630936905, 5210.270562121296, 5273.18265668821, 5294.417614062455, 5371.060064488718, 5398.6490878092945, 5245.26566280721, 5261.500157485042, 5310.575153987681, 5327.067763141445, 5369.812056578021, 4725.645576581753, 4769.260764581495, 4792.137415265251, 4804.951950480486, 4952.653314268003, 4889.584385327234, 4909.337239472679, 4912.410510738699, 4924.778384972685, 4930.482299358569, 4783.354247176621, 4791.544803243686, 4828.637852161102, 4839.685420447659, 4883.853484395474, 4795.448523419782, 4799.7360145192, 4819.879503012417, 4884.975575122137, 4904.926426103611, 4818.0, 4825.396825396825, 4859.757012149414, 4883.267509873512, 4898.975505122452, 5115.104856650109, 5247.160454327328, 5387.784488620489, 5406.8918621627745, 5429.104197807357, 5623.80316688918, 5745.540356771448, 5745.741441635137, 5747.4252574742195, 5751.309842818847, 5738.454846789548, 5793.971030144864, 5807.041838096709, 5810.215620891167, 5813.767449302023, 5197.948020519822, 5295.629305948547, 5338.813141540105, 5383.300170977787, 5383.596230282745, 5859.326177489558, 5867.20792692981, 5883.735231914574, 5915.024021036481, 5916.3787802281, 5619.044762390404, 5676.914846277309, 5683.26117604717, 5684.857878553069, 5693.914591281134, 5530.2810634618045, 5669.31968163823, 5739.799107031317, 5773.711314434261, 5778.479936805707, 5740.024195886709, 5747.166660834156, 5748.0, 5776.046952252831, 5782.45066718661, 5710.371859095496, 5739.7704091836695, 5744.425557444306, 5749.798757043567, 5763.0, 4228.9788551057045, 4320.956790432118, 4322.546132656089, 4417.955820441819, 4462.3752674625575, 3892.5328960524917, 3915.2169566086895, 3919.7844118573753, 3921.8627348042683, 3946.644801967837, 5379.381371142291, 5385.380681221632, 5386.48828361305, 5387.946120538744, 5390.8921821563345, 5685.374608793029, 5708.029634962067, 5736.254286942671, 5773.336066352339, 5798.217240672455, 5364.436734142937, 5397.190421436738, 5407.756650950717, 5410.810621628224, 5418.4039755626845, 4986.426560945538, 4989.25161225819, 4993.051320249141, 5001.924971125436, 5036.0, 5873.676947767828, 5897.911531327033, 5913.172155898178, 5915.0, 6003.039513677791, 5705.743241554057, 5745.2818397700285, 5746.827595172068, 5747.5401967841635, 5751.654900705971, 5951.464368206882, 5967.9701601491715, 5969.0, 5973.940260597512, 5976.551758618208, 5415.729213539228, 5567.721613919369, 5603.8879222416135, 5607.934492446422, 5676.17695434168, 5735.8566035849435, 5738.282714660668, 5742.942570574241, 5745.166950792028, 5748.48263656273, 5605.831825045255, 5606.271184745957, 5609.80365687199, 5613.663180209201, 5618.6628802272, 5434.918476222939, 5559.0827513461445, 5596.804111856066, 5618.7190640467, 5700.914486282792, 5511.311086114236, 5548.944510554843, 5561.249231353715, 5572.275604171432, 5576.275084239185, 5645.520130788929, 5656.095024796013, 5704.030314846403, 5710.057840456278, 5711.857203569778, 5296.920546191887, 5418.83743487696, 5442.210879422382, 5504.2018907257425, 5587.0502014656795], "value": 5274.778851388815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.8893904627644, 608.8964875971085, 624.2134910013383, 637.4677144584272, 643.8905386084367, 31563.368732625364, 44170.116597668064, 45147.09705805886, 45166.32250516245, 45179.0, 45946.0, 46432.0, 46450.0, 46544.30183547241, 46552.06895862081, 8954.059823718513, 9036.096390360977, 9038.954805225965, 9093.272538196929, 9095.95452022739, 8904.465732056098, 9009.0, 9009.324300677443, 9013.0, 9016.639334426614, 9008.0541543138, 9047.140521650466, 9061.32040096992, 9067.909320906805, 9082.500462474562, 9001.684941027099, 9022.593983270735, 9057.09429057089, 9066.0, 9069.410488318272, 8864.512451815148, 8917.910820891773, 8930.330225233134, 8938.240249578794, 8946.731598052003, 44445.55554444446, 44589.10821783546, 44621.77689111566, 44730.77634611806, 44959.32561011588, 3012.608360913089, 3039.7264246217724, 3091.381723655278, 3139.576157218769, 3332.6334103248623, 32984.47889139207, 33672.15819604505, 34097.488537671954, 34220.0, 34309.0, 4361.062371590124, 4430.71200371978, 4431.446069241345, 4448.5773851484055, 4532.297493888453, 386289.0, 387203.9999999972, 388725.05637471913, 389469.0, 390452.0, 60815.39184608186, 61010.38989610136, 61033.69483152556, 61044.69477652583, 61202.0, 63920.0, 63979.36020639734, 64037.67981160065, 64068.679656601416, 64091.35908640947, 17115.315387384613, 17382.0, 17388.565285867702, 17395.73906391405, 17398.0, 13286.667833304246, 13414.329283535784, 13417.0, 13421.194728316332, 13422.597322080353, 15637.765433518507, 17551.385701500643, 17640.294388224327, 17643.73534396985, 17666.5583360417, 6257.2804127525915, 6361.236651601745, 6450.612963222222, 6490.8052758417325, 6630.83422914431, 781.316348195329, 832.7793134819278, 922.2115091596722, 1109.944502774858, 1114.6098865397046, 33606.66393336084, 33644.83177584096, 33651.63942605144, 33774.0, 33904.0, 942.1708896171463, 969.2439896880445, 1048.4652827058103, 1080.783843231357, 1095.802755504001, 16748.916255418644, 17116.486505404857, 17170.0, 17314.220860061327, 17369.0, 584625.0, 586416.0, 586487.0, 589100.0, 590420.0, 60749.39250607526, 60900.390996089474, 61164.694176528836, 61335.38664613297, 61419.07871381933, 1206.2943178240828, 1262.8547717012477, 1274.3182397417338, 1284.6146156153304, 1358.2461733737673, 30219.848900755354, 33184.0, 34484.0, 34549.62822496092, 34873.82563087168, 52191.73904130455, 52738.20892686689, 52752.47247527629, 52795.0, 52841.20738189007, 416253.0, 419143.0, 420241.0, 421549.0, 507951.3638908804, 41031.79484102561, 42492.0, 42820.357694635226, 45677.39502386768, 45801.312980304654, 48472.0, 49774.0, 50367.74816125896, 50522.49477505178, 50732.74633626808, 42167.15665686731, 42693.78653106839, 43234.78382608067, 43350.34974475322, 44561.0, 42030.36954445747, 42357.5764242366, 42852.57147428486, 43797.562024379346, 44181.0, 40942.79528602338, 41473.79263103786, 42788.0, 43010.0, 43055.1388972225, 34477.655223447444, 37648.0, 37848.62151378561, 37991.0, 38636.420453693776, 28163.71836281611, 28647.283817904718, 28733.0, 28836.0, 28997.13008609745], "value": 20651.66959475362, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.6718287133015, 370.32785494327794, 375.3693794425365, 380.36858814368156, 391.2761391425861, 17561.912190439045, 18815.811841881576, 19207.903960480176, 19397.903010484944, 19489.610207795835, 11428.371439570821, 12056.81914771277, 12063.638090857288, 12065.75868482632, 12071.818922716166, 4904.4605093439695, 5131.435542090367, 5160.793568257265, 5210.947890521104, 5216.217567364888, 5113.6931784092885, 5155.865709543894, 5468.26178465908, 5694.288213973254, 5750.05124154514, 5710.343310519281, 5720.570957178207, 5801.883962320751, 5807.448292412235, 5807.651540907559, 5696.145578163268, 5759.251297331362, 5805.535557155417, 5811.564132690065, 5818.796342128047, 4960.131976904027, 5206.0368831766245, 5237.371515418136, 5293.788248470095, 5295.947040529622, 12990.935045324713, 13215.933920330433, 13424.93287533556, 13648.863511364958, 13661.453541858315, 2135.359392182339, 2202.405350555341, 2206.2498750424934, 2318.5131122464304, 2597.6363309136736, 9288.396254243453, 9293.21007714345, 9398.483083430408, 9765.804683906354, 9976.401415915068, 1781.6347648731978, 1833.532449225439, 1853.2864847033989, 1867.5891303913254, 1909.4844392014218, 79435.0, 82658.0, 82700.0, 82960.58519707424, 83708.0, 9608.90391096094, 9751.17115045215, 9936.751581210528, 9955.25335599833, 10273.897261027443, 13293.734125317633, 13301.534446294527, 13307.866921330857, 13323.533676321282, 13364.39860206293, 7546.0, 7651.135831092935, 7674.232576742393, 7840.764777056697, 7854.3716502679645, 7436.553806771611, 7719.884201736979, 7738.226177382329, 7753.573553454614, 8071.838563228703, 4042.43405923171, 4189.308764053958, 4270.12462445198, 4317.762523061262, 4486.913585182906, 1735.6875762362642, 1776.3338747969512, 1795.9820401795814, 1816.5912669649224, 1834.715619079045, 910.1308250620641, 910.5083255042204, 972.1785091597588, 990.3414229537422, 1034.534459493216, 18086.13253234847, 19245.903770481058, 19349.0, 19509.0, 19988.001199879895, 941.6986564299358, 966.5119114846957, 984.975375615601, 992.836182029957, 1052.7473406382567, 10404.635837745795, 10889.945550272356, 10914.126869850394, 10928.562857485611, 10941.28881622692, 395097.0, 401188.0, 402330.0, 526238.0, 533997.0, 89954.75113122225, 97499.0, 98292.0, 103958.0, 107870.46064769627, 436.3236982676838, 450.8782628690235, 455.2306601842875, 456.0309342646875, 458.01298202373937, 10271.794564108826, 10947.412625169465, 11408.315501069963, 16502.25739841734, 18074.186661599997, 34067.65932340744, 36007.459888102225, 41389.379159313234, 43412.04645790884, 45644.31533526933, 54069.72965135149, 56528.717356412955, 56644.43355566391, 57835.421645784685, 58249.417505826095, 7936.523808571505, 7948.920510794818, 7952.403569732409, 8056.274935255855, 8443.535605541509, 11420.543178272776, 12083.939580302042, 12098.637040888787, 12171.330576818005, 12466.688332791753, 10469.110125639101, 10487.318324308895, 10602.628907988183, 10644.308119972176, 11042.723931901766, 10554.419506927192, 10643.893561064291, 10667.733306667395, 10715.678529644123, 10855.945720271347, 10505.949405059433, 10622.681319560426, 10867.13062954946, 11035.779284414106, 11170.553177873118, 7896.170902055202, 8074.919250807417, 8111.918880811117, 8118.715844945398, 8137.918620813716, 9898.257630677585, 10323.122534584645, 10414.739631509274, 10809.35143891369, 11263.549458021915], "value": 8329.38926693635, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1273.4651446392513, 1330.5143622577762, 1425.5723283015093, 1488.9776653350202, 1786.2140658110438, 8501.957490212548, 8731.825363492726, 8876.112388761128, 9095.0, 9109.089091090887, 8974.641014359418, 9054.230390416806, 9060.456372617631, 9064.773380665487, 9070.86393704095, 2343.5664402085627, 2360.5160942006933, 2369.869657168855, 2371.0397289097855, 2373.027058905849, 2309.3764683535437, 2345.57779599672, 2373.786359227669, 2381.047580967618, 2381.357033600935, 2289.9771002290013, 2297.793198612125, 2313.0747700919596, 2315.166540045582, 2323.604987152188, 2301.171578231835, 2306.3081075677314, 2335.6613291072867, 2336.8597884127007, 2345.4253707841517, 2263.1400067974228, 2264.4791697909573, 2268.353519247023, 2270.5572413379396, 2274.579202847462, 8677.869831952527, 8691.26124279437, 8725.952885653762, 8744.300455963508, 8797.692080777206, 4996.225585034289, 5139.743012849342, 5166.3800343958965, 5181.611379146579, 5188.351456067991, 3252.1056709404866, 3378.4087784637836, 3454.5509083819193, 3464.0473869685784, 3464.428369319059, 1157.213095095335, 1175.5767923547517, 1202.3026644546142, 1211.113749703159, 1226.3929354969348, 12442.502299907992, 12532.937335313356, 12581.74836503274, 12606.936965315115, 12621.368931553385, 4176.164767046542, 4309.956900431018, 4457.1308594823695, 4460.843870464518, 4470.217711900469, 9889.851652225223, 9897.356671816453, 9903.851442228372, 9905.0, 9907.950460247652, 5173.586113110942, 5181.5077567631015, 5182.766775495112, 5187.610929180327, 5188.0, 4206.957930420718, 4547.954520454753, 4682.953170468251, 4715.269133284346, 4848.3454733610515, 3658.231771328051, 3719.367707489733, 3773.0378753418254, 3805.923881522354, 3807.505024346817, 1100.6532942123206, 1150.4017910686396, 1150.7525881935424, 1158.4786845919202, 1165.2076587920296, 875.4834647557844, 875.7372788163525, 925.3984909808678, 935.9298052645943, 969.471637957315, 5504.174373843875, 5608.074667679869, 5667.319921609351, 5697.344805347355, 5921.970390148022, 439.2313451459945, 445.4876891574715, 445.8751549566127, 446.5824454135394, 449.93925820014533, 3771.905702357463, 3831.0, 3840.8847734568008, 3842.615738426135, 3937.42907278443, 10441.269111162297, 10911.727206819893, 10985.890141098647, 11053.115750739922, 11094.833577496343, 11019.338839669645, 11042.779144417227, 11055.391953442477, 11055.834162487568, 11064.17018723599, 7593.31660150578, 7802.258785415376, 7855.685772569033, 7995.440319177718, 8020.879686804703, 5687.687177205294, 5699.01605797356, 5760.654360738369, 5825.330087040044, 5891.734871930859, 10029.297949143782, 10132.240081994027, 10136.543855526666, 10142.442165680959, 10257.230707696803, 9961.551730172305, 10150.593976240867, 10152.441615711206, 10199.490025498548, 10280.434576098387, 7001.194862590867, 7586.317231449196, 7759.883601745865, 7912.683492660229, 8005.919940800517, 8648.654053837776, 8661.956690216508, 8663.6101375437, 8663.6101375437, 8706.651733930572, 7511.586862722603, 7754.418418618513, 7844.4901081429525, 7905.486143400661, 7932.6430310635005, 7646.464747467624, 7658.310752032344, 7720.729774457867, 7775.883361749465, 7832.647530861011, 7056.788296351117, 7182.964085179749, 7188.424926006011, 7188.568685878864, 7195.856082878208, 4771.976140119277, 4860.805567777391, 4912.22498982821, 5043.167877300204, 5096.617753668564, 4810.759462026815, 4960.826371076995, 5085.669431486942, 5092.0579692756955, 5133.0], "value": 4483.4609360945105, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.2290807343751, 314.7324773942148, 318.0585467017627, 324.50998991522795, 328.033940046563, 5064.898702025957, 5159.71621560814, 5223.216517522369, 5247.009204753287, 5255.343082114736, 5141.485851414866, 5168.32811734474, 5168.48315168483, 5186.222066689998, 5217.973910130444, 4109.630133288004, 4112.198121366335, 4128.938065929014, 4142.337226043834, 4143.834246630131, 3977.34373828318, 4087.57080506547, 4112.342025275956, 4114.835406583733, 4133.276676581601, 4021.8592349267783, 4046.5953404659444, 4055.4119652650347, 4056.716029877895, 4057.8376864925367, 4032.1734044520804, 4038.434619153321, 4042.524478565317, 4049.129437171008, 4053.1691003344245, 3565.358235517606, 3579.284143171376, 3581.5164952731307, 3583.8566457341676, 3585.480105384731, 4910.1898186799135, 4965.3048573199785, 4968.552830245259, 5006.5494105530315, 5036.219385995177, 4596.126735920165, 4598.977005114986, 4607.9078418431445, 4608.5621865922685, 4627.293033575363, 5370.812021579226, 5387.138057910715, 5393.7303134843105, 5397.73011349431, 5403.540699040586, 3795.3737633290475, 3866.5553461352024, 3870.574236833946, 3892.6691231245377, 3898.5516665583255, 5515.420880807537, 5580.637258578179, 5611.242482264924, 5611.7474713637985, 5623.971880140655, 3958.8812335629977, 3965.5637879833193, 3971.6822654187595, 3972.4041393790585, 3975.8807235782974, 3924.2740093082302, 3986.5814089520763, 4010.2380547695843, 4015.297322968469, 4026.550441840935, 4595.264757638829, 4597.3793537872625, 4603.355530225771, 4607.378003969488, 4618.745968971739, 5390.245365648812, 5396.973015134978, 5421.810236641699, 5426.565874730091, 5448.782048718086, 1282.3588205897051, 1324.754920339721, 1372.7185926884965, 1373.3476598615778, 1374.9106308089943, 933.9673111441067, 953.3564843730454, 954.2223088183108, 955.2644463762864, 962.2782912815388, 5148.4851514848215, 5152.536271735562, 5153.510416510426, 5154.5103215194495, 5170.767315470813, 5224.582033437316, 5422.53908417781, 5439.728013599304, 5441.673499590037, 5453.15476101205, 5101.846944591668, 5105.489451054939, 5123.257127716461, 5130.538251557303, 5133.255677926754, 2523.899044038218, 2572.4212052288085, 2593.9351516212246, 2624.4619852930105, 2627.52858399297, 4859.65982381237, 4994.375703037121, 5052.873678158076, 5056.494350564914, 5073.797048118107, 4912.656114072052, 4933.260010998307, 4944.283078953532, 4963.230699241623, 4964.677295975822, 4955.628327875424, 4963.776630051656, 4993.706670928827, 5003.924941125813, 5037.773300201499, 4543.409356783597, 4786.497417771084, 4817.686850354716, 4825.372701548777, 4825.606071954604, 4757.7621118943225, 4775.785089671042, 4795.872969852152, 4796.304535842353, 4825.662203645851, 4938.654294199371, 4965.0, 4994.750262486788, 5020.0, 5031.345925029869, 3596.388613935585, 3643.508126402902, 3656.4880916671154, 3724.348239058208, 3774.3772277573894, 5065.822696205615, 5533.446655334435, 5549.1676248561425, 5557.610967232416, 5557.861053473695, 5398.2442458056685, 5401.243825864303, 5411.891762164813, 5422.15956526739, 5425.348958124892, 5303.9204411934625, 5370.167624018283, 5416.620836541404, 5416.83749487516, 5437.205722111615, 5343.839684809462, 5348.411674715856, 5386.299781028441, 5408.486193811582, 5418.864528386822, 5418.945810542002, 5420.674759514442, 5464.3442786865835, 5469.659933316363, 5490.3411590609385, 5197.350331208599, 5306.036766396458, 5335.22568264508, 5342.171963345688, 5346.732663366894], "value": 4015.305437838801, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.4549759955238, 675.7837492002556, 726.3825748114103, 817.1338381315805, 857.99571002145, 14660.0, 16165.43420980266, 16890.94295646285, 16946.74579881301, 17089.999999999985, 15227.619309517268, 15310.234488275597, 15442.15068171253, 15469.226538673049, 15476.458323958635, 776.0648418655535, 790.9723159689412, 791.2008871040262, 791.651673263765, 791.9485233459835, 646.2342124582361, 760.5626764610344, 786.2177133751925, 788.4283894176733, 788.6056971514242, 838.9874151887697, 841.8442588121187, 841.9284360829337, 841.949483031017, 841.9747407577782, 825.6119623776815, 826.2604968553142, 826.5123577089486, 827.9213474719952, 829.7303376402693, 498.1531396625751, 586.633354153654, 600.5645906717639, 600.942910423509, 613.438703586218, 15362.308696108701, 15393.46007908705, 15407.152606606633, 15407.922960385238, 15411.691766164726, 13866.65333366674, 14009.929950350284, 14379.065360751621, 14477.203753793538, 14618.561443156723, 12476.937615311956, 12637.988960883107, 12824.743505129845, 12846.16499927511, 12851.742965140646, 4412.183746006966, 4428.31361139024, 4454.532274111204, 4479.0, 4639.8144074237325, 74290.62854685746, 75436.62281688556, 75507.24492755056, 75688.0, 76100.61949690216, 10856.511456984455, 10912.50893709785, 10915.454227288605, 11078.778424431628, 11161.944190279159, 20795.480112996993, 20849.68725469119, 20926.47683807917, 20937.476563086046, 21015.579688406146, 8224.300934420642, 8469.491830490191, 8474.872876906851, 8733.56332183401, 8767.0, 4218.6625069994325, 4323.956760432418, 4386.232409328355, 4434.733915965053, 4570.108828778356, 3254.4792833146585, 3314.685104915029, 3323.3519463704347, 3333.633300336961, 3365.3942290387963, 586.234963372794, 589.864331203817, 590.347665829261, 592.1354821959901, 600.5675913342386, 648.322502984373, 706.7279097547481, 717.6913927011435, 719.1478098453349, 753.6344872736719, 9226.53867306632, 9678.95160524193, 9733.756656083655, 9976.45129517869, 10044.799104017879, 680.119245576979, 686.7733647896182, 707.2417571471201, 712.8609921065446, 759.9848003040019, 4651.558101980307, 4738.928916066262, 4764.071006153767, 4766.609778735885, 4927.827526036644, 59557.10664340038, 69939.0, 113564.0, 120538.39730801465, 121493.39253303854, 56062.71968640131, 57164.0, 86064.56967715247, 94503.52748236214, 94877.0, 56559.717201414554, 61430.0785488218, 87010.0, 88117.0, 91170.54414727884, 8895.377323587287, 9107.134822191882, 9127.900582865845, 9164.725058248263, 9291.349605527548, 53099.46900530945, 53490.0, 54567.72716136394, 54630.0, 57406.425935741776, 71063.0, 73534.0, 74051.0, 76678.0, 78664.60667696624, 11727.179097463095, 11945.16383853122, 13620.93189534046, 13661.248631325372, 14087.436502540193, 14317.284135792961, 15283.923580382027, 16051.640346256634, 16141.59646008859, 16149.596260093122, 13644.317784110954, 13802.65493362674, 13898.51355202563, 13966.0, 14350.928245358706, 12660.366981651065, 13768.227549372748, 13898.0, 13933.58199254024, 14410.855891440951, 14075.436982520994, 14177.0, 14439.78340324875, 14646.926765366532, 15143.848561514684, 11095.50070246857, 11374.43127843621, 11400.372979486208, 11521.308721476738, 11651.0, 10014.596496840852, 10238.846417303597, 11192.593180645405, 11205.60780372683, 11280.943595281971], "value": 7452.095721400304, "unit": "score"}], "name": "dromaeo_css", "value": 7039.988346462372, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 109.0, 110.0, 109.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 118.0, 117.0, 176.0, 119.0, 120.0, 151.0, 123.0, 152.0, 117.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 160.0, 162.0, 159.0, 161.0, 162.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 136.0, 77.0, 76.0, 74.0, 75.0, 106.0, 74.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 119.0, 84.0, 84.0, 85.0, 124.0, 83.0, 84.0, 129.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 118.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 147.0, 145.0, 147.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 51.0, 52.0, 53.0, 55.0, 53.0, 52.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 64.0, 64.0, 63.0, 64.0, 63.0, 65.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 76.0, 75.0, 75.0, 76.0, 76.0, 75.0, 76.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 118.0, 119.0, 120.0, 118.0, 127.0, 125.0, 119.0, 157.0, 119.0], "value": 124.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 158.0, 164.0, 162.0, 159.0, 161.0, 156.0, 166.0, 156.0, 157.0], "value": 159.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 63.0, 63.0, 63.0, 62.0, 63.0, 65.0, 62.0, 61.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1482.4, "alertThreshold": 2.0}]} 23:29:26 INFO - Return code: 0 23:29:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:29:26 INFO - # TBPL SUCCESS # 23:29:26 INFO - Running post-action listener: _resource_record_post_action 23:29:26 INFO - [mozharness: 2016-07-29 06:29:26.992516Z] Finished run-tests step (success) 23:29:26 INFO - Running post-run listener: _resource_record_post_run 23:29:27 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 555925504; Read time: 8; Write time: 407700 23:29:27 INFO - TinderboxPrint: CPU usage
13.0% 23:29:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:29:27 INFO - TinderboxPrint: I/O write bytes / time
555,925,504 / 407,700 23:29:27 INFO - TinderboxPrint: CPU idle
8,264.3 (86.9%) 23:29:27 INFO - TinderboxPrint: CPU user
1,195.5 (12.6%) 23:29:27 INFO - TinderboxPrint: Swap in / out
0 / 0 23:29:27 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 257409024; Read time: 0; Write time: 207464 23:29:27 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 298516480; Read time: 0; Write time: 200236 23:29:27 INFO - Running post-run listener: _upload_blobber_files 23:29:27 INFO - Blob upload gear active. 23:29:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:29:27 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 23:29:27 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:29:27 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:29:28 INFO - (blobuploader) - INFO - Open directory for files ... 23:29:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:29:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:31 INFO - (blobuploader) - INFO - Done attempting. 23:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:31 INFO - (blobuploader) - INFO - Done attempting. 23:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:32 INFO - (blobuploader) - INFO - Done attempting. 23:29:32 INFO - (blobuploader) - INFO - Iteration through files over. 23:29:32 INFO - Return code: 0 23:29:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:29:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:29:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/578116445e273c845c6dea0aa836e819d94cb15227de77b6b27b3523adc0780699b5b8f5c7385f1e364219be5b8523d31d978fb2e1e4cdb64d89311a75b81fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3cd2a045897ec1d2220d6b5a4a7cb5bf6d23454064130c91e0a9f253e7771a7bc6e6fbef947027074584f853d523857c3c87587fb862b7927cf3c17ca7f7bdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/107a144259c151466287f65fd574fc6075c1ce00221cbe698a2629f58d70e1d7cbdf4b484af95593137dd6824ac2930fc3a60d5767d23338ba24bf45381232ea"} 23:29:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:29:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:29:32 INFO - Contents: 23:29:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/578116445e273c845c6dea0aa836e819d94cb15227de77b6b27b3523adc0780699b5b8f5c7385f1e364219be5b8523d31d978fb2e1e4cdb64d89311a75b81fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3cd2a045897ec1d2220d6b5a4a7cb5bf6d23454064130c91e0a9f253e7771a7bc6e6fbef947027074584f853d523857c3c87587fb862b7927cf3c17ca7f7bdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/107a144259c151466287f65fd574fc6075c1ce00221cbe698a2629f58d70e1d7cbdf4b484af95593137dd6824ac2930fc3a60d5767d23338ba24bf45381232ea"} 23:29:32 INFO - Running post-run listener: copy_logs_to_upload_dir 23:29:32 INFO - Copying logs to upload dir... 23:29:32 INFO - mkdir: /builds/slave/test/build/upload/logs 23:29:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.107353 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-28 23:29:32.881036) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-28 23:29:32.886874) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/578116445e273c845c6dea0aa836e819d94cb15227de77b6b27b3523adc0780699b5b8f5c7385f1e364219be5b8523d31d978fb2e1e4cdb64d89311a75b81fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3cd2a045897ec1d2220d6b5a4a7cb5bf6d23454064130c91e0a9f253e7771a7bc6e6fbef947027074584f853d523857c3c87587fb862b7927cf3c17ca7f7bdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/107a144259c151466287f65fd574fc6075c1ce00221cbe698a2629f58d70e1d7cbdf4b484af95593137dd6824ac2930fc3a60d5767d23338ba24bf45381232ea"} build_url:https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011116 build_url: 'https://queue.taskcluster.net/v1/task/akNjU5X5TDKdTU7k5HcPvw/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/Fx-Team/sha512/578116445e273c845c6dea0aa836e819d94cb15227de77b6b27b3523adc0780699b5b8f5c7385f1e364219be5b8523d31d978fb2e1e4cdb64d89311a75b81fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3cd2a045897ec1d2220d6b5a4a7cb5bf6d23454064130c91e0a9f253e7771a7bc6e6fbef947027074584f853d523857c3c87587fb862b7927cf3c17ca7f7bdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/107a144259c151466287f65fd574fc6075c1ce00221cbe698a2629f58d70e1d7cbdf4b484af95593137dd6824ac2930fc3a60d5767d23338ba24bf45381232ea"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-28 23:29:32.912721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:29:32.913083) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1469771622.851141-1915805608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:29:32.997874) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-28 23:29:32.998184) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-28 23:29:32.998483) ========= ========= Total master_lag: 0.19 =========