builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-027 starttime: 1464670728.93 results: success (0) buildid: 20160530210038 builduid: f1b1b768da594579a36f92ce9157b7f4 revision: d049a47c24c1889cb547d4d899e998c75367e315 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:48.934139) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:48.934640) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:48.934968) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:49.012058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:49.012364) ========= 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-1464665101.182919-923360563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-30 21:58:49-- 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% 23.2M=0s 2016-05-30 21:58:49 (23.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126227 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:49.153491) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:49.153889) ========= 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-1464665101.182919-923360563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017783 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:49.209752) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:49.210107) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d049a47c24c1889cb547d4d899e998c75367e315 --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 d049a47c24c1889cb547d4d899e998c75367e315 --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-1464665101.182919-923360563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-30 21:58:49,875 truncating revision to first 12 chars 2016-05-30 21:58:49,875 Setting DEBUG logging. 2016-05-30 21:58:49,875 attempt 1/10 2016-05-30 21:58:49,875 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d049a47c24c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 21:58:50,409 unpacking tar archive at: fx-team-d049a47c24c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.874201 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:50.099030) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:50.099388) ========= script_repo_revision: d049a47c24c1889cb547d4d899e998c75367e315 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:50.099914) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:50.100224) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 21:58:50.110932) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 39 secs) (at 2016-05-30 21:58:50.111251) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:58:50 INFO - MultiFileLogger online at 20160530 21:58:50 in /builds/slave/test 21:58:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 21:58:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:58:50 INFO - {'append_to_log': False, 21:58:50 INFO - 'base_work_dir': '/builds/slave/test', 21:58:50 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:58:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:58:50 INFO - 'branch': 'Fx-Team-Non-PGO', 21:58:50 INFO - 'buildbot_json_path': 'buildprops.json', 21:58:50 INFO - 'config_files': ('talos/linux_config.py',), 21:58:50 INFO - 'default_actions': ('clobber', 21:58:50 INFO - 'read-buildbot-config', 21:58:50 INFO - 'download-and-extract', 21:58:50 INFO - 'populate-webroot', 21:58:50 INFO - 'create-virtualenv', 21:58:50 INFO - 'install', 21:58:50 INFO - 'run-tests'), 21:58:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:58:50 INFO - 'download_minidump_stackwalk': True, 21:58:50 INFO - 'download_symbols': 'ondemand', 21:58:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:58:50 INFO - 'tooltool.py': '/tools/tooltool.py', 21:58:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:58:50 INFO - '/tools/misc-python/virtualenv.py')}, 21:58:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:58:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:58:50 INFO - 'installer_path': 'installer.exe', 21:58:50 INFO - 'log_level': 'info', 21:58:50 INFO - 'log_name': 'talos', 21:58:50 INFO - 'log_to_console': True, 21:58:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:58:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:58:50 INFO - 'opt_config_files': (), 21:58:50 INFO - 'pip_index': False, 21:58:50 INFO - 'sps_profile': False, 21:58:50 INFO - 'sps_profile_interval': 0, 21:58:50 INFO - 'suite': 'dromaeojs', 21:58:50 INFO - 'system_bits': '32', 21:58:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:58:50 INFO - 'title': 'talos-linux64-ix-027', 21:58:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:58:50 INFO - 'use_talos_json': True, 21:58:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:58:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:58:50 INFO - 'work_dir': 'build'} 21:58:50 INFO - ##### 21:58:50 INFO - ##### Running clobber step. 21:58:50 INFO - ##### 21:58:50 INFO - Running pre-action listener: _resource_record_pre_action 21:58:50 INFO - Running main action method: clobber 21:58:50 INFO - rmtree: /builds/slave/test/build 21:58:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:58:51 INFO - Running post-action listener: _resource_record_post_action 21:58:51 INFO - ##### 21:58:51 INFO - ##### Finished clobber step (success) 21:58:51 INFO - ##### 21:58:51 INFO - ##### 21:58:51 INFO - ##### Running read-buildbot-config step. 21:58:51 INFO - ##### 21:58:51 INFO - Running pre-action listener: _resource_record_pre_action 21:58:51 INFO - Running main action method: read_buildbot_config 21:58:51 INFO - Using buildbot properties: 21:58:51 INFO - { 21:58:51 INFO - "project": "", 21:58:51 INFO - "product": "firefox", 21:58:51 INFO - "script_repo_revision": "production", 21:58:51 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:58:51 INFO - "repository": "", 21:58:51 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:58:51 INFO - "buildid": "20160530210038", 21:58:51 INFO - "pgo_build": "False", 21:58:51 INFO - "basedir": "/builds/slave/test", 21:58:51 INFO - "buildnumber": 3017, 21:58:51 INFO - "slavename": "talos-linux64-ix-027", 21:58:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:58:51 INFO - "platform": "ubuntu64_hw", 21:58:51 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:58:51 INFO - "branch": "fx-team", 21:58:51 INFO - "repo_path": "integration/fx-team", 21:58:51 INFO - "revision": "d049a47c24c1889cb547d4d899e998c75367e315", 21:58:51 INFO - "stage_platform": "linux64", 21:58:51 INFO - "builduid": "f1b1b768da594579a36f92ce9157b7f4", 21:58:51 INFO - "slavebuilddir": "test" 21:58:51 INFO - } 21:58:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:58:51 INFO - Running post-action listener: _resource_record_post_action 21:58:51 INFO - ##### 21:58:51 INFO - ##### Finished read-buildbot-config step (success) 21:58:51 INFO - ##### 21:58:51 INFO - ##### 21:58:51 INFO - ##### Running download-and-extract step. 21:58:51 INFO - ##### 21:58:51 INFO - Running pre-action listener: _resource_record_pre_action 21:58:51 INFO - Running main action method: download_and_extract 21:58:51 INFO - mkdir: /builds/slave/test/build/tests 21:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:58:51 INFO - https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:58:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:58:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:58:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:58:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:58:53 INFO - Downloaded 1361 bytes. 21:58:53 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:58:53 INFO - Using the following test package requirements: 21:58:53 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:58:53 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:58:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:58:53 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:58:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:58:53 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:58:53 INFO - u'jsshell-linux-x86_64.zip'], 21:58:53 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:58:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:58:53 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:58:53 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:58:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:58:53 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:58:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:58:53 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:58:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:58:53 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:58:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:58:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:58:53 INFO - https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:58:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:58:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:58:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:58:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:58:54 INFO - Downloaded 22837967 bytes. 21:58:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:58:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:58:55 INFO - Return code: 0 21:58:55 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:58: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')]} 21:58:55 INFO - https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:58:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:58:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:58:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:58:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:59:05 INFO - Downloaded 22837967 bytes. 21:59:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:59:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:59:06 INFO - Return code: 0 21:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:06 INFO - https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:59:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:59:07 INFO - Downloaded 10834055 bytes. 21:59:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:59:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:59:08 INFO - Return code: 0 21:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:08 INFO - https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:59:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:59:09 INFO - Downloaded 55001911 bytes. 21:59:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:09 INFO - mkdir: /builds/slave/test/properties 21:59:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:59:09 INFO - Writing to file /builds/slave/test/properties/build_url 21:59:09 INFO - Contents: 21:59:09 INFO - build_url:https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:11 INFO - Running post-action listener: _resource_record_post_action 21:59:11 INFO - Running post-action listener: set_extra_try_arguments 21:59:11 INFO - ##### 21:59:11 INFO - ##### Finished download-and-extract step (success) 21:59:11 INFO - ##### 21:59:11 INFO - ##### 21:59:11 INFO - ##### Running populate-webroot step. 21:59:11 INFO - ##### 21:59:11 INFO - Running pre-action listener: _resource_record_pre_action 21:59:11 INFO - Running main action method: populate_webroot 21:59:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:59:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:59:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:59:11 INFO - '--tppagecycles', 21:59:11 INFO - '7'], 21:59:11 INFO - 'tests': ['tsvgm']}}, 21:59:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:59:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:59:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:59:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:59:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:11 INFO - 'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:11 INFO - 'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['damp', 'tps']}, 21:59:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:11 INFO - 'tests': ['damp', 'tps']}, 21:59:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['dromaeo_dom']}, 21:59:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:59:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['a11yr', 21:59:11 INFO - 'ts_paint', 21:59:11 INFO - 'tpaint', 21:59:11 INFO - 'sessionrestore', 21:59:11 INFO - 'sessionrestore_no_auto_restore', 21:59:11 INFO - 'tabpaint']}, 21:59:11 INFO - 'other-e10s': {'tests': ['a11yr', 21:59:11 INFO - 'ts_paint', 21:59:11 INFO - 'tpaint', 21:59:11 INFO - 'sessionrestore', 21:59:11 INFO - 'sessionrestore_no_auto_restore', 21:59:11 INFO - 'tabpaint']}, 21:59:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:59:11 INFO - 'ts_paint', 21:59:11 INFO - 'tpaint', 21:59:11 INFO - 'sessionrestore', 21:59:11 INFO - 'sessionrestore_no_auto_restore', 21:59:11 INFO - 'tabpaint']}, 21:59:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:59:11 INFO - 'ts_paint', 21:59:11 INFO - 'tpaint', 21:59:11 INFO - 'sessionrestore', 21:59:11 INFO - 'sessionrestore_no_auto_restore', 21:59:11 INFO - 'tabpaint']}, 21:59:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['a11yr', 21:59:11 INFO - 'ts_paint', 21:59:11 INFO - 'tpaint', 21:59:11 INFO - 'sessionrestore', 21:59:11 INFO - 'sessionrestore_no_auto_restore', 21:59:11 INFO - 'tabpaint']}, 21:59:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['a11yr', 21:59:11 INFO - 'ts_paint', 21:59:11 INFO - 'tpaint', 21:59:11 INFO - 'sessionrestore', 21:59:11 INFO - 'sessionrestore_no_auto_restore', 21:59:11 INFO - 'tabpaint']}, 21:59:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['tsvgx', 21:59:11 INFO - 'tsvgr_opacity', 21:59:11 INFO - 'tart', 21:59:11 INFO - 'tscrollx', 21:59:11 INFO - 'cart']}, 21:59:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:59:11 INFO - 'tsvgr_opacity', 21:59:11 INFO - 'tart', 21:59:11 INFO - 'tscrollx', 21:59:11 INFO - 'cart']}, 21:59:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:11 INFO - 'talos_options': ['--disable-e10s'], 21:59:11 INFO - 'tests': ['tp5o']}, 21:59:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:11 INFO - 'tests': ['tp5o']}, 21:59:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:11 INFO - 'talos_options': ['--disable-e10s', 21:59:11 INFO - '--xperf_path', 21:59:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:11 INFO - 'tests': ['tp5n']}, 21:59:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:11 INFO - 'talos_options': ['--xperf_path', 21:59:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:11 INFO - 'tests': ['tp5n']}}, 21:59:11 INFO - 'talos.zip': {'path': '', 21:59:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:59:11 INFO - Running post-action listener: _resource_record_post_action 21:59:11 INFO - ##### 21:59:11 INFO - ##### Finished populate-webroot step (success) 21:59:11 INFO - ##### 21:59:11 INFO - ##### 21:59:11 INFO - ##### Running create-virtualenv step. 21:59:11 INFO - ##### 21:59:11 INFO - Running pre-action listener: _resource_record_pre_action 21:59:11 INFO - Running main action method: create_virtualenv 21:59:11 INFO - Creating virtualenv /builds/slave/test/build/venv 21:59:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:59:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:59:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:59:11 INFO - Using real prefix '/usr' 21:59:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:59:12 INFO - Installing distribute.............................................................................................................................................................................................done. 21:59:14 INFO - Installing pip.................done. 21:59:14 INFO - Return code: 0 21:59:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:59:14 INFO - Using env: {'DISPLAY': ':0', 21:59:14 INFO - 'HOME': '/home/cltbld', 21:59:14 INFO - 'LANG': 'en_US.UTF-8', 21:59:14 INFO - 'LANGUAGE': 'en_US:en', 21:59:14 INFO - 'LOGNAME': 'cltbld', 21:59:14 INFO - 'MAIL': '/var/mail/cltbld', 21:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:14 INFO - 'MOZ_NO_REMOTE': '1', 21:59:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:14 INFO - 'NO_EM_RESTART': '1', 21:59:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:14 INFO - 'PWD': '/builds/slave/test', 21:59:14 INFO - 'SHELL': '/bin/bash', 21:59:14 INFO - 'SHLVL': '1', 21:59:14 INFO - 'TERM': 'linux', 21:59:14 INFO - 'TMOUT': '86400', 21:59:14 INFO - 'USER': 'cltbld', 21:59:14 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:14 INFO - '_': '/tools/buildbot/bin/python'} 21:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:59:14 INFO - Cleaning up... 21:59:14 INFO - Return code: 0 21:59:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:59:14 INFO - Using env: {'DISPLAY': ':0', 21:59:14 INFO - 'HOME': '/home/cltbld', 21:59:14 INFO - 'LANG': 'en_US.UTF-8', 21:59:14 INFO - 'LANGUAGE': 'en_US:en', 21:59:14 INFO - 'LOGNAME': 'cltbld', 21:59:14 INFO - 'MAIL': '/var/mail/cltbld', 21:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:14 INFO - 'MOZ_NO_REMOTE': '1', 21:59:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:14 INFO - 'NO_EM_RESTART': '1', 21:59:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:14 INFO - 'PWD': '/builds/slave/test', 21:59:14 INFO - 'SHELL': '/bin/bash', 21:59:14 INFO - 'SHLVL': '1', 21:59:14 INFO - 'TERM': 'linux', 21:59:14 INFO - 'TMOUT': '86400', 21:59:14 INFO - 'USER': 'cltbld', 21:59:14 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:14 INFO - '_': '/tools/buildbot/bin/python'} 21:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:14 INFO - Downloading/unpacking psutil>=3.1.1 21:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:59:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:59:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:59:16 INFO - Installing collected packages: psutil 21:59:16 INFO - Running setup.py install for psutil 21:59:16 INFO - building 'psutil._psutil_linux' extension 21:59:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:59:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:59:17 INFO - building 'psutil._psutil_posix' extension 21:59:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:59:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:59:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:59:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:59:17 INFO - Successfully installed psutil 21:59:17 INFO - Cleaning up... 21:59:17 INFO - Return code: 0 21:59:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:59:17 INFO - Using env: {'DISPLAY': ':0', 21:59:17 INFO - 'HOME': '/home/cltbld', 21:59:17 INFO - 'LANG': 'en_US.UTF-8', 21:59:17 INFO - 'LANGUAGE': 'en_US:en', 21:59:17 INFO - 'LOGNAME': 'cltbld', 21:59:17 INFO - 'MAIL': '/var/mail/cltbld', 21:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:17 INFO - 'MOZ_NO_REMOTE': '1', 21:59:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:17 INFO - 'NO_EM_RESTART': '1', 21:59:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:17 INFO - 'PWD': '/builds/slave/test', 21:59:17 INFO - 'SHELL': '/bin/bash', 21:59:17 INFO - 'SHLVL': '1', 21:59:17 INFO - 'TERM': 'linux', 21:59:17 INFO - 'TMOUT': '86400', 21:59:17 INFO - 'USER': 'cltbld', 21:59:17 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:17 INFO - '_': '/tools/buildbot/bin/python'} 21:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:59:19 INFO - Installing collected packages: mozsystemmonitor 21:59:19 INFO - Running setup.py install for mozsystemmonitor 21:59:19 INFO - Successfully installed mozsystemmonitor 21:59:19 INFO - Cleaning up... 21:59:19 INFO - Return code: 0 21:59:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:19 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 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:59:19 INFO - Using env: {'DISPLAY': ':0', 21:59:19 INFO - 'HOME': '/home/cltbld', 21:59:19 INFO - 'LANG': 'en_US.UTF-8', 21:59:19 INFO - 'LANGUAGE': 'en_US:en', 21:59:19 INFO - 'LOGNAME': 'cltbld', 21:59:19 INFO - 'MAIL': '/var/mail/cltbld', 21:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:19 INFO - 'MOZ_NO_REMOTE': '1', 21:59:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:19 INFO - 'NO_EM_RESTART': '1', 21:59:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:19 INFO - 'PWD': '/builds/slave/test', 21:59:19 INFO - 'SHELL': '/bin/bash', 21:59:19 INFO - 'SHLVL': '1', 21:59:19 INFO - 'TERM': 'linux', 21:59:19 INFO - 'TMOUT': '86400', 21:59:19 INFO - 'USER': 'cltbld', 21:59:19 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:19 INFO - '_': '/tools/buildbot/bin/python'} 21:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:19 INFO - Downloading/unpacking blobuploader==1.2.4 21:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:21 INFO - Downloading blobuploader-1.2.4.tar.gz 21:59:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:59:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:59:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:22 INFO - Downloading docopt-0.6.1.tar.gz 21:59:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:59:22 INFO - Installing collected packages: blobuploader, requests, docopt 21:59:22 INFO - Running setup.py install for blobuploader 21:59:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:59:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:59:22 INFO - Running setup.py install for requests 21:59:22 INFO - Running setup.py install for docopt 21:59:23 INFO - Successfully installed blobuploader requests docopt 21:59:23 INFO - Cleaning up... 21:59:23 INFO - Return code: 0 21:59:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:59: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')]} 21:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59: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')]} 21:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:23 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 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:59:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:59:23 INFO - Using env: {'DISPLAY': ':0', 21:59:23 INFO - 'HOME': '/home/cltbld', 21:59:23 INFO - 'LANG': 'en_US.UTF-8', 21:59:23 INFO - 'LANGUAGE': 'en_US:en', 21:59:23 INFO - 'LOGNAME': 'cltbld', 21:59:23 INFO - 'MAIL': '/var/mail/cltbld', 21:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:23 INFO - 'MOZ_NO_REMOTE': '1', 21:59:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:23 INFO - 'NO_EM_RESTART': '1', 21:59:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:23 INFO - 'PWD': '/builds/slave/test', 21:59:23 INFO - 'SHELL': '/bin/bash', 21:59:23 INFO - 'SHLVL': '1', 21:59:23 INFO - 'TERM': 'linux', 21:59:23 INFO - 'TMOUT': '86400', 21:59:23 INFO - 'USER': 'cltbld', 21:59:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:23 INFO - '_': '/tools/buildbot/bin/python'} 21:59:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:59:23 INFO - Running setup.py (path:/tmp/pip-26OaUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:59:23 INFO - Running setup.py (path:/tmp/pip-z_l1Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:59:23 INFO - Running setup.py (path:/tmp/pip-QWOoLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:59:23 INFO - Running setup.py (path:/tmp/pip-NoUFkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:59:23 INFO - Running setup.py (path:/tmp/pip-ykFQlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:59:23 INFO - Running setup.py (path:/tmp/pip-Q8DOHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:59:23 INFO - Running setup.py (path:/tmp/pip-GWL_Pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:59:23 INFO - Running setup.py (path:/tmp/pip-d4CgSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:59:23 INFO - Running setup.py (path:/tmp/pip-T8iT59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:59:23 INFO - Running setup.py (path:/tmp/pip-pzbaxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:59:24 INFO - Running setup.py (path:/tmp/pip-frKYRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:59:24 INFO - Running setup.py (path:/tmp/pip-Bj3Fkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:59:24 INFO - Running setup.py (path:/tmp/pip-GbOTcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:59:24 INFO - Running setup.py (path:/tmp/pip-G5SbeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:59:24 INFO - Running setup.py (path:/tmp/pip-66Yq8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:59:24 INFO - Running setup.py (path:/tmp/pip-i3N0iF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:59:24 INFO - Running setup.py (path:/tmp/pip-oljL5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:59:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:59:24 INFO - Running setup.py install for manifestparser 21:59:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:59:24 INFO - Running setup.py install for mozcrash 21:59:24 INFO - Running setup.py install for mozdebug 21:59:24 INFO - Running setup.py install for mozdevice 21:59:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:59:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:59:24 INFO - Running setup.py install for mozfile 21:59:25 INFO - Running setup.py install for mozhttpd 21:59:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Running setup.py install for mozinfo 21:59:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Running setup.py install for mozInstall 21:59:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Running setup.py install for mozleak 21:59:25 INFO - Running setup.py install for mozlog 21:59:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Running setup.py install for moznetwork 21:59:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Running setup.py install for mozprocess 21:59:25 INFO - Running setup.py install for mozprofile 21:59:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:59:25 INFO - Running setup.py install for mozrunner 21:59:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:59:26 INFO - Running setup.py install for mozscreenshot 21:59:26 INFO - Running setup.py install for moztest 21:59:26 INFO - Running setup.py install for mozversion 21:59:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:59:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:59:26 INFO - Cleaning up... 21:59:26 INFO - Return code: 0 21:59:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:26 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 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:59:26 INFO - Using env: {'DISPLAY': ':0', 21:59:26 INFO - 'HOME': '/home/cltbld', 21:59:26 INFO - 'LANG': 'en_US.UTF-8', 21:59:26 INFO - 'LANGUAGE': 'en_US:en', 21:59:26 INFO - 'LOGNAME': 'cltbld', 21:59:26 INFO - 'MAIL': '/var/mail/cltbld', 21:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:26 INFO - 'MOZ_NO_REMOTE': '1', 21:59:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:26 INFO - 'NO_EM_RESTART': '1', 21:59:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:26 INFO - 'PWD': '/builds/slave/test', 21:59:26 INFO - 'SHELL': '/bin/bash', 21:59:26 INFO - 'SHLVL': '1', 21:59:26 INFO - 'TERM': 'linux', 21:59:26 INFO - 'TMOUT': '86400', 21:59:26 INFO - 'USER': 'cltbld', 21:59:26 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:26 INFO - '_': '/tools/buildbot/bin/python'} 21:59:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:59:26 INFO - Running setup.py (path:/tmp/pip-cbvUnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:59:26 INFO - Running setup.py (path:/tmp/pip-QKAneT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:59:26 INFO - Running setup.py (path:/tmp/pip-VYCtSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:59:26 INFO - Running setup.py (path:/tmp/pip-uJ3UNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:59:26 INFO - Running setup.py (path:/tmp/pip-qaFqcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:59:26 INFO - Running setup.py (path:/tmp/pip-xzAPo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:59:26 INFO - Running setup.py (path:/tmp/pip-xAxz0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:59:27 INFO - Running setup.py (path:/tmp/pip-gZK2yM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:59:27 INFO - Running setup.py (path:/tmp/pip-cufFK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:59:27 INFO - Running setup.py (path:/tmp/pip-JyUYEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:59:27 INFO - Running setup.py (path:/tmp/pip-7RGJ8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:59:27 INFO - Running setup.py (path:/tmp/pip-jHbdS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:59:27 INFO - Running setup.py (path:/tmp/pip-Z0KV4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:59:27 INFO - Running setup.py (path:/tmp/pip-tR9oNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:59:27 INFO - Running setup.py (path:/tmp/pip-fFPI4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:59:27 INFO - Running setup.py (path:/tmp/pip-5JOiGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:59:27 INFO - Running setup.py (path:/tmp/pip-f08M2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:59:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:29 INFO - Downloading blessings-1.6.tar.gz 21:59:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:59:29 INFO - Installing collected packages: blessings 21:59:29 INFO - Running setup.py install for blessings 21:59:29 INFO - Successfully installed blessings 21:59:29 INFO - Cleaning up... 21:59:29 INFO - Return code: 0 21:59:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:59:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:59:30 INFO - Reading from file tmpfile_stdout 21:59:30 INFO - Current package versions: 21:59:30 INFO - argparse == 1.2.1 21:59:30 INFO - blessings == 1.6 21:59:30 INFO - blobuploader == 1.2.4 21:59:30 INFO - docopt == 0.6.1 21:59:30 INFO - manifestparser == 1.1 21:59:30 INFO - mozInstall == 1.12 21:59:30 INFO - mozcrash == 0.17 21:59:30 INFO - mozdebug == 0.1 21:59:30 INFO - mozdevice == 0.48 21:59:30 INFO - mozfile == 1.2 21:59:30 INFO - mozhttpd == 0.7 21:59:30 INFO - mozinfo == 0.9 21:59:30 INFO - mozleak == 0.1 21:59:30 INFO - mozlog == 3.2 21:59:30 INFO - moznetwork == 0.27 21:59:30 INFO - mozprocess == 0.22 21:59:30 INFO - mozprofile == 0.28 21:59:30 INFO - mozrunner == 6.11 21:59:30 INFO - mozscreenshot == 0.1 21:59:30 INFO - mozsystemmonitor == 0.3 21:59:30 INFO - moztest == 0.7 21:59:30 INFO - mozversion == 1.4 21:59:30 INFO - psutil == 3.1.1 21:59:30 INFO - requests == 1.2.3 21:59:30 INFO - wsgiref == 0.1.2 21:59:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:30 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 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:59:30 INFO - Using env: {'DISPLAY': ':0', 21:59:30 INFO - 'HOME': '/home/cltbld', 21:59:30 INFO - 'LANG': 'en_US.UTF-8', 21:59:30 INFO - 'LANGUAGE': 'en_US:en', 21:59:30 INFO - 'LOGNAME': 'cltbld', 21:59:30 INFO - 'MAIL': '/var/mail/cltbld', 21:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:30 INFO - 'MOZ_NO_REMOTE': '1', 21:59:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:30 INFO - 'NO_EM_RESTART': '1', 21:59:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:30 INFO - 'PWD': '/builds/slave/test', 21:59:30 INFO - 'SHELL': '/bin/bash', 21:59:30 INFO - 'SHLVL': '1', 21:59:30 INFO - 'TERM': 'linux', 21:59:30 INFO - 'TMOUT': '86400', 21:59:30 INFO - 'USER': 'cltbld', 21:59:30 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:30 INFO - '_': '/tools/buildbot/bin/python'} 21:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:59:30 INFO - Cleaning up... 21:59:30 INFO - Return code: 0 21:59:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:30 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 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:59:30 INFO - Using env: {'DISPLAY': ':0', 21:59:30 INFO - 'HOME': '/home/cltbld', 21:59:30 INFO - 'LANG': 'en_US.UTF-8', 21:59:30 INFO - 'LANGUAGE': 'en_US:en', 21:59:30 INFO - 'LOGNAME': 'cltbld', 21:59:30 INFO - 'MAIL': '/var/mail/cltbld', 21:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:30 INFO - 'MOZ_NO_REMOTE': '1', 21:59:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:30 INFO - 'NO_EM_RESTART': '1', 21:59:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:30 INFO - 'PWD': '/builds/slave/test', 21:59:30 INFO - 'SHELL': '/bin/bash', 21:59:30 INFO - 'SHLVL': '1', 21:59:30 INFO - 'TERM': 'linux', 21:59:30 INFO - 'TMOUT': '86400', 21:59:30 INFO - 'USER': 'cltbld', 21:59:30 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:30 INFO - '_': '/tools/buildbot/bin/python'} 21:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:30 INFO - Downloading/unpacking jsonschema 21:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:59:32 INFO - Downloading/unpacking functools32 (from jsonschema) 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59:32 INFO - Downloading functools32-3.2.3-2.tar.gz 21:59:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:59:32 INFO - warning: no files found matching '*.txt' 21:59:32 INFO - no previously-included directories found matching 'build' 21:59:32 INFO - no previously-included directories found matching 'dist' 21:59:32 INFO - no previously-included directories found matching '.git*' 21:59:32 INFO - Installing collected packages: jsonschema, functools32 21:59:32 INFO - Running setup.py install for functools32 21:59:32 INFO - warning: no files found matching '*.txt' 21:59:32 INFO - no previously-included directories found matching 'build' 21:59:32 INFO - no previously-included directories found matching 'dist' 21:59:32 INFO - no previously-included directories found matching '.git*' 21:59:32 INFO - Successfully installed jsonschema functools32 21:59:32 INFO - Cleaning up... 21:59:32 INFO - Return code: 0 21:59:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:32 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff70ad1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, '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-1464665101.182919-923360563', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:59:32 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', 'flake8'] in /builds/slave/test/build 21:59:32 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 flake8 21:59:32 INFO - Using env: {'DISPLAY': ':0', 21:59:32 INFO - 'HOME': '/home/cltbld', 21:59:32 INFO - 'LANG': 'en_US.UTF-8', 21:59:32 INFO - 'LANGUAGE': 'en_US:en', 21:59:32 INFO - 'LOGNAME': 'cltbld', 21:59:32 INFO - 'MAIL': '/var/mail/cltbld', 21:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:32 INFO - 'MOZ_NO_REMOTE': '1', 21:59:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:32 INFO - 'NO_EM_RESTART': '1', 21:59:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:32 INFO - 'PWD': '/builds/slave/test', 21:59:32 INFO - 'SHELL': '/bin/bash', 21:59:32 INFO - 'SHLVL': '1', 21:59:32 INFO - 'TERM': 'linux', 21:59:32 INFO - 'TMOUT': '86400', 21:59:32 INFO - 'USER': 'cltbld', 21:59:32 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:32 INFO - '_': '/tools/buildbot/bin/python'} 21:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:33 INFO - Downloading/unpacking flake8 21:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:59:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:59:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:59:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:59:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:59:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:59:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:59:35 INFO - no previously-included directories found matching 'docs/_build' 21:59:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:59:35 INFO - Running setup.py install for pep8 21:59:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:59:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:59:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:59:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:59:35 INFO - no previously-included directories found matching 'docs/_build' 21:59:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:59:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:59:35 INFO - Cleaning up... 21:59:35 INFO - Return code: 0 21:59:35 INFO - Running post-action listener: _resource_record_post_action 21:59:35 INFO - Running post-action listener: _start_resource_monitoring 21:59:36 INFO - Starting resource monitoring. 21:59:36 INFO - ##### 21:59:36 INFO - ##### Finished create-virtualenv step (success) 21:59:36 INFO - ##### 21:59:36 INFO - ##### 21:59:36 INFO - ##### Running install step. 21:59:36 INFO - ##### 21:59:36 INFO - Running pre-action listener: _resource_record_pre_action 21:59:36 INFO - Running main action method: install 21:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:59:36 INFO - Reading from file tmpfile_stdout 21:59:36 INFO - Detecting whether we're running mozinstall >=1.0... 21:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:59:36 INFO - Reading from file tmpfile_stdout 21:59:36 INFO - Output received: 21:59:36 INFO - Usage: mozinstall [options] installer 21:59:36 INFO - Options: 21:59:36 INFO - -h, --help show this help message and exit 21:59:36 INFO - -d DEST, --destination=DEST 21:59:36 INFO - Directory to install application into. [default: 21:59:36 INFO - "/builds/slave/test"] 21:59:36 INFO - --app=APP Application being installed. [default: firefox] 21:59:36 INFO - mkdir: /builds/slave/test/build/application 21:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:59:52 INFO - Reading from file tmpfile_stdout 21:59:52 INFO - Output received: 21:59:52 INFO - /builds/slave/test/build/application/firefox/firefox 21:59:52 INFO - Running post-action listener: _resource_record_post_action 21:59:52 INFO - ##### 21:59:52 INFO - ##### Finished install step (success) 21:59:52 INFO - ##### 21:59:52 INFO - ##### 21:59:52 INFO - ##### Running run-tests step. 21:59:52 INFO - ##### 21:59:52 INFO - Running pre-action listener: _resource_record_pre_action 21:59:52 INFO - Running main action method: run_tests 21:59:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:59:52 INFO - Python 2.7.3 21:59:52 INFO - Return code: 0 21:59:52 INFO - grabbing minidump binary from tooltool 21:59: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')]} 21:59: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 0x1c5d9c0>, '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 0x1c5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5ed50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:59: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 21:59: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 21:59: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 21:59:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:59:52 INFO - Return code: 0 21:59:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:59:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:59:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:59:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:59:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:59:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:59:53 INFO - Return code: 0 21:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:59:53 INFO - Using env: {'DISPLAY': ':0', 21:59:53 INFO - 'HOME': '/home/cltbld', 21:59:53 INFO - 'LANG': 'en_US.UTF-8', 21:59:53 INFO - 'LANGUAGE': 'en_US:en', 21:59:53 INFO - 'LOGNAME': 'cltbld', 21:59:53 INFO - 'MAIL': '/var/mail/cltbld', 21:59:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:59:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:53 INFO - 'MOZ_NO_REMOTE': '1', 21:59:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:53 INFO - 'NO_EM_RESTART': '1', 21:59:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:53 INFO - 'PWD': '/builds/slave/test', 21:59:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:59:53 INFO - 'SHELL': '/bin/bash', 21:59:53 INFO - 'SHLVL': '1', 21:59:53 INFO - 'TERM': 'linux', 21:59:53 INFO - 'TMOUT': '86400', 21:59:53 INFO - 'USER': 'cltbld', 21:59:53 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1464665101.182919-923360563', 21:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:53 INFO - '_': '/tools/buildbot/bin/python'} 21:59:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:59:53 INFO - mozversion application_buildid: 20160530210038 21:59:53 INFO - mozversion application_changeset: d049a47c24c1889cb547d4d899e998c75367e315 21:59:53 INFO - mozversion application_display_name: Nightly 21:59:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:59:53 INFO - mozversion application_name: Firefox 21:59:53 INFO - mozversion application_remotingname: firefox 21:59:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:59:53 INFO - mozversion application_vendor: Mozilla 21:59:53 INFO - mozversion application_version: 49.0a1 21:59:53 INFO - mozversion platform_buildid: 20160530210038 21:59:53 INFO - mozversion platform_changeset: d049a47c24c1889cb547d4d899e998c75367e315 21:59:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:59:53 INFO - mozversion platform_version: 49.0a1 21:59:53 INFO - using testdate: 1464670793 21:59:53 INFO - actual date: 1464670793 21:59:53 INFO - starting webserver on 'localhost:53317' 21:59:53 INFO - SUITE-START | Running 2 tests 21:59:53 INFO - TEST-START | dromaeo_css 21:59:53 INFO - operating with platform_type : linux_ 21:59:53 INFO - Initialising browser for dromaeo_css test... 21:59:53 INFO - TEST-INFO | started process 6145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgE0y6s/profile http://localhost:53317/getInfo.html) 21:59:57 INFO - PROCESS | 6145 | __metrics Screen width/height:1600/1200 21:59:57 INFO - PROCESS | 6145 | colorDepth:24 21:59:57 INFO - PROCESS | 6145 | Browser inner width/height: 1024/697 21:59:57 INFO - PROCESS | 6145 | __metrics 21:59:58 ERROR - PROCESS | 6145 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:59:59 INFO - TEST-INFO | 6145: exit 0 21:59:59 INFO - Browser initialized. 21:59:59 INFO - Running cycle 1/1 for dromaeo_css test... 21:59:59 INFO - TEST-INFO | started process 6225 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgE0y6s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:00:11 INFO - PROCESS | 6225 | RSS: Main: 157114368 22:00:11 INFO - PROCESS | 6225 | 22:01:03 INFO - PROCESS | 6225 | 1464670863076 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] 22:03:14 INFO - PROCESS | 6225 | Cycle 1(1): loaded http://localhost:53317/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53317/tests/dromaeo/cssquery-ext.html) 22:03:15 INFO - PROCESS | 6225 | RSS: Main: 279883776 22:03:15 INFO - PROCESS | 6225 | 22:06:18 INFO - PROCESS | 6225 | Cycle 1(1): loaded http://localhost:53317/tests/dromaeo/cssquery-ext.html (next: http://localhost:53317/tests/dromaeo/cssquery-jquery.html) 22:06:19 INFO - PROCESS | 6225 | RSS: Main: 242913280 22:06:19 INFO - PROCESS | 6225 | 22:09:21 INFO - PROCESS | 6225 | Cycle 1(1): loaded http://localhost:53317/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53317/tests/dromaeo/cssquery-mootools.html) 22:09:22 INFO - PROCESS | 6225 | RSS: Main: 243064832 22:09:22 INFO - PROCESS | 6225 | 22:12:24 INFO - PROCESS | 6225 | Cycle 1(1): loaded http://localhost:53317/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53317/tests/dromaeo/cssquery-prototype.html) 22:12:25 INFO - PROCESS | 6225 | RSS: Main: 234676224 22:12:25 INFO - PROCESS | 6225 | 22:15:27 INFO - PROCESS | 6225 | Cycle 1(1): loaded http://localhost:53317/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53317/tests/dromaeo/cssquery-yui.html) 22:15:28 INFO - PROCESS | 6225 | RSS: Main: 212606976 22:15:28 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | Cycle 1(1): loaded http://localhost:53317/tests/dromaeo/cssquery-yui.html (next: http://localhost:53317/tests/dromaeo/cssquery-dojo.html) 22:18:31 INFO - PROCESS | 6225 | RSS: Main: 274292736 22:18:31 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | __start_tp_report 22:18:31 INFO - PROCESS | 6225 | _x_x_mozilla_page_load 22:18:31 INFO - PROCESS | 6225 | _x_x_mozilla_page_load_details 22:18:31 INFO - PROCESS | 6225 | |i|pagename|runs| 22:18:31 INFO - PROCESS | 6225 | |0;dojo.html;2688.1397952655147;2694.609281654159;2714.8327317567537;2739.80821342506;2766.00423847415;4577.496475387705;4720.504347043562;4732.5977291930185;4809;4873.074115917978;4660.464046634638;4664.2770370592525;4673.042026384592;4732.408448943874;4734.526547345273;4243.893902652428;4322.222000039991;4350.325699516564;4376.540463251361;4559.475660299058;4473.239549276631;4489.685721999462;4506.36910832482;4506.707064040843;4552.954470455303;4216.072464057902;4279.293916503773;4358;4398.7580683062415;4433.423654924839;4338.110687309094;4339.7396156230725;4344.283193273106;4465.37484752135;4474.7762611869275;4388.363687265361;4395.406620106277;4440.17856696509;4441.378207051015;4450.799714012877;4480.708753930984;4606.262997920317;4652.837150699743;4692.272697731857;4720.33915251865;5139.460356662571;5184.429712731597;5191.948080519184;5193.091209038404;5205.791768329263;5146.742662866842;5187.144121219994;5205.557527610158;5214.191800270964;5216.739163041832;4627.1439783640135;4719;4775.474697783241;4788.281757736368;4804.975975120172;5303.416624171338;5306.575473962073;5323.1216849219445;5334.75993580282;5334.759935802898;5052.5452709255605;5090.10923088458;5091.134507133796;5096.388433387943;5098.59211263098;4877.122118018719;5089.312942752681;5171.974140129275;5175.094358487249;5194.532492075731;5111.3355263815465;5138.434772175057;5141.691498510027;5143.8456846294675;5198.818041368534;4812.35033270504;5107.565856902131;5119.283300337956;5120.078385890501;5127.41034781005;4151.813168407369;4270.0605866709275;4320.265554855682;4321.178975994551;4375.7374557526655;3759.2105657812167;3773.716971227169;3797.1456422305314;3828.559715632682;3832.885013449601;4497.2129877271345;4504.25679762842;4510.255807791743;4510.503844577094;4560.293154561048;4974.253861920741;5031.22016087507;5073.289739436482;5082.466341034138;5098.796048158107;4380.627646650007;4385.166818304512;4412.382266482695;4414.514403415622;4439.62263207631;4401.625861801783;4506.662000349987;4508.368828364031;4510.075434535912;4548.886277843014;4797.328374027708;4867.41591009067;4874.90250194987;4884.096442158212;4926.359573255454;5142.485751424828;5181.663191892515;5183.740812959261;5185.066687996197;5235.397929238034;4704.929426058543;4821.46963833985;4907.55831975124;4925.9014819704125;4943.77753001123;5023.799048038038;5040.445550989315;5070.366204224472;5072.213806859942;5118.513741194581;5130.5639020682165;5134.743262836769;5139.331886854835;5146.614003949644;5189.584833213409;4746.027064451848;4945.8021679132435;4984.127777638972;4995.850124496271;4997;4996.075725990703;5013.824516141917;5023.6985780853265;5024.145895197752;5051.419086805065;4941.6293777966075;4944.653874228769;4966.304717339648;4976.32819569368;4981.053599816023;4787.664863459524;4834.395700537433;5017.096922553976;5050.292958985819;5110.182370820651;4732.905341893212;4856.68431551948;4908.190148625579;4914.5822605078965;4954.405471343318 22:18:31 INFO - PROCESS | 6225 | |1;ext.html;552.0448141752097;576.8298351986165;590.506926716192;592.8428966323925;605.7365046204905;28197.718022819765;28862.134135975928;40915.181696366126;42352.78823605881;43329.35005974905;39299.21401571974;39870;39928.00179995502;43281.35077973833;43342.13315733684;7751.418643601724;7862.410319226081;7915.999999999971;7927.643256053462;7935.285824275814;7449.590272535035;10087.798244035115;10338.53476593555;10340.379577225352;10359.223058270622;9993.450360230221;10127.544260508295;10129.037741414553;10417.635382761604;10424.530896109693;10124.3925364478;10235.897641023606;10238.129758970497;10242.948785256027;10246.436445995467;9988.700338989842;10034;10039.247056470795;10044.447555384451;10050.497475126214;40201;40650;40678;40707;40747;2994.4759667058397;3005.023367405602;3035.620547431571;3252.7560432967384;3252.7723059385617;30288.8485557573;30609.846950765328;30789.38421231563;30986.38027239465;31271.218219544466;3279.1310302769784;3324.335132973415;3364.9327013459592;3375.476801095834;3379.4423920053164;266069;267270;267950;267999;268603;60249.397506025256;61022;61210.387896121356;61248;61289.38710612926;63765.681171593846;63883.36116638774;64111.35888641147;64150;64245.678771605846;15720.92139539295;16266.75599866003;16291.42979995718;16293.103879286517;16300.592485187964;10230;11643.825342619868;11748.29510229389;12314.938425307995;12614.117011809085;19421.92386605865;19468.076114141357;19549;19631;19684.70472942907;7344.742933997284;7368.484206105629;7385.261473852572;7484.812879677943;7504;817.3420396580809;896.7489103051156;916.7937214126902;957.875476188091;987.4272921705393;28010.43979120447;28862.567061494094;29140.83436662552;29398.706012940023;29441.55837662437;925.3892430995511;943.2972435535432;1008.5965613754411;1045.341434896026;1048.1876545677162;14808.925955370154;15288.769654967015;15340.309686064154;15386.769198462032;15469.613259668598;369352;370242;370609;371195;371629;50844.23733643998;51240.743796281524;51609.483905160465;51624;51696.741516292175;1096.2381145104075;1184.9348285844362;1212.9090318226167;1227.656256248252;1236.4250623459955;26772.464550708486;30147.397052058397;30223.244418889703;30685.386292274477;30833.075007749805;43196.352054719835;43221;43625.34561981636;43727.12545749004;43747.78126109349;295469;295553;295696;296037;296449;32840.34319313648;38390.808045959595;39345.21309573735;39810.800945995084;39894.2021159581;43182.352264715424;43437;43554.78222608867;43782.56217437785;44428;37378.626213737516;37497.437538437494;37500.062498436666;37941.81029094837;38061.2387752249;37410.62589374071;37533;37661;37765.24469510649;37887;36421;36478;36939.81530092332;37034;37120;29956.550651740676;31983.680163199002;32095.518567221043;32871.17822054468;32965.34069318648;24531;24773.62839557444;24911.87544062268;25244.788180759177;25396.111136110147 22:18:31 INFO - PROCESS | 6225 | |2;jquery.html;350.4183056126829;350.59156083163117;351.2605964444842;364.2950890027795;374.38787582302945;15491.612709682264;16117.597060073489;16306.266218020188;16680.41618543351;16805.41181058662;15332.080075195496;15405.614859628487;15691.529254122366;15791.052536847797;15836.049837009787;4681.438227412698;4781.856544303677;4915.729634870081;4916;4917.3607431033915;3580.7135429165733;3759;4851.199552073921;5350.946490535103;5643.830685079454;5572.554195664357;5585.776568937237;5601.271834661488;5602.971985140069;5609.943900561003;5508;5567.4432556744405;5599.32808063033;5602.439756024406;5603.60774745768;3207.1180425382972;4669.276262179367;4974.950250497485;4989.17678583037;5000.249962505617;18937.43187704371;19399.127039283267;19417.80582194174;19861.9006904966;19887.403377898685;2030.4517780199265;2044.8875311857842;2297.1156104899733;2313.97686023141;2410.3733029412306;7250.818729531751;12313.630591082281;12775.29735864527;13128.146670466389;13253.536126235633;1641.564985278902;1661.1113054515897;1661.3603762551384;1707.085492740433;1772.59230377014;63780;64211.60790886067;64640.67679661571;64654;65938.67030664864;8144.75565733029;8801.691940782042;8991.325650576233;9137.223336016514;9236.445813251226;12256.14207005501;12352.567660131852;12352.691182720324;12353.135280530456;12376.195547289397;6871.862562748717;7008.334208250208;7090.574565525982;7100.076989991268;7183.353498185188;6772.55978361405;6773.9322606774285;6854.897176542356;6860.794176174722;6885.070515480346;5743.190894868103;5810.796622118206;5944.227250457413;5951.285845698458;5964.403559644001;1522.5736793697783;1609.855113039832;1615.854573088404;1662.9750553741703;1701.4725435115154;658.5982550644103;892.054422312351;932.4684929590069;974;985.2364417576435;15926.442574509836;16344.264508097163;16730.49808505747;16908;17126.743098853527;859.4800145911759;874.925631321332;891.0732837848694;924.8890133184061;940.9978373032699;9261.166495015346;9706;9780.853287200698;9824.803503929881;9948.955359687274;389985;395406;395569.3544516432;395971;399661;68341.65829170922;68709;70442.64778676073;70666.64666676633;71101.28898710947;847.6143354773563;901.7970956534728;933.2207606648388;952.6522819170953;963.2967933408651;11351.602693905674;14520.491782787552;14530.85469145295;15119.395224190908;15817.920910395374;26907;31530;33220.16949576183;35346;36446.817765911;42405.57594424016;45477.317840231764;47956.28065579089;48182.75908620434;48306;6883.7590684325805;7023.22744498115;7057.788266352018;7154.213036566078;7163.820904477429;9588.616455341988;9640.457526795679;10394.064534191959;10644.042036216777;10705;9035.186833185046;9105.726828195166;9137.269018478639;9163.954180229057;9573.994730553191;8813.955930220307;8997.460152390877;9422.387544809566;9620.903790962;9627.277954153327;8756.649734010569;9018.503982281038;9025.864612030693;9389.765255868659;9434.150926416656;6789.219239787488;6810.28492008332;6842.299299528797;7015.579065256101;7045.859082818418;7312;9306.162445379949;9485.952570237105;9729.513524323615;9758.268129890144 22:18:31 INFO - PROCESS | 6225 | |3;mootools.html;1246.2460211572004;1406.4936622815787;1465.2087872548825;1549.5583758628786;1759.1556053094514;6331.113644089825;7209.783706488807;7481.289277518634;7490.4756667033325;7707.92292077079;7390.445716571266;7554.206808285134;7559.924400755991;7572.242775722425;7587.962060189691;2117.322456813816;2144.185209620342;2146.227358151072;2146.334636262756;2147.9892600536978;2077.83377329814;2099.254764558583;2148.5488047510044;2153.76308606054;2161.5136594266323;2108.4834215617198;2129.957400851982;2131.840111991599;2137.8289736821093;2148.924787632433;2083.874967501955;2087.903850478511;2093.4870956615573;2093.602215579043;2093.9371818845457;2040.9183632654824;2119.809217170462;2120.978790212109;2122.07689654999;2124.4370241885913;7416.703331866719;7515.060617422822;7598.8480230395635;7610.695572177106;7688.269614386681;4498.707584007029;4604.401427814396;4609.354690343354;4618.399608050965;4619.28401097827;2729.440464704731;2808.6629604447394;2851.615031970678;2856.857257097157;2866.240446281737;845.7081309650854;891.5052146058935;898.0839543665425;909.4770506958525;912.575652321667;10261.281710280284;10337.948310258476;10354.792904141876;10366.688999330032;10369.63706270277;3927.116398810302;4002.199560087994;4061.3501839705505;4075.8369665213654;4084.101497670508;8107.351411887035;8147.307478864244;8148;8189.754307370788;8196.631151598192;4423.778811059434;4428.822847086145;4438.755868427203;4439.600435960779;4440.955590444054;3522.084258092863;4030.375291829771;4092.4679791627495;4173.770442625624;4225.9577404226175;3258.2405501029652;3307.3550657622013;3317.6682331767115;3336;3357.5635167428077;1027.753339198602;1032.3805716569998;1035.4305132177271;1037.3620223562455;1046.4506134279563;732.6922692469176;803.9758807235793;816.163432481711;832.983340333202;847.4999750147442;4767.642426818003;4988.6009119270375;5012.7994880205115;5066.36670416198;5083.313752643419;381.5440548544518;383.8656470235395;387.3975967370765;392.7152814209732;396.2293339454733;3366.646502117291;3490.5636795400574;3517.630648781841;3520.207953210507;3610.1696609779897;9025.954870225607;9312.301577381724;9354.53227338644;9362.531873406302;9370.812583748288;9052;9182.219511341476;9200.631974721071;9235.584398702074;9279.582418791171;6806.693698783567;7108.68010939509;7135.89296160558;7196.136463624399;7222.458315626349;5489.066858633962;5502.669839809624;5522.751476183501;5584.218209450762;5653.265075540154;8572.571371431279;8716.607752651273;8721.825563488568;8800.075992020746;8801.119888011404;7915.920840791749;8156.306713929383;8166.510009399455;8390.07709152005;8831.249343805848;5881.911771323519;6250.531210159347;6564.277929427854;6700.899486507608;6706.6646667665445;7273.0908636420445;7520.736774212876;7534.811629709301;7546;7568.772936811904;6567.868642627216;6635.734570617315;6698.430633396216;6728.360805723449;6795.864082718417;6662.333766623299;6678.23200331968;6681.231658359351;6749.291324410866;6795.592264464148;6108.816735497942;6137.263528376445;6150.231221097363;6150.938490615036;6157.753689852356;4293.334533147367;4317.395564620892;4469.351943968171;4476.306172543261;4501.3022981437925;4316.611504964569;4447.243968525294;4540.500544939988;4545.181867263792;4598.563136502027 22:18:31 INFO - PROCESS | 6225 | |4;prototype.html;271.58040826922405;274.6511929849091;275.4738449561338;280.4783103427625;285.328052436512;4743.857684269473;4806.279058141281;4808.591269742072;4819.951520603747;4852.369192005039;4770.379850619423;4774.450938142115;4778.928316075262;4782.632596864548;4785.162596545608;3539.8406971747277;3644.416893297073;3672.926541469169;3750.1937083527037;3776.622337766229;3708.388115960877;3748.887533374003;3754.906127346811;3757.7369584129124;3757.887263382103;3533.964660353402;3692.02161427222;3696.5933747287777;3699.4450832375223;3709.8684901105153;3516.4549494828343;3625.166211771281;3655.574326012857;3661.707063434919;3663.1757854482794;3207.83960801959;3222.7099561039386;3225.225945773021;3225.7741958062943;3248.7238584720326;4607.258681084749;4622.560856718606;4628.305754136873;4632.513586073481;4667.299905014241;3968.1666849961543;4135.338345864678;4176.665866730685;4182.853600123981;4190.832366705328;4982.103221420143;5007.699538027694;5009.398872135331;5021.297018417425;5023.522765337287;3468.219650578625;3469.254110366258;3483.3381657484997;3484.860605575774;3492.161881148506;5109.463506331856;5231.476852314776;5239.711815850127;5243.475652434764;5295.655782374133;3500.8774692885627;3526.7883926964464;3526.85377252394;3527.7177825773874;3545.7695249809194;3567.2152126532123;3568.553930758655;3571.74997750155;3575.570931488186;3580.2481478889727;4143.81352839123;4143.854965076268;4152.6055024772595;4164.729292595972;4172.144710334374;4908.699194713404;4975.203967365276;5035.194368900959;5086.491350864958;5095.770690318945;1226.6320103968774;1240.962771116868;1242.881926217008;1243.975120497585;1254.7615952968906;757.8787394017041;771.4676872957641;772.9729459468891;785.4344871692373;788.9447738658239;4402.801873915681;4483.708558943658;4525.705829121097;4539.863804085882;4551.158035763328;5213.947860521422;5221.712805795718;5223.843284701465;5225.399079105876;5238.764255608507;4487.820487180542;4505.481869584974;4528.230200865861;4533.614642755337;4548.863534093983;2620.947581048368;2788.470190663808;2792.134438324126;2798.6361772969385;2806.943861122766;4927.5811556017425;4929.22547882762;4955;4982.750862456863;5001.249812528077;5183.97408012965;5277.366715994105;5334.5732341412595;5347.1979203119845;5372.247885296062;4905.950940490692;4966.577840883493;4992.10300085965;4993.901341704819;5004.799808007639;5237.8428647140645;5245.475452454724;5250.868728281671;5252.606054545848;5259.289995850511;4875.902921842557;5141.768620412166;5159.329287192641;5162.97418512905;5174.8965020700125;4986.601071914166;4989.426215985063;4998.400511836177;5005.424376196638;5014.17266151081;3580.2481478889727;3604.3692353838496;3608.5850127235703;3613.1509095362044;3616.5840928293583;5131.281620573048;5221.921671175011;5283.498067683564;5304.946950530445;5305.761240744098;5066.214736715815;5076.289319495348;5082.313887625123;5087.567556757717;5093.974530127326;5059;5059.721715305694;5065.696058236517;5070.923936141035;5090.91635495594;4865.70805751656;4940.75296235194;4994.950050499594;5000.67495612784;5014.42334131565;4875.366202393666;5003.274525193754;5135.3324067870935;5137.17805151174;5140.871478213075;5021.271915572294;5030.849074527624;5037.420696619935;5038.445770965118;5040.941402305557 22:18:31 INFO - PROCESS | 6225 | |5;yui.html;638.9393007664272;664.926858045615;687.8521117959638;739.4158614694388;780.3250188586874;10700.571977120915;11608.94195529021;11652.708682282926;11654.883451165497;11671.358075305865;11124.61063862765;11149.442527873594;11161.107111431087;11171.553137874496;11177;595.5414330965176;739.334598861024;764.3579393309633;779.392074182136;780.0249687890138;768.6195333310019;785.9528428294292;785.968561257549;786.744308099867;787.7951732549521;828.2711214131556;835.6824406725466;836.0719601242616;836.1763662792144;836.9916300837006;630.7540059376847;786.960651967405;816.3265306122413;822.1696086952192;824.9793755156139;601.7863658401249;601.9668918209496;602.1359349333676;602.16299343912;602.1810337940398;10942.781144377068;11001.669949901516;11028.338299702044;11039.172062095295;11043.171762117874;11452.141089418326;11747.236429632047;11818.17272790905;12128.151029427943;12364;10388.324758890723;10474.21443391741;10523.473826308731;10614.628488002883;10761.192910531663;3943.1719338938856;3989.1024519483176;4015.417764424142;4124.092699606082;4168.6665066794585;44766.776166119285;44789;45004;46563.767181164214;47087.99999999966;9182.265418766616;9242.66979528119;9375.484348360906;9415.62337506492;9423.858642120374;16396.672066558604;16979.15104244783;17059.65880682404;17063.06153161588;17108.05905675175;6049.939500605025;6095.847603809941;6121.908171377433;6205.906911396332;6251.812445626639;3631.5279013728045;3685.3182161300247;3692.8522859085874;3703.092742278105;3706.0734816295926;2623.6064590311607;2705.6212130301774;2709.593560965871;2709.593560965871;2713.33523286796;501.2931766209627;507.4750375122733;509.6687153350352;516.4706176169457;522.7778194267421;480.5962991087503;573.4265734265651;584.6638183044766;589.7699897040158;597.0924195223237;7141.571505709674;7155.427565794724;7276.272372762787;7382.409407247407;7394.482386232912;541.5207541325876;548.6598309048416;554.2517601238377;558.6201383059558;568.655963142293;3333.4999750037687;3382.0868365541164;3408.5398471206568;3411.1301618087227;3493.615702272748;38694.6130538691;38905.80547097247;38937;38966.415503767465;39025;48798.756006219744;48962.26556601654;50104.24843627348;50996.23505647418;51142;45845;46312;46733.766331168124;46944;47524.28713569299;6204.875902481835;6268.341824108403;6282.183316168869;6381.234251889803;6464.741410343534;29617.703822961495;31022;31529;31940.20149496281;32164.839175803972;37365.252694946495;39494.2101157981;40190.59809401986;40263;40757.388639171026;9119.498427586546;9301.953490232505;9315.580798863932;9329.953350233205;9394.765130871783;9390.67132650354;9634.518274086407;9698;9883.30816842814;9993.200543956611;8773.298136148966;8790.164934331227;8796.560171991503;8836.646534138563;9195.264378849542;8662.870056949025;8762.868556971524;9021.142991415805;9127.908720912706;9228.95385523068;8310.21052999964;8334.541600212047;8414.368922330972;8636.352273579381;8652.351073669626;7920.960395197987;7948.244916732901;8000.959995199987;8143.75568732939;8298.83402331938;7765.22347765219;7954.443188976716;7955.9204407957495;8111.107778144281;8189.222023907958 22:18:31 INFO - PROCESS | 6225 | __end_tp_report 22:18:31 INFO - PROCESS | 6225 | __start_cc_report 22:18:31 INFO - PROCESS | 6225 | _x_x_mozilla_cycle_collect,1125 22:18:31 INFO - PROCESS | 6225 | __end_cc_report 22:18:31 INFO - PROCESS | 6225 | __startTimestamp1464671911529__endTimestamp 22:18:31 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | ------- Summary: start ------- 22:18:31 INFO - PROCESS | 6225 | Number of tests: 6 22:18:31 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | [#0] dojo.html Cycles:165 Average:4729.83 Median:4856.68 stddev:499.89 (10.3%) stddev-sans-first:475.07 22:18:31 INFO - PROCESS | 6225 | Values: 2688.1 2694.6 2714.8 2739.8 2766.0 4577.5 4720.5 4732.6 4809.0 4873.1 4660.5 4664.3 4673.0 4732.4 4734.5 4243.9 4322.2 4350.3 4376.5 4559.5 4473.2 4489.7 4506.4 4506.7 4553.0 4216.1 4279.3 4358.0 4398.8 4433.4 4338.1 4339.7 4344.3 4465.4 4474.8 4388.4 4395.4 4440.2 4441.4 4450.8 4480.7 4606.3 4652.8 4692.3 4720.3 5139.5 5184.4 5191.9 5193.1 5205.8 5146.7 5187.1 5205.6 5214.2 5216.7 4627.1 4719.0 4775.5 4788.3 4805.0 5303.4 5306.6 5323.1 5334.8 5334.8 5052.5 5090.1 5091.1 5096.4 5098.6 4877.1 5089.3 5172.0 5175.1 5194.5 5111.3 5138.4 5141.7 5143.8 5198.8 4812.4 5107.6 5119.3 5120.1 5127.4 4151.8 4270.1 4320.3 4321.2 4375.7 3759.2 3773.7 3797.1 3828.6 3832.9 4497.2 4504.3 4510.3 4510.5 4560.3 4974.3 5031.2 5073.3 5082.5 5098.8 4380.6 4385.2 4412.4 4414.5 4439.6 4401.6 4506.7 4508.4 4510.1 4548.9 4797.3 4867.4 4874.9 4884.1 4926.4 5142.5 5181.7 5183.7 5185.1 5235.4 4704.9 4821.5 4907.6 4925.9 4943.8 5023.8 5040.4 5070.4 5072.2 5118.5 5130.6 5134.7 5139.3 5146.6 5189.6 4746.0 4945.8 4984.1 4995.9 4997.0 4996.1 5013.8 5023.7 5024.1 5051.4 4941.6 4944.7 4966.3 4976.3 4981.1 4787.7 4834.4 5017.1 5050.3 5110.2 4732.9 4856.7 4908.2 4914.6 4954.4 22:18:31 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | [#1] ext.html Cycles:180 Average:48362.39 Median:3370.20 stddev:82353.84 (2443.6%) stddev-sans-first:82506.63 22:18:31 INFO - PROCESS | 6225 | Values: 552.0 576.8 590.5 592.8 605.7 28197.7 28862.1 40915.2 42352.8 43329.4 39299.2 39870.0 39928.0 43281.4 43342.1 7751.4 7862.4 7916.0 7927.6 7935.3 7449.6 10087.8 10338.5 10340.4 10359.2 9993.5 10127.5 10129.0 10417.6 10424.5 10124.4 10235.9 10238.1 10242.9 10246.4 9988.7 10034.0 10039.2 10044.4 10050.5 40201.0 40650.0 40678.0 40707.0 40747.0 2994.5 3005.0 3035.6 3252.8 3252.8 30288.8 30609.8 30789.4 30986.4 31271.2 3279.1 3324.3 3364.9 3375.5 3379.4 266069.0 267270.0 267950.0 267999.0 268603.0 60249.4 61022.0 61210.4 61248.0 61289.4 63765.7 63883.4 64111.4 64150.0 64245.7 15720.9 16266.8 16291.4 16293.1 16300.6 10230.0 11643.8 11748.3 12314.9 12614.1 19421.9 19468.1 19549.0 19631.0 19684.7 7344.7 7368.5 7385.3 7484.8 7504.0 817.3 896.7 916.8 957.9 987.4 28010.4 28862.6 29140.8 29398.7 29441.6 925.4 943.3 1008.6 1045.3 1048.2 14808.9 15288.8 15340.3 15386.8 15469.6 369352.0 370242.0 370609.0 371195.0 371629.0 50844.2 51240.7 51609.5 51624.0 51696.7 1096.2 1184.9 1212.9 1227.7 1236.4 26772.5 30147.4 30223.2 30685.4 30833.1 43196.4 43221.0 43625.3 43727.1 43747.8 295469.0 295553.0 295696.0 296037.0 296449.0 32840.3 38390.8 39345.2 39810.8 39894.2 43182.4 43437.0 43554.8 43782.6 44428.0 37378.6 37497.4 37500.1 37941.8 38061.2 37410.6 37533.0 37661.0 37765.2 37887.0 36421.0 36478.0 36939.8 37034.0 37120.0 29956.6 31983.7 32095.5 32871.2 32965.3 24531.0 24773.6 24911.9 25244.8 25396.1 22:18:31 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | [#2] jquery.html Cycles:180 Average:23565.54 Median:5600.30 stddev:64999.26 (1160.6%) stddev-sans-first:65158.23 22:18:31 INFO - PROCESS | 6225 | Values: 350.4 350.6 351.3 364.3 374.4 15491.6 16117.6 16306.3 16680.4 16805.4 15332.1 15405.6 15691.5 15791.1 15836.0 4681.4 4781.9 4915.7 4916.0 4917.4 3580.7 3759.0 4851.2 5350.9 5643.8 5572.6 5585.8 5601.3 5603.0 5609.9 5508.0 5567.4 5599.3 5602.4 5603.6 3207.1 4669.3 4975.0 4989.2 5000.2 18937.4 19399.1 19417.8 19861.9 19887.4 2030.5 2044.9 2297.1 2314.0 2410.4 7250.8 12313.6 12775.3 13128.1 13253.5 1641.6 1661.1 1661.4 1707.1 1772.6 63780.0 64211.6 64640.7 64654.0 65938.7 8144.8 8801.7 8991.3 9137.2 9236.4 12256.1 12352.6 12352.7 12353.1 12376.2 6871.9 7008.3 7090.6 7100.1 7183.4 6772.6 6773.9 6854.9 6860.8 6885.1 5743.2 5810.8 5944.2 5951.3 5964.4 1522.6 1609.9 1615.9 1663.0 1701.5 658.6 892.1 932.5 974.0 985.2 15926.4 16344.3 16730.5 16908.0 17126.7 859.5 874.9 891.1 924.9 941.0 9261.2 9706.0 9780.9 9824.8 9949.0 389985.0 395406.0 395569.4 395971.0 399661.0 68341.7 68709.0 70442.6 70666.6 71101.3 847.6 901.8 933.2 952.7 963.3 11351.6 14520.5 14530.9 15119.4 15817.9 26907.0 31530.0 33220.2 35346.0 36446.8 42405.6 45477.3 47956.3 48182.8 48306.0 6883.8 7023.2 7057.8 7154.2 7163.8 9588.6 9640.5 10394.1 10644.0 10705.0 9035.2 9105.7 9137.3 9164.0 9574.0 8814.0 8997.5 9422.4 9620.9 9627.3 8756.6 9018.5 9025.9 9389.8 9434.2 6789.2 6810.3 6842.3 7015.6 7045.9 7312.0 9306.2 9486.0 9729.5 9758.3 22:18:31 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | [#3] mootools.html Cycles:180 Average:4950.37 Median:4878.12 stddev:2775.63 (56.9%) stddev-sans-first:2769.46 22:18:31 INFO - PROCESS | 6225 | Values: 1246.2 1406.5 1465.2 1549.6 1759.2 6331.1 7209.8 7481.3 7490.5 7707.9 7390.4 7554.2 7559.9 7572.2 7588.0 2117.3 2144.2 2146.2 2146.3 2148.0 2077.8 2099.3 2148.5 2153.8 2161.5 2108.5 2130.0 2131.8 2137.8 2148.9 2083.9 2087.9 2093.5 2093.6 2093.9 2040.9 2119.8 2121.0 2122.1 2124.4 7416.7 7515.1 7598.8 7610.7 7688.3 4498.7 4604.4 4609.4 4618.4 4619.3 2729.4 2808.7 2851.6 2856.9 2866.2 845.7 891.5 898.1 909.5 912.6 10261.3 10337.9 10354.8 10366.7 10369.6 3927.1 4002.2 4061.4 4075.8 4084.1 8107.4 8147.3 8148.0 8189.8 8196.6 4423.8 4428.8 4438.8 4439.6 4441.0 3522.1 4030.4 4092.5 4173.8 4226.0 3258.2 3307.4 3317.7 3336.0 3357.6 1027.8 1032.4 1035.4 1037.4 1046.5 732.7 804.0 816.2 833.0 847.5 4767.6 4988.6 5012.8 5066.4 5083.3 381.5 383.9 387.4 392.7 396.2 3366.6 3490.6 3517.6 3520.2 3610.2 9026.0 9312.3 9354.5 9362.5 9370.8 9052.0 9182.2 9200.6 9235.6 9279.6 6806.7 7108.7 7135.9 7196.1 7222.5 5489.1 5502.7 5522.8 5584.2 5653.3 8572.6 8716.6 8721.8 8800.1 8801.1 7915.9 8156.3 8166.5 8390.1 8831.2 5881.9 6250.5 6564.3 6700.9 6706.7 7273.1 7520.7 7534.8 7546.0 7568.8 6567.9 6635.7 6698.4 6728.4 6795.9 6662.3 6678.2 6681.2 6749.3 6795.6 6108.8 6137.3 6150.2 6150.9 6157.8 4293.3 4317.4 4469.4 4476.3 4501.3 4316.6 4447.2 4540.5 4545.2 4598.6 22:18:31 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | [#4] prototype.html Cycles:180 Average:4172.80 Median:4795.72 stddev:1249.15 (26.0%) stddev-sans-first:1217.85 22:18:31 INFO - PROCESS | 6225 | Values: 271.6 274.7 275.5 280.5 285.3 4743.9 4806.3 4808.6 4820.0 4852.4 4770.4 4774.5 4778.9 4782.6 4785.2 3539.8 3644.4 3672.9 3750.2 3776.6 3708.4 3748.9 3754.9 3757.7 3757.9 3534.0 3692.0 3696.6 3699.4 3709.9 3516.5 3625.2 3655.6 3661.7 3663.2 3207.8 3222.7 3225.2 3225.8 3248.7 4607.3 4622.6 4628.3 4632.5 4667.3 3968.2 4135.3 4176.7 4182.9 4190.8 4982.1 5007.7 5009.4 5021.3 5023.5 3468.2 3469.3 3483.3 3484.9 3492.2 5109.5 5231.5 5239.7 5243.5 5295.7 3500.9 3526.8 3526.9 3527.7 3545.8 3567.2 3568.6 3571.7 3575.6 3580.2 4143.8 4143.9 4152.6 4164.7 4172.1 4908.7 4975.2 5035.2 5086.5 5095.8 1226.6 1241.0 1242.9 1244.0 1254.8 757.9 771.5 773.0 785.4 788.9 4402.8 4483.7 4525.7 4539.9 4551.2 5213.9 5221.7 5223.8 5225.4 5238.8 4487.8 4505.5 4528.2 4533.6 4548.9 2620.9 2788.5 2792.1 2798.6 2806.9 4927.6 4929.2 4955.0 4982.8 5001.2 5184.0 5277.4 5334.6 5347.2 5372.2 4906.0 4966.6 4992.1 4993.9 5004.8 5237.8 5245.5 5250.9 5252.6 5259.3 4875.9 5141.8 5159.3 5163.0 5174.9 4986.6 4989.4 4998.4 5005.4 5014.2 3580.2 3604.4 3608.6 3613.2 3616.6 5131.3 5221.9 5283.5 5304.9 5305.8 5066.2 5076.3 5082.3 5087.6 5094.0 5059.0 5059.7 5065.7 5070.9 5090.9 4865.7 4940.8 4995.0 5000.7 5014.4 4875.4 5003.3 5135.3 5137.2 5140.9 5021.3 5030.8 5037.4 5038.4 5040.9 22:18:31 INFO - PROCESS | 6225 | 22:18:31 INFO - PROCESS | 6225 | [#5] yui.html Cycles:180 Average:12095.10 Median:571.04 stddev:14267.46 (2498.5%) stddev-sans-first:14281.54 22:18:31 INFO - PROCESS | 6225 | Values: 638.9 664.9 687.9 739.4 780.3 10700.6 11608.9 11652.7 11654.9 11671.4 11124.6 11149.4 11161.1 11171.6 11177.0 595.5 739.3 764.4 779.4 780.0 768.6 786.0 786.0 786.7 787.8 828.3 835.7 836.1 836.2 837.0 630.8 787.0 816.3 822.2 825.0 601.8 602.0 602.1 602.2 602.2 10942.8 11001.7 11028.3 11039.2 11043.2 11452.1 11747.2 11818.2 12128.2 12364.0 10388.3 10474.2 10523.5 10614.6 10761.2 3943.2 3989.1 4015.4 4124.1 4168.7 44766.8 44789.0 45004.0 46563.8 47088.0 9182.3 9242.7 9375.5 9415.6 9423.9 16396.7 16979.2 17059.7 17063.1 17108.1 6049.9 6095.8 6121.9 6205.9 6251.8 3631.5 3685.3 3692.9 3703.1 3706.1 2623.6 2705.6 2709.6 2709.6 2713.3 501.3 507.5 509.7 516.5 522.8 480.6 573.4 584.7 589.8 597.1 7141.6 7155.4 7276.3 7382.4 7394.5 541.5 548.7 554.3 558.6 568.7 3333.5 3382.1 3408.5 3411.1 3493.6 38694.6 38905.8 38937.0 38966.4 39025.0 48798.8 48962.3 50104.2 50996.2 51142.0 45845.0 46312.0 46733.8 46944.0 47524.3 6204.9 6268.3 6282.2 6381.2 6464.7 29617.7 31022.0 31529.0 31940.2 32164.8 37365.3 39494.2 40190.6 40263.0 40757.4 9119.5 9302.0 9315.6 9330.0 9394.8 9390.7 9634.5 9698.0 9883.3 9993.2 8773.3 8790.2 8796.6 8836.6 9195.3 8662.9 8762.9 9021.1 9127.9 9229.0 8310.2 8334.5 8414.4 8636.4 8652.4 7921.0 7948.2 8001.0 8143.8 8298.8 7765.2 7954.4 7955.9 8111.1 8189.2 22:18:31 INFO - PROCESS | 6225 | -------- Summary: end -------- 22:18:31 INFO - PROCESS | 6225 | 22:18:32 INFO - TEST-INFO | 6225: exit 0 22:18:32 INFO - TEST-OK | dromaeo_css | took 1118980ms 22:18:32 INFO - TEST-START | kraken 22:18:32 INFO - operating with platform_type : linux_ 22:18:32 INFO - Initialising browser for kraken test... 22:18:32 INFO - TEST-INFO | started process 6405 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplaMagA/profile http://localhost:53317/getInfo.html) 22:18:36 INFO - PROCESS | 6405 | __metrics Screen width/height:1600/1200 22:18:36 INFO - PROCESS | 6405 | colorDepth:24 22:18:36 INFO - PROCESS | 6405 | Browser inner width/height: 1024/697 22:18:36 INFO - PROCESS | 6405 | __metrics 22:18:37 ERROR - PROCESS | 6405 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:18:38 INFO - TEST-INFO | 6405: exit 0 22:18:38 INFO - Browser initialized. 22:18:38 INFO - Running cycle 1/1 for kraken test... 22:18:38 INFO - TEST-INFO | started process 6487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplaMagA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:18:50 INFO - PROCESS | 6487 | RSS: Main: 156942336 22:18:50 INFO - PROCESS | 6487 | 22:18:52 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53317/tests/kraken/driver.html?testName=audio-beat-detection) 22:18:53 INFO - PROCESS | 6487 | RSS: Main: 214163456 22:18:53 INFO - PROCESS | 6487 | 22:18:56 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53317/tests/kraken/driver.html?testName=audio-dft) 22:18:57 INFO - PROCESS | 6487 | RSS: Main: 201826304 22:18:57 INFO - PROCESS | 6487 | 22:19:00 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53317/tests/kraken/driver.html?testName=audio-fft) 22:19:01 INFO - PROCESS | 6487 | RSS: Main: 269701120 22:19:01 INFO - PROCESS | 6487 | 22:19:03 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53317/tests/kraken/driver.html?testName=audio-oscillator) 22:19:03 INFO - PROCESS | 6487 | RSS: Main: 180932608 22:19:03 INFO - PROCESS | 6487 | 22:19:06 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53317/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:19:06 INFO - PROCESS | 6487 | RSS: Main: 184451072 22:19:06 INFO - PROCESS | 6487 | 22:19:09 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53317/tests/kraken/driver.html?testName=imaging-darkroom) 22:19:10 INFO - PROCESS | 6487 | RSS: Main: 220721152 22:19:10 INFO - PROCESS | 6487 | 22:19:13 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53317/tests/kraken/driver.html?testName=imaging-desaturate) 22:19:14 INFO - PROCESS | 6487 | RSS: Main: 220844032 22:19:14 INFO - PROCESS | 6487 | 22:19:17 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53317/tests/kraken/driver.html?testName=json-parse-financial) 22:19:17 INFO - PROCESS | 6487 | RSS: Main: 221421568 22:19:17 INFO - PROCESS | 6487 | 22:19:19 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53317/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:19:20 INFO - PROCESS | 6487 | RSS: Main: 204845056 22:19:20 INFO - PROCESS | 6487 | 22:19:22 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53317/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:19:22 INFO - PROCESS | 6487 | RSS: Main: 210903040 22:19:22 INFO - PROCESS | 6487 | 22:19:25 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53317/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:19:25 INFO - PROCESS | 6487 | RSS: Main: 218206208 22:19:25 INFO - PROCESS | 6487 | 22:19:28 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53317/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:19:29 INFO - PROCESS | 6487 | RSS: Main: 222515200 22:19:29 INFO - PROCESS | 6487 | 22:19:31 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53317/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:19:32 INFO - PROCESS | 6487 | RSS: Main: 221622272 22:19:32 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | Cycle 1(1): loaded http://localhost:53317/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53317/tests/kraken/driver.html?testName=ai-astar) 22:19:34 INFO - PROCESS | 6487 | RSS: Main: 263974912 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | __start_tp_report 22:19:34 INFO - PROCESS | 6487 | _x_x_mozilla_page_load 22:19:34 INFO - PROCESS | 6487 | _x_x_mozilla_page_load_details 22:19:34 INFO - PROCESS | 6487 | |i|pagename|runs| 22:19:34 INFO - PROCESS | 6487 | |0;ai-astar;113;109;112;114;113;114;114;112;114;114 22:19:34 INFO - PROCESS | 6487 | |1;audio-beat-detection;162;129;131;193;134;135;163;129;185;130 22:19:34 INFO - PROCESS | 6487 | |2;audio-dft;162;160;161;161;161;161;162;161;162;161 22:19:34 INFO - PROCESS | 6487 | |3;audio-fft;76;74;76;120;79;76;75;77;99;75 22:19:34 INFO - PROCESS | 6487 | |4;audio-oscillator;85;85;112;84;84;86;122;84;85;124 22:19:34 INFO - PROCESS | 6487 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;116;117 22:19:34 INFO - PROCESS | 6487 | |6;imaging-darkroom;146;145;145;146;145;146;146;146;145;145 22:19:34 INFO - PROCESS | 6487 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;95 22:19:34 INFO - PROCESS | 6487 | |8;json-parse-financial;63;64;64;64;64;64;65;62;64;64 22:19:34 INFO - PROCESS | 6487 | |9;json-stringify-tinderbox;65;65;65;66;65;65;65;65;66;65 22:19:34 INFO - PROCESS | 6487 | |10;stanford-crypto-aes;84;82;89;83;83;85;86;84;116;84 22:19:34 INFO - PROCESS | 6487 | |11;stanford-crypto-ccm;133;129;136;136;135;135;127;129;134;132 22:19:34 INFO - PROCESS | 6487 | |12;stanford-crypto-pbkdf2;179;173;173;172;168;170;168;169;168;168 22:19:34 INFO - PROCESS | 6487 | |13;stanford-crypto-sha256-iterative;66;66;66;64;66;65;66;65;69;68 22:19:34 INFO - PROCESS | 6487 | __end_tp_report 22:19:34 INFO - PROCESS | 6487 | __start_cc_report 22:19:34 INFO - PROCESS | 6487 | _x_x_mozilla_cycle_collect,1868 22:19:34 INFO - PROCESS | 6487 | __end_cc_report 22:19:34 INFO - PROCESS | 6487 | __startTimestamp1464671974559__endTimestamp 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | ------- Summary: start ------- 22:19:34 INFO - PROCESS | 6487 | Number of tests: 14 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:1.60 (1.4%) stddev-sans-first:1.69 22:19:34 INFO - PROCESS | 6487 | Values: 113.0 109.0 112.0 114.0 113.0 114.0 114.0 112.0 114.0 114.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#1] audio-beat-detection Cycles:10 Average:149.10 Median:148.50 stddev:24.73 (16.7%) stddev-sans-first:25.78 22:19:34 INFO - PROCESS | 6487 | Values: 162.0 129.0 131.0 193.0 134.0 135.0 163.0 129.0 185.0 130.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 22:19:34 INFO - PROCESS | 6487 | Values: 162.0 160.0 161.0 161.0 161.0 161.0 162.0 161.0 162.0 161.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.00 stddev:15.03 (19.8%) stddev-sans-first:15.74 22:19:34 INFO - PROCESS | 6487 | Values: 76.0 74.0 76.0 120.0 79.0 76.0 75.0 77.0 99.0 75.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:84.50 stddev:17.01 (20.1%) stddev-sans-first:17.64 22:19:34 INFO - PROCESS | 6487 | Values: 85.0 85.0 112.0 84.0 84.0 86.0 122.0 84.0 85.0 124.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:19:34 INFO - PROCESS | 6487 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 22:19:34 INFO - PROCESS | 6487 | Values: 146.0 145.0 145.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 22:19:34 INFO - PROCESS | 6487 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 22:19:34 INFO - PROCESS | 6487 | Values: 63.0 64.0 64.0 64.0 64.0 64.0 65.0 62.0 64.0 64.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 22:19:34 INFO - PROCESS | 6487 | Values: 65.0 65.0 65.0 66.0 65.0 65.0 65.0 65.0 66.0 65.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:84.00 stddev:10.17 (12.1%) stddev-sans-first:10.70 22:19:34 INFO - PROCESS | 6487 | Values: 84.0 82.0 89.0 83.0 83.0 85.0 86.0 84.0 116.0 84.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:134.50 stddev:3.24 (2.4%) stddev-sans-first:3.43 22:19:34 INFO - PROCESS | 6487 | Values: 133.0 129.0 136.0 136.0 135.0 135.0 127.0 129.0 134.0 132.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:171.00 stddev:3.55 (2.1%) stddev-sans-first:2.20 22:19:34 INFO - PROCESS | 6487 | Values: 179.0 173.0 173.0 172.0 168.0 170.0 168.0 169.0 168.0 168.0 22:19:34 INFO - PROCESS | 6487 | 22:19:34 INFO - PROCESS | 6487 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:1.45 (2.2%) stddev-sans-first:1.54 22:19:34 INFO - PROCESS | 6487 | Values: 66.0 66.0 66.0 64.0 66.0 65.0 66.0 65.0 69.0 68.0 22:19:34 INFO - PROCESS | 6487 | -------- Summary: end -------- 22:19:34 INFO - PROCESS | 6487 | 22:19:35 INFO - TEST-INFO | 6487: exit 0 22:19:35 INFO - TEST-OK | kraken | took 63262ms 22:19:35 INFO - SUITE-END | took 1182s 22:19:35 INFO - Completed test suite (00:19:42) 22:19:35 INFO - javascript benchmark 22:19:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2688.1397952655147, 2694.609281654159, 2714.8327317567537, 2739.80821342506, 2766.00423847415, 4577.496475387705, 4720.504347043562, 4732.5977291930185, 4809.0, 4873.074115917978, 4660.464046634638, 4664.2770370592525, 4673.042026384592, 4732.408448943874, 4734.526547345273, 4243.893902652428, 4322.222000039991, 4350.325699516564, 4376.540463251361, 4559.475660299058, 4473.239549276631, 4489.685721999462, 4506.36910832482, 4506.707064040843, 4552.954470455303, 4216.072464057902, 4279.293916503773, 4358.0, 4398.7580683062415, 4433.423654924839, 4338.110687309094, 4339.7396156230725, 4344.283193273106, 4465.37484752135, 4474.7762611869275, 4388.363687265361, 4395.406620106277, 4440.17856696509, 4441.378207051015, 4450.799714012877, 4480.708753930984, 4606.262997920317, 4652.837150699743, 4692.272697731857, 4720.33915251865, 5139.460356662571, 5184.429712731597, 5191.948080519184, 5193.091209038404, 5205.791768329263, 5146.742662866842, 5187.144121219994, 5205.557527610158, 5214.191800270964, 5216.739163041832, 4627.1439783640135, 4719.0, 4775.474697783241, 4788.281757736368, 4804.975975120172, 5303.416624171338, 5306.575473962073, 5323.1216849219445, 5334.75993580282, 5334.759935802898, 5052.5452709255605, 5090.10923088458, 5091.134507133796, 5096.388433387943, 5098.59211263098, 4877.122118018719, 5089.312942752681, 5171.974140129275, 5175.094358487249, 5194.532492075731, 5111.3355263815465, 5138.434772175057, 5141.691498510027, 5143.8456846294675, 5198.818041368534, 4812.35033270504, 5107.565856902131, 5119.283300337956, 5120.078385890501, 5127.41034781005, 4151.813168407369, 4270.0605866709275, 4320.265554855682, 4321.178975994551, 4375.7374557526655, 3759.2105657812167, 3773.716971227169, 3797.1456422305314, 3828.559715632682, 3832.885013449601, 4497.2129877271345, 4504.25679762842, 4510.255807791743, 4510.503844577094, 4560.293154561048, 4974.253861920741, 5031.22016087507, 5073.289739436482, 5082.466341034138, 5098.796048158107, 4380.627646650007, 4385.166818304512, 4412.382266482695, 4414.514403415622, 4439.62263207631, 4401.625861801783, 4506.662000349987, 4508.368828364031, 4510.075434535912, 4548.886277843014, 4797.328374027708, 4867.41591009067, 4874.90250194987, 4884.096442158212, 4926.359573255454, 5142.485751424828, 5181.663191892515, 5183.740812959261, 5185.066687996197, 5235.397929238034, 4704.929426058543, 4821.46963833985, 4907.55831975124, 4925.9014819704125, 4943.77753001123, 5023.799048038038, 5040.445550989315, 5070.366204224472, 5072.213806859942, 5118.513741194581, 5130.5639020682165, 5134.743262836769, 5139.331886854835, 5146.614003949644, 5189.584833213409, 4746.027064451848, 4945.8021679132435, 4984.127777638972, 4995.850124496271, 4997.0, 4996.075725990703, 5013.824516141917, 5023.6985780853265, 5024.145895197752, 5051.419086805065, 4941.6293777966075, 4944.653874228769, 4966.304717339648, 4976.32819569368, 4981.053599816023, 4787.664863459524, 4834.395700537433, 5017.096922553976, 5050.292958985819, 5110.182370820651, 4732.905341893212, 4856.68431551948, 4908.190148625579, 4914.5822605078965, 4954.405471343318], "value": 4698.46418485933, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.0448141752097, 576.8298351986165, 590.506926716192, 592.8428966323925, 605.7365046204905, 28197.718022819765, 28862.134135975928, 40915.181696366126, 42352.78823605881, 43329.35005974905, 39299.21401571974, 39870.0, 39928.00179995502, 43281.35077973833, 43342.13315733684, 7751.418643601724, 7862.410319226081, 7915.999999999971, 7927.643256053462, 7935.285824275814, 7449.590272535035, 10087.798244035115, 10338.53476593555, 10340.379577225352, 10359.223058270622, 9993.450360230221, 10127.544260508295, 10129.037741414553, 10417.635382761604, 10424.530896109693, 10124.3925364478, 10235.897641023606, 10238.129758970497, 10242.948785256027, 10246.436445995467, 9988.700338989842, 10034.0, 10039.247056470795, 10044.447555384451, 10050.497475126214, 40201.0, 40650.0, 40678.0, 40707.0, 40747.0, 2994.4759667058397, 3005.023367405602, 3035.620547431571, 3252.7560432967384, 3252.7723059385617, 30288.8485557573, 30609.846950765328, 30789.38421231563, 30986.38027239465, 31271.218219544466, 3279.1310302769784, 3324.335132973415, 3364.9327013459592, 3375.476801095834, 3379.4423920053164, 266069.0, 267270.0, 267950.0, 267999.0, 268603.0, 60249.397506025256, 61022.0, 61210.387896121356, 61248.0, 61289.38710612926, 63765.681171593846, 63883.36116638774, 64111.35888641147, 64150.0, 64245.678771605846, 15720.92139539295, 16266.75599866003, 16291.42979995718, 16293.103879286517, 16300.592485187964, 10230.0, 11643.825342619868, 11748.29510229389, 12314.938425307995, 12614.117011809085, 19421.92386605865, 19468.076114141357, 19549.0, 19631.0, 19684.70472942907, 7344.742933997284, 7368.484206105629, 7385.261473852572, 7484.812879677943, 7504.0, 817.3420396580809, 896.7489103051156, 916.7937214126902, 957.875476188091, 987.4272921705393, 28010.43979120447, 28862.567061494094, 29140.83436662552, 29398.706012940023, 29441.55837662437, 925.3892430995511, 943.2972435535432, 1008.5965613754411, 1045.341434896026, 1048.1876545677162, 14808.925955370154, 15288.769654967015, 15340.309686064154, 15386.769198462032, 15469.613259668598, 369352.0, 370242.0, 370609.0, 371195.0, 371629.0, 50844.23733643998, 51240.743796281524, 51609.483905160465, 51624.0, 51696.741516292175, 1096.2381145104075, 1184.9348285844362, 1212.9090318226167, 1227.656256248252, 1236.4250623459955, 26772.464550708486, 30147.397052058397, 30223.244418889703, 30685.386292274477, 30833.075007749805, 43196.352054719835, 43221.0, 43625.34561981636, 43727.12545749004, 43747.78126109349, 295469.0, 295553.0, 295696.0, 296037.0, 296449.0, 32840.34319313648, 38390.808045959595, 39345.21309573735, 39810.800945995084, 39894.2021159581, 43182.352264715424, 43437.0, 43554.78222608867, 43782.56217437785, 44428.0, 37378.626213737516, 37497.437538437494, 37500.062498436666, 37941.81029094837, 38061.2387752249, 37410.62589374071, 37533.0, 37661.0, 37765.24469510649, 37887.0, 36421.0, 36478.0, 36939.81530092332, 37034.0, 37120.0, 29956.550651740676, 31983.680163199002, 32095.518567221043, 32871.17822054468, 32965.34069318648, 24531.0, 24773.62839557444, 24911.87544062268, 25244.788180759177, 25396.111136110147], "value": 18724.999488971647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.4183056126829, 350.59156083163117, 351.2605964444842, 364.2950890027795, 374.38787582302945, 15491.612709682264, 16117.597060073489, 16306.266218020188, 16680.41618543351, 16805.41181058662, 15332.080075195496, 15405.614859628487, 15691.529254122366, 15791.052536847797, 15836.049837009787, 4681.438227412698, 4781.856544303677, 4915.729634870081, 4916.0, 4917.3607431033915, 3580.7135429165733, 3759.0, 4851.199552073921, 5350.946490535103, 5643.830685079454, 5572.554195664357, 5585.776568937237, 5601.271834661488, 5602.971985140069, 5609.943900561003, 5508.0, 5567.4432556744405, 5599.32808063033, 5602.439756024406, 5603.60774745768, 3207.1180425382972, 4669.276262179367, 4974.950250497485, 4989.17678583037, 5000.249962505617, 18937.43187704371, 19399.127039283267, 19417.80582194174, 19861.9006904966, 19887.403377898685, 2030.4517780199265, 2044.8875311857842, 2297.1156104899733, 2313.97686023141, 2410.3733029412306, 7250.818729531751, 12313.630591082281, 12775.29735864527, 13128.146670466389, 13253.536126235633, 1641.564985278902, 1661.1113054515897, 1661.3603762551384, 1707.085492740433, 1772.59230377014, 63780.0, 64211.60790886067, 64640.67679661571, 64654.0, 65938.67030664864, 8144.75565733029, 8801.691940782042, 8991.325650576233, 9137.223336016514, 9236.445813251226, 12256.14207005501, 12352.567660131852, 12352.691182720324, 12353.135280530456, 12376.195547289397, 6871.862562748717, 7008.334208250208, 7090.574565525982, 7100.076989991268, 7183.353498185188, 6772.55978361405, 6773.9322606774285, 6854.897176542356, 6860.794176174722, 6885.070515480346, 5743.190894868103, 5810.796622118206, 5944.227250457413, 5951.285845698458, 5964.403559644001, 1522.5736793697783, 1609.855113039832, 1615.854573088404, 1662.9750553741703, 1701.4725435115154, 658.5982550644103, 892.054422312351, 932.4684929590069, 974.0, 985.2364417576435, 15926.442574509836, 16344.264508097163, 16730.49808505747, 16908.0, 17126.743098853527, 859.4800145911759, 874.925631321332, 891.0732837848694, 924.8890133184061, 940.9978373032699, 9261.166495015346, 9706.0, 9780.853287200698, 9824.803503929881, 9948.955359687274, 389985.0, 395406.0, 395569.3544516432, 395971.0, 399661.0, 68341.65829170922, 68709.0, 70442.64778676073, 70666.64666676633, 71101.28898710947, 847.6143354773563, 901.7970956534728, 933.2207606648388, 952.6522819170953, 963.2967933408651, 11351.602693905674, 14520.491782787552, 14530.85469145295, 15119.395224190908, 15817.920910395374, 26907.0, 31530.0, 33220.16949576183, 35346.0, 36446.817765911, 42405.57594424016, 45477.317840231764, 47956.28065579089, 48182.75908620434, 48306.0, 6883.7590684325805, 7023.22744498115, 7057.788266352018, 7154.213036566078, 7163.820904477429, 9588.616455341988, 9640.457526795679, 10394.064534191959, 10644.042036216777, 10705.0, 9035.186833185046, 9105.726828195166, 9137.269018478639, 9163.954180229057, 9573.994730553191, 8813.955930220307, 8997.460152390877, 9422.387544809566, 9620.903790962, 9627.277954153327, 8756.649734010569, 9018.503982281038, 9025.864612030693, 9389.765255868659, 9434.150926416656, 6789.219239787488, 6810.28492008332, 6842.299299528797, 7015.579065256101, 7045.859082818418, 7312.0, 9306.162445379949, 9485.952570237105, 9729.513524323615, 9758.268129890144], "value": 7898.069274623232, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1246.2460211572004, 1406.4936622815787, 1465.2087872548825, 1549.5583758628786, 1759.1556053094514, 6331.113644089825, 7209.783706488807, 7481.289277518634, 7490.4756667033325, 7707.92292077079, 7390.445716571266, 7554.206808285134, 7559.924400755991, 7572.242775722425, 7587.962060189691, 2117.322456813816, 2144.185209620342, 2146.227358151072, 2146.334636262756, 2147.9892600536978, 2077.83377329814, 2099.254764558583, 2148.5488047510044, 2153.76308606054, 2161.5136594266323, 2108.4834215617198, 2129.957400851982, 2131.840111991599, 2137.8289736821093, 2148.924787632433, 2083.874967501955, 2087.903850478511, 2093.4870956615573, 2093.602215579043, 2093.9371818845457, 2040.9183632654824, 2119.809217170462, 2120.978790212109, 2122.07689654999, 2124.4370241885913, 7416.703331866719, 7515.060617422822, 7598.8480230395635, 7610.695572177106, 7688.269614386681, 4498.707584007029, 4604.401427814396, 4609.354690343354, 4618.399608050965, 4619.28401097827, 2729.440464704731, 2808.6629604447394, 2851.615031970678, 2856.857257097157, 2866.240446281737, 845.7081309650854, 891.5052146058935, 898.0839543665425, 909.4770506958525, 912.575652321667, 10261.281710280284, 10337.948310258476, 10354.792904141876, 10366.688999330032, 10369.63706270277, 3927.116398810302, 4002.199560087994, 4061.3501839705505, 4075.8369665213654, 4084.101497670508, 8107.351411887035, 8147.307478864244, 8148.0, 8189.754307370788, 8196.631151598192, 4423.778811059434, 4428.822847086145, 4438.755868427203, 4439.600435960779, 4440.955590444054, 3522.084258092863, 4030.375291829771, 4092.4679791627495, 4173.770442625624, 4225.9577404226175, 3258.2405501029652, 3307.3550657622013, 3317.6682331767115, 3336.0, 3357.5635167428077, 1027.753339198602, 1032.3805716569998, 1035.4305132177271, 1037.3620223562455, 1046.4506134279563, 732.6922692469176, 803.9758807235793, 816.163432481711, 832.983340333202, 847.4999750147442, 4767.642426818003, 4988.6009119270375, 5012.7994880205115, 5066.36670416198, 5083.313752643419, 381.5440548544518, 383.8656470235395, 387.3975967370765, 392.7152814209732, 396.2293339454733, 3366.646502117291, 3490.5636795400574, 3517.630648781841, 3520.207953210507, 3610.1696609779897, 9025.954870225607, 9312.301577381724, 9354.53227338644, 9362.531873406302, 9370.812583748288, 9052.0, 9182.219511341476, 9200.631974721071, 9235.584398702074, 9279.582418791171, 6806.693698783567, 7108.68010939509, 7135.89296160558, 7196.136463624399, 7222.458315626349, 5489.066858633962, 5502.669839809624, 5522.751476183501, 5584.218209450762, 5653.265075540154, 8572.571371431279, 8716.607752651273, 8721.825563488568, 8800.075992020746, 8801.119888011404, 7915.920840791749, 8156.306713929383, 8166.510009399455, 8390.07709152005, 8831.249343805848, 5881.911771323519, 6250.531210159347, 6564.277929427854, 6700.899486507608, 6706.6646667665445, 7273.0908636420445, 7520.736774212876, 7534.811629709301, 7546.0, 7568.772936811904, 6567.868642627216, 6635.734570617315, 6698.430633396216, 6728.360805723449, 6795.864082718417, 6662.333766623299, 6678.23200331968, 6681.231658359351, 6749.291324410866, 6795.592264464148, 6108.816735497942, 6137.263528376445, 6150.231221097363, 6150.938490615036, 6157.753689852356, 4293.334533147367, 4317.395564620892, 4469.351943968171, 4476.306172543261, 4501.3022981437925, 4316.611504964569, 4447.243968525294, 4540.500544939988, 4545.181867263792, 4598.563136502027], "value": 3916.0042217383952, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.58040826922405, 274.6511929849091, 275.4738449561338, 280.4783103427625, 285.328052436512, 4743.857684269473, 4806.279058141281, 4808.591269742072, 4819.951520603747, 4852.369192005039, 4770.379850619423, 4774.450938142115, 4778.928316075262, 4782.632596864548, 4785.162596545608, 3539.8406971747277, 3644.416893297073, 3672.926541469169, 3750.1937083527037, 3776.622337766229, 3708.388115960877, 3748.887533374003, 3754.906127346811, 3757.7369584129124, 3757.887263382103, 3533.964660353402, 3692.02161427222, 3696.5933747287777, 3699.4450832375223, 3709.8684901105153, 3516.4549494828343, 3625.166211771281, 3655.574326012857, 3661.707063434919, 3663.1757854482794, 3207.83960801959, 3222.7099561039386, 3225.225945773021, 3225.7741958062943, 3248.7238584720326, 4607.258681084749, 4622.560856718606, 4628.305754136873, 4632.513586073481, 4667.299905014241, 3968.1666849961543, 4135.338345864678, 4176.665866730685, 4182.853600123981, 4190.832366705328, 4982.103221420143, 5007.699538027694, 5009.398872135331, 5021.297018417425, 5023.522765337287, 3468.219650578625, 3469.254110366258, 3483.3381657484997, 3484.860605575774, 3492.161881148506, 5109.463506331856, 5231.476852314776, 5239.711815850127, 5243.475652434764, 5295.655782374133, 3500.8774692885627, 3526.7883926964464, 3526.85377252394, 3527.7177825773874, 3545.7695249809194, 3567.2152126532123, 3568.553930758655, 3571.74997750155, 3575.570931488186, 3580.2481478889727, 4143.81352839123, 4143.854965076268, 4152.6055024772595, 4164.729292595972, 4172.144710334374, 4908.699194713404, 4975.203967365276, 5035.194368900959, 5086.491350864958, 5095.770690318945, 1226.6320103968774, 1240.962771116868, 1242.881926217008, 1243.975120497585, 1254.7615952968906, 757.8787394017041, 771.4676872957641, 772.9729459468891, 785.4344871692373, 788.9447738658239, 4402.801873915681, 4483.708558943658, 4525.705829121097, 4539.863804085882, 4551.158035763328, 5213.947860521422, 5221.712805795718, 5223.843284701465, 5225.399079105876, 5238.764255608507, 4487.820487180542, 4505.481869584974, 4528.230200865861, 4533.614642755337, 4548.863534093983, 2620.947581048368, 2788.470190663808, 2792.134438324126, 2798.6361772969385, 2806.943861122766, 4927.5811556017425, 4929.22547882762, 4955.0, 4982.750862456863, 5001.249812528077, 5183.97408012965, 5277.366715994105, 5334.5732341412595, 5347.1979203119845, 5372.247885296062, 4905.950940490692, 4966.577840883493, 4992.10300085965, 4993.901341704819, 5004.799808007639, 5237.8428647140645, 5245.475452454724, 5250.868728281671, 5252.606054545848, 5259.289995850511, 4875.902921842557, 5141.768620412166, 5159.329287192641, 5162.97418512905, 5174.8965020700125, 4986.601071914166, 4989.426215985063, 4998.400511836177, 5005.424376196638, 5014.17266151081, 3580.2481478889727, 3604.3692353838496, 3608.5850127235703, 3613.1509095362044, 3616.5840928293583, 5131.281620573048, 5221.921671175011, 5283.498067683564, 5304.946950530445, 5305.761240744098, 5066.214736715815, 5076.289319495348, 5082.313887625123, 5087.567556757717, 5093.974530127326, 5059.0, 5059.721715305694, 5065.696058236517, 5070.923936141035, 5090.91635495594, 4865.70805751656, 4940.75296235194, 4994.950050499594, 5000.67495612784, 5014.42334131565, 4875.366202393666, 5003.274525193754, 5135.3324067870935, 5137.17805151174, 5140.871478213075, 5021.271915572294, 5030.849074527624, 5037.420696619935, 5038.445770965118, 5040.941402305557], "value": 3763.61898424131, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.9393007664272, 664.926858045615, 687.8521117959638, 739.4158614694388, 780.3250188586874, 10700.571977120915, 11608.94195529021, 11652.708682282926, 11654.883451165497, 11671.358075305865, 11124.61063862765, 11149.442527873594, 11161.107111431087, 11171.553137874496, 11177.0, 595.5414330965176, 739.334598861024, 764.3579393309633, 779.392074182136, 780.0249687890138, 768.6195333310019, 785.9528428294292, 785.968561257549, 786.744308099867, 787.7951732549521, 828.2711214131556, 835.6824406725466, 836.0719601242616, 836.1763662792144, 836.9916300837006, 630.7540059376847, 786.960651967405, 816.3265306122413, 822.1696086952192, 824.9793755156139, 601.7863658401249, 601.9668918209496, 602.1359349333676, 602.16299343912, 602.1810337940398, 10942.781144377068, 11001.669949901516, 11028.338299702044, 11039.172062095295, 11043.171762117874, 11452.141089418326, 11747.236429632047, 11818.17272790905, 12128.151029427943, 12364.0, 10388.324758890723, 10474.21443391741, 10523.473826308731, 10614.628488002883, 10761.192910531663, 3943.1719338938856, 3989.1024519483176, 4015.417764424142, 4124.092699606082, 4168.6665066794585, 44766.776166119285, 44789.0, 45004.0, 46563.767181164214, 47087.99999999966, 9182.265418766616, 9242.66979528119, 9375.484348360906, 9415.62337506492, 9423.858642120374, 16396.672066558604, 16979.15104244783, 17059.65880682404, 17063.06153161588, 17108.05905675175, 6049.939500605025, 6095.847603809941, 6121.908171377433, 6205.906911396332, 6251.812445626639, 3631.5279013728045, 3685.3182161300247, 3692.8522859085874, 3703.092742278105, 3706.0734816295926, 2623.6064590311607, 2705.6212130301774, 2709.593560965871, 2709.593560965871, 2713.33523286796, 501.2931766209627, 507.4750375122733, 509.6687153350352, 516.4706176169457, 522.7778194267421, 480.5962991087503, 573.4265734265651, 584.6638183044766, 589.7699897040158, 597.0924195223237, 7141.571505709674, 7155.427565794724, 7276.272372762787, 7382.409407247407, 7394.482386232912, 541.5207541325876, 548.6598309048416, 554.2517601238377, 558.6201383059558, 568.655963142293, 3333.4999750037687, 3382.0868365541164, 3408.5398471206568, 3411.1301618087227, 3493.615702272748, 38694.6130538691, 38905.80547097247, 38937.0, 38966.415503767465, 39025.0, 48798.756006219744, 48962.26556601654, 50104.24843627348, 50996.23505647418, 51142.0, 45845.0, 46312.0, 46733.766331168124, 46944.0, 47524.28713569299, 6204.875902481835, 6268.341824108403, 6282.183316168869, 6381.234251889803, 6464.741410343534, 29617.703822961495, 31022.0, 31529.0, 31940.20149496281, 32164.839175803972, 37365.252694946495, 39494.2101157981, 40190.59809401986, 40263.0, 40757.388639171026, 9119.498427586546, 9301.953490232505, 9315.580798863932, 9329.953350233205, 9394.765130871783, 9390.67132650354, 9634.518274086407, 9698.0, 9883.30816842814, 9993.200543956611, 8773.298136148966, 8790.164934331227, 8796.560171991503, 8836.646534138563, 9195.264378849542, 8662.870056949025, 8762.868556971524, 9021.142991415805, 9127.908720912706, 9228.95385523068, 8310.21052999964, 8334.541600212047, 8414.368922330972, 8636.352273579381, 8652.351073669626, 7920.960395197987, 7948.244916732901, 8000.959995199987, 8143.75568732939, 8298.83402331938, 7765.22347765219, 7954.443188976716, 7955.9204407957495, 8111.107778144281, 8189.222023907958], "value": 5531.105145698344, "unit": "score"}], "name": "dromaeo_css", "value": 6197.298127734069, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 112.0, 114.0, 113.0, 114.0, 114.0, 112.0, 114.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 129.0, 131.0, 193.0, 134.0, 135.0, 163.0, 129.0, 185.0, 130.0], "value": 149.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 162.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 120.0, 79.0, 76.0, 75.0, 77.0, 99.0, 75.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 112.0, 84.0, 84.0, 86.0, 122.0, 84.0, 85.0, 124.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 65.0, 62.0, 64.0, 64.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 66.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 89.0, 83.0, 83.0, 85.0, 86.0, 84.0, 116.0, 84.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 129.0, 136.0, 136.0, 135.0, 135.0, 127.0, 129.0, 134.0, 132.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 173.0, 173.0, 172.0, 168.0, 170.0, 168.0, 169.0, 168.0, 168.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 64.0, 66.0, 65.0, 66.0, 65.0, 69.0, 68.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1544.2999999999995, "alertThreshold": 2.0}]} 22:19:35 INFO - Return code: 0 22:19:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:19:35 ERROR - # TBPL SUCCESS # 22:19:35 INFO - Running post-action listener: _resource_record_post_action 22:19:35 INFO - ##### 22:19:35 INFO - ##### Finished run-tests step (success) 22:19:35 INFO - ##### 22:19:35 INFO - Running post-run listener: _resource_record_post_run 22:19:35 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 522424320; Read time: 8; Write time: 347944 22:19:35 INFO - TinderboxPrint: CPU usage
13.1% 22:19:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 22:19:35 INFO - TinderboxPrint: I/O write bytes / time
522,424,320 / 347,944 22:19:35 INFO - TinderboxPrint: CPU idle
8,317.4 (86.9%) 22:19:35 INFO - TinderboxPrint: CPU user
1,209.2 (12.6%) 22:19:35 INFO - TinderboxPrint: Swap in / out
0 / 0 22:19:35 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 237113344; Read time: 0; Write time: 194500 22:19:35 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285310976; Read time: 8; Write time: 153444 22:19:36 INFO - Running post-run listener: _upload_blobber_files 22:19:36 INFO - Blob upload gear active. 22:19:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:19:36 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 22:19:36 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:19:36 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:19:36 INFO - (blobuploader) - INFO - Open directory for files ... 22:19:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:19:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:19:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:19:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:19:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:19:40 INFO - (blobuploader) - INFO - Done attempting. 22:19:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:19:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:19:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:19:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:19:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:19:41 INFO - (blobuploader) - INFO - Done attempting. 22:19:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:19:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:19:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:19:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:19:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:19:42 INFO - (blobuploader) - INFO - Done attempting. 22:19:42 INFO - (blobuploader) - INFO - Iteration through files over. 22:19:42 INFO - Return code: 0 22:19:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:19:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:19:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2e780e4cdb98df820b39ea0d44b834df7fb057ece01da731e9b12bdc95b9083f271ea66a249adf14da86b3cd7e543265dc3fed68e801a66efbd5cbac82ba0b7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eb03bb060cf9aed44ed06c0a7ece21e038695cb209f841c7b2e9435e18125dbf38d770b6515dc36a84869d3be2ab9f01a827c253bb69bc2014ad57f6e353b08a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7cc5ab94736ec4214a62943cef02368257d2c130d9b5863dd17fe11c0287125c6211a7b1fb461a7d3f87b2d7077f2ab1697cfde7e9a80655dcb66cdd16b6a8b1"} 22:19:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:19:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:19:42 INFO - Contents: 22:19:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2e780e4cdb98df820b39ea0d44b834df7fb057ece01da731e9b12bdc95b9083f271ea66a249adf14da86b3cd7e543265dc3fed68e801a66efbd5cbac82ba0b7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eb03bb060cf9aed44ed06c0a7ece21e038695cb209f841c7b2e9435e18125dbf38d770b6515dc36a84869d3be2ab9f01a827c253bb69bc2014ad57f6e353b08a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7cc5ab94736ec4214a62943cef02368257d2c130d9b5863dd17fe11c0287125c6211a7b1fb461a7d3f87b2d7077f2ab1697cfde7e9a80655dcb66cdd16b6a8b1"} 22:19:42 INFO - Running post-run listener: copy_logs_to_upload_dir 22:19:42 INFO - Copying logs to upload dir... 22:19:42 INFO - mkdir: /builds/slave/test/build/upload/logs 22:19:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.351569 ========= master_lag: 168.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 39 secs) (at 2016-05-30 22:22:29.543036) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 22:22:29.546137) ========= 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-1464665101.182919-923360563 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-Non-PGO/sha512/2e780e4cdb98df820b39ea0d44b834df7fb057ece01da731e9b12bdc95b9083f271ea66a249adf14da86b3cd7e543265dc3fed68e801a66efbd5cbac82ba0b7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eb03bb060cf9aed44ed06c0a7ece21e038695cb209f841c7b2e9435e18125dbf38d770b6515dc36a84869d3be2ab9f01a827c253bb69bc2014ad57f6e353b08a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7cc5ab94736ec4214a62943cef02368257d2c130d9b5863dd17fe11c0287125c6211a7b1fb461a7d3f87b2d7077f2ab1697cfde7e9a80655dcb66cdd16b6a8b1"} build_url:https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/OfldPwLMSbCVIDG0dn76Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2e780e4cdb98df820b39ea0d44b834df7fb057ece01da731e9b12bdc95b9083f271ea66a249adf14da86b3cd7e543265dc3fed68e801a66efbd5cbac82ba0b7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eb03bb060cf9aed44ed06c0a7ece21e038695cb209f841c7b2e9435e18125dbf38d770b6515dc36a84869d3be2ab9f01a827c253bb69bc2014ad57f6e353b08a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7cc5ab94736ec4214a62943cef02368257d2c130d9b5863dd17fe11c0287125c6211a7b1fb461a7d3f87b2d7077f2ab1697cfde7e9a80655dcb66cdd16b6a8b1"}' ========= master_lag: 0.14 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 22:22:29.693933) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 mins, 4 secs) (at 2016-05-30 22:22:29.694258) ========= 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-1464665101.182919-923360563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 244.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 mins, 4 secs) (at 2016-05-30 22:26:33.813873) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 22:26:33.814254) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 22:26:33.921966) ========= ========= Total master_lag: 412.40 =========