builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1463375957.64 results: success (0) buildid: 20160515204333 builduid: d37ac373503e492d851f410c6d1a2cff revision: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.644233) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.645146) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.645781) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.672832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.673119) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-15 22:19:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-05-15 22:19:17 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098682 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.814140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.814479) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014729 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.870848) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:17.871204) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 --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 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 --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=c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-15 22:19:17,955 truncating revision to first 12 chars 2016-05-15 22:19:17,955 Setting DEBUG logging. 2016-05-15 22:19:17,955 attempt 1/10 2016-05-15 22:19:17,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/668a9155cc8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-15 22:19:18,208 unpacking tar archive at: fx-team-668a9155cc8b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.525110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:18.414284) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:18.414604) ========= script_repo_revision: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:18.415007) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:18.415310) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-15 22:19:18.436114) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-15 22:19:18.436430) ========= /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=c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:19:18 INFO - MultiFileLogger online at 20160515 22:19:18 in /builds/slave/test 22:19:18 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 22:19:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:19:18 INFO - {'append_to_log': False, 22:19:18 INFO - 'base_work_dir': '/builds/slave/test', 22:19:18 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 22:19:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:19:18 INFO - 'branch': 'Fx-Team-Non-PGO', 22:19:18 INFO - 'buildbot_json_path': 'buildprops.json', 22:19:18 INFO - 'config_files': ('talos/linux_config.py',), 22:19:18 INFO - 'default_actions': ('clobber', 22:19:18 INFO - 'read-buildbot-config', 22:19:18 INFO - 'download-and-extract', 22:19:18 INFO - 'populate-webroot', 22:19:18 INFO - 'create-virtualenv', 22:19:18 INFO - 'install', 22:19:18 INFO - 'run-tests'), 22:19:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:19:18 INFO - 'download_minidump_stackwalk': True, 22:19:18 INFO - 'download_symbols': 'ondemand', 22:19:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:19:18 INFO - 'tooltool.py': '/tools/tooltool.py', 22:19:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:19:18 INFO - '/tools/misc-python/virtualenv.py')}, 22:19:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:19:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:19:18 INFO - 'installer_path': 'installer.exe', 22:19:18 INFO - 'log_level': 'info', 22:19:18 INFO - 'log_name': 'talos', 22:19:18 INFO - 'log_to_console': True, 22:19:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:19:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:19:18 INFO - 'opt_config_files': (), 22:19:18 INFO - 'pip_index': False, 22:19:18 INFO - 'sps_profile': False, 22:19:18 INFO - 'sps_profile_interval': 0, 22:19:18 INFO - 'suite': 'dromaeojs', 22:19:18 INFO - 'system_bits': '32', 22:19:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:19:18 INFO - 'title': 'talos-linux64-ix-004', 22:19:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:19:18 INFO - 'use_talos_json': True, 22:19:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:19:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:19:18 INFO - 'work_dir': 'build'} 22:19:18 INFO - ##### 22:19:18 INFO - ##### Running clobber step. 22:19:18 INFO - ##### 22:19:18 INFO - Running pre-action listener: _resource_record_pre_action 22:19:18 INFO - Running main action method: clobber 22:19:18 INFO - rmtree: /builds/slave/test/build 22:19:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:19:19 INFO - Running post-action listener: _resource_record_post_action 22:19:19 INFO - ##### 22:19:19 INFO - ##### Finished clobber step (success) 22:19:19 INFO - ##### 22:19:19 INFO - ##### 22:19:19 INFO - ##### Running read-buildbot-config step. 22:19:19 INFO - ##### 22:19:19 INFO - Running pre-action listener: _resource_record_pre_action 22:19:19 INFO - Running main action method: read_buildbot_config 22:19:19 INFO - Using buildbot properties: 22:19:19 INFO - { 22:19:19 INFO - "project": "", 22:19:19 INFO - "product": "firefox", 22:19:19 INFO - "script_repo_revision": "production", 22:19:19 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:19:19 INFO - "repository": "", 22:19:19 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 22:19:19 INFO - "buildid": "20160515204333", 22:19:19 INFO - "pgo_build": "False", 22:19:19 INFO - "basedir": "/builds/slave/test", 22:19:19 INFO - "buildnumber": 1687, 22:19:19 INFO - "slavename": "talos-linux64-ix-004", 22:19:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:19:19 INFO - "platform": "ubuntu64_hw", 22:19:19 INFO - "scheduler": "tests-fx-team-linux64-talos", 22:19:19 INFO - "branch": "fx-team", 22:19:19 INFO - "repo_path": "integration/fx-team", 22:19:19 INFO - "revision": "668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1", 22:19:19 INFO - "stage_platform": "linux64", 22:19:19 INFO - "builduid": "d37ac373503e492d851f410c6d1a2cff", 22:19:19 INFO - "slavebuilddir": "test" 22:19:19 INFO - } 22:19:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:19:19 INFO - Running post-action listener: _resource_record_post_action 22:19:19 INFO - ##### 22:19:19 INFO - ##### Finished read-buildbot-config step (success) 22:19:19 INFO - ##### 22:19:19 INFO - ##### 22:19:19 INFO - ##### Running download-and-extract step. 22:19:19 INFO - ##### 22:19:19 INFO - Running pre-action listener: _resource_record_pre_action 22:19:19 INFO - Running main action method: download_and_extract 22:19:19 INFO - mkdir: /builds/slave/test/build/tests 22:19: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')]} 22:19:19 INFO - https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:19:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:19:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:19:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:21 INFO - Downloaded 1361 bytes. 22:19:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:19:21 INFO - Using the following test package requirements: 22:19:21 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:19:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:19:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:19:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:21 INFO - u'jsshell-linux-x86_64.zip'], 22:19:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:19:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:19:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:19:21 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:19:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:19:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:19:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:21 INFO - https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:19:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:19:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:19:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:30 INFO - Downloaded 22448136 bytes. 22:19:30 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'] 22:19:30 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 22:19:31 INFO - Return code: 0 22:19:31 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 22:19:31 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')]} 22:19:31 INFO - https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:19:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:19:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:19:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:40 INFO - Downloaded 22448136 bytes. 22:19:40 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'] 22:19:40 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 22:19:41 INFO - Return code: 0 22:19:41 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')]} 22:19:41 INFO - https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:19:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:19:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:19:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:43 INFO - Downloaded 10840194 bytes. 22:19:43 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'] 22:19:43 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 22:19:43 INFO - Return code: 0 22:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:43 INFO - https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:19:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:19:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:19:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSEV7kfsTFC9isyp2FYYUg/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 22:19:46 INFO - Downloaded 54740302 bytes. 22:19:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:19:46 INFO - mkdir: /builds/slave/test/properties 22:19:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:19:46 INFO - Writing to file /builds/slave/test/properties/build_url 22:19:46 INFO - Contents: 22:19:46 INFO - build_url:https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:19:47 INFO - Running post-action listener: _resource_record_post_action 22:19:47 INFO - Running post-action listener: set_extra_try_arguments 22:19:47 INFO - ##### 22:19:47 INFO - ##### Finished download-and-extract step (success) 22:19:47 INFO - ##### 22:19:47 INFO - ##### 22:19:47 INFO - ##### Running populate-webroot step. 22:19:47 INFO - ##### 22:19:47 INFO - Running pre-action listener: _resource_record_pre_action 22:19:47 INFO - Running main action method: populate_webroot 22:19:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:19:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:19:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:19:47 INFO - '--tppagecycles', 22:19:47 INFO - '7'], 22:19:47 INFO - 'tests': ['tsvgm']}}, 22:19:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:19:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:19:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:19:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:19:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:47 INFO - 'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:19:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:19:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:47 INFO - 'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['damp', 'tps']}, 22:19:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:47 INFO - 'tests': ['damp', 'tps']}, 22:19:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['dromaeo_dom']}, 22:19:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:19:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['a11yr', 22:19:47 INFO - 'ts_paint', 22:19:47 INFO - 'tpaint', 22:19:47 INFO - 'sessionrestore', 22:19:47 INFO - 'sessionrestore_no_auto_restore', 22:19:47 INFO - 'tabpaint']}, 22:19:47 INFO - 'other-e10s': {'tests': ['a11yr', 22:19:47 INFO - 'ts_paint', 22:19:47 INFO - 'tpaint', 22:19:47 INFO - 'sessionrestore', 22:19:47 INFO - 'sessionrestore_no_auto_restore', 22:19:47 INFO - 'tabpaint']}, 22:19:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:19:47 INFO - 'ts_paint', 22:19:47 INFO - 'tpaint', 22:19:47 INFO - 'sessionrestore', 22:19:47 INFO - 'sessionrestore_no_auto_restore', 22:19:47 INFO - 'tabpaint']}, 22:19:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:19:47 INFO - 'ts_paint', 22:19:47 INFO - 'tpaint', 22:19:47 INFO - 'sessionrestore', 22:19:47 INFO - 'sessionrestore_no_auto_restore', 22:19:47 INFO - 'tabpaint']}, 22:19:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['a11yr', 22:19:47 INFO - 'ts_paint', 22:19:47 INFO - 'tpaint', 22:19:47 INFO - 'sessionrestore', 22:19:47 INFO - 'sessionrestore_no_auto_restore', 22:19:47 INFO - 'tabpaint']}, 22:19:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['a11yr', 22:19:47 INFO - 'ts_paint', 22:19:47 INFO - 'tpaint', 22:19:47 INFO - 'sessionrestore', 22:19:47 INFO - 'sessionrestore_no_auto_restore', 22:19:47 INFO - 'tabpaint']}, 22:19:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['tsvgx', 22:19:47 INFO - 'tsvgr_opacity', 22:19:47 INFO - 'tart', 22:19:47 INFO - 'tscrollx', 22:19:47 INFO - 'cart']}, 22:19:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:19:47 INFO - 'tsvgr_opacity', 22:19:47 INFO - 'tart', 22:19:47 INFO - 'tscrollx', 22:19:47 INFO - 'cart']}, 22:19:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:47 INFO - 'talos_options': ['--disable-e10s'], 22:19:47 INFO - 'tests': ['tp5o']}, 22:19:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:47 INFO - 'tests': ['tp5o']}, 22:19:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:47 INFO - 'talos_options': ['--disable-e10s', 22:19:47 INFO - '--xperf_path', 22:19:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:19:47 INFO - 'tests': ['tp5n']}, 22:19:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:47 INFO - 'talos_options': ['--xperf_path', 22:19:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:19:47 INFO - 'tests': ['tp5n']}}, 22:19:47 INFO - 'talos.zip': {'path': '', 22:19:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:19:47 INFO - Running post-action listener: _resource_record_post_action 22:19:47 INFO - ##### 22:19:47 INFO - ##### Finished populate-webroot step (success) 22:19:47 INFO - ##### 22:19:47 INFO - ##### 22:19:47 INFO - ##### Running create-virtualenv step. 22:19:47 INFO - ##### 22:19:47 INFO - Running pre-action listener: _resource_record_pre_action 22:19:47 INFO - Running main action method: create_virtualenv 22:19:47 INFO - Creating virtualenv /builds/slave/test/build/venv 22:19:47 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 22:19:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:19:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:19:47 INFO - Using real prefix '/usr' 22:19:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:19:47 INFO - Installing distribute.............................................................................................................................................................................................done. 22:19:49 INFO - Installing pip.................done. 22:19:49 INFO - Return code: 0 22:19:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:19:49 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')]} 22:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:49 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')]} 22:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:49 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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:19:49 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 22:19:49 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 22:19:49 INFO - Using env: {'DISPLAY': ':0', 22:19:49 INFO - 'HOME': '/home/cltbld', 22:19:49 INFO - 'LANG': 'en_US.UTF-8', 22:19:49 INFO - 'LANGUAGE': 'en_US:en', 22:19:49 INFO - 'LOGNAME': 'cltbld', 22:19:49 INFO - 'MAIL': '/var/mail/cltbld', 22:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:49 INFO - 'MOZ_NO_REMOTE': '1', 22:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:49 INFO - 'NO_EM_RESTART': '1', 22:19:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:49 INFO - 'PWD': '/builds/slave/test', 22:19:49 INFO - 'SHELL': '/bin/bash', 22:19:49 INFO - 'SHLVL': '1', 22:19:49 INFO - 'TERM': 'linux', 22:19:49 INFO - 'TMOUT': '86400', 22:19:49 INFO - 'USER': 'cltbld', 22:19:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:49 INFO - '_': '/tools/buildbot/bin/python'} 22:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:49 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 22:19:49 INFO - Cleaning up... 22:19:49 INFO - Return code: 0 22:19:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:19:49 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')]} 22:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:49 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')]} 22:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:49 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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:19:49 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 22:19:49 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 22:19:49 INFO - Using env: {'DISPLAY': ':0', 22:19:49 INFO - 'HOME': '/home/cltbld', 22:19:49 INFO - 'LANG': 'en_US.UTF-8', 22:19:49 INFO - 'LANGUAGE': 'en_US:en', 22:19:49 INFO - 'LOGNAME': 'cltbld', 22:19:49 INFO - 'MAIL': '/var/mail/cltbld', 22:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:49 INFO - 'MOZ_NO_REMOTE': '1', 22:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:49 INFO - 'NO_EM_RESTART': '1', 22:19:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:49 INFO - 'PWD': '/builds/slave/test', 22:19:49 INFO - 'SHELL': '/bin/bash', 22:19:49 INFO - 'SHLVL': '1', 22:19:49 INFO - 'TERM': 'linux', 22:19:49 INFO - 'TMOUT': '86400', 22:19:49 INFO - 'USER': 'cltbld', 22:19:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:49 INFO - '_': '/tools/buildbot/bin/python'} 22:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:50 INFO - Downloading/unpacking psutil>=3.1.1 22:19:50 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 22:19:50 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 22:19:50 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 22:19:50 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 22:19:50 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 22:19:50 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 22:19:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:19:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:19:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:19:52 INFO - Installing collected packages: psutil 22:19:52 INFO - Running setup.py install for psutil 22:19:52 INFO - building 'psutil._psutil_linux' extension 22:19:52 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 22:19:52 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 22:19:52 INFO - building 'psutil._psutil_posix' extension 22:19:52 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 22:19:52 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 22:19:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:19:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:19:52 INFO - Successfully installed psutil 22:19:52 INFO - Cleaning up... 22:19:52 INFO - Return code: 0 22:19:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:19: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')]} 22:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19: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')]} 22:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:52 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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:19:52 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 22:19:52 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 22:19:52 INFO - Using env: {'DISPLAY': ':0', 22:19:52 INFO - 'HOME': '/home/cltbld', 22:19:52 INFO - 'LANG': 'en_US.UTF-8', 22:19:52 INFO - 'LANGUAGE': 'en_US:en', 22:19:52 INFO - 'LOGNAME': 'cltbld', 22:19:52 INFO - 'MAIL': '/var/mail/cltbld', 22:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:52 INFO - 'MOZ_NO_REMOTE': '1', 22:19:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:52 INFO - 'NO_EM_RESTART': '1', 22:19:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:52 INFO - 'PWD': '/builds/slave/test', 22:19:52 INFO - 'SHELL': '/bin/bash', 22:19:52 INFO - 'SHLVL': '1', 22:19:52 INFO - 'TERM': 'linux', 22:19:52 INFO - 'TMOUT': '86400', 22:19:52 INFO - 'USER': 'cltbld', 22:19:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:52 INFO - '_': '/tools/buildbot/bin/python'} 22:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:19:52 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 22:19:52 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 22:19:52 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 22:19:52 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 22:19:52 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 22:19:52 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 22:19:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:19:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:19:54 INFO - Installing collected packages: mozsystemmonitor 22:19:54 INFO - Running setup.py install for mozsystemmonitor 22:19:54 INFO - Successfully installed mozsystemmonitor 22:19:54 INFO - Cleaning up... 22:19:54 INFO - Return code: 0 22:19:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:19:54 INFO - Using env: {'DISPLAY': ':0', 22:19:54 INFO - 'HOME': '/home/cltbld', 22:19:54 INFO - 'LANG': 'en_US.UTF-8', 22:19:54 INFO - 'LANGUAGE': 'en_US:en', 22:19:54 INFO - 'LOGNAME': 'cltbld', 22:19:54 INFO - 'MAIL': '/var/mail/cltbld', 22:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:54 INFO - 'MOZ_NO_REMOTE': '1', 22:19:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:54 INFO - 'NO_EM_RESTART': '1', 22:19:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:54 INFO - 'PWD': '/builds/slave/test', 22:19:54 INFO - 'SHELL': '/bin/bash', 22:19:54 INFO - 'SHLVL': '1', 22:19:54 INFO - 'TERM': 'linux', 22:19:54 INFO - 'TMOUT': '86400', 22:19:54 INFO - 'USER': 'cltbld', 22:19:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:54 INFO - '_': '/tools/buildbot/bin/python'} 22:19:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:55 INFO - Downloading/unpacking blobuploader==1.2.4 22:19:55 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 22:19:55 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 22:19:55 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 22:19:55 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 22:19:55 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 22:19:55 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 22:19:56 INFO - Downloading blobuploader-1.2.4.tar.gz 22:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:19:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:19:57 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 22:19:57 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 22:19:57 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 22:19:57 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 22:19:57 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 22:19:57 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 22:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:19:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:19:57 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 22:19:57 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 22:19:57 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 22:19:57 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 22:19:57 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 22:19:57 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 22:19:57 INFO - Downloading docopt-0.6.1.tar.gz 22:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:19:57 INFO - Installing collected packages: blobuploader, requests, docopt 22:19:57 INFO - Running setup.py install for blobuploader 22:19:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:19:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:19:57 INFO - Running setup.py install for requests 22:19:58 INFO - Running setup.py install for docopt 22:19:58 INFO - Successfully installed blobuploader requests docopt 22:19:58 INFO - Cleaning up... 22:19:58 INFO - Return code: 0 22:19:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:19:58 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')]} 22:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:58 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')]} 22:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:58 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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:19:58 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 22:19:58 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 22:19:58 INFO - Using env: {'DISPLAY': ':0', 22:19:58 INFO - 'HOME': '/home/cltbld', 22:19:58 INFO - 'LANG': 'en_US.UTF-8', 22:19:58 INFO - 'LANGUAGE': 'en_US:en', 22:19:58 INFO - 'LOGNAME': 'cltbld', 22:19:58 INFO - 'MAIL': '/var/mail/cltbld', 22:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:58 INFO - 'MOZ_NO_REMOTE': '1', 22:19:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:58 INFO - 'NO_EM_RESTART': '1', 22:19:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:58 INFO - 'PWD': '/builds/slave/test', 22:19:58 INFO - 'SHELL': '/bin/bash', 22:19:58 INFO - 'SHLVL': '1', 22:19:58 INFO - 'TERM': 'linux', 22:19:58 INFO - 'TMOUT': '86400', 22:19:58 INFO - 'USER': 'cltbld', 22:19:58 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:58 INFO - '_': '/tools/buildbot/bin/python'} 22:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:19:58 INFO - Running setup.py (path:/tmp/pip-5VK6em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:19:58 INFO - Running setup.py (path:/tmp/pip-IXxCjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:19:58 INFO - Running setup.py (path:/tmp/pip-mal9K8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:19:58 INFO - Running setup.py (path:/tmp/pip-CpRHnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:19:58 INFO - Running setup.py (path:/tmp/pip-uszti2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:19:59 INFO - Running setup.py (path:/tmp/pip-mUriZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:19:59 INFO - Running setup.py (path:/tmp/pip-dkL_wN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:19:59 INFO - Running setup.py (path:/tmp/pip-TLwGNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:19:59 INFO - Running setup.py (path:/tmp/pip-2iss4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:19:59 INFO - Running setup.py (path:/tmp/pip-xOWMGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:19:59 INFO - Running setup.py (path:/tmp/pip-m6EXGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:19:59 INFO - Running setup.py (path:/tmp/pip-2Oo5XV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:19:59 INFO - Running setup.py (path:/tmp/pip-KM9ikk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:19:59 INFO - Running setup.py (path:/tmp/pip-f_LRTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:19:59 INFO - Running setup.py (path:/tmp/pip-q_NQun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:19:59 INFO - Running setup.py (path:/tmp/pip-pCA9_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:19:59 INFO - Running setup.py (path:/tmp/pip-ejQOtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:19:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:19:59 INFO - Running setup.py install for manifestparser 22:19:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:19:59 INFO - Running setup.py install for mozcrash 22:20:00 INFO - Running setup.py install for mozdebug 22:20:00 INFO - Running setup.py install for mozdevice 22:20:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Running setup.py install for mozfile 22:20:00 INFO - Running setup.py install for mozhttpd 22:20:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Running setup.py install for mozinfo 22:20:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Running setup.py install for mozInstall 22:20:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Running setup.py install for mozleak 22:20:00 INFO - Running setup.py install for mozlog 22:20:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Running setup.py install for moznetwork 22:20:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:20:00 INFO - Running setup.py install for mozprocess 22:20:01 INFO - Running setup.py install for mozprofile 22:20:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:20:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:20:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:20:01 INFO - Running setup.py install for mozrunner 22:20:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:20:01 INFO - Running setup.py install for mozscreenshot 22:20:01 INFO - Running setup.py install for moztest 22:20:01 INFO - Running setup.py install for mozversion 22:20:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:20:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:20:01 INFO - Cleaning up... 22:20:01 INFO - Return code: 0 22:20:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:01 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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:20:01 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 22:20:01 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 22:20:01 INFO - Using env: {'DISPLAY': ':0', 22:20:01 INFO - 'HOME': '/home/cltbld', 22:20:01 INFO - 'LANG': 'en_US.UTF-8', 22:20:01 INFO - 'LANGUAGE': 'en_US:en', 22:20:01 INFO - 'LOGNAME': 'cltbld', 22:20:01 INFO - 'MAIL': '/var/mail/cltbld', 22:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:01 INFO - 'MOZ_NO_REMOTE': '1', 22:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:01 INFO - 'NO_EM_RESTART': '1', 22:20:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:01 INFO - 'PWD': '/builds/slave/test', 22:20:01 INFO - 'SHELL': '/bin/bash', 22:20:01 INFO - 'SHLVL': '1', 22:20:01 INFO - 'TERM': 'linux', 22:20:01 INFO - 'TMOUT': '86400', 22:20:01 INFO - 'USER': 'cltbld', 22:20:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:01 INFO - '_': '/tools/buildbot/bin/python'} 22:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:20:01 INFO - Running setup.py (path:/tmp/pip-c1LP6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:20:01 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)) 22:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:20:01 INFO - Running setup.py (path:/tmp/pip-t7DBqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:20:02 INFO - Running setup.py (path:/tmp/pip-EIDfKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:20:02 INFO - Running setup.py (path:/tmp/pip-SIal78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:20:02 INFO - Running setup.py (path:/tmp/pip-BPpGSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:20:02 INFO - Running setup.py (path:/tmp/pip-9D1iYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:20:02 INFO - Running setup.py (path:/tmp/pip-WNEcaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:20:02 INFO - Running setup.py (path:/tmp/pip-YLM0pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:20:02 INFO - Running setup.py (path:/tmp/pip-TPNC5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:20:02 INFO - Running setup.py (path:/tmp/pip-NIwEzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:20:02 INFO - Running setup.py (path:/tmp/pip-ap01Nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:20:02 INFO - Running setup.py (path:/tmp/pip-fVC_Sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:20:02 INFO - Running setup.py (path:/tmp/pip-pE7ujd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:20:02 INFO - Running setup.py (path:/tmp/pip-u45HWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:02 INFO - Running setup.py (path:/tmp/pip-tcQJBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:02 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)) 22:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:20:02 INFO - Running setup.py (path:/tmp/pip-NN0z_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:20:03 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)) 22:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:20:03 INFO - Running setup.py (path:/tmp/pip-0YA2_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:20:03 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)) 22:20:03 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)) 22:20:03 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)) 22:20:03 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)) 22:20:03 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)) 22:20:03 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)) 22:20:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:20:03 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 22:20:03 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 22:20:03 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 22:20:03 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 22:20:03 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 22:20:03 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 22:20:04 INFO - Downloading blessings-1.6.tar.gz 22:20:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:20:05 INFO - Installing collected packages: blessings 22:20:05 INFO - Running setup.py install for blessings 22:20:05 INFO - Successfully installed blessings 22:20:05 INFO - Cleaning up... 22:20:05 INFO - Return code: 0 22:20:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:20:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:05 INFO - Reading from file tmpfile_stdout 22:20:05 INFO - Current package versions: 22:20:05 INFO - argparse == 1.2.1 22:20:05 INFO - blessings == 1.6 22:20:05 INFO - blobuploader == 1.2.4 22:20:05 INFO - docopt == 0.6.1 22:20:05 INFO - manifestparser == 1.1 22:20:05 INFO - mozInstall == 1.12 22:20:05 INFO - mozcrash == 0.17 22:20:05 INFO - mozdebug == 0.1 22:20:05 INFO - mozdevice == 0.48 22:20:05 INFO - mozfile == 1.2 22:20:05 INFO - mozhttpd == 0.7 22:20:05 INFO - mozinfo == 0.9 22:20:05 INFO - mozleak == 0.1 22:20:05 INFO - mozlog == 3.2 22:20:05 INFO - moznetwork == 0.27 22:20:05 INFO - mozprocess == 0.22 22:20:05 INFO - mozprofile == 0.28 22:20:05 INFO - mozrunner == 6.11 22:20:05 INFO - mozscreenshot == 0.1 22:20:05 INFO - mozsystemmonitor == 0.3 22:20:05 INFO - moztest == 0.7 22:20:05 INFO - mozversion == 1.4 22:20:05 INFO - psutil == 3.1.1 22:20:05 INFO - requests == 1.2.3 22:20:05 INFO - wsgiref == 0.1.2 22:20:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:05 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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:20:05 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 22:20:05 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 22:20:05 INFO - Using env: {'DISPLAY': ':0', 22:20:05 INFO - 'HOME': '/home/cltbld', 22:20:05 INFO - 'LANG': 'en_US.UTF-8', 22:20:05 INFO - 'LANGUAGE': 'en_US:en', 22:20:05 INFO - 'LOGNAME': 'cltbld', 22:20:05 INFO - 'MAIL': '/var/mail/cltbld', 22:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:05 INFO - 'MOZ_NO_REMOTE': '1', 22:20:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:05 INFO - 'NO_EM_RESTART': '1', 22:20:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:05 INFO - 'PWD': '/builds/slave/test', 22:20:05 INFO - 'SHELL': '/bin/bash', 22:20:05 INFO - 'SHLVL': '1', 22:20:05 INFO - 'TERM': 'linux', 22:20:05 INFO - 'TMOUT': '86400', 22:20:05 INFO - 'USER': 'cltbld', 22:20:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:05 INFO - '_': '/tools/buildbot/bin/python'} 22:20:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 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)) 22:20:05 INFO - Cleaning up... 22:20:05 INFO - Return code: 0 22:20:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:05 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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:20:05 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 22:20:05 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 22:20:05 INFO - Using env: {'DISPLAY': ':0', 22:20:05 INFO - 'HOME': '/home/cltbld', 22:20:05 INFO - 'LANG': 'en_US.UTF-8', 22:20:05 INFO - 'LANGUAGE': 'en_US:en', 22:20:05 INFO - 'LOGNAME': 'cltbld', 22:20:05 INFO - 'MAIL': '/var/mail/cltbld', 22:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:05 INFO - 'MOZ_NO_REMOTE': '1', 22:20:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:05 INFO - 'NO_EM_RESTART': '1', 22:20:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:05 INFO - 'PWD': '/builds/slave/test', 22:20:05 INFO - 'SHELL': '/bin/bash', 22:20:05 INFO - 'SHLVL': '1', 22:20:05 INFO - 'TERM': 'linux', 22:20:05 INFO - 'TMOUT': '86400', 22:20:05 INFO - 'USER': 'cltbld', 22:20:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:05 INFO - '_': '/tools/buildbot/bin/python'} 22:20:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:05 INFO - Downloading/unpacking jsonschema 22:20:05 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 22:20:05 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 22:20:05 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 22:20:05 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 22:20:05 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 22:20:05 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 22:20:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:20:07 INFO - Downloading/unpacking functools32 (from jsonschema) 22:20:07 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 22:20:07 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 22:20:07 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 22:20:07 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 22:20:07 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 22:20:07 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 22:20:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:20:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:20:08 INFO - warning: no files found matching '*.txt' 22:20:08 INFO - no previously-included directories found matching 'build' 22:20:08 INFO - no previously-included directories found matching 'dist' 22:20:08 INFO - no previously-included directories found matching '.git*' 22:20:08 INFO - Installing collected packages: jsonschema, functools32 22:20:08 INFO - Running setup.py install for functools32 22:20:08 INFO - warning: no files found matching '*.txt' 22:20:08 INFO - no previously-included directories found matching 'build' 22:20:08 INFO - no previously-included directories found matching 'dist' 22:20:08 INFO - no previously-included directories found matching '.git*' 22:20:08 INFO - Successfully installed jsonschema functools32 22:20:08 INFO - Cleaning up... 22:20:08 INFO - Return code: 0 22:20:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:20: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')]} 22:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20: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')]} 22:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:08 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 0x7f0537c52f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', '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 22:20:08 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 22:20:08 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 22:20:08 INFO - Using env: {'DISPLAY': ':0', 22:20:08 INFO - 'HOME': '/home/cltbld', 22:20:08 INFO - 'LANG': 'en_US.UTF-8', 22:20:08 INFO - 'LANGUAGE': 'en_US:en', 22:20:08 INFO - 'LOGNAME': 'cltbld', 22:20:08 INFO - 'MAIL': '/var/mail/cltbld', 22:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:08 INFO - 'MOZ_NO_REMOTE': '1', 22:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:08 INFO - 'NO_EM_RESTART': '1', 22:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:08 INFO - 'PWD': '/builds/slave/test', 22:20:08 INFO - 'SHELL': '/bin/bash', 22:20:08 INFO - 'SHLVL': '1', 22:20:08 INFO - 'TERM': 'linux', 22:20:08 INFO - 'TMOUT': '86400', 22:20:08 INFO - 'USER': 'cltbld', 22:20:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:08 INFO - '_': '/tools/buildbot/bin/python'} 22:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:08 INFO - Downloading/unpacking flake8 22:20:08 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 22:20:08 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 22:20:08 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 22:20:08 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 22:20:08 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 22:20:08 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 22:20:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:20:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:20:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:10 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 22:20:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:20:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:20:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:20:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:20:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:20:11 INFO - no previously-included directories found matching 'docs/_build' 22:20:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:20:11 INFO - Running setup.py install for pep8 22:20:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:20:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:20:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:20:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:20:11 INFO - no previously-included directories found matching 'docs/_build' 22:20:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:20:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:20:11 INFO - Cleaning up... 22:20:11 INFO - Return code: 0 22:20:11 INFO - Running post-action listener: _resource_record_post_action 22:20:11 INFO - Running post-action listener: _start_resource_monitoring 22:20:11 INFO - Starting resource monitoring. 22:20:11 INFO - ##### 22:20:11 INFO - ##### Finished create-virtualenv step (success) 22:20:11 INFO - ##### 22:20:11 INFO - ##### 22:20:11 INFO - ##### Running install step. 22:20:11 INFO - ##### 22:20:11 INFO - Running pre-action listener: _resource_record_pre_action 22:20:11 INFO - Running main action method: install 22:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:11 INFO - Reading from file tmpfile_stdout 22:20:11 INFO - Detecting whether we're running mozinstall >=1.0... 22:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:20:11 INFO - Reading from file tmpfile_stdout 22:20:11 INFO - Output received: 22:20:11 INFO - Usage: mozinstall [options] installer 22:20:11 INFO - Options: 22:20:11 INFO - -h, --help show this help message and exit 22:20:11 INFO - -d DEST, --destination=DEST 22:20:11 INFO - Directory to install application into. [default: 22:20:11 INFO - "/builds/slave/test"] 22:20:11 INFO - --app=APP Application being installed. [default: firefox] 22:20:11 INFO - mkdir: /builds/slave/test/build/application 22:20:11 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'] 22:20:11 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 22:20:27 INFO - Reading from file tmpfile_stdout 22:20:27 INFO - Output received: 22:20:27 INFO - /builds/slave/test/build/application/firefox/firefox 22:20:27 INFO - Running post-action listener: _resource_record_post_action 22:20:27 INFO - ##### 22:20:27 INFO - ##### Finished install step (success) 22:20:27 INFO - ##### 22:20:27 INFO - ##### 22:20:27 INFO - ##### Running run-tests step. 22:20:27 INFO - ##### 22:20:27 INFO - Running pre-action listener: _resource_record_pre_action 22:20:27 INFO - Running main action method: run_tests 22:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:20:27 INFO - Python 2.7.3 22:20:27 INFO - Return code: 0 22:20:27 INFO - grabbing minidump binary from tooltool 22:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:27 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 0xfca9c0>, '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 0xfcb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcbd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:20:27 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 22:20:27 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 22:20:27 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 22:20:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:20:27 INFO - Return code: 0 22:20:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:20:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:20:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:20:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:20:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:20:28 INFO - Return code: 0 22:20:28 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/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 22:20:28 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/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 22:20:28 INFO - Using env: {'DISPLAY': ':0', 22:20:28 INFO - 'HOME': '/home/cltbld', 22:20:28 INFO - 'LANG': 'en_US.UTF-8', 22:20:28 INFO - 'LANGUAGE': 'en_US:en', 22:20:28 INFO - 'LOGNAME': 'cltbld', 22:20:28 INFO - 'MAIL': '/var/mail/cltbld', 22:20:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:20:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:28 INFO - 'MOZ_NO_REMOTE': '1', 22:20:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:20:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:28 INFO - 'NO_EM_RESTART': '1', 22:20:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:28 INFO - 'PWD': '/builds/slave/test', 22:20:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:20:28 INFO - 'SHELL': '/bin/bash', 22:20:28 INFO - 'SHLVL': '1', 22:20:28 INFO - 'TERM': 'linux', 22:20:28 INFO - 'TMOUT': '86400', 22:20:28 INFO - 'USER': 'cltbld', 22:20:28 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075', 22:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:28 INFO - '_': '/tools/buildbot/bin/python'} 22:20:28 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/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 22:20:28 INFO - mozversion application_buildid: 20160515204333 22:20:28 INFO - mozversion application_changeset: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 22:20:28 INFO - mozversion application_display_name: Nightly 22:20:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:20:28 INFO - mozversion application_name: Firefox 22:20:28 INFO - mozversion application_remotingname: firefox 22:20:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:20:28 INFO - mozversion application_vendor: Mozilla 22:20:28 INFO - mozversion application_version: 49.0a1 22:20:28 INFO - mozversion platform_buildid: 20160515204333 22:20:28 INFO - mozversion platform_changeset: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 22:20:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:20:28 INFO - mozversion platform_version: 49.0a1 22:20:28 INFO - using testdate: 1463376028 22:20:28 INFO - actual date: 1463376028 22:20:28 INFO - starting webserver on 'localhost:58393' 22:20:28 INFO - SUITE-START | Running 2 tests 22:20:28 INFO - TEST-START | dromaeo_css 22:20:28 INFO - operating with platform_type : linux_ 22:20:28 INFO - Initialising browser for dromaeo_css test... 22:20:28 INFO - TEST-INFO | started process 20220 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvNdcq0/profile http://localhost:58393/getInfo.html) 22:20:32 INFO - PROCESS | 20220 | __metrics Screen width/height:1600/1200 22:20:32 INFO - PROCESS | 20220 | colorDepth:24 22:20:32 INFO - PROCESS | 20220 | Browser inner width/height: 1024/697 22:20:32 INFO - PROCESS | 20220 | __metrics 22:20:34 ERROR - PROCESS | 20220 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:20:35 INFO - TEST-INFO | 20220: exit 0 22:20:35 INFO - Browser initialized. 22:20:35 INFO - Running cycle 1/1 for dromaeo_css test... 22:20:35 INFO - TEST-INFO | started process 20300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvNdcq0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:47 INFO - PROCESS | 20300 | RSS: Main: 153952256 22:20:47 INFO - PROCESS | 20300 | 22:21:38 INFO - PROCESS | 20300 | 1463376098879 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:23:50 INFO - PROCESS | 20300 | Cycle 1(1): loaded http://localhost:58393/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58393/tests/dromaeo/cssquery-ext.html) 22:23:51 INFO - PROCESS | 20300 | RSS: Main: 232366080 22:23:51 INFO - PROCESS | 20300 | 22:26:54 INFO - PROCESS | 20300 | Cycle 1(1): loaded http://localhost:58393/tests/dromaeo/cssquery-ext.html (next: http://localhost:58393/tests/dromaeo/cssquery-jquery.html) 22:26:54 INFO - PROCESS | 20300 | RSS: Main: 234553344 22:26:54 INFO - PROCESS | 20300 | 22:29:57 INFO - PROCESS | 20300 | Cycle 1(1): loaded http://localhost:58393/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58393/tests/dromaeo/cssquery-mootools.html) 22:29:57 INFO - PROCESS | 20300 | RSS: Main: 236023808 22:29:57 INFO - PROCESS | 20300 | 22:32:59 INFO - PROCESS | 20300 | Cycle 1(1): loaded http://localhost:58393/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58393/tests/dromaeo/cssquery-prototype.html) 22:33:00 INFO - PROCESS | 20300 | RSS: Main: 238567424 22:33:00 INFO - PROCESS | 20300 | 22:36:03 INFO - PROCESS | 20300 | Cycle 1(1): loaded http://localhost:58393/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58393/tests/dromaeo/cssquery-yui.html) 22:36:03 INFO - PROCESS | 20300 | RSS: Main: 216092672 22:36:03 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | Cycle 1(1): loaded http://localhost:58393/tests/dromaeo/cssquery-yui.html (next: http://localhost:58393/tests/dromaeo/cssquery-dojo.html) 22:39:06 INFO - PROCESS | 20300 | RSS: Main: 268775424 22:39:06 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | __start_tp_report 22:39:06 INFO - PROCESS | 20300 | _x_x_mozilla_page_load 22:39:06 INFO - PROCESS | 20300 | _x_x_mozilla_page_load_details 22:39:06 INFO - PROCESS | 20300 | |i|pagename|runs| 22:39:06 INFO - PROCESS | 20300 | |0;dojo.html;2685.154176434423;2703.094463354776;2728.617993480912;2731.6312297839786;2738.0280000599787;4612.976935115328;4691.929621055679;4718.669693121483;4783.641226907983;4825.445073816508;4727.763611819412;4786.688865223758;4793.976030119845;4818.373611430509;4840.7579621018895;4354.586314300136;4372.344148377753;4441.222786012451;4467.798949047301;4591.6097131743845;4477.910441791162;4513.706609070417;4533.161365147458;4536.138133754593;4566.2009148399065;4298.333758267489;4336.349547567858;4338.631216346583;4385.320275357325;4409.470863496369;4356.390105385249;4360.040791026001;4370.759608221547;4382.912341753147;4413.426254586915;4378.737275763465;4389.604935555815;4399.296112621965;4409.867703968886;4433.955660443419;4539.205639013159;4590.908181836345;4637.907241855144;4654.883627909296;4701.882452938704;5062.417821950449;5093.490650934914;5139.408967968694;5145.485451454824;5150.175971844525;5087.364079490064;5104.821331253388;5105.540501354859;5146.3824341078935;5160.0969830279555;4689.929651055203;4768.9523104769205;4816.614670826326;4824.541668541483;4861.829835955796;5272.182811664198;5292.788288468495;5325;5331.70675612845;5332.493413125747;4872.634552408513;5063.518965698253;5100.897982040413;5112.795488180505;5115.974420127875;5007.474215207423;5040.143175660147;5066.417362003343;5074.16276314404;5074.949250507521;5019.498050194951;5024.32171656829;5029.044481548494;5036.6726162799305;5076.340075790124;4827.879303017453;4997.075541024922;5020.623453241022;5023.171176755867;5047.772850221749;4244.766537840386;4259.084296876124;4259.233337999128;4284.592963668509;4306.461692288464;3751.4185301278344;3760.4359346098304;3779.6787273082095;3802.7718336899875;3815.3895376740143;4318.805653745589;4352.673549483801;4375.190589740908;4444.711093778894;4450.109978004412;5037.268813775489;5048.469910659402;5064.974675126601;5069.493050694901;5121;4374.12517496502;4375.934360984587;4378.146261478981;4411.779411029435;4420.889477763081;4461.576150265784;4541.977290113528;4554.111948170142;4571.862844114682;4603.332516785047;4883.536064074049;4897.632677549127;4899.216125420058;4938.259261110934;4947.554720075066;5166.044281807877;5180;5182.455842136521;5185.844424667266;5231.686098834082;4673.369095172108;4793.904121917472;4831.879203019953;4882.7314497701545;4890.046440943982;5029.9245511318095;5042.193249080129;5058.9494105058475;5061.493850614909;5098.719570423662;5101.413337466239;5111.1311077117725;5111.616628752933;5112.539871411591;5127.102757017582;4835.178019736582;4949;4968.080905032436;4996.850094497171;5011.4236862761245;4982.676126051795;4995.300657907823;5006.07387633289;5008.449070602158;5061.266116413173;4925.926111108408;4943.233798761198;4945.159322915185;4984.626153038609;4991.376077990251;4823.5658790709;4927.704337739747;4956.182229932021;4972.950270497394;4974.154393752999;4847.757612119451;4856.902861942812;4876.809500562849;4883.462819089827;4927.285543596086 22:39:06 INFO - PROCESS | 20300 | |1;ext.html;567.2767221792215;595.2678205806855;595.9523238140948;599.4694695194752;610.3012051201373;42835;44357.334639980385;44370.33444498335;44533;48227.51772482274;43414;44183;44248.3362749559;44258.77870610642;44260.3360949586;7496.175420703718;8085.070216925041;8212.83574328513;10334.844977325347;10574.471276436187;10299.845502317472;10417.114545263661;10421.843672344883;10424.426656533888;10439.947800260989;10472.68581942543;10494.685159445191;10522.947385263064;10528.842067368996;10529.052385285322;7507.136679281871;10246.12907902833;10482.947585262102;10486.318389304672;10820.78358432827;10365.067143957005;10415.79168416635;10416.32293900894;10419;10425.218108641806;41359.7932010341;41690.79154604208;41815.1636967259;41887;41947.37078943818;3374.679405456502;3433.124553238908;3434.8282585870857;3450.7929524228625;3521.5774107107054;31038.224044398845;31203;31324.530132048036;31935;32145.83927080373;3536.9469457957894;3544.0962554548682;3578.3737845877126;3612.2594868052247;3637.1270894985014;275091;276598;277152;277219;277440.61279693677;34483.82758086194;57303.71348143316;57519;57619.42380576224;57685.423145768844;34826.30347393038;36471.81764091216;36514.63485365112;36544.08639784026;36565.81717091451;12611.306378149106;13697.726045479234;13881.236531990635;13891.166530008231;13922.234277114654;11060.17048721349;11347.37589432589;11399.202055856176;11535.711607209887;11556.075513958864;8466.872996905051;8492.957535212408;8536.8719469208;8578.356623253281;8580.828383432296;3284.638689744126;3309.3712194682935;3314.767966242388;3323.6344002159744;3346.263821959165;923.4367036107968;948.3172116076414;959.318883592657;997.1773287038078;1060.2949038889205;29307;29396.265093372836;29557.85221073924;30009.249768755955;30078.84960575183;934.1872570863393;1015.987596775564;1055.9968030371178;1067.1836045425257;1072.2065671403107;15170.848291517164;15688.75518010844;15726.449574265074;15865.841341586667;15877.920610396874;393271;402456;402586;402598;403659;53078.01914856401;53527.73236133872;53618;53660;53727.73136134294;1165.3706998221035;1230.7723071231671;1287.9484820607258;1291.8449786025549;1303.7783576791778;29110.708892911647;30504.542431863985;30638.540421893245;30664.386712266078;31035.68964310328;44437.77781111074;44888.55111448844;44946;45046;45089.77455112703;299117;300120;300537;301399;302774;24096;24603.630945535475;26342.73657263403;27075;27367.178984630493;43907.560924391626;44276.33585496156;44605.10789784121;44982.325265120395;45831;37708.2458350837;38279.42580861345;38663.68017918934;38694.61305387023;39098.609013909496;37503.62119774172;37756.62243377641;38210.80894595509;38521.6147838518;38674.22651546897;36764.63235367612;37630.435543467414;37665.05837354088;37856.810715947344;37875.431868521446;32855;34578.30843383068;34770.478442823885;34791.65208347985;35123.47314790229;25288.367790805376;25740.871295643403;26207;26428.8678556606;26667.466650667266 22:39:06 INFO - PROCESS | 20300 | |2;jquery.html;385.030568641399;391.3640334456508;391.73166381029006;392.06296950288817;415.17173239387427;12884.613461596144;13150.276734779583;16334.428295009677;17863.910680446596;17886.373976910796;16316.265768040437;16524.256408461617;16850.073245971467;17553.29786808526;17558.297668093262;5053.343065401493;5169.069567477853;5171.534561889429;5186.507281808241;5263.3683957925105;5062.924056139161;5089.923651145235;5120.743962801864;5222.63441559091;5239.240310155025;5102.693838369692;5115.283860259567;5134.127198376285;5138.511841375082;5143.742812859361;5046.924296135561;5064.670796398259;5124.666896651705;5125.051865404894;5128.794848206086;4615.469221039556;4633.9536604634195;4641.187792136363;4641.605463535603;4655.231886738684;20289.8985505073;20765.792342076536;21110.472238194016;21630.783692163193;21667.34997950064;2067.1627990663656;2101.6637338025994;2314.2478694424212;2560.206336035816;2585.8448493090473;13175.56477364285;13460.192388456626;13545.932270338586;13871.167729936236;13981.650458738512;1777.8044415114327;1836.7795864496345;1872.6348362069402;1901.7622797150357;1937.2638397408894;40442;55765.72117139429;63849.361506384805;64980.02529962102;66661;9812.215022798158;10086.697399078039;10129.544170512345;10261.179105671528;10363.844542331872;14065.578032659037;14118.929405353112;14214.928925355307;14221.715565688628;14234.715305693828;7461.925380746232;7685.462017658822;7700.075990881131;7782.766517004499;7842;7331.633418329062;7353.926460735431;7409.666565004588;7466.36535894455;7491.8501629967095;6438.7102580384;6475.474564919685;6484.546081774325;6494.253160886464;6536.738530458729;1803.2426380920044;1812.7915289741584;1836.7795864496345;1897.1747289928926;1904.4572296895594;931.3992474853817;971.2036130373167;986.921046316293;993.1508560181086;1034.7464871106627;16716.24776885043;18001.459956201335;18051.277948881896;18472.983985880637;19211.90394048021;883.902770695223;919.8068405634891;923.5197697197419;942.3215284994793;956.0439560439561;9744;10120.089191972758;10220.182385409149;10249.836026474324;10273.486325683685;423766;449171;453835.3849384524;454004;454471;61287.69356153191;86558;87956.56021719979;88377;88515.11484885069;924.778053267211;926.4163576946615;954.5561313989028;957.4255446731905;990.4849478271402;15010.924945375204;15642.843571564139;16107.919460402623;16364.918175409046;16795.748063779298;27654.170374888785;34007.48988765121;34011;35845.28309433849;38456.807715961244;48779.75610121927;49996.50003500064;53511.7324413391;55405.72297138489;55851.16223256736;7882.842343153219;8015.919840801751;8030.357571394391;8240.340772738047;8333.000039994997;9070.002299746891;11500.769984600429;11915.52337906474;12185.147039707135;12408.379581021094;10028.849567256351;10442;10806.459677016275;10849.132069434585;10956.12351011933;9836.409815411098;10478.161747060369;10633.734156646145;10656.094231990368;10786.460676966277;10492.580296788043;10548.736281593021;10806.621768238389;10864.348139111678;11116.221864469733;7751.534907905544;7805.8143719758555;7893.447458677838;7958.403119765925;7968.92031079705;7741.845163096819;10475.109615682752;10663.573457061631;10713.410762408143;11044.944775276072 22:39:06 INFO - PROCESS | 20300 | |3;mootools.html;1270.7585558743833;1492.2538730634678;1502.2113390470001;1688.2909178145178;1877.1083735225761;6873.415759660428;7915.327197188239;8217.055038670554;8223.424360294774;8505.70230041947;8181.3863960202925;8332.125126861683;8334.749957501284;8344.499330040202;8351.20663536963;2250.133698526067;2282.041542552132;2291.255342013844;2291.472961218921;2291.472961218921;2268.7958083772455;2269;2321.059970711856;2321.419645088728;2323.6058364981045;2258.378945789913;2283.4976305212826;2284.977150228493;2300.056976639578;2300.401895507172;2229.754726980047;2241.0587553227683;2242.4618091657885;2244.8540844845033;2247.213475283646;2237.89929453175;2239.6304609739373;2250.4148921280585;2250.673652320421;2269.500709843832;8102.7569172924905;8185.3042491388305;8230.588470576507;8250.5462199579;8258.25675689191;4838.927416088726;4904.754762261872;4964.60283177345;4974.552290293855;4977.50224977503;2945.2489615148;2988.4620768261702;3035.0287907869492;3048.0703385467423;3051.8474076296097;913.634546181526;922.2714055895834;931.7810314576069;932.3054324528202;941.6798288581915;11160.720981975435;11213.159013074051;11251.212415131025;11273.098152147808;11273.492692828842;4205.411242426063;4330.177266319452;4366.214081465367;4394.978025109854;4511.120331535319;8941.59762810675;8992.730218093468;8998.14517620825;9011.28785530747;9012.684556040507;4713.128071306819;4774.928376074362;4775.331453596499;4776.283557466339;4776.713397196179;3772.3398405279513;4243.618074373321;4370.278903980871;4380.474343078851;4404.339349097597;3557.5553055868018;3598.370285200132;3658.6158453362546;3742.8689995850013;3770.849166033383;1098.4507746126937;1105.8562386889653;1125.5104029747085;1132.5583022621183;1141.14414189358;707.5683832861951;758.9810254743676;897.4121950122681;913.5706218077493;919.5861862162054;5345.1447768356875;5489.039418101816;5490.094134467848;5491.945080549223;5530.778768849201;465.2253997094867;469.23046204225096;469.25154378765814;476.94276686797804;623.4171050068201;3596.352656521799;3678.1172518595345;3699.8520059197867;3727.4408838674417;3762.9435558466644;9735.41587504752;10020.799584008279;10072.59709611622;10123.746906327253;10142.44216568081;10023.498825058863;10033.397996120257;10057.094861462503;10077.193824494023;10082.19342452602;7218.097737782672;7521.7743467696055;7621.923780762121;7645.082590089225;7645.350145237717;5586.776528938797;5719.828405147852;5746.942530574641;5797.826065218051;5862.179294898803;9073.001969783187;9283.350165488351;9368.34421590482;9381.624735010522;9473.289503287144;9527.09492598202;9589.856152157863;9593.760155996157;9608;9633.470159141314;6907.93092069093;7012.894806578008;7067.6819543119655;7075.858482830417;7078.823029424306;7340.339369456561;7348.448866335153;7357.742479013209;7423.703051877865;7456.664450099867;6800.693968771516;6823.692933817891;6948.930510694829;6987.336203060701;7085.751998680228;6525.608463492205;6738.393544581011;6858.485613578901;6953.617551034539;6977.476689248225;6400.615963042233;6413.743450261937;6458.483321334376;6463.7737679181;6467.417932386108;4543.4775000875325;4690.788914498788;4740.739259340631;4771.904561908812;4824.927626085681;4698.812047518061;4740.97629511864;4743.45450273223;4765.5710986010035;4827.227643577011 22:39:06 INFO - PROCESS | 20300 | |4;prototype.html;307.7030665407881;321.8262138445239;329.03264402656174;331.600421492102;333.64300198787305;4865.951340486594;4895.16782147035;4907.950920490794;4920.97539512302;4924.409070911491;4616.3767891334655;4626.958934239794;4705.2942058691215;4803.447603525587;4850.345203397549;3835.520559930009;3842.4812650292133;3851.807409629507;3880.2239552089554;3883.1457079442475;3826.8469261229516;3885.805709714517;3892.260470510594;3917.3928041153667;3917.3928041153667;3686.889393318205;3744.2324323513612;3859.903502412434;3866.4973553438012;3894.7468414553105;3799.772013679188;3821.025638462202;3821.3694740367805;3855.1711382052995;3883.592222816606;3429.7770644908;3439.518467414564;3449.2066824630474;3450.6204317525053;3470.8091054991964;4450.888727781799;4696.624270058422;4715.740634265114;4720.551547602972;4720.725404140862;4292.29177185764;4329.718568293051;4345.283028300327;4353.238183317907;4386.539413361583;5110.437851836295;5113.8721531962;5115.2327150927285;5124.590032797368;5132.486751324875;3592.7305452091196;3634.6183650716557;3650.5071815305123;3659.6157403472785;3672.669459748609;5248.475152484759;5262.210668399733;5272.472752724735;5300.655457395257;5307.920381194285;3697.149655579178;3703.277860817169;3721.0883333583447;3725.832337544817;3736.5702944161226;3762.285165818486;3769.6230376962635;3781.3193625147187;3782.451544526028;3792.6156952712063;4088.4276201331836;4313.698041137153;4314.223439780807;4326.502452218036;4330.332821863542;5072.163093089673;5077.416097148801;5078.568321692624;5103.948960510422;5124;1252.4238850129043;1275.2029981261712;1299.9025073119365;1310.711643438442;1320.735852829438;826.5330088500099;831.9143517709447;840.6297734692412;843.9198276163754;845.1717317029346;4039.6970227233073;4059.7970101494807;4061.593840615974;4087.8569250076102;4111.423061936135;4679.642903557999;4689.95310046902;4692.498400511803;4700.7179569225955;4707.670463067621;4010.3382941814857;4029.5366032906004;4085.836566537365;4086.979565102215;4100.91798164041;2403.9879800601234;2469.592517234636;2474.8267621266336;2501.3371456564023;2505.8245922785595;4407.272799987966;4428.911421771611;4445.310781902856;4449.621782148553;4513.661475389359;4760.523947605281;4768;4773.522647735199;4793.952060479421;4809.114531281203;4521.502634710179;4525.932111018337;4659.720416775004;5036.722980236123;5041.168207245837;5287.154055351125;5306.602004849575;5312.07835690361;5312.867178320573;5316.441773613869;5210.739463026909;5224;5234.162533994542;5236.659617124875;5246.186841039645;4789.497102804295;4984.352034235526;5024.748762561931;5029.119904016856;5054.4692807254705;3866.8839934802;3867.303885300673;3884.8057597120596;3905.2970465316516;3923.7645741255615;5075.543201111917;5285.630005899704;5298.496642818926;5301.363836339664;5303.575713942798;4973.278874563241;5070.873228169177;5106.719130447861;5114;5118.232265160255;5069.721165335942;5072.51811077947;5091.210862316347;5091.949080509147;5093.083245015932;4857.927131093107;5062.341895553554;5065.290859279778;5083.46623604531;5091.6181286402925;5118.616103792305;5122.538971492583;5125;5151.922721159111;5167.250748641351;5042.243663450365;5046.520580544842;5067.239914012927;5086.6439349246675;5086.9745651271505 22:39:06 INFO - PROCESS | 20300 | |5;yui.html;675.8817206988776;762.3824701991384;774.0904437286191;782.3193821375404;786.6342150899832;11630;12134.15060945733;12303.384830758449;12306.200096993689;12309.938450307736;12884.613461596144;13021.15362501439;13092.869071309284;13099.214047157177;13163.4076466559;668.6322522612568;853.4452605806244;873.8863947686792;888;893.4237416866117;875.0374587953232;880.2034159086037;880.2297989259398;881.4755220643739;881.951492667903;924.0943875002501;928.2295694573488;928.3640706116298;928.4475736936546;928.5635751196927;697.909271794666;901.5762591581946;917.2295271971543;921.0697195832191;927.6706769096941;689.6655122265697;689.8723736108785;689.917209934811;689.9379055884995;689.9448044156505;13274.933625331909;13383.464661413533;13431.731365372736;13505.527306544322;13522.459101635923;11752.294862308203;12110.636680899586;12254.877451225462;12321.322327271997;12560.748785024249;11210.66368008961;11253.381064041474;11381.943090284578;11396.373199474026;11490.02334801543;4157.958420415787;4189.895252618679;4197.496300443936;4255.91488170238;4262.936055959163;46152.7692361536;46222;46275.76862115735;46290.5370946293;46369.76815115937;10085.142762865238;10141.29010969225;10148.949255253825;10295.176385889112;10441.738956526147;18528.907355463136;18854.905725471286;18871.81128188728;18884.81115188858;19029.524261893286;6804.013418054355;6830.624315662587;6978.546394484342;7105.467089968273;7171.462140339496;3790.2040571479747;3817.5609804872797;3827.5598306195143;3905.7656540607654;3910.1006768443417;2849.6010558521825;2900.9274768130967;2929.8974535891143;2935.618369611937;2936.339323652204;517.6428264497484;530.5304805247384;531.4605675239635;536.5734241278193;546.4781134017004;484.6171524495609;616.4298024327479;622.3745136138152;623.5354660777659;624.3662682377391;8567.571621418903;8835.20483156506;8836.160564746338;9044.728658140266;9163.129502697233;632.0708558419117;639.1020616034479;642.3929635098979;645.5610185074195;645.5642441352069;4011.8194681239415;4033.3143365627916;4083.448734420815;4199.349100889367;4207.095474473003;56440;57104;57657.423425765206;57711.71144144337;57723.42276577181;49444.258336124985;49924.750376248616;51575;51685.48314516882;52999.47000530022;36637.633623663954;45271.32093018608;47385.526144738804;47490;48009;8706;8732.170443807829;8758.21176094129;8827.831330714367;8851.424657397249;31178.532322014733;31571.21071973219;31760.364792703553;32130.196745080626;32667.019989400356;37700.05749856275;39313.606863930996;41040.79479602583;41150.79424602858;41501.58498414977;9720.319577629498;11219.943900280447;11313.09311132438;11383.886161138615;11464.885351146382;10481.842772358574;10821.56713731442;10873.45632718345;10898.291611045257;11041.116710663287;9642.469664168539;9807.460589667637;10027.649032283834;10130.392176469435;10413.791724165323;10374.22193335518;10415.94792026035;10488.160947124365;10510.316829406063;10573.841392378967;9458.574364153492;9518.19095376873;9864.901350986398;9978.850317245393;10429.58281668755;8502.319814414956;8657.22085012352;8784.648614055366;8793;8920.286377089687;8568.100349463217;8678.435901666371;8821.338399620183;8888.777780555538;8900.198982091642 22:39:06 INFO - PROCESS | 20300 | __end_tp_report 22:39:06 INFO - PROCESS | 20300 | __start_cc_report 22:39:06 INFO - PROCESS | 20300 | _x_x_mozilla_cycle_collect,1139 22:39:06 INFO - PROCESS | 20300 | __end_cc_report 22:39:06 INFO - PROCESS | 20300 | __startTimestamp1463377146619__endTimestamp 22:39:06 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | ------- Summary: start ------- 22:39:06 INFO - PROCESS | 20300 | Number of tests: 6 22:39:06 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | [#0] dojo.html Cycles:165 Average:4718.20 Median:4861.83 stddev:489.95 (10.1%) stddev-sans-first:464.77 22:39:06 INFO - PROCESS | 20300 | Values: 2685.2 2703.1 2728.6 2731.6 2738.0 4613.0 4691.9 4718.7 4783.6 4825.4 4727.8 4786.7 4794.0 4818.4 4840.8 4354.6 4372.3 4441.2 4467.8 4591.6 4477.9 4513.7 4533.2 4536.1 4566.2 4298.3 4336.3 4338.6 4385.3 4409.5 4356.4 4360.0 4370.8 4382.9 4413.4 4378.7 4389.6 4399.3 4409.9 4434.0 4539.2 4590.9 4637.9 4654.9 4701.9 5062.4 5093.5 5139.4 5145.5 5150.2 5087.4 5104.8 5105.5 5146.4 5160.1 4689.9 4769.0 4816.6 4824.5 4861.8 5272.2 5292.8 5325.0 5331.7 5332.5 4872.6 5063.5 5100.9 5112.8 5116.0 5007.5 5040.1 5066.4 5074.2 5074.9 5019.5 5024.3 5029.0 5036.7 5076.3 4827.9 4997.1 5020.6 5023.2 5047.8 4244.8 4259.1 4259.2 4284.6 4306.5 3751.4 3760.4 3779.7 3802.8 3815.4 4318.8 4352.7 4375.2 4444.7 4450.1 5037.3 5048.5 5065.0 5069.5 5121.0 4374.1 4375.9 4378.1 4411.8 4420.9 4461.6 4542.0 4554.1 4571.9 4603.3 4883.5 4897.6 4899.2 4938.3 4947.6 5166.0 5180.0 5182.5 5185.8 5231.7 4673.4 4793.9 4831.9 4882.7 4890.0 5029.9 5042.2 5058.9 5061.5 5098.7 5101.4 5111.1 5111.6 5112.5 5127.1 4835.2 4949.0 4968.1 4996.9 5011.4 4982.7 4995.3 5006.1 5008.4 5061.3 4925.9 4943.2 4945.2 4984.6 4991.4 4823.6 4927.7 4956.2 4973.0 4974.2 4847.8 4856.9 4876.8 4883.5 4927.3 22:39:06 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | [#1] ext.html Cycles:180 Average:48528.13 Median:18967.31 stddev:86948.09 (458.4%) stddev-sans-first:87117.44 22:39:06 INFO - PROCESS | 20300 | Values: 567.3 595.3 596.0 599.5 610.3 42835.0 44357.3 44370.3 44533.0 48227.5 43414.0 44183.0 44248.3 44258.8 44260.3 7496.2 8085.1 8212.8 10334.8 10574.5 10299.8 10417.1 10421.8 10424.4 10439.9 10472.7 10494.7 10522.9 10528.8 10529.1 7507.1 10246.1 10482.9 10486.3 10820.8 10365.1 10415.8 10416.3 10419.0 10425.2 41359.8 41690.8 41815.2 41887.0 41947.4 3374.7 3433.1 3434.8 3450.8 3521.6 31038.2 31203.0 31324.5 31935.0 32145.8 3536.9 3544.1 3578.4 3612.3 3637.1 275091.0 276598.0 277152.0 277219.0 277440.6 34483.8 57303.7 57519.0 57619.4 57685.4 34826.3 36471.8 36514.6 36544.1 36565.8 12611.3 13697.7 13881.2 13891.2 13922.2 11060.2 11347.4 11399.2 11535.7 11556.1 8466.9 8493.0 8536.9 8578.4 8580.8 3284.6 3309.4 3314.8 3323.6 3346.3 923.4 948.3 959.3 997.2 1060.3 29307.0 29396.3 29557.9 30009.2 30078.8 934.2 1016.0 1056.0 1067.2 1072.2 15170.8 15688.8 15726.4 15865.8 15877.9 393271.0 402456.0 402586.0 402598.0 403659.0 53078.0 53527.7 53618.0 53660.0 53727.7 1165.4 1230.8 1287.9 1291.8 1303.8 29110.7 30504.5 30638.5 30664.4 31035.7 44437.8 44888.6 44946.0 45046.0 45089.8 299117.0 300120.0 300537.0 301399.0 302774.0 24096.0 24603.6 26342.7 27075.0 27367.2 43907.6 44276.3 44605.1 44982.3 45831.0 37708.2 38279.4 38663.7 38694.6 39098.6 37503.6 37756.6 38210.8 38521.6 38674.2 36764.6 37630.4 37665.1 37856.8 37875.4 32855.0 34578.3 34770.5 34791.7 35123.5 25288.4 25740.9 26207.0 26428.9 26667.5 22:39:06 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | [#2] jquery.html Cycles:180 Average:25996.13 Median:20428.59 stddev:73461.85 (359.6%) stddev-sans-first:73642.76 22:39:06 INFO - PROCESS | 20300 | Values: 385.0 391.4 391.7 392.1 415.2 12884.6 13150.3 16334.4 17863.9 17886.4 16316.3 16524.3 16850.1 17553.3 17558.3 5053.3 5169.1 5171.5 5186.5 5263.4 5062.9 5089.9 5120.7 5222.6 5239.2 5102.7 5115.3 5134.1 5138.5 5143.7 5046.9 5064.7 5124.7 5125.1 5128.8 4615.5 4634.0 4641.2 4641.6 4655.2 20289.9 20765.8 21110.5 21630.8 21667.3 2067.2 2101.7 2314.2 2560.2 2585.8 13175.6 13460.2 13545.9 13871.2 13981.7 1777.8 1836.8 1872.6 1901.8 1937.3 40442.0 55765.7 63849.4 64980.0 66661.0 9812.2 10086.7 10129.5 10261.2 10363.8 14065.6 14118.9 14214.9 14221.7 14234.7 7461.9 7685.5 7700.1 7782.8 7842.0 7331.6 7353.9 7409.7 7466.4 7491.9 6438.7 6475.5 6484.5 6494.3 6536.7 1803.2 1812.8 1836.8 1897.2 1904.5 931.4 971.2 986.9 993.2 1034.7 16716.2 18001.5 18051.3 18473.0 19211.9 883.9 919.8 923.5 942.3 956.0 9744.0 10120.1 10220.2 10249.8 10273.5 423766.0 449171.0 453835.4 454004.0 454471.0 61287.7 86558.0 87956.6 88377.0 88515.1 924.8 926.4 954.6 957.4 990.5 15010.9 15642.8 16107.9 16364.9 16795.7 27654.2 34007.5 34011.0 35845.3 38456.8 48779.8 49996.5 53511.7 55405.7 55851.2 7882.8 8015.9 8030.4 8240.3 8333.0 9070.0 11500.8 11915.5 12185.1 12408.4 10028.8 10442.0 10806.5 10849.1 10956.1 9836.4 10478.2 10633.7 10656.1 10786.5 10492.6 10548.7 10806.6 10864.3 11116.2 7751.5 7805.8 7893.4 7958.4 7968.9 7741.8 10475.1 10663.6 10713.4 11044.9 22:39:06 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | [#3] mootools.html Cycles:180 Average:5318.08 Median:4800.82 stddev:3012.97 (62.8%) stddev-sans-first:3006.06 22:39:06 INFO - PROCESS | 20300 | Values: 1270.8 1492.3 1502.2 1688.3 1877.1 6873.4 7915.3 8217.1 8223.4 8505.7 8181.4 8332.1 8334.7 8344.5 8351.2 2250.1 2282.0 2291.3 2291.5 2291.5 2268.8 2269.0 2321.1 2321.4 2323.6 2258.4 2283.5 2285.0 2300.1 2300.4 2229.8 2241.1 2242.5 2244.9 2247.2 2237.9 2239.6 2250.4 2250.7 2269.5 8102.8 8185.3 8230.6 8250.5 8258.3 4838.9 4904.8 4964.6 4974.6 4977.5 2945.2 2988.5 3035.0 3048.1 3051.8 913.6 922.3 931.8 932.3 941.7 11160.7 11213.2 11251.2 11273.1 11273.5 4205.4 4330.2 4366.2 4395.0 4511.1 8941.6 8992.7 8998.1 9011.3 9012.7 4713.1 4774.9 4775.3 4776.3 4776.7 3772.3 4243.6 4370.3 4380.5 4404.3 3557.6 3598.4 3658.6 3742.9 3770.8 1098.5 1105.9 1125.5 1132.6 1141.1 707.6 759.0 897.4 913.6 919.6 5345.1 5489.0 5490.1 5491.9 5530.8 465.2 469.2 469.3 476.9 623.4 3596.4 3678.1 3699.9 3727.4 3762.9 9735.4 10020.8 10072.6 10123.7 10142.4 10023.5 10033.4 10057.1 10077.2 10082.2 7218.1 7521.8 7621.9 7645.1 7645.4 5586.8 5719.8 5746.9 5797.8 5862.2 9073.0 9283.4 9368.3 9381.6 9473.3 9527.1 9589.9 9593.8 9608.0 9633.5 6907.9 7012.9 7067.7 7075.9 7078.8 7340.3 7348.4 7357.7 7423.7 7456.7 6800.7 6823.7 6948.9 6987.3 7085.8 6525.6 6738.4 6858.5 6953.6 6977.5 6400.6 6413.7 6458.5 6463.8 6467.4 4543.5 4690.8 4740.7 4771.9 4824.9 4698.8 4741.0 4743.5 4765.6 4827.2 22:39:06 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | [#4] prototype.html Cycles:180 Average:4171.11 Median:4669.68 stddev:1210.03 (25.9%) stddev-sans-first:1178.16 22:39:06 INFO - PROCESS | 20300 | Values: 307.7 321.8 329.0 331.6 333.6 4866.0 4895.2 4908.0 4921.0 4924.4 4616.4 4627.0 4705.3 4803.4 4850.3 3835.5 3842.5 3851.8 3880.2 3883.1 3826.8 3885.8 3892.3 3917.4 3917.4 3686.9 3744.2 3859.9 3866.5 3894.7 3799.8 3821.0 3821.4 3855.2 3883.6 3429.8 3439.5 3449.2 3450.6 3470.8 4450.9 4696.6 4715.7 4720.6 4720.7 4292.3 4329.7 4345.3 4353.2 4386.5 5110.4 5113.9 5115.2 5124.6 5132.5 3592.7 3634.6 3650.5 3659.6 3672.7 5248.5 5262.2 5272.5 5300.7 5307.9 3697.1 3703.3 3721.1 3725.8 3736.6 3762.3 3769.6 3781.3 3782.5 3792.6 4088.4 4313.7 4314.2 4326.5 4330.3 5072.2 5077.4 5078.6 5103.9 5124.0 1252.4 1275.2 1299.9 1310.7 1320.7 826.5 831.9 840.6 843.9 845.2 4039.7 4059.8 4061.6 4087.9 4111.4 4679.6 4690.0 4692.5 4700.7 4707.7 4010.3 4029.5 4085.8 4087.0 4100.9 2404.0 2469.6 2474.8 2501.3 2505.8 4407.3 4428.9 4445.3 4449.6 4513.7 4760.5 4768.0 4773.5 4794.0 4809.1 4521.5 4525.9 4659.7 5036.7 5041.2 5287.2 5306.6 5312.1 5312.9 5316.4 5210.7 5224.0 5234.2 5236.7 5246.2 4789.5 4984.4 5024.7 5029.1 5054.5 3866.9 3867.3 3884.8 3905.3 3923.8 5075.5 5285.6 5298.5 5301.4 5303.6 4973.3 5070.9 5106.7 5114.0 5118.2 5069.7 5072.5 5091.2 5091.9 5093.1 4857.9 5062.3 5065.3 5083.5 5091.6 5118.6 5122.5 5125.0 5151.9 5167.3 5042.2 5046.5 5067.2 5086.6 5087.0 22:39:06 INFO - PROCESS | 20300 | 22:39:06 INFO - PROCESS | 20300 | [#5] yui.html Cycles:180 Average:13306.07 Median:47437.76 stddev:15443.92 (32.6%) stddev-sans-first:15458.12 22:39:06 INFO - PROCESS | 20300 | Values: 675.9 762.4 774.1 782.3 786.6 11630.0 12134.2 12303.4 12306.2 12309.9 12884.6 13021.2 13092.9 13099.2 13163.4 668.6 853.4 873.9 888.0 893.4 875.0 880.2 880.2 881.5 882.0 924.1 928.2 928.4 928.4 928.6 697.9 901.6 917.2 921.1 927.7 689.7 689.9 689.9 689.9 689.9 13274.9 13383.5 13431.7 13505.5 13522.5 11752.3 12110.6 12254.9 12321.3 12560.7 11210.7 11253.4 11381.9 11396.4 11490.0 4158.0 4189.9 4197.5 4255.9 4262.9 46152.8 46222.0 46275.8 46290.5 46369.8 10085.1 10141.3 10148.9 10295.2 10441.7 18528.9 18854.9 18871.8 18884.8 19029.5 6804.0 6830.6 6978.5 7105.5 7171.5 3790.2 3817.6 3827.6 3905.8 3910.1 2849.6 2900.9 2929.9 2935.6 2936.3 517.6 530.5 531.5 536.6 546.5 484.6 616.4 622.4 623.5 624.4 8567.6 8835.2 8836.2 9044.7 9163.1 632.1 639.1 642.4 645.6 645.6 4011.8 4033.3 4083.4 4199.3 4207.1 56440.0 57104.0 57657.4 57711.7 57723.4 49444.3 49924.8 51575.0 51685.5 52999.5 36637.6 45271.3 47385.5 47490.0 48009.0 8706.0 8732.2 8758.2 8827.8 8851.4 31178.5 31571.2 31760.4 32130.2 32667.0 37700.1 39313.6 41040.8 41150.8 41501.6 9720.3 11219.9 11313.1 11383.9 11464.9 10481.8 10821.6 10873.5 10898.3 11041.1 9642.5 9807.5 10027.6 10130.4 10413.8 10374.2 10415.9 10488.2 10510.3 10573.8 9458.6 9518.2 9864.9 9978.9 10429.6 8502.3 8657.2 8784.6 8793.0 8920.3 8568.1 8678.4 8821.3 8888.8 8900.2 22:39:06 INFO - PROCESS | 20300 | -------- Summary: end -------- 22:39:06 INFO - PROCESS | 20300 | 22:39:07 INFO - TEST-INFO | 20300: exit 0 22:39:07 INFO - TEST-OK | dromaeo_css | took 1118783ms 22:39:07 INFO - TEST-START | kraken 22:39:07 INFO - operating with platform_type : linux_ 22:39:07 INFO - Initialising browser for kraken test... 22:39:07 INFO - TEST-INFO | started process 20479 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9dLArb/profile http://localhost:58393/getInfo.html) 22:39:11 INFO - PROCESS | 20479 | __metrics Screen width/height:1600/1200 22:39:11 INFO - PROCESS | 20479 | colorDepth:24 22:39:11 INFO - PROCESS | 20479 | Browser inner width/height: 1024/697 22:39:11 INFO - PROCESS | 20479 | __metrics 22:39:12 ERROR - PROCESS | 20479 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:39:13 INFO - TEST-INFO | 20479: exit 0 22:39:13 INFO - Browser initialized. 22:39:13 INFO - Running cycle 1/1 for kraken test... 22:39:13 INFO - TEST-INFO | started process 20561 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9dLArb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:39:25 INFO - PROCESS | 20561 | RSS: Main: 154464256 22:39:25 INFO - PROCESS | 20561 | 22:39:28 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58393/tests/kraken/driver.html?testName=audio-beat-detection) 22:39:29 INFO - PROCESS | 20561 | RSS: Main: 210612224 22:39:29 INFO - PROCESS | 20561 | 22:39:32 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58393/tests/kraken/driver.html?testName=audio-dft) 22:39:32 INFO - PROCESS | 20561 | RSS: Main: 197136384 22:39:32 INFO - PROCESS | 20561 | 22:39:36 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58393/tests/kraken/driver.html?testName=audio-fft) 22:39:36 INFO - PROCESS | 20561 | RSS: Main: 266956800 22:39:36 INFO - PROCESS | 20561 | 22:39:38 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58393/tests/kraken/driver.html?testName=audio-oscillator) 22:39:39 INFO - PROCESS | 20561 | RSS: Main: 176369664 22:39:39 INFO - PROCESS | 20561 | 22:39:41 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58393/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:39:42 INFO - PROCESS | 20561 | RSS: Main: 180867072 22:39:42 INFO - PROCESS | 20561 | 22:39:45 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58393/tests/kraken/driver.html?testName=imaging-darkroom) 22:39:46 INFO - PROCESS | 20561 | RSS: Main: 216489984 22:39:46 INFO - PROCESS | 20561 | 22:39:50 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58393/tests/kraken/driver.html?testName=imaging-desaturate) 22:39:50 INFO - PROCESS | 20561 | RSS: Main: 214904832 22:39:50 INFO - PROCESS | 20561 | 22:39:53 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58393/tests/kraken/driver.html?testName=json-parse-financial) 22:39:54 INFO - PROCESS | 20561 | RSS: Main: 215457792 22:39:54 INFO - PROCESS | 20561 | 22:39:56 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58393/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:39:56 INFO - PROCESS | 20561 | RSS: Main: 209903616 22:39:56 INFO - PROCESS | 20561 | 22:39:58 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58393/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:39:59 INFO - PROCESS | 20561 | RSS: Main: 211652608 22:39:59 INFO - PROCESS | 20561 | 22:40:01 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58393/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:40:02 INFO - PROCESS | 20561 | RSS: Main: 216711168 22:40:02 INFO - PROCESS | 20561 | 22:40:05 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:40:05 INFO - PROCESS | 20561 | RSS: Main: 219803648 22:40:05 INFO - PROCESS | 20561 | 22:40:08 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:40:09 INFO - PROCESS | 20561 | RSS: Main: 219574272 22:40:09 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | Cycle 1(1): loaded http://localhost:58393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58393/tests/kraken/driver.html?testName=ai-astar) 22:40:11 INFO - PROCESS | 20561 | RSS: Main: 266293248 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | __start_tp_report 22:40:11 INFO - PROCESS | 20561 | _x_x_mozilla_page_load 22:40:11 INFO - PROCESS | 20561 | _x_x_mozilla_page_load_details 22:40:11 INFO - PROCESS | 20561 | |i|pagename|runs| 22:40:11 INFO - PROCESS | 20561 | |0;ai-astar;114;107;113;115;112;113;113;112;114;113 22:40:11 INFO - PROCESS | 20561 | |1;audio-beat-detection;164;129;156;222;138;163;150;136;192;129 22:40:11 INFO - PROCESS | 20561 | |2;audio-dft;161;160;160;161;161;164;161;162;161;159 22:40:11 INFO - PROCESS | 20561 | |3;audio-fft;75;78;76;154;76;76;77;75;128;75 22:40:11 INFO - PROCESS | 20561 | |4;audio-oscillator;92;93;146;93;93;97;128;93;93;133 22:40:11 INFO - PROCESS | 20561 | |5;imaging-gaussian-blur;116;117;117;117;118;117;117;117;117;117 22:40:11 INFO - PROCESS | 20561 | |6;imaging-darkroom;146;146;146;147;145;146;147;146;145;146 22:40:11 INFO - PROCESS | 20561 | |7;imaging-desaturate;94;95;96;95;95;95;95;95;95;94 22:40:11 INFO - PROCESS | 20561 | |8;json-parse-financial;65;65;65;63;64;64;65;66;65;66 22:40:11 INFO - PROCESS | 20561 | |9;json-stringify-tinderbox;63;63;63;63;63;63;62;64;64;64 22:40:11 INFO - PROCESS | 20561 | |10;stanford-crypto-aes;82;82;82;83;82;82;83;84;149;89 22:40:11 INFO - PROCESS | 20561 | |11;stanford-crypto-ccm;131;128;136;127;127;125;128;129;155;137 22:40:11 INFO - PROCESS | 20561 | |12;stanford-crypto-pbkdf2;173;170;173;172;170;170;179;172;171;168 22:40:11 INFO - PROCESS | 20561 | |13;stanford-crypto-sha256-iterative;67;66;66;66;72;66;66;64;65;72 22:40:11 INFO - PROCESS | 20561 | __end_tp_report 22:40:11 INFO - PROCESS | 20561 | __start_cc_report 22:40:11 INFO - PROCESS | 20561 | _x_x_mozilla_cycle_collect,2108 22:40:11 INFO - PROCESS | 20561 | __end_cc_report 22:40:11 INFO - PROCESS | 20561 | __startTimestamp1463377211401__endTimestamp 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | ------- Summary: start ------- 22:40:11 INFO - PROCESS | 20561 | Number of tests: 14 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:2.17 (1.9%) stddev-sans-first:2.24 22:40:11 INFO - PROCESS | 20561 | Values: 114.0 107.0 113.0 115.0 112.0 113.0 113.0 112.0 114.0 113.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#1] audio-beat-detection Cycles:10 Average:157.90 Median:159.50 stddev:29.72 (18.6%) stddev-sans-first:31.44 22:40:11 INFO - PROCESS | 20561 | Values: 164.0 129.0 156.0 222.0 138.0 163.0 150.0 136.0 192.0 129.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.33 (0.8%) stddev-sans-first:1.41 22:40:11 INFO - PROCESS | 20561 | Values: 161.0 160.0 160.0 161.0 161.0 164.0 161.0 162.0 161.0 159.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#3] audio-fft Cycles:10 Average:89.00 Median:76.00 stddev:28.10 (37.0%) stddev-sans-first:29.34 22:40:11 INFO - PROCESS | 20561 | Values: 75.0 78.0 76.0 154.0 76.0 76.0 77.0 75.0 128.0 75.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#4] audio-oscillator Cycles:10 Average:106.10 Median:93.00 stddev:20.91 (22.5%) stddev-sans-first:21.55 22:40:11 INFO - PROCESS | 20561 | Values: 92.0 93.0 146.0 93.0 93.0 97.0 128.0 93.0 93.0 133.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 22:40:11 INFO - PROCESS | 20561 | Values: 116.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 22:40:11 INFO - PROCESS | 20561 | Values: 146.0 146.0 146.0 147.0 145.0 146.0 147.0 146.0 145.0 146.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 22:40:11 INFO - PROCESS | 20561 | Values: 94.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:65.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 22:40:11 INFO - PROCESS | 20561 | Values: 65.0 65.0 65.0 63.0 64.0 64.0 65.0 66.0 65.0 66.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 22:40:11 INFO - PROCESS | 20561 | Values: 63.0 63.0 63.0 63.0 63.0 63.0 62.0 64.0 64.0 64.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:82.50 stddev:20.91 (25.3%) stddev-sans-first:21.99 22:40:11 INFO - PROCESS | 20561 | Values: 82.0 82.0 82.0 83.0 82.0 82.0 83.0 84.0 149.0 89.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#11] stanford-crypto-ccm Cycles:10 Average:132.30 Median:130.00 stddev:8.88 (6.8%) stddev-sans-first:9.41 22:40:11 INFO - PROCESS | 20561 | Values: 131.0 128.0 136.0 127.0 127.0 125.0 128.0 129.0 155.0 137.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:172.00 stddev:2.97 (1.7%) stddev-sans-first:3.12 22:40:11 INFO - PROCESS | 20561 | Values: 173.0 170.0 173.0 172.0 170.0 170.0 179.0 172.0 171.0 168.0 22:40:11 INFO - PROCESS | 20561 | 22:40:11 INFO - PROCESS | 20561 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.00 stddev:2.75 (4.2%) stddev-sans-first:2.92 22:40:11 INFO - PROCESS | 20561 | Values: 67.0 66.0 66.0 66.0 72.0 66.0 66.0 64.0 65.0 72.0 22:40:11 INFO - PROCESS | 20561 | -------- Summary: end -------- 22:40:11 INFO - PROCESS | 20561 | 22:40:12 INFO - TEST-INFO | 20561: exit 0 22:40:12 INFO - TEST-OK | kraken | took 65037ms 22:40:12 INFO - SUITE-END | took 1183s 22:40:12 INFO - Completed test suite (00:19:43) 22:40:12 INFO - javascript benchmark 22:40:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2685.154176434423, 2703.094463354776, 2728.617993480912, 2731.6312297839786, 2738.0280000599787, 4612.976935115328, 4691.929621055679, 4718.669693121483, 4783.641226907983, 4825.445073816508, 4727.763611819412, 4786.688865223758, 4793.976030119845, 4818.373611430509, 4840.7579621018895, 4354.586314300136, 4372.344148377753, 4441.222786012451, 4467.798949047301, 4591.6097131743845, 4477.910441791162, 4513.706609070417, 4533.161365147458, 4536.138133754593, 4566.2009148399065, 4298.333758267489, 4336.349547567858, 4338.631216346583, 4385.320275357325, 4409.470863496369, 4356.390105385249, 4360.040791026001, 4370.759608221547, 4382.912341753147, 4413.426254586915, 4378.737275763465, 4389.604935555815, 4399.296112621965, 4409.867703968886, 4433.955660443419, 4539.205639013159, 4590.908181836345, 4637.907241855144, 4654.883627909296, 4701.882452938704, 5062.417821950449, 5093.490650934914, 5139.408967968694, 5145.485451454824, 5150.175971844525, 5087.364079490064, 5104.821331253388, 5105.540501354859, 5146.3824341078935, 5160.0969830279555, 4689.929651055203, 4768.9523104769205, 4816.614670826326, 4824.541668541483, 4861.829835955796, 5272.182811664198, 5292.788288468495, 5325.0, 5331.70675612845, 5332.493413125747, 4872.634552408513, 5063.518965698253, 5100.897982040413, 5112.795488180505, 5115.974420127875, 5007.474215207423, 5040.143175660147, 5066.417362003343, 5074.16276314404, 5074.949250507521, 5019.498050194951, 5024.32171656829, 5029.044481548494, 5036.6726162799305, 5076.340075790124, 4827.879303017453, 4997.075541024922, 5020.623453241022, 5023.171176755867, 5047.772850221749, 4244.766537840386, 4259.084296876124, 4259.233337999128, 4284.592963668509, 4306.461692288464, 3751.4185301278344, 3760.4359346098304, 3779.6787273082095, 3802.7718336899875, 3815.3895376740143, 4318.805653745589, 4352.673549483801, 4375.190589740908, 4444.711093778894, 4450.109978004412, 5037.268813775489, 5048.469910659402, 5064.974675126601, 5069.493050694901, 5121.0, 4374.12517496502, 4375.934360984587, 4378.146261478981, 4411.779411029435, 4420.889477763081, 4461.576150265784, 4541.977290113528, 4554.111948170142, 4571.862844114682, 4603.332516785047, 4883.536064074049, 4897.632677549127, 4899.216125420058, 4938.259261110934, 4947.554720075066, 5166.044281807877, 5180.0, 5182.455842136521, 5185.844424667266, 5231.686098834082, 4673.369095172108, 4793.904121917472, 4831.879203019953, 4882.7314497701545, 4890.046440943982, 5029.9245511318095, 5042.193249080129, 5058.9494105058475, 5061.493850614909, 5098.719570423662, 5101.413337466239, 5111.1311077117725, 5111.616628752933, 5112.539871411591, 5127.102757017582, 4835.178019736582, 4949.0, 4968.080905032436, 4996.850094497171, 5011.4236862761245, 4982.676126051795, 4995.300657907823, 5006.07387633289, 5008.449070602158, 5061.266116413173, 4925.926111108408, 4943.233798761198, 4945.159322915185, 4984.626153038609, 4991.376077990251, 4823.5658790709, 4927.704337739747, 4956.182229932021, 4972.950270497394, 4974.154393752999, 4847.757612119451, 4856.902861942812, 4876.809500562849, 4883.462819089827, 4927.285543596086], "value": 4687.5797518252575, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.2767221792215, 595.2678205806855, 595.9523238140948, 599.4694695194752, 610.3012051201373, 42835.0, 44357.334639980385, 44370.33444498335, 44533.0, 48227.51772482274, 43414.0, 44183.0, 44248.3362749559, 44258.77870610642, 44260.3360949586, 7496.175420703718, 8085.070216925041, 8212.83574328513, 10334.844977325347, 10574.471276436187, 10299.845502317472, 10417.114545263661, 10421.843672344883, 10424.426656533888, 10439.947800260989, 10472.68581942543, 10494.685159445191, 10522.947385263064, 10528.842067368996, 10529.052385285322, 7507.136679281871, 10246.12907902833, 10482.947585262102, 10486.318389304672, 10820.78358432827, 10365.067143957005, 10415.79168416635, 10416.32293900894, 10419.0, 10425.218108641806, 41359.7932010341, 41690.79154604208, 41815.1636967259, 41887.0, 41947.37078943818, 3374.679405456502, 3433.124553238908, 3434.8282585870857, 3450.7929524228625, 3521.5774107107054, 31038.224044398845, 31203.0, 31324.530132048036, 31935.0, 32145.83927080373, 3536.9469457957894, 3544.0962554548682, 3578.3737845877126, 3612.2594868052247, 3637.1270894985014, 275091.0, 276598.0, 277152.0, 277219.0, 277440.61279693677, 34483.82758086194, 57303.71348143316, 57519.0, 57619.42380576224, 57685.423145768844, 34826.30347393038, 36471.81764091216, 36514.63485365112, 36544.08639784026, 36565.81717091451, 12611.306378149106, 13697.726045479234, 13881.236531990635, 13891.166530008231, 13922.234277114654, 11060.17048721349, 11347.37589432589, 11399.202055856176, 11535.711607209887, 11556.075513958864, 8466.872996905051, 8492.957535212408, 8536.8719469208, 8578.356623253281, 8580.828383432296, 3284.638689744126, 3309.3712194682935, 3314.767966242388, 3323.6344002159744, 3346.263821959165, 923.4367036107968, 948.3172116076414, 959.318883592657, 997.1773287038078, 1060.2949038889205, 29307.0, 29396.265093372836, 29557.85221073924, 30009.249768755955, 30078.84960575183, 934.1872570863393, 1015.987596775564, 1055.9968030371178, 1067.1836045425257, 1072.2065671403107, 15170.848291517164, 15688.75518010844, 15726.449574265074, 15865.841341586667, 15877.920610396874, 393271.0, 402456.0, 402586.0, 402598.0, 403659.0, 53078.01914856401, 53527.73236133872, 53618.0, 53660.0, 53727.73136134294, 1165.3706998221035, 1230.7723071231671, 1287.9484820607258, 1291.8449786025549, 1303.7783576791778, 29110.708892911647, 30504.542431863985, 30638.540421893245, 30664.386712266078, 31035.68964310328, 44437.77781111074, 44888.55111448844, 44946.0, 45046.0, 45089.77455112703, 299117.0, 300120.0, 300537.0, 301399.0, 302774.0, 24096.0, 24603.630945535475, 26342.73657263403, 27075.0, 27367.178984630493, 43907.560924391626, 44276.33585496156, 44605.10789784121, 44982.325265120395, 45831.0, 37708.2458350837, 38279.42580861345, 38663.68017918934, 38694.61305387023, 39098.609013909496, 37503.62119774172, 37756.62243377641, 38210.80894595509, 38521.6147838518, 38674.22651546897, 36764.63235367612, 37630.435543467414, 37665.05837354088, 37856.810715947344, 37875.431868521446, 32855.0, 34578.30843383068, 34770.478442823885, 34791.65208347985, 35123.47314790229, 25288.367790805376, 25740.871295643403, 26207.0, 26428.8678556606, 26667.466650667266], "value": 17922.572339017042, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.030568641399, 391.3640334456508, 391.73166381029006, 392.06296950288817, 415.17173239387427, 12884.613461596144, 13150.276734779583, 16334.428295009677, 17863.910680446596, 17886.373976910796, 16316.265768040437, 16524.256408461617, 16850.073245971467, 17553.29786808526, 17558.297668093262, 5053.343065401493, 5169.069567477853, 5171.534561889429, 5186.507281808241, 5263.3683957925105, 5062.924056139161, 5089.923651145235, 5120.743962801864, 5222.63441559091, 5239.240310155025, 5102.693838369692, 5115.283860259567, 5134.127198376285, 5138.511841375082, 5143.742812859361, 5046.924296135561, 5064.670796398259, 5124.666896651705, 5125.051865404894, 5128.794848206086, 4615.469221039556, 4633.9536604634195, 4641.187792136363, 4641.605463535603, 4655.231886738684, 20289.8985505073, 20765.792342076536, 21110.472238194016, 21630.783692163193, 21667.34997950064, 2067.1627990663656, 2101.6637338025994, 2314.2478694424212, 2560.206336035816, 2585.8448493090473, 13175.56477364285, 13460.192388456626, 13545.932270338586, 13871.167729936236, 13981.650458738512, 1777.8044415114327, 1836.7795864496345, 1872.6348362069402, 1901.7622797150357, 1937.2638397408894, 40442.0, 55765.72117139429, 63849.361506384805, 64980.02529962102, 66661.0, 9812.215022798158, 10086.697399078039, 10129.544170512345, 10261.179105671528, 10363.844542331872, 14065.578032659037, 14118.929405353112, 14214.928925355307, 14221.715565688628, 14234.715305693828, 7461.925380746232, 7685.462017658822, 7700.075990881131, 7782.766517004499, 7842.0, 7331.633418329062, 7353.926460735431, 7409.666565004588, 7466.36535894455, 7491.8501629967095, 6438.7102580384, 6475.474564919685, 6484.546081774325, 6494.253160886464, 6536.738530458729, 1803.2426380920044, 1812.7915289741584, 1836.7795864496345, 1897.1747289928926, 1904.4572296895594, 931.3992474853817, 971.2036130373167, 986.921046316293, 993.1508560181086, 1034.7464871106627, 16716.24776885043, 18001.459956201335, 18051.277948881896, 18472.983985880637, 19211.90394048021, 883.902770695223, 919.8068405634891, 923.5197697197419, 942.3215284994793, 956.0439560439561, 9744.0, 10120.089191972758, 10220.182385409149, 10249.836026474324, 10273.486325683685, 423766.0, 449171.0, 453835.3849384524, 454004.0, 454471.0, 61287.69356153191, 86558.0, 87956.56021719979, 88377.0, 88515.11484885069, 924.778053267211, 926.4163576946615, 954.5561313989028, 957.4255446731905, 990.4849478271402, 15010.924945375204, 15642.843571564139, 16107.919460402623, 16364.918175409046, 16795.748063779298, 27654.170374888785, 34007.48988765121, 34011.0, 35845.28309433849, 38456.807715961244, 48779.75610121927, 49996.50003500064, 53511.7324413391, 55405.72297138489, 55851.16223256736, 7882.842343153219, 8015.919840801751, 8030.357571394391, 8240.340772738047, 8333.000039994997, 9070.002299746891, 11500.769984600429, 11915.52337906474, 12185.147039707135, 12408.379581021094, 10028.849567256351, 10442.0, 10806.459677016275, 10849.132069434585, 10956.12351011933, 9836.409815411098, 10478.161747060369, 10633.734156646145, 10656.094231990368, 10786.460676966277, 10492.580296788043, 10548.736281593021, 10806.621768238389, 10864.348139111678, 11116.221864469733, 7751.534907905544, 7805.8143719758555, 7893.447458677838, 7958.403119765925, 7968.92031079705, 7741.845163096819, 10475.109615682752, 10663.573457061631, 10713.410762408143, 11044.944775276072], "value": 8551.975417167729, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1270.7585558743833, 1492.2538730634678, 1502.2113390470001, 1688.2909178145178, 1877.1083735225761, 6873.415759660428, 7915.327197188239, 8217.055038670554, 8223.424360294774, 8505.70230041947, 8181.3863960202925, 8332.125126861683, 8334.749957501284, 8344.499330040202, 8351.20663536963, 2250.133698526067, 2282.041542552132, 2291.255342013844, 2291.472961218921, 2291.472961218921, 2268.7958083772455, 2269.0, 2321.059970711856, 2321.419645088728, 2323.6058364981045, 2258.378945789913, 2283.4976305212826, 2284.977150228493, 2300.056976639578, 2300.401895507172, 2229.754726980047, 2241.0587553227683, 2242.4618091657885, 2244.8540844845033, 2247.213475283646, 2237.89929453175, 2239.6304609739373, 2250.4148921280585, 2250.673652320421, 2269.500709843832, 8102.7569172924905, 8185.3042491388305, 8230.588470576507, 8250.5462199579, 8258.25675689191, 4838.927416088726, 4904.754762261872, 4964.60283177345, 4974.552290293855, 4977.50224977503, 2945.2489615148, 2988.4620768261702, 3035.0287907869492, 3048.0703385467423, 3051.8474076296097, 913.634546181526, 922.2714055895834, 931.7810314576069, 932.3054324528202, 941.6798288581915, 11160.720981975435, 11213.159013074051, 11251.212415131025, 11273.098152147808, 11273.492692828842, 4205.411242426063, 4330.177266319452, 4366.214081465367, 4394.978025109854, 4511.120331535319, 8941.59762810675, 8992.730218093468, 8998.14517620825, 9011.28785530747, 9012.684556040507, 4713.128071306819, 4774.928376074362, 4775.331453596499, 4776.283557466339, 4776.713397196179, 3772.3398405279513, 4243.618074373321, 4370.278903980871, 4380.474343078851, 4404.339349097597, 3557.5553055868018, 3598.370285200132, 3658.6158453362546, 3742.8689995850013, 3770.849166033383, 1098.4507746126937, 1105.8562386889653, 1125.5104029747085, 1132.5583022621183, 1141.14414189358, 707.5683832861951, 758.9810254743676, 897.4121950122681, 913.5706218077493, 919.5861862162054, 5345.1447768356875, 5489.039418101816, 5490.094134467848, 5491.945080549223, 5530.778768849201, 465.2253997094867, 469.23046204225096, 469.25154378765814, 476.94276686797804, 623.4171050068201, 3596.352656521799, 3678.1172518595345, 3699.8520059197867, 3727.4408838674417, 3762.9435558466644, 9735.41587504752, 10020.799584008279, 10072.59709611622, 10123.746906327253, 10142.44216568081, 10023.498825058863, 10033.397996120257, 10057.094861462503, 10077.193824494023, 10082.19342452602, 7218.097737782672, 7521.7743467696055, 7621.923780762121, 7645.082590089225, 7645.350145237717, 5586.776528938797, 5719.828405147852, 5746.942530574641, 5797.826065218051, 5862.179294898803, 9073.001969783187, 9283.350165488351, 9368.34421590482, 9381.624735010522, 9473.289503287144, 9527.09492598202, 9589.856152157863, 9593.760155996157, 9608.0, 9633.470159141314, 6907.93092069093, 7012.894806578008, 7067.6819543119655, 7075.858482830417, 7078.823029424306, 7340.339369456561, 7348.448866335153, 7357.742479013209, 7423.703051877865, 7456.664450099867, 6800.693968771516, 6823.692933817891, 6948.930510694829, 6987.336203060701, 7085.751998680228, 6525.608463492205, 6738.393544581011, 6858.485613578901, 6953.617551034539, 6977.476689248225, 6400.615963042233, 6413.743450261937, 6458.483321334376, 6463.7737679181, 6467.417932386108, 4543.4775000875325, 4690.788914498788, 4740.739259340631, 4771.904561908812, 4824.927626085681, 4698.812047518061, 4740.97629511864, 4743.45450273223, 4765.5710986010035, 4827.227643577011], "value": 4212.089466621461, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.7030665407881, 321.8262138445239, 329.03264402656174, 331.600421492102, 333.64300198787305, 4865.951340486594, 4895.16782147035, 4907.950920490794, 4920.97539512302, 4924.409070911491, 4616.3767891334655, 4626.958934239794, 4705.2942058691215, 4803.447603525587, 4850.345203397549, 3835.520559930009, 3842.4812650292133, 3851.807409629507, 3880.2239552089554, 3883.1457079442475, 3826.8469261229516, 3885.805709714517, 3892.260470510594, 3917.3928041153667, 3917.3928041153667, 3686.889393318205, 3744.2324323513612, 3859.903502412434, 3866.4973553438012, 3894.7468414553105, 3799.772013679188, 3821.025638462202, 3821.3694740367805, 3855.1711382052995, 3883.592222816606, 3429.7770644908, 3439.518467414564, 3449.2066824630474, 3450.6204317525053, 3470.8091054991964, 4450.888727781799, 4696.624270058422, 4715.740634265114, 4720.551547602972, 4720.725404140862, 4292.29177185764, 4329.718568293051, 4345.283028300327, 4353.238183317907, 4386.539413361583, 5110.437851836295, 5113.8721531962, 5115.2327150927285, 5124.590032797368, 5132.486751324875, 3592.7305452091196, 3634.6183650716557, 3650.5071815305123, 3659.6157403472785, 3672.669459748609, 5248.475152484759, 5262.210668399733, 5272.472752724735, 5300.655457395257, 5307.920381194285, 3697.149655579178, 3703.277860817169, 3721.0883333583447, 3725.832337544817, 3736.5702944161226, 3762.285165818486, 3769.6230376962635, 3781.3193625147187, 3782.451544526028, 3792.6156952712063, 4088.4276201331836, 4313.698041137153, 4314.223439780807, 4326.502452218036, 4330.332821863542, 5072.163093089673, 5077.416097148801, 5078.568321692624, 5103.948960510422, 5124.0, 1252.4238850129043, 1275.2029981261712, 1299.9025073119365, 1310.711643438442, 1320.735852829438, 826.5330088500099, 831.9143517709447, 840.6297734692412, 843.9198276163754, 845.1717317029346, 4039.6970227233073, 4059.7970101494807, 4061.593840615974, 4087.8569250076102, 4111.423061936135, 4679.642903557999, 4689.95310046902, 4692.498400511803, 4700.7179569225955, 4707.670463067621, 4010.3382941814857, 4029.5366032906004, 4085.836566537365, 4086.979565102215, 4100.91798164041, 2403.9879800601234, 2469.592517234636, 2474.8267621266336, 2501.3371456564023, 2505.8245922785595, 4407.272799987966, 4428.911421771611, 4445.310781902856, 4449.621782148553, 4513.661475389359, 4760.523947605281, 4768.0, 4773.522647735199, 4793.952060479421, 4809.114531281203, 4521.502634710179, 4525.932111018337, 4659.720416775004, 5036.722980236123, 5041.168207245837, 5287.154055351125, 5306.602004849575, 5312.07835690361, 5312.867178320573, 5316.441773613869, 5210.739463026909, 5224.0, 5234.162533994542, 5236.659617124875, 5246.186841039645, 4789.497102804295, 4984.352034235526, 5024.748762561931, 5029.119904016856, 5054.4692807254705, 3866.8839934802, 3867.303885300673, 3884.8057597120596, 3905.2970465316516, 3923.7645741255615, 5075.543201111917, 5285.630005899704, 5298.496642818926, 5301.363836339664, 5303.575713942798, 4973.278874563241, 5070.873228169177, 5106.719130447861, 5114.0, 5118.232265160255, 5069.721165335942, 5072.51811077947, 5091.210862316347, 5091.949080509147, 5093.083245015932, 4857.927131093107, 5062.341895553554, 5065.290859279778, 5083.46623604531, 5091.6181286402925, 5118.616103792305, 5122.538971492583, 5125.0, 5151.922721159111, 5167.250748641351, 5042.243663450365, 5046.520580544842, 5067.239914012927, 5086.6439349246675, 5086.9745651271505], "value": 3792.700953784865, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.8817206988776, 762.3824701991384, 774.0904437286191, 782.3193821375404, 786.6342150899832, 11630.0, 12134.15060945733, 12303.384830758449, 12306.200096993689, 12309.938450307736, 12884.613461596144, 13021.15362501439, 13092.869071309284, 13099.214047157177, 13163.4076466559, 668.6322522612568, 853.4452605806244, 873.8863947686792, 888.0, 893.4237416866117, 875.0374587953232, 880.2034159086037, 880.2297989259398, 881.4755220643739, 881.951492667903, 924.0943875002501, 928.2295694573488, 928.3640706116298, 928.4475736936546, 928.5635751196927, 697.909271794666, 901.5762591581946, 917.2295271971543, 921.0697195832191, 927.6706769096941, 689.6655122265697, 689.8723736108785, 689.917209934811, 689.9379055884995, 689.9448044156505, 13274.933625331909, 13383.464661413533, 13431.731365372736, 13505.527306544322, 13522.459101635923, 11752.294862308203, 12110.636680899586, 12254.877451225462, 12321.322327271997, 12560.748785024249, 11210.66368008961, 11253.381064041474, 11381.943090284578, 11396.373199474026, 11490.02334801543, 4157.958420415787, 4189.895252618679, 4197.496300443936, 4255.91488170238, 4262.936055959163, 46152.7692361536, 46222.0, 46275.76862115735, 46290.5370946293, 46369.76815115937, 10085.142762865238, 10141.29010969225, 10148.949255253825, 10295.176385889112, 10441.738956526147, 18528.907355463136, 18854.905725471286, 18871.81128188728, 18884.81115188858, 19029.524261893286, 6804.013418054355, 6830.624315662587, 6978.546394484342, 7105.467089968273, 7171.462140339496, 3790.2040571479747, 3817.5609804872797, 3827.5598306195143, 3905.7656540607654, 3910.1006768443417, 2849.6010558521825, 2900.9274768130967, 2929.8974535891143, 2935.618369611937, 2936.339323652204, 517.6428264497484, 530.5304805247384, 531.4605675239635, 536.5734241278193, 546.4781134017004, 484.6171524495609, 616.4298024327479, 622.3745136138152, 623.5354660777659, 624.3662682377391, 8567.571621418903, 8835.20483156506, 8836.160564746338, 9044.728658140266, 9163.129502697233, 632.0708558419117, 639.1020616034479, 642.3929635098979, 645.5610185074195, 645.5642441352069, 4011.8194681239415, 4033.3143365627916, 4083.448734420815, 4199.349100889367, 4207.095474473003, 56440.0, 57104.0, 57657.423425765206, 57711.71144144337, 57723.42276577181, 49444.258336124985, 49924.750376248616, 51575.0, 51685.48314516882, 52999.47000530022, 36637.633623663954, 45271.32093018608, 47385.526144738804, 47490.0, 48009.0, 8706.0, 8732.170443807829, 8758.21176094129, 8827.831330714367, 8851.424657397249, 31178.532322014733, 31571.21071973219, 31760.364792703553, 32130.196745080626, 32667.019989400356, 37700.05749856275, 39313.606863930996, 41040.79479602583, 41150.79424602858, 41501.58498414977, 9720.319577629498, 11219.943900280447, 11313.09311132438, 11383.886161138615, 11464.885351146382, 10481.842772358574, 10821.56713731442, 10873.45632718345, 10898.291611045257, 11041.116710663287, 9642.469664168539, 9807.460589667637, 10027.649032283834, 10130.392176469435, 10413.791724165323, 10374.22193335518, 10415.94792026035, 10488.160947124365, 10510.316829406063, 10573.841392378967, 9458.574364153492, 9518.19095376873, 9864.901350986398, 9978.850317245393, 10429.58281668755, 8502.319814414956, 8657.22085012352, 8784.648614055366, 8793.0, 8920.286377089687, 8568.100349463217, 8678.435901666371, 8821.338399620183, 8888.777780555538, 8900.198982091642], "value": 6168.499631380013, "unit": "score"}], "name": "dromaeo_css", "value": 6432.038300514829, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 107.0, 113.0, 115.0, 112.0, 113.0, 113.0, 112.0, 114.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 129.0, 156.0, 222.0, 138.0, 163.0, 150.0, 136.0, 192.0, 129.0], "value": 157.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 161.0, 164.0, 161.0, 162.0, 161.0, 159.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 76.0, 154.0, 76.0, 76.0, 77.0, 75.0, 128.0, 75.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 146.0, 93.0, 93.0, 97.0, 128.0, 93.0, 93.0, 133.0], "value": 106.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 147.0, 146.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 63.0, 64.0, 64.0, 65.0, 66.0, 65.0, 66.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 62.0, 64.0, 64.0, 64.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 83.0, 82.0, 82.0, 83.0, 84.0, 149.0, 89.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 128.0, 136.0, 127.0, 127.0, 125.0, 128.0, 129.0, 155.0, 137.0], "value": 132.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 173.0, 172.0, 170.0, 170.0, 179.0, 172.0, 171.0, 168.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 72.0, 66.0, 66.0, 64.0, 65.0, 72.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1573.3999999999999, "alertThreshold": 2.0}]} 22:40:12 INFO - Return code: 0 22:40:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:40:12 ERROR - # TBPL SUCCESS # 22:40:12 INFO - Running post-action listener: _resource_record_post_action 22:40:12 INFO - ##### 22:40:12 INFO - ##### Finished run-tests step (success) 22:40:12 INFO - ##### 22:40:12 INFO - Running post-run listener: _resource_record_post_run 22:40:12 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 530817024; Read time: 12; Write time: 434096 22:40:12 INFO - TinderboxPrint: CPU usage
13.1% 22:40:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:40:12 INFO - TinderboxPrint: I/O write bytes / time
530,817,024 / 434,096 22:40:12 INFO - TinderboxPrint: CPU idle
8,325.4 (86.8%) 22:40:12 INFO - TinderboxPrint: CPU user
1,216.9 (12.7%) 22:40:12 INFO - TinderboxPrint: Swap in / out
0 / 0 22:40:12 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 236093440; Read time: 0; Write time: 223832 22:40:12 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294354944; Read time: 12; Write time: 210204 22:40:13 INFO - Running post-run listener: _upload_blobber_files 22:40:13 INFO - Blob upload gear active. 22:40:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:40:13 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:40:13 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:40:13 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:40:13 INFO - (blobuploader) - INFO - Open directory for files ... 22:40:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:40:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:40:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:14 INFO - (blobuploader) - INFO - Done attempting. 22:40:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:40:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:40:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:15 INFO - (blobuploader) - INFO - Done attempting. 22:40:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:40:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:40:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:19 INFO - (blobuploader) - INFO - Done attempting. 22:40:19 INFO - (blobuploader) - INFO - Iteration through files over. 22:40:19 INFO - Return code: 0 22:40:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:40:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:40:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6d9f7d24f09b229f55a079dbc3fe11cba69ccc20290d3171aae0becd1a6d3b158f2be75ad7966b7ed13ca85fe27ba47b7a641a62bff999ea8f0a3677984f443c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/317398e46f08da6bc30e4295162e2ec24484356f29da4c76e2af9dcbe9c0327001cb5cd314d71eb97cd903089119c3be7d6d7412e6d8c5dcdbf338a79b1b64a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/33f7106bb319bbb2bba0476cc07ff76f9e0e756b628bb9dcd2e36b7ee74897d4815954d8474c091259083dd9735fcf64ca53f3c96fdf4d39377f3dd49c380018"} 22:40:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:40:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:40:19 INFO - Contents: 22:40:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6d9f7d24f09b229f55a079dbc3fe11cba69ccc20290d3171aae0becd1a6d3b158f2be75ad7966b7ed13ca85fe27ba47b7a641a62bff999ea8f0a3677984f443c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/317398e46f08da6bc30e4295162e2ec24484356f29da4c76e2af9dcbe9c0327001cb5cd314d71eb97cd903089119c3be7d6d7412e6d8c5dcdbf338a79b1b64a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/33f7106bb319bbb2bba0476cc07ff76f9e0e756b628bb9dcd2e36b7ee74897d4815954d8474c091259083dd9735fcf64ca53f3c96fdf4d39377f3dd49c380018"} 22:40:19 INFO - Running post-run listener: copy_logs_to_upload_dir 22:40:19 INFO - Copying logs to upload dir... 22:40:19 INFO - mkdir: /builds/slave/test/build/upload/logs 22:40:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.576367 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-15 22:40:19.035317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-15 22:40:19.038994) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075 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/6d9f7d24f09b229f55a079dbc3fe11cba69ccc20290d3171aae0becd1a6d3b158f2be75ad7966b7ed13ca85fe27ba47b7a641a62bff999ea8f0a3677984f443c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/317398e46f08da6bc30e4295162e2ec24484356f29da4c76e2af9dcbe9c0327001cb5cd314d71eb97cd903089119c3be7d6d7412e6d8c5dcdbf338a79b1b64a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/33f7106bb319bbb2bba0476cc07ff76f9e0e756b628bb9dcd2e36b7ee74897d4815954d8474c091259083dd9735fcf64ca53f3c96fdf4d39377f3dd49c380018"} build_url:https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011240 build_url: 'https://queue.taskcluster.net/v1/task/DSEV7kfsTFC9isyp2FYYUg/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/6d9f7d24f09b229f55a079dbc3fe11cba69ccc20290d3171aae0becd1a6d3b158f2be75ad7966b7ed13ca85fe27ba47b7a641a62bff999ea8f0a3677984f443c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/317398e46f08da6bc30e4295162e2ec24484356f29da4c76e2af9dcbe9c0327001cb5cd314d71eb97cd903089119c3be7d6d7412e6d8c5dcdbf338a79b1b64a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/33f7106bb319bbb2bba0476cc07ff76f9e0e756b628bb9dcd2e36b7ee74897d4815954d8474c091259083dd9735fcf64ca53f3c96fdf4d39377f3dd49c380018"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-15 22:40:19.064327) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:40:19.064770) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463375746.886398-480208075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:40:19.149606) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-15 22:40:19.149977) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-15 22:40:19.150285) ========= ========= Total master_lag: 0.22 =========