builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1463421138.74 results: success (0) buildid: 20160516100443 builduid: 3d831e24ae854dbdb8ef0aac6c54c99a revision: 133ef863766e96786f6147f008cb4b95867857f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:18.739299) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:18.739883) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:18.740154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:18.756361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:18.756657) ========= 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=aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-16 10:52:19-- 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% 225M=0s 2016-05-16 10:52:19 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075626 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:18.845641) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:18.845927) ========= 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=aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018607 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:18.902234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 10:52:18.902566) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 133ef863766e96786f6147f008cb4b95867857f0 --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 133ef863766e96786f6147f008cb4b95867857f0 --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=aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-16 10:52:19,471 truncating revision to first 12 chars 2016-05-16 10:52:19,471 Setting DEBUG logging. 2016-05-16 10:52:19,471 attempt 1/10 2016-05-16 10:52:19,471 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/133ef863766e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 10:52:20,148 unpacking tar archive at: fx-team-133ef863766e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.286436 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 10:52:20.201638) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:20.201950) ========= script_repo_revision: 133ef863766e96786f6147f008cb4b95867857f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:20.202325) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:20.202585) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 10:52:20.211099) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-16 10:52:20.211386) ========= /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=aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:52:20 INFO - MultiFileLogger online at 20160516 10:52:20 in /builds/slave/test 10:52:20 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 10:52:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:52:20 INFO - {'append_to_log': False, 10:52:20 INFO - 'base_work_dir': '/builds/slave/test', 10:52:20 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 10:52:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:52:20 INFO - 'branch': 'Fx-Team-Non-PGO', 10:52:20 INFO - 'buildbot_json_path': 'buildprops.json', 10:52:20 INFO - 'config_files': ('talos/linux_config.py',), 10:52:20 INFO - 'default_actions': ('clobber', 10:52:20 INFO - 'read-buildbot-config', 10:52:20 INFO - 'download-and-extract', 10:52:20 INFO - 'populate-webroot', 10:52:20 INFO - 'create-virtualenv', 10:52:20 INFO - 'install', 10:52:20 INFO - 'run-tests'), 10:52:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:52:20 INFO - 'download_minidump_stackwalk': True, 10:52:20 INFO - 'download_symbols': 'ondemand', 10:52:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:52:20 INFO - 'tooltool.py': '/tools/tooltool.py', 10:52:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:52:20 INFO - '/tools/misc-python/virtualenv.py')}, 10:52:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:52:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:52:20 INFO - 'installer_path': 'installer.exe', 10:52:20 INFO - 'log_level': 'info', 10:52:20 INFO - 'log_name': 'talos', 10:52:20 INFO - 'log_to_console': True, 10:52:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:52:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:52:20 INFO - 'opt_config_files': (), 10:52:20 INFO - 'pip_index': False, 10:52:20 INFO - 'sps_profile': False, 10:52:20 INFO - 'sps_profile_interval': 0, 10:52:20 INFO - 'suite': 'dromaeojs', 10:52:20 INFO - 'system_bits': '32', 10:52:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:52:20 INFO - 'title': 'talos-linux64-ix-009', 10:52:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:52:20 INFO - 'use_talos_json': True, 10:52:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:52:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:52:20 INFO - 'work_dir': 'build'} 10:52:20 INFO - ##### 10:52:20 INFO - ##### Running clobber step. 10:52:20 INFO - ##### 10:52:20 INFO - Running pre-action listener: _resource_record_pre_action 10:52:20 INFO - Running main action method: clobber 10:52:20 INFO - rmtree: /builds/slave/test/build 10:52:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:52:21 INFO - Running post-action listener: _resource_record_post_action 10:52:21 INFO - ##### 10:52:21 INFO - ##### Finished clobber step (success) 10:52:21 INFO - ##### 10:52:21 INFO - ##### 10:52:21 INFO - ##### Running read-buildbot-config step. 10:52:21 INFO - ##### 10:52:21 INFO - Running pre-action listener: _resource_record_pre_action 10:52:21 INFO - Running main action method: read_buildbot_config 10:52:21 INFO - Using buildbot properties: 10:52:21 INFO - { 10:52:21 INFO - "project": "", 10:52:21 INFO - "product": "firefox", 10:52:21 INFO - "script_repo_revision": "production", 10:52:21 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 10:52:21 INFO - "repository": "", 10:52:21 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 10:52:21 INFO - "buildid": "20160516100443", 10:52:21 INFO - "pgo_build": "False", 10:52:21 INFO - "basedir": "/builds/slave/test", 10:52:21 INFO - "buildnumber": 2976, 10:52:21 INFO - "slavename": "talos-linux64-ix-009", 10:52:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:52:21 INFO - "platform": "ubuntu64_hw", 10:52:21 INFO - "scheduler": "tests-fx-team-linux64-talos", 10:52:21 INFO - "branch": "fx-team", 10:52:21 INFO - "repo_path": "integration/fx-team", 10:52:21 INFO - "revision": "133ef863766e96786f6147f008cb4b95867857f0", 10:52:21 INFO - "stage_platform": "linux64", 10:52:21 INFO - "builduid": "3d831e24ae854dbdb8ef0aac6c54c99a", 10:52:21 INFO - "slavebuilddir": "test" 10:52:21 INFO - } 10:52:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 10:52:21 INFO - Running post-action listener: _resource_record_post_action 10:52:21 INFO - ##### 10:52:21 INFO - ##### Finished read-buildbot-config step (success) 10:52:21 INFO - ##### 10:52:21 INFO - ##### 10:52:21 INFO - ##### Running download-and-extract step. 10:52:21 INFO - ##### 10:52:21 INFO - Running pre-action listener: _resource_record_pre_action 10:52:21 INFO - Running main action method: download_and_extract 10:52:21 INFO - mkdir: /builds/slave/test/build/tests 10:52: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')]} 10:52:21 INFO - https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:52:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:52:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:52:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:26 INFO - Downloaded 1361 bytes. 10:52:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:52:26 INFO - Using the following test package requirements: 10:52:26 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:52:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:52:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:52:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:52:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:52:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:52:26 INFO - u'jsshell-linux-x86_64.zip'], 10:52:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:52:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:52:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:52:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:52:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:52:26 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:52:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:52:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:52:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:52:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:52:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:52:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:26 INFO - https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:52:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:52:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:52:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:28 INFO - Downloaded 22448136 bytes. 10:52:28 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'] 10:52:28 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 10:52:29 INFO - Return code: 0 10:52:29 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 10:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:29 INFO - https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:52:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:52:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:52:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:31 INFO - Downloaded 22448136 bytes. 10:52:31 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'] 10:52:31 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 10:52:32 INFO - Return code: 0 10:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:32 INFO - https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:52:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:52:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:52:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:33 INFO - Downloaded 10840194 bytes. 10:52:33 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'] 10:52:33 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 10:52:34 INFO - Return code: 0 10:52:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:34 INFO - https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:52:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:52:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:52:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d_UybYtPSJyKInBKrbWH_w/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 10:52:35 INFO - Downloaded 54752085 bytes. 10:52:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:52:35 INFO - mkdir: /builds/slave/test/properties 10:52:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:52:35 INFO - Writing to file /builds/slave/test/properties/build_url 10:52:35 INFO - Contents: 10:52:35 INFO - build_url:https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:52:36 INFO - Running post-action listener: _resource_record_post_action 10:52:36 INFO - Running post-action listener: set_extra_try_arguments 10:52:36 INFO - ##### 10:52:36 INFO - ##### Finished download-and-extract step (success) 10:52:36 INFO - ##### 10:52:36 INFO - ##### 10:52:36 INFO - ##### Running populate-webroot step. 10:52:36 INFO - ##### 10:52:36 INFO - Running pre-action listener: _resource_record_pre_action 10:52:36 INFO - Running main action method: populate_webroot 10:52:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:52:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:52:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:52:36 INFO - '--tppagecycles', 10:52:36 INFO - '7'], 10:52:36 INFO - 'tests': ['tsvgm']}}, 10:52:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:52:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:52:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:52:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:52:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:36 INFO - 'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:36 INFO - 'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['damp', 'tps']}, 10:52:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:36 INFO - 'tests': ['damp', 'tps']}, 10:52:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['dromaeo_dom']}, 10:52:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:52:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['a11yr', 10:52:36 INFO - 'ts_paint', 10:52:36 INFO - 'tpaint', 10:52:36 INFO - 'sessionrestore', 10:52:36 INFO - 'sessionrestore_no_auto_restore', 10:52:36 INFO - 'tabpaint']}, 10:52:36 INFO - 'other-e10s': {'tests': ['a11yr', 10:52:36 INFO - 'ts_paint', 10:52:36 INFO - 'tpaint', 10:52:36 INFO - 'sessionrestore', 10:52:36 INFO - 'sessionrestore_no_auto_restore', 10:52:36 INFO - 'tabpaint']}, 10:52:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:52:36 INFO - 'ts_paint', 10:52:36 INFO - 'tpaint', 10:52:36 INFO - 'sessionrestore', 10:52:36 INFO - 'sessionrestore_no_auto_restore', 10:52:36 INFO - 'tabpaint']}, 10:52:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:52:36 INFO - 'ts_paint', 10:52:36 INFO - 'tpaint', 10:52:36 INFO - 'sessionrestore', 10:52:36 INFO - 'sessionrestore_no_auto_restore', 10:52:36 INFO - 'tabpaint']}, 10:52:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['a11yr', 10:52:36 INFO - 'ts_paint', 10:52:36 INFO - 'tpaint', 10:52:36 INFO - 'sessionrestore', 10:52:36 INFO - 'sessionrestore_no_auto_restore', 10:52:36 INFO - 'tabpaint']}, 10:52:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['a11yr', 10:52:36 INFO - 'ts_paint', 10:52:36 INFO - 'tpaint', 10:52:36 INFO - 'sessionrestore', 10:52:36 INFO - 'sessionrestore_no_auto_restore', 10:52:36 INFO - 'tabpaint']}, 10:52:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['tsvgx', 10:52:36 INFO - 'tsvgr_opacity', 10:52:36 INFO - 'tart', 10:52:36 INFO - 'tscrollx', 10:52:36 INFO - 'cart']}, 10:52:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:52:36 INFO - 'tsvgr_opacity', 10:52:36 INFO - 'tart', 10:52:36 INFO - 'tscrollx', 10:52:36 INFO - 'cart']}, 10:52:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:36 INFO - 'talos_options': ['--disable-e10s'], 10:52:36 INFO - 'tests': ['tp5o']}, 10:52:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:36 INFO - 'tests': ['tp5o']}, 10:52:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:36 INFO - 'talos_options': ['--disable-e10s', 10:52:36 INFO - '--xperf_path', 10:52:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:36 INFO - 'tests': ['tp5n']}, 10:52:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:36 INFO - 'talos_options': ['--xperf_path', 10:52:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:36 INFO - 'tests': ['tp5n']}}, 10:52:36 INFO - 'talos.zip': {'path': '', 10:52:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:52:36 INFO - Running post-action listener: _resource_record_post_action 10:52:36 INFO - ##### 10:52:36 INFO - ##### Finished populate-webroot step (success) 10:52:36 INFO - ##### 10:52:36 INFO - ##### 10:52:36 INFO - ##### Running create-virtualenv step. 10:52:36 INFO - ##### 10:52:36 INFO - Running pre-action listener: _resource_record_pre_action 10:52:36 INFO - Running main action method: create_virtualenv 10:52:36 INFO - Creating virtualenv /builds/slave/test/build/venv 10:52:36 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 10:52:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:52:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:52:36 INFO - Using real prefix '/usr' 10:52:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:52:37 INFO - Installing distribute.............................................................................................................................................................................................done. 10:52:39 INFO - Installing pip.................done. 10:52:39 INFO - Return code: 0 10:52:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:52:39 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')]} 10:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:39 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')]} 10:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:39 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:39 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 10:52:39 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 10:52:39 INFO - Using env: {'DISPLAY': ':0', 10:52:39 INFO - 'HOME': '/home/cltbld', 10:52:39 INFO - 'LANG': 'en_US.UTF-8', 10:52:39 INFO - 'LANGUAGE': 'en_US:en', 10:52:39 INFO - 'LOGNAME': 'cltbld', 10:52:39 INFO - 'MAIL': '/var/mail/cltbld', 10:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:39 INFO - 'MOZ_NO_REMOTE': '1', 10:52:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:39 INFO - 'NO_EM_RESTART': '1', 10:52:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:39 INFO - 'PWD': '/builds/slave/test', 10:52:39 INFO - 'SHELL': '/bin/bash', 10:52:39 INFO - 'SHLVL': '1', 10:52:39 INFO - 'TERM': 'linux', 10:52:39 INFO - 'TMOUT': '86400', 10:52:39 INFO - 'USER': 'cltbld', 10:52:39 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:39 INFO - '_': '/tools/buildbot/bin/python'} 10:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:39 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 10:52:39 INFO - Cleaning up... 10:52:39 INFO - Return code: 0 10:52:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:52:39 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')]} 10:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:39 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')]} 10:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:39 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:39 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 10:52:39 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 10:52:39 INFO - Using env: {'DISPLAY': ':0', 10:52:39 INFO - 'HOME': '/home/cltbld', 10:52:39 INFO - 'LANG': 'en_US.UTF-8', 10:52:39 INFO - 'LANGUAGE': 'en_US:en', 10:52:39 INFO - 'LOGNAME': 'cltbld', 10:52:39 INFO - 'MAIL': '/var/mail/cltbld', 10:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:39 INFO - 'MOZ_NO_REMOTE': '1', 10:52:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:39 INFO - 'NO_EM_RESTART': '1', 10:52:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:39 INFO - 'PWD': '/builds/slave/test', 10:52:39 INFO - 'SHELL': '/bin/bash', 10:52:39 INFO - 'SHLVL': '1', 10:52:39 INFO - 'TERM': 'linux', 10:52:39 INFO - 'TMOUT': '86400', 10:52:39 INFO - 'USER': 'cltbld', 10:52:39 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:39 INFO - '_': '/tools/buildbot/bin/python'} 10:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:39 INFO - Downloading/unpacking psutil>=3.1.1 10:52:39 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 10:52:39 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 10:52:39 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 10:52:39 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 10:52:39 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 10:52:39 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 10:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:52:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:41 INFO - Installing collected packages: psutil 10:52:41 INFO - Running setup.py install for psutil 10:52:41 INFO - building 'psutil._psutil_linux' extension 10:52:41 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 10:52:41 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 10:52:41 INFO - building 'psutil._psutil_posix' extension 10:52:41 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 10:52:41 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 10:52:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:41 INFO - Successfully installed psutil 10:52:41 INFO - Cleaning up... 10:52:42 INFO - Return code: 0 10:52:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:52:42 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')]} 10:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:42 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')]} 10:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:42 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:42 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 10:52:42 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 10:52:42 INFO - Using env: {'DISPLAY': ':0', 10:52:42 INFO - 'HOME': '/home/cltbld', 10:52:42 INFO - 'LANG': 'en_US.UTF-8', 10:52:42 INFO - 'LANGUAGE': 'en_US:en', 10:52:42 INFO - 'LOGNAME': 'cltbld', 10:52:42 INFO - 'MAIL': '/var/mail/cltbld', 10:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:42 INFO - 'MOZ_NO_REMOTE': '1', 10:52:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:42 INFO - 'NO_EM_RESTART': '1', 10:52:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:42 INFO - 'PWD': '/builds/slave/test', 10:52:42 INFO - 'SHELL': '/bin/bash', 10:52:42 INFO - 'SHLVL': '1', 10:52:42 INFO - 'TERM': 'linux', 10:52:42 INFO - 'TMOUT': '86400', 10:52:42 INFO - 'USER': 'cltbld', 10:52:42 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:42 INFO - '_': '/tools/buildbot/bin/python'} 10:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:52:42 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 10:52:42 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 10:52:42 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 10:52:42 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 10:52:42 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 10:52:42 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 10:52:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:52:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:52:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:52:44 INFO - Installing collected packages: mozsystemmonitor 10:52:44 INFO - Running setup.py install for mozsystemmonitor 10:52:44 INFO - Successfully installed mozsystemmonitor 10:52:44 INFO - Cleaning up... 10:52:44 INFO - Return code: 0 10:52:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:52:44 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')]} 10:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:44 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')]} 10:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:44 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:44 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 10:52:44 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 10:52:44 INFO - Using env: {'DISPLAY': ':0', 10:52:44 INFO - 'HOME': '/home/cltbld', 10:52:44 INFO - 'LANG': 'en_US.UTF-8', 10:52:44 INFO - 'LANGUAGE': 'en_US:en', 10:52:44 INFO - 'LOGNAME': 'cltbld', 10:52:44 INFO - 'MAIL': '/var/mail/cltbld', 10:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:44 INFO - 'MOZ_NO_REMOTE': '1', 10:52:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:44 INFO - 'NO_EM_RESTART': '1', 10:52:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:44 INFO - 'PWD': '/builds/slave/test', 10:52:44 INFO - 'SHELL': '/bin/bash', 10:52:44 INFO - 'SHLVL': '1', 10:52:44 INFO - 'TERM': 'linux', 10:52:44 INFO - 'TMOUT': '86400', 10:52:44 INFO - 'USER': 'cltbld', 10:52:44 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:44 INFO - '_': '/tools/buildbot/bin/python'} 10:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:44 INFO - Downloading/unpacking blobuploader==1.2.4 10:52:44 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 10:52:44 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 10:52:44 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 10:52:44 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 10:52:44 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 10:52:44 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 10:52:46 INFO - Downloading blobuploader-1.2.4.tar.gz 10:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:52:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:52:46 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 10:52:46 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 10:52:46 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 10:52:46 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 10:52:46 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 10:52:46 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 10:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:52:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:52:46 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 10:52:46 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 10:52:46 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 10:52:46 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 10:52:46 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 10:52:46 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 10:52:47 INFO - Downloading docopt-0.6.1.tar.gz 10:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:52:47 INFO - Installing collected packages: blobuploader, requests, docopt 10:52:47 INFO - Running setup.py install for blobuploader 10:52:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:52:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:52:47 INFO - Running setup.py install for requests 10:52:47 INFO - Running setup.py install for docopt 10:52:47 INFO - Successfully installed blobuploader requests docopt 10:52:47 INFO - Cleaning up... 10:52:47 INFO - Return code: 0 10:52:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:47 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:47 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 10:52:47 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 10:52:47 INFO - Using env: {'DISPLAY': ':0', 10:52:47 INFO - 'HOME': '/home/cltbld', 10:52:47 INFO - 'LANG': 'en_US.UTF-8', 10:52:47 INFO - 'LANGUAGE': 'en_US:en', 10:52:47 INFO - 'LOGNAME': 'cltbld', 10:52:47 INFO - 'MAIL': '/var/mail/cltbld', 10:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:47 INFO - 'MOZ_NO_REMOTE': '1', 10:52:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:47 INFO - 'NO_EM_RESTART': '1', 10:52:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:47 INFO - 'PWD': '/builds/slave/test', 10:52:47 INFO - 'SHELL': '/bin/bash', 10:52:47 INFO - 'SHLVL': '1', 10:52:47 INFO - 'TERM': 'linux', 10:52:47 INFO - 'TMOUT': '86400', 10:52:47 INFO - 'USER': 'cltbld', 10:52:47 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:47 INFO - '_': '/tools/buildbot/bin/python'} 10:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:48 INFO - Running setup.py (path:/tmp/pip-c9mWwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:48 INFO - Running setup.py (path:/tmp/pip-Ifg9Hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:48 INFO - Running setup.py (path:/tmp/pip-jqVAAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:48 INFO - Running setup.py (path:/tmp/pip-uZUqBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:48 INFO - Running setup.py (path:/tmp/pip-9dksNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:48 INFO - Running setup.py (path:/tmp/pip-JWP70t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:48 INFO - Running setup.py (path:/tmp/pip-df_wzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:48 INFO - Running setup.py (path:/tmp/pip-MwuQWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:48 INFO - Running setup.py (path:/tmp/pip-A3wd4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:48 INFO - Running setup.py (path:/tmp/pip-gcq8i0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:48 INFO - Running setup.py (path:/tmp/pip-1T0Dpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:48 INFO - Running setup.py (path:/tmp/pip-PNCh5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:48 INFO - Running setup.py (path:/tmp/pip-9XK6IZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:48 INFO - Running setup.py (path:/tmp/pip-FjxqWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:49 INFO - Running setup.py (path:/tmp/pip-DIA9hP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:49 INFO - Running setup.py (path:/tmp/pip-1MgC7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:49 INFO - Running setup.py (path:/tmp/pip-8HOZuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:52:49 INFO - Running setup.py install for manifestparser 10:52:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:52:49 INFO - Running setup.py install for mozcrash 10:52:49 INFO - Running setup.py install for mozdebug 10:52:49 INFO - Running setup.py install for mozdevice 10:52:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:52:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:52:49 INFO - Running setup.py install for mozfile 10:52:49 INFO - Running setup.py install for mozhttpd 10:52:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:52:49 INFO - Running setup.py install for mozinfo 10:52:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:52:49 INFO - Running setup.py install for mozInstall 10:52:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Running setup.py install for mozleak 10:52:50 INFO - Running setup.py install for mozlog 10:52:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Running setup.py install for moznetwork 10:52:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Running setup.py install for mozprocess 10:52:50 INFO - Running setup.py install for mozprofile 10:52:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Running setup.py install for mozrunner 10:52:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:52:50 INFO - Running setup.py install for mozscreenshot 10:52:50 INFO - Running setup.py install for moztest 10:52:50 INFO - Running setup.py install for mozversion 10:52:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:52:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:52:51 INFO - Cleaning up... 10:52:51 INFO - Return code: 0 10:52:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:51 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:51 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 10:52:51 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 10:52:51 INFO - Using env: {'DISPLAY': ':0', 10:52:51 INFO - 'HOME': '/home/cltbld', 10:52:51 INFO - 'LANG': 'en_US.UTF-8', 10:52:51 INFO - 'LANGUAGE': 'en_US:en', 10:52:51 INFO - 'LOGNAME': 'cltbld', 10:52:51 INFO - 'MAIL': '/var/mail/cltbld', 10:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:51 INFO - 'MOZ_NO_REMOTE': '1', 10:52:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:51 INFO - 'NO_EM_RESTART': '1', 10:52:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:51 INFO - 'PWD': '/builds/slave/test', 10:52:51 INFO - 'SHELL': '/bin/bash', 10:52:51 INFO - 'SHLVL': '1', 10:52:51 INFO - 'TERM': 'linux', 10:52:51 INFO - 'TMOUT': '86400', 10:52:51 INFO - 'USER': 'cltbld', 10:52:51 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:51 INFO - '_': '/tools/buildbot/bin/python'} 10:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:51 INFO - Running setup.py (path:/tmp/pip-tLHeOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:51 INFO - Running setup.py (path:/tmp/pip-mL7nwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:51 INFO - Running setup.py (path:/tmp/pip-HsRvpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:51 INFO - Running setup.py (path:/tmp/pip-FyCrxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:51 INFO - Running setup.py (path:/tmp/pip-vJ78hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:51 INFO - Running setup.py (path:/tmp/pip-cMTrbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:51 INFO - Running setup.py (path:/tmp/pip-XiPqjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:51 INFO - Running setup.py (path:/tmp/pip-U0Pl5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:51 INFO - Running setup.py (path:/tmp/pip-wIJ6d4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:51 INFO - Running setup.py (path:/tmp/pip-CW_s9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:51 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)) 10:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:51 INFO - Running setup.py (path:/tmp/pip-qTcsij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:52 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)) 10:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:52 INFO - Running setup.py (path:/tmp/pip-e310Sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:52 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)) 10:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:52 INFO - Running setup.py (path:/tmp/pip-YhrevO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:52 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)) 10:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:52 INFO - Running setup.py (path:/tmp/pip-UcFDYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:52 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)) 10:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:52 INFO - Running setup.py (path:/tmp/pip-XI0Z7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:52 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)) 10:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:52 INFO - Running setup.py (path:/tmp/pip-HVl_TN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:52 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)) 10:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:52 INFO - Running setup.py (path:/tmp/pip-_DFLLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:52 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)) 10:52:52 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)) 10:52:52 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)) 10:52:52 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)) 10:52:52 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)) 10:52:52 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)) 10:52:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52:54 INFO - Downloading blessings-1.6.tar.gz 10:52:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:52:54 INFO - Installing collected packages: blessings 10:52:54 INFO - Running setup.py install for blessings 10:52:54 INFO - Successfully installed blessings 10:52:54 INFO - Cleaning up... 10:52:54 INFO - Return code: 0 10:52:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:52:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:54 INFO - Reading from file tmpfile_stdout 10:52:54 INFO - Current package versions: 10:52:54 INFO - argparse == 1.2.1 10:52:54 INFO - blessings == 1.6 10:52:54 INFO - blobuploader == 1.2.4 10:52:54 INFO - docopt == 0.6.1 10:52:54 INFO - manifestparser == 1.1 10:52:54 INFO - mozInstall == 1.12 10:52:54 INFO - mozcrash == 0.17 10:52:54 INFO - mozdebug == 0.1 10:52:54 INFO - mozdevice == 0.48 10:52:54 INFO - mozfile == 1.2 10:52:54 INFO - mozhttpd == 0.7 10:52:54 INFO - mozinfo == 0.9 10:52:54 INFO - mozleak == 0.1 10:52:54 INFO - mozlog == 3.2 10:52:54 INFO - moznetwork == 0.27 10:52:54 INFO - mozprocess == 0.22 10:52:54 INFO - mozprofile == 0.28 10:52:54 INFO - mozrunner == 6.11 10:52:54 INFO - mozscreenshot == 0.1 10:52:54 INFO - mozsystemmonitor == 0.3 10:52:54 INFO - moztest == 0.7 10:52:54 INFO - mozversion == 1.4 10:52:54 INFO - psutil == 3.1.1 10:52:54 INFO - requests == 1.2.3 10:52:54 INFO - wsgiref == 0.1.2 10:52:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52: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')]} 10:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52: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')]} 10:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:54 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:54 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 10:52:54 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 10:52:54 INFO - Using env: {'DISPLAY': ':0', 10:52:54 INFO - 'HOME': '/home/cltbld', 10:52:54 INFO - 'LANG': 'en_US.UTF-8', 10:52:54 INFO - 'LANGUAGE': 'en_US:en', 10:52:54 INFO - 'LOGNAME': 'cltbld', 10:52:54 INFO - 'MAIL': '/var/mail/cltbld', 10:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:54 INFO - 'MOZ_NO_REMOTE': '1', 10:52:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:54 INFO - 'NO_EM_RESTART': '1', 10:52:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:54 INFO - 'PWD': '/builds/slave/test', 10:52:54 INFO - 'SHELL': '/bin/bash', 10:52:54 INFO - 'SHLVL': '1', 10:52:54 INFO - 'TERM': 'linux', 10:52:54 INFO - 'TMOUT': '86400', 10:52:54 INFO - 'USER': 'cltbld', 10:52:54 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:54 INFO - '_': '/tools/buildbot/bin/python'} 10:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 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)) 10:52:55 INFO - Cleaning up... 10:52:55 INFO - Return code: 0 10:52:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:55 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:55 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 10:52:55 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 10:52:55 INFO - Using env: {'DISPLAY': ':0', 10:52:55 INFO - 'HOME': '/home/cltbld', 10:52:55 INFO - 'LANG': 'en_US.UTF-8', 10:52:55 INFO - 'LANGUAGE': 'en_US:en', 10:52:55 INFO - 'LOGNAME': 'cltbld', 10:52:55 INFO - 'MAIL': '/var/mail/cltbld', 10:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:55 INFO - 'MOZ_NO_REMOTE': '1', 10:52:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:55 INFO - 'NO_EM_RESTART': '1', 10:52:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:55 INFO - 'PWD': '/builds/slave/test', 10:52:55 INFO - 'SHELL': '/bin/bash', 10:52:55 INFO - 'SHLVL': '1', 10:52:55 INFO - 'TERM': 'linux', 10:52:55 INFO - 'TMOUT': '86400', 10:52:55 INFO - 'USER': 'cltbld', 10:52:55 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:55 INFO - '_': '/tools/buildbot/bin/python'} 10:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:55 INFO - Downloading/unpacking jsonschema 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:52:57 INFO - Downloading/unpacking functools32 (from jsonschema) 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52:57 INFO - Downloading functools32-3.2.3-2.tar.gz 10:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:52:57 INFO - warning: no files found matching '*.txt' 10:52:57 INFO - no previously-included directories found matching 'build' 10:52:57 INFO - no previously-included directories found matching 'dist' 10:52:57 INFO - no previously-included directories found matching '.git*' 10:52:57 INFO - Installing collected packages: jsonschema, functools32 10:52:57 INFO - Running setup.py install for functools32 10:52:57 INFO - warning: no files found matching '*.txt' 10:52:57 INFO - no previously-included directories found matching 'build' 10:52:57 INFO - no previously-included directories found matching 'dist' 10:52:57 INFO - no previously-included directories found matching '.git*' 10:52:57 INFO - Successfully installed jsonschema functools32 10:52:57 INFO - Cleaning up... 10:52:57 INFO - Return code: 0 10:52:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:52:57 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')]} 10:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:57 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')]} 10:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:57 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 0x7f1b6c14df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, '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': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', '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 10:52:57 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 10:52:57 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 10:52:57 INFO - Using env: {'DISPLAY': ':0', 10:52:57 INFO - 'HOME': '/home/cltbld', 10:52:57 INFO - 'LANG': 'en_US.UTF-8', 10:52:57 INFO - 'LANGUAGE': 'en_US:en', 10:52:57 INFO - 'LOGNAME': 'cltbld', 10:52:57 INFO - 'MAIL': '/var/mail/cltbld', 10:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:57 INFO - 'MOZ_NO_REMOTE': '1', 10:52:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:57 INFO - 'NO_EM_RESTART': '1', 10:52:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:57 INFO - 'PWD': '/builds/slave/test', 10:52:57 INFO - 'SHELL': '/bin/bash', 10:52:57 INFO - 'SHLVL': '1', 10:52:57 INFO - 'TERM': 'linux', 10:52:57 INFO - 'TMOUT': '86400', 10:52:57 INFO - 'USER': 'cltbld', 10:52:57 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:57 INFO - '_': '/tools/buildbot/bin/python'} 10:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:57 INFO - Downloading/unpacking flake8 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:52:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:52:59 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 10:52:59 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 10:52:59 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 10:52:59 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 10:52:59 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 10:52:59 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 10:52:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:52:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:52:59 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 10:52:59 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 10:52:59 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 10:52:59 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 10:52:59 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 10:52:59 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 10:53:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:53:00 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 10:53:00 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 10:53:00 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 10:53:00 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 10:53:00 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 10:53:00 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 10:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:53:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:53:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:53:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:53:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:53:00 INFO - no previously-included directories found matching 'docs/_build' 10:53:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:53:00 INFO - Running setup.py install for pep8 10:53:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:53:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:53:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:53:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:53:00 INFO - no previously-included directories found matching 'docs/_build' 10:53:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:53:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:53:00 INFO - Cleaning up... 10:53:00 INFO - Return code: 0 10:53:00 INFO - Running post-action listener: _resource_record_post_action 10:53:00 INFO - Running post-action listener: _start_resource_monitoring 10:53:00 INFO - Starting resource monitoring. 10:53:00 INFO - ##### 10:53:00 INFO - ##### Finished create-virtualenv step (success) 10:53:00 INFO - ##### 10:53:00 INFO - ##### 10:53:00 INFO - ##### Running install step. 10:53:00 INFO - ##### 10:53:00 INFO - Running pre-action listener: _resource_record_pre_action 10:53:00 INFO - Running main action method: install 10:53:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:00 INFO - Reading from file tmpfile_stdout 10:53:00 INFO - Detecting whether we're running mozinstall >=1.0... 10:53:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:53:00 INFO - Reading from file tmpfile_stdout 10:53:00 INFO - Output received: 10:53:00 INFO - Usage: mozinstall [options] installer 10:53:00 INFO - Options: 10:53:00 INFO - -h, --help show this help message and exit 10:53:00 INFO - -d DEST, --destination=DEST 10:53:00 INFO - Directory to install application into. [default: 10:53:00 INFO - "/builds/slave/test"] 10:53:00 INFO - --app=APP Application being installed. [default: firefox] 10:53:00 INFO - mkdir: /builds/slave/test/build/application 10:53:00 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'] 10:53:00 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 10:53:16 INFO - Reading from file tmpfile_stdout 10:53:16 INFO - Output received: 10:53:16 INFO - /builds/slave/test/build/application/firefox/firefox 10:53:16 INFO - Running post-action listener: _resource_record_post_action 10:53:16 INFO - ##### 10:53:16 INFO - ##### Finished install step (success) 10:53:16 INFO - ##### 10:53:16 INFO - ##### 10:53:16 INFO - ##### Running run-tests step. 10:53:16 INFO - ##### 10:53:16 INFO - Running pre-action listener: _resource_record_pre_action 10:53:16 INFO - Running main action method: run_tests 10:53:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:53:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:53:16 INFO - Python 2.7.3 10:53:16 INFO - Return code: 0 10:53:16 INFO - grabbing minidump binary from tooltool 10:53:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:16 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 0x2ced9c0>, '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 0x2cee8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceed50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:53:16 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 10:53:16 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 10:53:16 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 10:53:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:53:16 INFO - Return code: 0 10:53:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:53:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:53:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:53:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:53:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:53:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:53:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:53:17 INFO - Return code: 0 10:53:17 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/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 10:53:17 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/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 10:53:17 INFO - Using env: {'DISPLAY': ':0', 10:53:17 INFO - 'HOME': '/home/cltbld', 10:53:17 INFO - 'LANG': 'en_US.UTF-8', 10:53:17 INFO - 'LANGUAGE': 'en_US:en', 10:53:17 INFO - 'LOGNAME': 'cltbld', 10:53:17 INFO - 'MAIL': '/var/mail/cltbld', 10:53:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:53:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:17 INFO - 'MOZ_NO_REMOTE': '1', 10:53:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:53:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:53:17 INFO - 'NO_EM_RESTART': '1', 10:53:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:17 INFO - 'PWD': '/builds/slave/test', 10:53:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:53:17 INFO - 'SHELL': '/bin/bash', 10:53:17 INFO - 'SHLVL': '1', 10:53:17 INFO - 'TERM': 'linux', 10:53:17 INFO - 'TMOUT': '86400', 10:53:17 INFO - 'USER': 'cltbld', 10:53:17 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722', 10:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:17 INFO - '_': '/tools/buildbot/bin/python'} 10:53:17 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/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 10:53:18 INFO - mozversion application_buildid: 20160516100443 10:53:18 INFO - mozversion application_changeset: 133ef863766e96786f6147f008cb4b95867857f0 10:53:18 INFO - mozversion application_display_name: Nightly 10:53:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:53:18 INFO - mozversion application_name: Firefox 10:53:18 INFO - mozversion application_remotingname: firefox 10:53:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:53:18 INFO - mozversion application_vendor: Mozilla 10:53:18 INFO - mozversion application_version: 49.0a1 10:53:18 INFO - mozversion platform_buildid: 20160516100443 10:53:18 INFO - mozversion platform_changeset: 133ef863766e96786f6147f008cb4b95867857f0 10:53:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:53:18 INFO - mozversion platform_version: 49.0a1 10:53:18 INFO - using testdate: 1463421198 10:53:18 INFO - actual date: 1463421198 10:53:18 INFO - starting webserver on 'localhost:50611' 10:53:18 INFO - SUITE-START | Running 2 tests 10:53:18 INFO - TEST-START | dromaeo_css 10:53:18 INFO - operating with platform_type : linux_ 10:53:18 INFO - Initialising browser for dromaeo_css test... 10:53:18 INFO - TEST-INFO | started process 13182 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCd1Zh_/profile http://localhost:50611/getInfo.html) 10:53:22 INFO - PROCESS | 13182 | __metrics Screen width/height:1600/1200 10:53:22 INFO - PROCESS | 13182 | colorDepth:24 10:53:22 INFO - PROCESS | 13182 | Browser inner width/height: 1024/697 10:53:22 INFO - PROCESS | 13182 | __metrics 10:53:24 ERROR - PROCESS | 13182 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:53:25 INFO - TEST-INFO | 13182: exit 0 10:53:25 INFO - Browser initialized. 10:53:25 INFO - Running cycle 1/1 for dromaeo_css test... 10:53:25 INFO - TEST-INFO | started process 13265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCd1Zh_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:53:37 INFO - PROCESS | 13265 | RSS: Main: 154144768 10:53:37 INFO - PROCESS | 13265 | 10:54:28 INFO - PROCESS | 13265 | 1463421268811 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] 10:56:40 INFO - PROCESS | 13265 | Cycle 1(1): loaded http://localhost:50611/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50611/tests/dromaeo/cssquery-ext.html) 10:56:42 INFO - PROCESS | 13265 | RSS: Main: 272072704 10:56:42 INFO - PROCESS | 13265 | 10:59:44 INFO - PROCESS | 13265 | Cycle 1(1): loaded http://localhost:50611/tests/dromaeo/cssquery-ext.html (next: http://localhost:50611/tests/dromaeo/cssquery-jquery.html) 10:59:45 INFO - PROCESS | 13265 | RSS: Main: 241319936 10:59:45 INFO - PROCESS | 13265 | 11:02:47 INFO - PROCESS | 13265 | Cycle 1(1): loaded http://localhost:50611/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50611/tests/dromaeo/cssquery-mootools.html) 11:02:47 INFO - PROCESS | 13265 | RSS: Main: 239108096 11:02:47 INFO - PROCESS | 13265 | 11:05:50 INFO - PROCESS | 13265 | Cycle 1(1): loaded http://localhost:50611/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50611/tests/dromaeo/cssquery-prototype.html) 11:05:50 INFO - PROCESS | 13265 | RSS: Main: 233353216 11:05:50 INFO - PROCESS | 13265 | 11:08:53 INFO - PROCESS | 13265 | Cycle 1(1): loaded http://localhost:50611/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50611/tests/dromaeo/cssquery-yui.html) 11:08:54 INFO - PROCESS | 13265 | RSS: Main: 225198080 11:08:54 INFO - PROCESS | 13265 | 11:11:56 INFO - PROCESS | 13265 | Cycle 1(1): loaded http://localhost:50611/tests/dromaeo/cssquery-yui.html (next: http://localhost:50611/tests/dromaeo/cssquery-dojo.html) 11:11:57 INFO - PROCESS | 13265 | RSS: Main: 268668928 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - PROCESS | 13265 | __start_tp_report 11:11:57 INFO - PROCESS | 13265 | _x_x_mozilla_page_load 11:11:57 INFO - PROCESS | 13265 | _x_x_mozilla_page_load_details 11:11:57 INFO - PROCESS | 13265 | |i|pagename|runs| 11:11:57 INFO - PROCESS | 13265 | |0;dojo.html;2688.892444302228;2704.215777424547;2730.6586676665415;2732.9726702732964;2764.8341099534027;4655.976720116403;4704.364910737054;4736.668433209677;4767.451743049546;4812.639052071096;4767.785449654765;4777.59390451812;4801.159797035513;4812.133815913135;4836.879078023051;4323.092150648352;4340.305551111831;4410.779461026952;4456.064226512436;4577.313402989545;4469.128519938613;4502.707324023928;4518.661100417482;4528.796204170803;4542.523035081318;4296.806643701041;4314.56854314566;4347.7173983690955;4364.410804541378;4385.429894113777;4305.870823875289;4312.956870431287;4326.394304797331;4359.80380882861;4365.912681746379;4325.762083085429;4329.393884856123;4343;4346.6088052075465;4376.474823021226;4539.137563862889;4607.354970304126;4630.9073818523775;4679.88300292492;4683.250679891201;5117.923231151536;5156.742162891878;5167.354080739908;5174.586033117342;5206.427292997767;5156.484351564851;5173.637845350827;5201.193814958688;5204.29741984831;5223.503767142115;4691.906161876777;4720.244760838249;4738.763061846894;4746.0745154694505;4820.662553621213;5228.320318358589;5262.394824595144;5262.631615786935;5266.289050978069;5280.471952804689;4846.684965477232;5016.874578135576;5063.3417655704525;5069.9746501267255;5077.695338279715;5011.123053465702;5015.623828212899;5035.219540971156;5041.697498150123;5112.182050871843;5001.574866136347;5003.899922001539;5018.974905125524;5023.372078490189;5079.339685840817;4872.561469467765;4972.328735620717;4982.551570358685;4984.352034235599;5030.3963524377305;4139.77231252284;4151.481064866892;4174.979125104416;4182.979085104555;4213.705040647125;3695.5380577427823;3698.112453011258;3706.610805865399;3740.5698344690163;3740.6446387593137;4339.414179085846;4344.804483798237;4361.9127617447475;4400.559944005638;4401.075774087478;4932.802687892444;4971.676841005323;5053.393592768891;5081.593472522263;5100.0819852426175;4379.211741886427;4392.989612389105;4398.956010439919;4401.801918913657;4435.866923992286;4576.359309696645;4581.152486789954;4584.587387135174;4590.403247577793;4625.791839367236;4660.533946605312;4827.300041494034;4827.903441931212;4869.099216644932;4872.878178045577;5066.544011039025;5102.642815002914;5103.795848166032;5109.182530795055;5153.948460515347;4672.556107169813;4799.400074990626;4817;4857;4908.509149085063;4996.050750357421;5019.648624596243;5030.496950305087;5041.621878359065;5043.773030213722;5099.3370861787735;5105.897882042413;5111.948880511147;5112.71880046601;5167.354080739908;4862.05189987949;4902.779374928208;4937.382827146606;4947.82682606107;4951.579115775199;4878.463369029475;4904.975475122602;4930.901381972412;4953.331300274417;4981.352424184833;4832.587501997645;4888.560029597354;4938.703677779345;4941.555260026615;4957.652964292465;4805.159097158054;4869.342638743724;4946.307516947558;4973.950260497493;5047.2681461187185;4818.8795280118275;4819.855404337875;4864.708117512961;4866.732329721899;4871.171900776805 11:11:57 INFO - PROCESS | 13265 | |1;ext.html;549.2338188227423;603.5533705058257;603.8550747820524;605.3008774865033;609.2384519350811;28216.294592635193;42221.57778422215;42562.57437425625;43279.99999999996;43430;40038.79980600093;40582.79708601453;43516.38901665894;43713.78143109288;43747.12505749891;7503.924960750377;7945.364370850347;8083.191680831928;8084.838303233962;8091.39314551411;10070.1440377568;10323.174146068297;10329.741756456073;10335.741606459824;10625.14998800098;9931.404115753077;10035.799284014316;10309.690709278733;10313.53589088489;10315.53580088894;10168.084872361485;10246.795064098751;10257;10257.33327333721;10258.589656413735;10193.49032548377;10307.330023548446;10308.175345972379;10308.329958552746;10309.123724483428;41130;41426.58573414257;41548.376774348406;41562.79218603918;41563;3312.7018568328726;3387.8475468603974;3444.534987776643;3577.3739595570933;3618.240169564395;30735.846320768476;31012.689873101204;31315.217119571964;31455.056348309587;31964.520532192037;3452.8273586320583;3458.394780913355;3564.1802385451233;3597.547939961328;3703;287893;287917;288229;387105;388088.99999999715;60096.399036009956;60119.39880601138;60139.09791353133;60505.39494604998;60756;65151.67424162849;65162.34837651563;65367.346326537074;65460.363490912154;65465;13482.056256061982;13627.931860340635;13679.521216757366;13968.720625587635;14019.929900350637;10422.947885260677;11298.322100673986;11593;11923.224990375598;11933.761324773455;20681.27615533449;20823.375298741063;21109.472263193544;21160.470988225108;21194;7598.468107232551;7602.160908741161;7657.0811502620045;7789.961050194826;7956.2043795619975;851.7274472168847;902.3187493442409;905.4335998722247;923.0584803500392;1045.3988956349976;27398;28524.286892827844;28619;28847.278818029718;28867.711322886502;972.86456228579;999.3604093380243;1007.496251874063;1039.6361273554346;1065.2969040433431;14034.228117453435;14398.928005359907;14603.634909127357;14605.561833145022;14637.341319640642;393232;394380;394897;395217;397090.1600412005;51029.74485127551;51562.74218628883;51711.22433163506;51720.48279517156;51723;1124.44901998022;1256.2399748152236;1286.414681319997;1299.1295831792534;1323.3383308345826;29189.270268243465;30052.24869378283;30149.547756784104;30258.84870575633;30719.2320191997;43182.56817431911;43533.12933741244;43699;43869.561304387826;44025.339619905084;232708;233672;233765;234175;234842;33437.66562334443;34338;38725.80637096796;39587;39806.80096599498;43100;43753;43973.56026439695;44038.33942490801;44996.32505512355;37224.255514890094;37510.43734344042;37820.62179378171;38350;38645.6135438642;36758.44862327121;38304.616953830104;38664.42003369895;38811.22377552489;38838.6116138835;36167.457488137174;37036.62963370332;37041.07397314981;37793.2441351177;37846.24307513779;29732.415253178824;30231.395372092877;30717.078487644514;30809.383812324075;30939.69060309368;23021.949209678896;23215.419614509774;23764.405889852893;23870.761292386855;23963.880180598986 11:11:57 INFO - PROCESS | 13265 | |2;jquery.html;385.3814627522826;386.3350048168368;389.4664309895444;399.6463130129835;412.15713865145773;12847.678808029805;13133.737325253489;16345.26446309916;17651.382201622946;17679.204435800388;15846.366145354172;17230;17628.823711762878;17637.82362176378;18492.352767653134;5195.19474481456;5360.785568577272;5364.6781193128345;5374.3013408256875;5378.677279363251;5181.145111056671;5239.633225674204;5318.654287471302;5338.119210330291;5341.519263266306;5270.288511051017;5282.2076688496645;5283.996040752264;5285.6300058995885;5303.363596368441;5134.897302053938;5231.555317797992;5273.551748101416;5275.762590683409;5284.101702710529;4687.320338550925;4693.78877950493;4697.107549565571;4702.741349225791;4709.929351059703;20940.685889711665;21274.787252127437;21418.25036123744;21459.248926287662;21785.346439606838;2092.7593326767465;2142.8500004999655;2170.0885628036262;2367.9763202367926;2384.3443053160345;12951.417186226548;13016.41426135826;13165.144265622705;13322.800157997637;13608;1760.8063113057553;1774.1395423219728;1829.2042961311872;1841.4659748673002;1868.3274021352302;70010.2998970014;70083.64958175228;70306.64846675734;70844.29155708429;72058.63970680165;9630.807383852283;9676.322657414054;9969.355476472856;10030.398176109456;10210.336328138646;13666.52167174144;13761.38073786682;13775.173489590657;13779.517716880062;13804;7179.389751871045;7215.494915355875;7277.16312624055;7404.629768511553;7406;6500;6563.671816409161;6586.6377349245295;6668.033135195369;6727.730890764413;5583.441655834384;6030;6279.277883043508;6376.171097757262;6392.3927226913365;1693.7036018696679;1760.9383671571434;1787;1791.2566284991842;1800.4598620413824;840.4243093480862;880.221004411088;908.8909330880205;944.5560586524323;982.9066238707311;16347.754783678254;17401.56496087608;17467.912660436617;17788.644227115386;18089.366872159673;906.0395980260945;906.4651855405342;917.6237742525666;962.7929995050957;982.9950850245702;9903.405795652285;10218.489075546193;10323;10399.792004160026;10436.843447348296;395647;397068;404788;406738;407260;68798;70177.2982270181;70938.2906170942;71232.2876771236;71518.2848171522;958.1328897347911;976.6484065736329;985.6156099121348;996.785691076422;998.6804222648683;14632.195229262492;15759;15810.7628385572;15825.208739563206;16155.273012714692;31894.840525797223;33143.502847456824;34415;34484;34905.825470872485;46969.53030469651;47661.28508072312;47939.520604793506;47975.5202447985;48032.27951580659;7725.304722574995;7741.458097933316;7876.330511906552;7968.721094761656;8112.553809540532;11032.558697652325;11585.189036767346;11803.763924721286;12077.033837293171;12421.627351179479;9964.601415943282;10167.491625418847;10183.33808302458;10942.671719848417;11006.614768483065;10322.690319290434;10615.416152111708;10643.361398315816;10644.946775266384;10738.463076846283;10411.323263987817;10497.580096796042;10586.364818110938;10652.201084918817;10969.616063437741;7605.923940760743;7615.961920190363;7672.961635191788;7814.4529882907655;7828.960855195915;10232.897671023493;10480.109190718875;10594.947025264824;10748.83876741865;10785.89214107849 11:11:57 INFO - PROCESS | 13265 | |3;mootools.html;1344.0322967463435;1487.620656732533;1547.326912792935;1709.1710520397612;1886.1323791056113;6860.656967151641;7720.30517253447;7928.841423171533;7941.205879412056;8059.516429014263;7830.334421574159;7985.840283194332;8003.199680031994;8009;8010;2294.254367330616;2323.6166032604683;2327.511222643247;2327.895244713992;2329.231353653299;2256.402053455827;2273.4771002669395;2306.1006207579057;2310.214527060799;2313.1672597864754;2312.4681323295654;2316.0967222783042;2330.720313562375;2352.376620195641;2356.45801465663;2255.763144869798;2291.8166546676307;2296.4144143243534;2297.873616951067;2301.2520930697506;2243.9102435902546;2250.0999600159903;2257.2325409360897;2257.3905045637584;2259.0173274625427;7831.608419578998;7897.723579674741;7909.129995700526;7971.521708697497;8112.067112282104;4621.884452888671;4713.717176969393;4715.056988602293;4720.669553131283;4723.36234608327;2969.3170570768843;3000.369922316316;3073.386472102142;3100.829454380008;3117.4700300948894;912.5209265135791;921.7326176507303;921.7326975177191;923.3167456082517;926.3284119013729;10945.671629851116;11119.054880335181;11133.165012624086;11148.665540033811;11156.944215278872;4160.791960401968;4212.641925436311;4295.6778241632;4306.633936115465;4359.931816704928;8778.868316975251;8828;8831.60257788401;8837.779055523535;8841.557922103997;4684.695494792828;4694.248920172756;4713.316569097462;4722.268048452495;4723.527647235249;3674.5590529136675;4228.577142285808;4305.84929527465;4349.586789255079;4355.5208927018;3571.9285614288087;3572.4284114541547;3651.397519409321;3673.9632603674154;3691.3909204981414;1079.3631757263252;1092.2900114925203;1099.1701265544623;1101.545959333688;1106.253279036647;733.9926600734032;779.7387875061804;859.6690274244336;881.6466723579275;886.5655828644047;5054.519820617035;5118.718470484159;5165.225216217522;5301.390340110936;5466.590005749585;872.4459967920416;921.1939552891221;921.2307723051193;929.7768535551554;931.0875342164717;3544.7518673692593;3591.874284400086;3651.3427583034777;3655.707543396575;3662.4140137578393;9488.146066854017;9885.654002110035;9950.402975821475;9963.302568820258;9984.950075249722;9477.099675530813;9662.516874156265;9760.56077476501;9784.315097943218;9786.657466988621;7335.706571737071;7379.520331178456;7471.953926450301;7481.513701609378;7486.737964171228;5121.564667003347;5125.692458452505;5246.239295302236;5312.256284120149;5364.597655175956;9034.774130646787;9092.545372731469;9105.590248438968;9175.77060573491;9187.540622968958;8612.526311052952;8933.240674542476;8947.463152210888;9165.908340916505;9177.908220917705;7064;7296.379807716191;7307.232740562377;7314.232005639331;7314.305141011595;7559.357454616419;7614.2766437188375;7627.656755445907;7639.121501027453;7898.763037108896;7185.712571497291;7222.205557388788;7254;7321.63391830417;7327.487075904635;6991.230964593992;7071.82320441993;7092.432605391659;7169.641517924188;7179.892301615376;6492.188476440445;6524.2823289439075;6545.280019197789;6563.146790917151;6563.474922006323;4432.312991486196;4447.866564003085;4548.931766023578;4566.680332376702;4597.609203217764;4611.6310695144975;4672.065586882569;4693.178693728652;4694.201985662514;4731.952680473151 11:11:57 INFO - PROCESS | 13265 | |4;prototype.html;314.1470906488882;322.4872452800048;330.23385745071437;331.2282382049824;332.19608547315494;4880.511948805118;4889.511048895109;4934.555889969902;4967.701937883729;4974.8507544773665;4591.862244132673;4678.766061696919;4703.365045718827;4715.198416269234;4830.44449888263;3717.2008018276065;3759;3771.076086358832;3864.80675966202;3887.7451078374474;3858.054776579742;3858.5562660294004;3866.4586957825927;3874;3877.573466918651;3603.6396360364015;3799.8480060797533;3848.3842585186376;3867.1878905429894;3869.6323849234277;3754.812259387047;3760.3419401604883;3773.8301776419853;3786.848526058968;3806.371948628473;3332.4506752334482;3340.9331813363838;3347;3349.531065650811;3370.561826962504;4654.301854721785;4655.673133157034;4664.48404268614;4675.696079754805;4725.243960966229;4229.471316085489;4351;4408.20652282589;4434.556544345572;4439;5073.771680274396;5084.669496482754;5088.643794934356;5096.949030509684;5112.41207261166;3788.5074940257864;3789.77261364319;3792.412176112707;3798.30975216029;3811.161544460242;5008.749562521896;5229.398619158807;5246.239295302198;5260.579153667697;5328.653637513588;3726.677029654488;3743.0642339415144;3757.830897609614;3762.473253744478;3770.886873393748;3762.887113386603;3804.6956243500454;3825.923481530354;3834.2331533693373;3840.327942610032;4236.173946080485;4332.4801023877335;4359.847405340798;4362.8909277267685;4384.4519435070615;5001.399832020108;5013.674111182761;5046.318746969186;5052.34319538465;5101.7704203310195;1282.8262140141853;1317.22942078883;1328.7276108397755;1332.9800052999212;1341.6645838540364;935.9859602105973;946.6781294359951;966.2704657983178;974.3082411487923;984.2913102566141;4454.331850222493;4463.598276155161;4473.351364052194;4476.283794592915;4483.215437298525;4990.650654454226;5125.743712814345;5144.405234377355;5155.664881782672;5175.81687262331;4382.603482301242;4432.423784907941;4437.2251099560435;4449.28811390176;4464.2187617166865;2725.6456660634385;2734.7128551502205;2744.6294750208867;2751.2984189032068;2752.1698070783104;4700.542831722177;4849.2968519565175;4865.513448655106;4885.6335774817035;4906.2640603908985;5248.895022099613;5272.551833094153;5287.947120528823;5303.257543943851;5327.227552004938;4881.585065269492;4893.339399181065;4927.384076990376;4944.802207911715;4954.455009948902;4916.566299681681;5267.71027593486;5272.130098533699;5275.657082289639;5278.709670968134;5137.691738495702;5152.845414637567;5154.819581314636;5171.767270472913;5178.6374953752875;4775.785089670903;5000.799968001239;5001.224810154432;5012.147934851157;5017.924731128963;3731.085883958339;3746.4380342947684;3758.8872333830027;3779.5653499847867;3788.412796016622;5237.580993520585;5237.6954140178195;5246;5250.684958902478;5256.526912577886;4900.41810906789;5043.344365232496;5085.728567858036;5101.642884998015;5108.897822043613;4861.732604706775;4950.752462376795;5050.611081952493;5054.826424637294;5061.645684802029;4949.802007919643;4951.480094590016;4985.277134815504;4986.451490336132;4994.450610432777;5043.142665746906;5047.369078865142;5079.72061536619;5080.212567052113;5094.694318340911;4864.221724524201;5006.374203224597;5022.547970682656;5050.207176452306;5062.139436295765 11:11:57 INFO - PROCESS | 13265 | |5;yui.html;786.4966421490246;809.538563019079;809.8623234050211;813.8941937548118;817.5094943034177;11221.775564488706;12050.75898482031;12127.272363658187;12169.939150304235;12247.510099596027;12061.819072713917;12464.93767531161;12762.298073605947;12835.358232088405;12857.292848893307;864.9264812490945;868.2316150207081;883.2360008592585;883.7569668341193;883.9779005524881;873.1181506678237;877.7279043496504;878.1613559051091;878.2008372381134;878.2754227762122;924.6948506992676;926.6617684545126;927.6474939522972;928.3594319919268;928.6053427293373;903.0427746588605;909.0409617853162;910.9453432794054;912.4388501071859;913.5797533134765;669.1568623534382;671.8622682350107;675.2909445082644;676.6041865508683;678.3318431345122;10846.511906964126;11003.22977391583;11005.284656497302;11050.668479945534;11427.771444571063;9718.61125554977;9899.45552994585;9902.405855648683;9959.452230127341;10274.383536987805;9096.954515227448;9277.61509720095;9297.349185557014;9347.76630584234;9544.809103817954;3208.2781374190854;3254.4955531892356;3305.8842940496966;3355.1612096975755;3358.798472091658;44062.77968610136;44078.33882491765;44109.33835992463;44773.776131119135;46907.530924690655;7128.4539210988;7139.071920650283;7311.8537629248185;7366.742164024234;7587.924120758722;13042.804357934638;13056;13068.411921463558;13077.542286019943;13078.476860925646;6600.2409722882485;6629.834254143589;6669.233038200572;6772.492063095287;6859.828504287333;3589.3180295743723;3626.1841085755495;3634.945475817865;3648.1062139775395;3696.094456858033;2861.3275880168144;2947.0864032149675;2955.674875763664;2957.7781666374676;2958.5857979882835;511.94283803944734;514.5112143463724;524.046235850748;524.9737513124328;528.0626887275117;484.95392937670863;529.9178627312773;562.6511562831071;563.1665135599336;654.3652656922777;8051.718189863327;8150.836983260301;8169;8202.917970820336;8326.64936307157;506.43026595080534;565.4430386069718;602.4246844263718;609.7103875659116;620.8758248350348;3546.3793836078585;3701.648343407372;4149.91700166001;4155.480564929384;4175.498940127143;52114;52770.47229527732;54115;54172;54191;36875.631243687756;38054.42918356227;49272.75363623231;49422.50577494251;49792.75103624531;40116;46116.7694161527;46341.30488042682;46481.76759116183;47337.763311183226;8559.358048146289;8633.568321584022;8771.780705482413;8802.867956980523;9028.368014239202;30043.549346760254;30687;31156.22109447282;31415.84292078525;31701.36597268088;38533.22933541369;39617;39746.40380394354;40021.39967900542;40202;10957.83563246536;11136.721581960515;11201.271917325348;11320.37737924422;11851.05191584654;12107.455164517794;12196.81704774447;12227.75544489123;12330.691732706755;13102.279374634487;11569.826452603049;11892.167548271538;12207.328596927255;12288.938555307166;12451.937740311241;11076.11391088727;11476;11700.2979821211;11753.941230293794;11999.40002999864;10469.476526173508;10780.514876830404;11027.117830573374;11121.944390277997;11170.27393219438;9575.99308722373;9774.169195618451;9840.950795245977;10060.144887684628;10109;8438.915610844058;8595.65617375323;8813.823723525365;8814.779630509289;8824.735257942271 11:11:57 INFO - PROCESS | 13265 | __end_tp_report 11:11:57 INFO - PROCESS | 13265 | __start_cc_report 11:11:57 INFO - PROCESS | 13265 | _x_x_mozilla_cycle_collect,1094 11:11:57 INFO - PROCESS | 13265 | __end_cc_report 11:11:57 INFO - PROCESS | 13265 | __startTimestamp1463422317215__endTimestamp 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - PROCESS | 13265 | ------- Summary: start ------- 11:11:57 INFO - PROCESS | 13265 | Number of tests: 6 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - PROCESS | 13265 | [#0] dojo.html Cycles:165 Average:4695.36 Median:4827.90 stddev:491.13 (10.2%) stddev-sans-first:466.73 11:11:57 INFO - PROCESS | 13265 | Values: 2688.9 2704.2 2730.7 2733.0 2764.8 4656.0 4704.4 4736.7 4767.5 4812.6 4767.8 4777.6 4801.2 4812.1 4836.9 4323.1 4340.3 4410.8 4456.1 4577.3 4469.1 4502.7 4518.7 4528.8 4542.5 4296.8 4314.6 4347.7 4364.4 4385.4 4305.9 4313.0 4326.4 4359.8 4365.9 4325.8 4329.4 4343.0 4346.6 4376.5 4539.1 4607.4 4630.9 4679.9 4683.3 5117.9 5156.7 5167.4 5174.6 5206.4 5156.5 5173.6 5201.2 5204.3 5223.5 4691.9 4720.2 4738.8 4746.1 4820.7 5228.3 5262.4 5262.6 5266.3 5280.5 4846.7 5016.9 5063.3 5070.0 5077.7 5011.1 5015.6 5035.2 5041.7 5112.2 5001.6 5003.9 5019.0 5023.4 5079.3 4872.6 4972.3 4982.6 4984.4 5030.4 4139.8 4151.5 4175.0 4183.0 4213.7 3695.5 3698.1 3706.6 3740.6 3740.6 4339.4 4344.8 4361.9 4400.6 4401.1 4932.8 4971.7 5053.4 5081.6 5100.1 4379.2 4393.0 4399.0 4401.8 4435.9 4576.4 4581.2 4584.6 4590.4 4625.8 4660.5 4827.3 4827.9 4869.1 4872.9 5066.5 5102.6 5103.8 5109.2 5153.9 4672.6 4799.4 4817.0 4857.0 4908.5 4996.1 5019.6 5030.5 5041.6 5043.8 5099.3 5105.9 5111.9 5112.7 5167.4 4862.1 4902.8 4937.4 4947.8 4951.6 4878.5 4905.0 4930.9 4953.3 4981.4 4832.6 4888.6 4938.7 4941.6 4957.7 4805.2 4869.3 4946.3 4974.0 5047.3 4818.9 4819.9 4864.7 4866.7 4871.2 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - PROCESS | 13265 | [#1] ext.html Cycles:180 Average:49104.33 Median:3587.46 stddev:85983.78 (2396.8%) stddev-sans-first:86147.70 11:11:57 INFO - PROCESS | 13265 | Values: 549.2 603.6 603.9 605.3 609.2 28216.3 42221.6 42562.6 43280.0 43430.0 40038.8 40582.8 43516.4 43713.8 43747.1 7503.9 7945.4 8083.2 8084.8 8091.4 10070.1 10323.2 10329.7 10335.7 10625.1 9931.4 10035.8 10309.7 10313.5 10315.5 10168.1 10246.8 10257.0 10257.3 10258.6 10193.5 10307.3 10308.2 10308.3 10309.1 41130.0 41426.6 41548.4 41562.8 41563.0 3312.7 3387.8 3444.5 3577.4 3618.2 30735.8 31012.7 31315.2 31455.1 31964.5 3452.8 3458.4 3564.2 3597.5 3703.0 287893.0 287917.0 288229.0 387105.0 388089.0 60096.4 60119.4 60139.1 60505.4 60756.0 65151.7 65162.3 65367.3 65460.4 65465.0 13482.1 13627.9 13679.5 13968.7 14019.9 10422.9 11298.3 11593.0 11923.2 11933.8 20681.3 20823.4 21109.5 21160.5 21194.0 7598.5 7602.2 7657.1 7790.0 7956.2 851.7 902.3 905.4 923.1 1045.4 27398.0 28524.3 28619.0 28847.3 28867.7 972.9 999.4 1007.5 1039.6 1065.3 14034.2 14398.9 14603.6 14605.6 14637.3 393232.0 394380.0 394897.0 395217.0 397090.2 51029.7 51562.7 51711.2 51720.5 51723.0 1124.4 1256.2 1286.4 1299.1 1323.3 29189.3 30052.2 30149.5 30258.8 30719.2 43182.6 43533.1 43699.0 43869.6 44025.3 232708.0 233672.0 233765.0 234175.0 234842.0 33437.7 34338.0 38725.8 39587.0 39806.8 43100.0 43753.0 43973.6 44038.3 44996.3 37224.3 37510.4 37820.6 38350.0 38645.6 36758.4 38304.6 38664.4 38811.2 38838.6 36167.5 37036.6 37041.1 37793.2 37846.2 29732.4 30231.4 30717.1 30809.4 30939.7 23021.9 23215.4 23764.4 23870.8 23963.9 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - PROCESS | 13265 | [#2] jquery.html Cycles:180 Average:24554.70 Median:202593.82 stddev:66151.75 (32.7%) stddev-sans-first:66312.44 11:11:57 INFO - PROCESS | 13265 | Values: 385.4 386.3 389.5 399.6 412.2 12847.7 13133.7 16345.3 17651.4 17679.2 15846.4 17230.0 17628.8 17637.8 18492.4 5195.2 5360.8 5364.7 5374.3 5378.7 5181.1 5239.6 5318.7 5338.1 5341.5 5270.3 5282.2 5284.0 5285.6 5303.4 5134.9 5231.6 5273.6 5275.8 5284.1 4687.3 4693.8 4697.1 4702.7 4709.9 20940.7 21274.8 21418.3 21459.2 21785.3 2092.8 2142.9 2170.1 2368.0 2384.3 12951.4 13016.4 13165.1 13322.8 13608.0 1760.8 1774.1 1829.2 1841.5 1868.3 70010.3 70083.6 70306.6 70844.3 72058.6 9630.8 9676.3 9969.4 10030.4 10210.3 13666.5 13761.4 13775.2 13779.5 13804.0 7179.4 7215.5 7277.2 7404.6 7406.0 6500.0 6563.7 6586.6 6668.0 6727.7 5583.4 6030.0 6279.3 6376.2 6392.4 1693.7 1760.9 1787.0 1791.3 1800.5 840.4 880.2 908.9 944.6 982.9 16347.8 17401.6 17467.9 17788.6 18089.4 906.0 906.5 917.6 962.8 983.0 9903.4 10218.5 10323.0 10399.8 10436.8 395647.0 397068.0 404788.0 406738.0 407260.0 68798.0 70177.3 70938.3 71232.3 71518.3 958.1 976.6 985.6 996.8 998.7 14632.2 15759.0 15810.8 15825.2 16155.3 31894.8 33143.5 34415.0 34484.0 34905.8 46969.5 47661.3 47939.5 47975.5 48032.3 7725.3 7741.5 7876.3 7968.7 8112.6 11032.6 11585.2 11803.8 12077.0 12421.6 9964.6 10167.5 10183.3 10942.7 11006.6 10322.7 10615.4 10643.4 10644.9 10738.5 10411.3 10497.6 10586.4 10652.2 10969.6 7605.9 7616.0 7673.0 7814.5 7829.0 10232.9 10480.1 10594.9 10748.8 10785.9 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - PROCESS | 13265 | [#3] mootools.html Cycles:180 Average:5252.58 Median:5273.81 stddev:2926.58 (55.5%) stddev-sans-first:2920.05 11:11:57 INFO - PROCESS | 13265 | Values: 1344.0 1487.6 1547.3 1709.2 1886.1 6860.7 7720.3 7928.8 7941.2 8059.5 7830.3 7985.8 8003.2 8009.0 8010.0 2294.3 2323.6 2327.5 2327.9 2329.2 2256.4 2273.5 2306.1 2310.2 2313.2 2312.5 2316.1 2330.7 2352.4 2356.5 2255.8 2291.8 2296.4 2297.9 2301.3 2243.9 2250.1 2257.2 2257.4 2259.0 7831.6 7897.7 7909.1 7971.5 8112.1 4621.9 4713.7 4715.1 4720.7 4723.4 2969.3 3000.4 3073.4 3100.8 3117.5 912.5 921.7 921.7 923.3 926.3 10945.7 11119.1 11133.2 11148.7 11156.9 4160.8 4212.6 4295.7 4306.6 4359.9 8778.9 8828.0 8831.6 8837.8 8841.6 4684.7 4694.2 4713.3 4722.3 4723.5 3674.6 4228.6 4305.8 4349.6 4355.5 3571.9 3572.4 3651.4 3674.0 3691.4 1079.4 1092.3 1099.2 1101.5 1106.3 734.0 779.7 859.7 881.6 886.6 5054.5 5118.7 5165.2 5301.4 5466.6 872.4 921.2 921.2 929.8 931.1 3544.8 3591.9 3651.3 3655.7 3662.4 9488.1 9885.7 9950.4 9963.3 9985.0 9477.1 9662.5 9760.6 9784.3 9786.7 7335.7 7379.5 7472.0 7481.5 7486.7 5121.6 5125.7 5246.2 5312.3 5364.6 9034.8 9092.5 9105.6 9175.8 9187.5 8612.5 8933.2 8947.5 9165.9 9177.9 7064.0 7296.4 7307.2 7314.2 7314.3 7559.4 7614.3 7627.7 7639.1 7898.8 7185.7 7222.2 7254.0 7321.6 7327.5 6991.2 7071.8 7092.4 7169.6 7179.9 6492.2 6524.3 6545.3 6563.1 6563.5 4432.3 4447.9 4548.9 4566.7 4597.6 4611.6 4672.1 4693.2 4694.2 4732.0 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - PROCESS | 13265 | [#4] prototype.html Cycles:180 Average:4226.52 Median:4803.11 stddev:1201.29 (25.0%) stddev-sans-first:1168.21 11:11:57 INFO - PROCESS | 13265 | Values: 314.1 322.5 330.2 331.2 332.2 4880.5 4889.5 4934.6 4967.7 4974.9 4591.9 4678.8 4703.4 4715.2 4830.4 3717.2 3759.0 3771.1 3864.8 3887.7 3858.1 3858.6 3866.5 3874.0 3877.6 3603.6 3799.8 3848.4 3867.2 3869.6 3754.8 3760.3 3773.8 3786.8 3806.4 3332.5 3340.9 3347.0 3349.5 3370.6 4654.3 4655.7 4664.5 4675.7 4725.2 4229.5 4351.0 4408.2 4434.6 4439.0 5073.8 5084.7 5088.6 5096.9 5112.4 3788.5 3789.8 3792.4 3798.3 3811.2 5008.7 5229.4 5246.2 5260.6 5328.7 3726.7 3743.1 3757.8 3762.5 3770.9 3762.9 3804.7 3825.9 3834.2 3840.3 4236.2 4332.5 4359.8 4362.9 4384.5 5001.4 5013.7 5046.3 5052.3 5101.8 1282.8 1317.2 1328.7 1333.0 1341.7 936.0 946.7 966.3 974.3 984.3 4454.3 4463.6 4473.4 4476.3 4483.2 4990.7 5125.7 5144.4 5155.7 5175.8 4382.6 4432.4 4437.2 4449.3 4464.2 2725.6 2734.7 2744.6 2751.3 2752.2 4700.5 4849.3 4865.5 4885.6 4906.3 5248.9 5272.6 5287.9 5303.3 5327.2 4881.6 4893.3 4927.4 4944.8 4954.5 4916.6 5267.7 5272.1 5275.7 5278.7 5137.7 5152.8 5154.8 5171.8 5178.6 4775.8 5000.8 5001.2 5012.1 5017.9 3731.1 3746.4 3758.9 3779.6 3788.4 5237.6 5237.7 5246.0 5250.7 5256.5 4900.4 5043.3 5085.7 5101.6 5108.9 4861.7 4950.8 5050.6 5054.8 5061.6 4949.8 4951.5 4985.3 4986.5 4994.5 5043.1 5047.4 5079.7 5080.2 5094.7 4864.2 5006.4 5022.5 5050.2 5062.1 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - PROCESS | 13265 | [#5] yui.html Cycles:180 Average:12643.26 Median:24878.85 stddev:14631.33 (58.8%) stddev-sans-first:14645.28 11:11:57 INFO - PROCESS | 13265 | Values: 786.5 809.5 809.9 813.9 817.5 11221.8 12050.8 12127.3 12169.9 12247.5 12061.8 12464.9 12762.3 12835.4 12857.3 864.9 868.2 883.2 883.8 884.0 873.1 877.7 878.2 878.2 878.3 924.7 926.7 927.6 928.4 928.6 903.0 909.0 910.9 912.4 913.6 669.2 671.9 675.3 676.6 678.3 10846.5 11003.2 11005.3 11050.7 11427.8 9718.6 9899.5 9902.4 9959.5 10274.4 9097.0 9277.6 9297.3 9347.8 9544.8 3208.3 3254.5 3305.9 3355.2 3358.8 44062.8 44078.3 44109.3 44773.8 46907.5 7128.5 7139.1 7311.9 7366.7 7587.9 13042.8 13056.0 13068.4 13077.5 13078.5 6600.2 6629.8 6669.2 6772.5 6859.8 3589.3 3626.2 3634.9 3648.1 3696.1 2861.3 2947.1 2955.7 2957.8 2958.6 511.9 514.5 524.0 525.0 528.1 485.0 529.9 562.7 563.2 654.4 8051.7 8150.8 8169.0 8202.9 8326.6 506.4 565.4 602.4 609.7 620.9 3546.4 3701.6 4149.9 4155.5 4175.5 52114.0 52770.5 54115.0 54172.0 54191.0 36875.6 38054.4 49272.8 49422.5 49792.8 40116.0 46116.8 46341.3 46481.8 47337.8 8559.4 8633.6 8771.8 8802.9 9028.4 30043.5 30687.0 31156.2 31415.8 31701.4 38533.2 39617.0 39746.4 40021.4 40202.0 10957.8 11136.7 11201.3 11320.4 11851.1 12107.5 12196.8 12227.8 12330.7 13102.3 11569.8 11892.2 12207.3 12288.9 12451.9 11076.1 11476.0 11700.3 11753.9 11999.4 10469.5 10780.5 11027.1 11121.9 11170.3 9576.0 9774.2 9841.0 10060.1 10109.0 8438.9 8595.7 8813.8 8814.8 8824.7 11:11:57 INFO - PROCESS | 13265 | -------- Summary: end -------- 11:11:57 INFO - PROCESS | 13265 | 11:11:57 INFO - TEST-INFO | 13265: exit 0 11:11:57 INFO - TEST-OK | dromaeo_css | took 1119888ms 11:11:57 INFO - TEST-START | kraken 11:11:57 INFO - operating with platform_type : linux_ 11:11:57 INFO - Initialising browser for kraken test... 11:11:57 INFO - TEST-INFO | started process 13444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiExRkY/profile http://localhost:50611/getInfo.html) 11:12:02 INFO - PROCESS | 13444 | __metrics Screen width/height:1600/1200 11:12:02 INFO - PROCESS | 13444 | colorDepth:24 11:12:02 INFO - PROCESS | 13444 | Browser inner width/height: 1024/697 11:12:02 INFO - PROCESS | 13444 | __metrics 11:12:04 ERROR - PROCESS | 13444 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:12:04 INFO - TEST-INFO | 13444: exit 0 11:12:04 INFO - Browser initialized. 11:12:04 INFO - Running cycle 1/1 for kraken test... 11:12:04 INFO - TEST-INFO | started process 13525 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiExRkY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:16 INFO - PROCESS | 13525 | RSS: Main: 155021312 11:12:16 INFO - PROCESS | 13525 | 11:12:19 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50611/tests/kraken/driver.html?testName=audio-beat-detection) 11:12:20 INFO - PROCESS | 13525 | RSS: Main: 209469440 11:12:20 INFO - PROCESS | 13525 | 11:12:23 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50611/tests/kraken/driver.html?testName=audio-dft) 11:12:24 INFO - PROCESS | 13525 | RSS: Main: 198152192 11:12:24 INFO - PROCESS | 13525 | 11:12:27 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50611/tests/kraken/driver.html?testName=audio-fft) 11:12:28 INFO - PROCESS | 13525 | RSS: Main: 267214848 11:12:28 INFO - PROCESS | 13525 | 11:12:30 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50611/tests/kraken/driver.html?testName=audio-oscillator) 11:12:30 INFO - PROCESS | 13525 | RSS: Main: 175976448 11:12:30 INFO - PROCESS | 13525 | 11:12:33 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50611/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:12:33 INFO - PROCESS | 13525 | RSS: Main: 179916800 11:12:33 INFO - PROCESS | 13525 | 11:12:37 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50611/tests/kraken/driver.html?testName=imaging-darkroom) 11:12:37 INFO - PROCESS | 13525 | RSS: Main: 216137728 11:12:37 INFO - PROCESS | 13525 | 11:12:41 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50611/tests/kraken/driver.html?testName=imaging-desaturate) 11:12:42 INFO - PROCESS | 13525 | RSS: Main: 214777856 11:12:42 INFO - PROCESS | 13525 | 11:12:45 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50611/tests/kraken/driver.html?testName=json-parse-financial) 11:12:45 INFO - PROCESS | 13525 | RSS: Main: 215220224 11:12:45 INFO - PROCESS | 13525 | 11:12:47 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50611/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:12:48 INFO - PROCESS | 13525 | RSS: Main: 203321344 11:12:48 INFO - PROCESS | 13525 | 11:12:50 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50611/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:12:50 INFO - PROCESS | 13525 | RSS: Main: 207331328 11:12:50 INFO - PROCESS | 13525 | 11:12:53 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50611/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:12:53 INFO - PROCESS | 13525 | RSS: Main: 215388160 11:12:53 INFO - PROCESS | 13525 | 11:12:56 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50611/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:12:57 INFO - PROCESS | 13525 | RSS: Main: 223531008 11:12:57 INFO - PROCESS | 13525 | 11:13:00 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50611/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:13:00 INFO - PROCESS | 13525 | RSS: Main: 220246016 11:13:00 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | Cycle 1(1): loaded http://localhost:50611/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50611/tests/kraken/driver.html?testName=ai-astar) 11:13:02 INFO - PROCESS | 13525 | RSS: Main: 266756096 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | __start_tp_report 11:13:02 INFO - PROCESS | 13525 | _x_x_mozilla_page_load 11:13:02 INFO - PROCESS | 13525 | _x_x_mozilla_page_load_details 11:13:02 INFO - PROCESS | 13525 | |i|pagename|runs| 11:13:02 INFO - PROCESS | 13525 | |0;ai-astar;116;109;113;113;113;115;115;112;114;114 11:13:02 INFO - PROCESS | 13525 | |1;audio-beat-detection;166;136;164;227;134;165;152;130;194;131 11:13:02 INFO - PROCESS | 13525 | |2;audio-dft;161;161;161;162;161;160;161;161;161;161 11:13:02 INFO - PROCESS | 13525 | |3;audio-fft;75;77;76;154;78;75;76;75;138;78 11:13:02 INFO - PROCESS | 13525 | |4;audio-oscillator;93;93;150;93;92;92;124;97;92;125 11:13:02 INFO - PROCESS | 13525 | |5;imaging-gaussian-blur;116;116;117;116;117;116;117;117;116;117 11:13:02 INFO - PROCESS | 13525 | |6;imaging-darkroom;146;149;148;147;147;148;146;147;147;145 11:13:02 INFO - PROCESS | 13525 | |7;imaging-desaturate;95;94;94;94;95;95;94;94;94;94 11:13:02 INFO - PROCESS | 13525 | |8;json-parse-financial;63;66;64;65;65;64;63;118;63;63 11:13:02 INFO - PROCESS | 13525 | |9;json-stringify-tinderbox;64;64;65;64;63;64;64;64;64;63 11:13:02 INFO - PROCESS | 13525 | |10;stanford-crypto-aes;88;84;82;81;83;84;84;84;85;83 11:13:02 INFO - PROCESS | 13525 | |11;stanford-crypto-ccm;134;139;145;131;129;129;128;141;198;133 11:13:02 INFO - PROCESS | 13525 | |12;stanford-crypto-pbkdf2;176;169;170;171;169;171;172;174;180;168 11:13:02 INFO - PROCESS | 13525 | |13;stanford-crypto-sha256-iterative;66;67;66;66;66;66;67;67;64;65 11:13:02 INFO - PROCESS | 13525 | __end_tp_report 11:13:02 INFO - PROCESS | 13525 | __start_cc_report 11:13:02 INFO - PROCESS | 13525 | _x_x_mozilla_cycle_collect,2103 11:13:02 INFO - PROCESS | 13525 | __end_cc_report 11:13:02 INFO - PROCESS | 13525 | __startTimestamp1463422382867__endTimestamp 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | ------- Summary: start ------- 11:13:02 INFO - PROCESS | 13525 | Number of tests: 14 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:1.96 (1.7%) stddev-sans-first:1.83 11:13:02 INFO - PROCESS | 13525 | Values: 116.0 109.0 113.0 113.0 113.0 115.0 115.0 112.0 114.0 114.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#1] audio-beat-detection Cycles:10 Average:159.90 Median:164.50 stddev:31.23 (19.0%) stddev-sans-first:33.05 11:13:02 INFO - PROCESS | 13525 | Values: 166.0 136.0 164.0 227.0 134.0 165.0 152.0 130.0 194.0 131.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 11:13:02 INFO - PROCESS | 13525 | Values: 161.0 161.0 161.0 162.0 161.0 160.0 161.0 161.0 161.0 161.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#3] audio-fft Cycles:10 Average:90.20 Median:76.00 stddev:29.67 (39.0%) stddev-sans-first:30.96 11:13:02 INFO - PROCESS | 13525 | Values: 75.0 77.0 76.0 154.0 78.0 75.0 76.0 75.0 138.0 78.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#4] audio-oscillator Cycles:10 Average:105.10 Median:92.50 stddev:20.52 (22.2%) stddev-sans-first:21.29 11:13:02 INFO - PROCESS | 13525 | Values: 93.0 93.0 150.0 93.0 92.0 92.0 124.0 97.0 92.0 125.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 11:13:02 INFO - PROCESS | 13525 | Values: 116.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 117.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:1.15 (0.8%) stddev-sans-first:1.17 11:13:02 INFO - PROCESS | 13525 | Values: 146.0 149.0 148.0 147.0 147.0 148.0 146.0 147.0 147.0 145.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 11:13:02 INFO - PROCESS | 13525 | Values: 95.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 94.0 94.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#8] json-parse-financial Cycles:10 Average:69.40 Median:64.00 stddev:17.11 (26.7%) stddev-sans-first:17.99 11:13:02 INFO - PROCESS | 13525 | Values: 63.0 66.0 64.0 65.0 65.0 64.0 63.0 118.0 63.0 63.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 11:13:02 INFO - PROCESS | 13525 | Values: 64.0 64.0 65.0 64.0 63.0 64.0 64.0 64.0 64.0 63.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:1.87 (2.2%) stddev-sans-first:1.22 11:13:02 INFO - PROCESS | 13525 | Values: 88.0 84.0 82.0 81.0 83.0 84.0 84.0 84.0 85.0 83.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:136.50 stddev:20.92 (15.3%) stddev-sans-first:22.05 11:13:02 INFO - PROCESS | 13525 | Values: 134.0 139.0 145.0 131.0 129.0 129.0 128.0 141.0 198.0 133.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:171.50 stddev:3.71 (2.2%) stddev-sans-first:3.64 11:13:02 INFO - PROCESS | 13525 | Values: 176.0 169.0 170.0 171.0 169.0 171.0 172.0 174.0 180.0 168.0 11:13:02 INFO - PROCESS | 13525 | 11:13:02 INFO - PROCESS | 13525 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 11:13:02 INFO - PROCESS | 13525 | Values: 66.0 67.0 66.0 66.0 66.0 66.0 67.0 67.0 64.0 65.0 11:13:02 INFO - PROCESS | 13525 | -------- Summary: end -------- 11:13:02 INFO - PROCESS | 13525 | 11:13:03 INFO - TEST-INFO | 13525: exit 0 11:13:03 INFO - TEST-OK | kraken | took 66083ms 11:13:03 INFO - SUITE-END | took 1185s 11:13:04 INFO - Completed test suite (00:19:46) 11:13:04 INFO - javascript benchmark 11:13:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2688.892444302228, 2704.215777424547, 2730.6586676665415, 2732.9726702732964, 2764.8341099534027, 4655.976720116403, 4704.364910737054, 4736.668433209677, 4767.451743049546, 4812.639052071096, 4767.785449654765, 4777.59390451812, 4801.159797035513, 4812.133815913135, 4836.879078023051, 4323.092150648352, 4340.305551111831, 4410.779461026952, 4456.064226512436, 4577.313402989545, 4469.128519938613, 4502.707324023928, 4518.661100417482, 4528.796204170803, 4542.523035081318, 4296.806643701041, 4314.56854314566, 4347.7173983690955, 4364.410804541378, 4385.429894113777, 4305.870823875289, 4312.956870431287, 4326.394304797331, 4359.80380882861, 4365.912681746379, 4325.762083085429, 4329.393884856123, 4343.0, 4346.6088052075465, 4376.474823021226, 4539.137563862889, 4607.354970304126, 4630.9073818523775, 4679.88300292492, 4683.250679891201, 5117.923231151536, 5156.742162891878, 5167.354080739908, 5174.586033117342, 5206.427292997767, 5156.484351564851, 5173.637845350827, 5201.193814958688, 5204.29741984831, 5223.503767142115, 4691.906161876777, 4720.244760838249, 4738.763061846894, 4746.0745154694505, 4820.662553621213, 5228.320318358589, 5262.394824595144, 5262.631615786935, 5266.289050978069, 5280.471952804689, 4846.684965477232, 5016.874578135576, 5063.3417655704525, 5069.9746501267255, 5077.695338279715, 5011.123053465702, 5015.623828212899, 5035.219540971156, 5041.697498150123, 5112.182050871843, 5001.574866136347, 5003.899922001539, 5018.974905125524, 5023.372078490189, 5079.339685840817, 4872.561469467765, 4972.328735620717, 4982.551570358685, 4984.352034235599, 5030.3963524377305, 4139.77231252284, 4151.481064866892, 4174.979125104416, 4182.979085104555, 4213.705040647125, 3695.5380577427823, 3698.112453011258, 3706.610805865399, 3740.5698344690163, 3740.6446387593137, 4339.414179085846, 4344.804483798237, 4361.9127617447475, 4400.559944005638, 4401.075774087478, 4932.802687892444, 4971.676841005323, 5053.393592768891, 5081.593472522263, 5100.0819852426175, 4379.211741886427, 4392.989612389105, 4398.956010439919, 4401.801918913657, 4435.866923992286, 4576.359309696645, 4581.152486789954, 4584.587387135174, 4590.403247577793, 4625.791839367236, 4660.533946605312, 4827.300041494034, 4827.903441931212, 4869.099216644932, 4872.878178045577, 5066.544011039025, 5102.642815002914, 5103.795848166032, 5109.182530795055, 5153.948460515347, 4672.556107169813, 4799.400074990626, 4817.0, 4857.0, 4908.509149085063, 4996.050750357421, 5019.648624596243, 5030.496950305087, 5041.621878359065, 5043.773030213722, 5099.3370861787735, 5105.897882042413, 5111.948880511147, 5112.71880046601, 5167.354080739908, 4862.05189987949, 4902.779374928208, 4937.382827146606, 4947.82682606107, 4951.579115775199, 4878.463369029475, 4904.975475122602, 4930.901381972412, 4953.331300274417, 4981.352424184833, 4832.587501997645, 4888.560029597354, 4938.703677779345, 4941.555260026615, 4957.652964292465, 4805.159097158054, 4869.342638743724, 4946.307516947558, 4973.950260497493, 5047.2681461187185, 4818.8795280118275, 4819.855404337875, 4864.708117512961, 4866.732329721899, 4871.171900776805], "value": 4664.6211171021605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.2338188227423, 603.5533705058257, 603.8550747820524, 605.3008774865033, 609.2384519350811, 28216.294592635193, 42221.57778422215, 42562.57437425625, 43279.99999999996, 43430.0, 40038.79980600093, 40582.79708601453, 43516.38901665894, 43713.78143109288, 43747.12505749891, 7503.924960750377, 7945.364370850347, 8083.191680831928, 8084.838303233962, 8091.39314551411, 10070.1440377568, 10323.174146068297, 10329.741756456073, 10335.741606459824, 10625.14998800098, 9931.404115753077, 10035.799284014316, 10309.690709278733, 10313.53589088489, 10315.53580088894, 10168.084872361485, 10246.795064098751, 10257.0, 10257.33327333721, 10258.589656413735, 10193.49032548377, 10307.330023548446, 10308.175345972379, 10308.329958552746, 10309.123724483428, 41130.0, 41426.58573414257, 41548.376774348406, 41562.79218603918, 41563.0, 3312.7018568328726, 3387.8475468603974, 3444.534987776643, 3577.3739595570933, 3618.240169564395, 30735.846320768476, 31012.689873101204, 31315.217119571964, 31455.056348309587, 31964.520532192037, 3452.8273586320583, 3458.394780913355, 3564.1802385451233, 3597.547939961328, 3703.0, 287893.0, 287917.0, 288229.0, 387105.0, 388088.99999999715, 60096.399036009956, 60119.39880601138, 60139.09791353133, 60505.39494604998, 60756.0, 65151.67424162849, 65162.34837651563, 65367.346326537074, 65460.363490912154, 65465.0, 13482.056256061982, 13627.931860340635, 13679.521216757366, 13968.720625587635, 14019.929900350637, 10422.947885260677, 11298.322100673986, 11593.0, 11923.224990375598, 11933.761324773455, 20681.27615533449, 20823.375298741063, 21109.472263193544, 21160.470988225108, 21194.0, 7598.468107232551, 7602.160908741161, 7657.0811502620045, 7789.961050194826, 7956.2043795619975, 851.7274472168847, 902.3187493442409, 905.4335998722247, 923.0584803500392, 1045.3988956349976, 27398.0, 28524.286892827844, 28619.0, 28847.278818029718, 28867.711322886502, 972.86456228579, 999.3604093380243, 1007.496251874063, 1039.6361273554346, 1065.2969040433431, 14034.228117453435, 14398.928005359907, 14603.634909127357, 14605.561833145022, 14637.341319640642, 393232.0, 394380.0, 394897.0, 395217.0, 397090.1600412005, 51029.74485127551, 51562.74218628883, 51711.22433163506, 51720.48279517156, 51723.0, 1124.44901998022, 1256.2399748152236, 1286.414681319997, 1299.1295831792534, 1323.3383308345826, 29189.270268243465, 30052.24869378283, 30149.547756784104, 30258.84870575633, 30719.2320191997, 43182.56817431911, 43533.12933741244, 43699.0, 43869.561304387826, 44025.339619905084, 232708.0, 233672.0, 233765.0, 234175.0, 234842.0, 33437.66562334443, 34338.0, 38725.80637096796, 39587.0, 39806.80096599498, 43100.0, 43753.0, 43973.56026439695, 44038.33942490801, 44996.32505512355, 37224.255514890094, 37510.43734344042, 37820.62179378171, 38350.0, 38645.6135438642, 36758.44862327121, 38304.616953830104, 38664.42003369895, 38811.22377552489, 38838.6116138835, 36167.457488137174, 37036.62963370332, 37041.07397314981, 37793.2441351177, 37846.24307513779, 29732.415253178824, 30231.395372092877, 30717.078487644514, 30809.383812324075, 30939.69060309368, 23021.949209678896, 23215.419614509774, 23764.405889852893, 23870.761292386855, 23963.880180598986], "value": 18903.29574517398, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.3814627522826, 386.3350048168368, 389.4664309895444, 399.6463130129835, 412.15713865145773, 12847.678808029805, 13133.737325253489, 16345.26446309916, 17651.382201622946, 17679.204435800388, 15846.366145354172, 17230.0, 17628.823711762878, 17637.82362176378, 18492.352767653134, 5195.19474481456, 5360.785568577272, 5364.6781193128345, 5374.3013408256875, 5378.677279363251, 5181.145111056671, 5239.633225674204, 5318.654287471302, 5338.119210330291, 5341.519263266306, 5270.288511051017, 5282.2076688496645, 5283.996040752264, 5285.6300058995885, 5303.363596368441, 5134.897302053938, 5231.555317797992, 5273.551748101416, 5275.762590683409, 5284.101702710529, 4687.320338550925, 4693.78877950493, 4697.107549565571, 4702.741349225791, 4709.929351059703, 20940.685889711665, 21274.787252127437, 21418.25036123744, 21459.248926287662, 21785.346439606838, 2092.7593326767465, 2142.8500004999655, 2170.0885628036262, 2367.9763202367926, 2384.3443053160345, 12951.417186226548, 13016.41426135826, 13165.144265622705, 13322.800157997637, 13608.0, 1760.8063113057553, 1774.1395423219728, 1829.2042961311872, 1841.4659748673002, 1868.3274021352302, 70010.2998970014, 70083.64958175228, 70306.64846675734, 70844.29155708429, 72058.63970680165, 9630.807383852283, 9676.322657414054, 9969.355476472856, 10030.398176109456, 10210.336328138646, 13666.52167174144, 13761.38073786682, 13775.173489590657, 13779.517716880062, 13804.0, 7179.389751871045, 7215.494915355875, 7277.16312624055, 7404.629768511553, 7406.0, 6500.0, 6563.671816409161, 6586.6377349245295, 6668.033135195369, 6727.730890764413, 5583.441655834384, 6030.0, 6279.277883043508, 6376.171097757262, 6392.3927226913365, 1693.7036018696679, 1760.9383671571434, 1787.0, 1791.2566284991842, 1800.4598620413824, 840.4243093480862, 880.221004411088, 908.8909330880205, 944.5560586524323, 982.9066238707311, 16347.754783678254, 17401.56496087608, 17467.912660436617, 17788.644227115386, 18089.366872159673, 906.0395980260945, 906.4651855405342, 917.6237742525666, 962.7929995050957, 982.9950850245702, 9903.405795652285, 10218.489075546193, 10323.0, 10399.792004160026, 10436.843447348296, 395647.0, 397068.0, 404788.0, 406738.0, 407260.0, 68798.0, 70177.2982270181, 70938.2906170942, 71232.2876771236, 71518.2848171522, 958.1328897347911, 976.6484065736329, 985.6156099121348, 996.785691076422, 998.6804222648683, 14632.195229262492, 15759.0, 15810.7628385572, 15825.208739563206, 16155.273012714692, 31894.840525797223, 33143.502847456824, 34415.0, 34484.0, 34905.825470872485, 46969.53030469651, 47661.28508072312, 47939.520604793506, 47975.5202447985, 48032.27951580659, 7725.304722574995, 7741.458097933316, 7876.330511906552, 7968.721094761656, 8112.553809540532, 11032.558697652325, 11585.189036767346, 11803.763924721286, 12077.033837293171, 12421.627351179479, 9964.601415943282, 10167.491625418847, 10183.33808302458, 10942.671719848417, 11006.614768483065, 10322.690319290434, 10615.416152111708, 10643.361398315816, 10644.946775266384, 10738.463076846283, 10411.323263987817, 10497.580096796042, 10586.364818110938, 10652.201084918817, 10969.616063437741, 7605.923940760743, 7615.961920190363, 7672.961635191788, 7814.4529882907655, 7828.960855195915, 10232.897671023493, 10480.109190718875, 10594.947025264824, 10748.83876741865, 10785.89214107849], "value": 8431.708841977246, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.0322967463435, 1487.620656732533, 1547.326912792935, 1709.1710520397612, 1886.1323791056113, 6860.656967151641, 7720.30517253447, 7928.841423171533, 7941.205879412056, 8059.516429014263, 7830.334421574159, 7985.840283194332, 8003.199680031994, 8009.0, 8010.0, 2294.254367330616, 2323.6166032604683, 2327.511222643247, 2327.895244713992, 2329.231353653299, 2256.402053455827, 2273.4771002669395, 2306.1006207579057, 2310.214527060799, 2313.1672597864754, 2312.4681323295654, 2316.0967222783042, 2330.720313562375, 2352.376620195641, 2356.45801465663, 2255.763144869798, 2291.8166546676307, 2296.4144143243534, 2297.873616951067, 2301.2520930697506, 2243.9102435902546, 2250.0999600159903, 2257.2325409360897, 2257.3905045637584, 2259.0173274625427, 7831.608419578998, 7897.723579674741, 7909.129995700526, 7971.521708697497, 8112.067112282104, 4621.884452888671, 4713.717176969393, 4715.056988602293, 4720.669553131283, 4723.36234608327, 2969.3170570768843, 3000.369922316316, 3073.386472102142, 3100.829454380008, 3117.4700300948894, 912.5209265135791, 921.7326176507303, 921.7326975177191, 923.3167456082517, 926.3284119013729, 10945.671629851116, 11119.054880335181, 11133.165012624086, 11148.665540033811, 11156.944215278872, 4160.791960401968, 4212.641925436311, 4295.6778241632, 4306.633936115465, 4359.931816704928, 8778.868316975251, 8828.0, 8831.60257788401, 8837.779055523535, 8841.557922103997, 4684.695494792828, 4694.248920172756, 4713.316569097462, 4722.268048452495, 4723.527647235249, 3674.5590529136675, 4228.577142285808, 4305.84929527465, 4349.586789255079, 4355.5208927018, 3571.9285614288087, 3572.4284114541547, 3651.397519409321, 3673.9632603674154, 3691.3909204981414, 1079.3631757263252, 1092.2900114925203, 1099.1701265544623, 1101.545959333688, 1106.253279036647, 733.9926600734032, 779.7387875061804, 859.6690274244336, 881.6466723579275, 886.5655828644047, 5054.519820617035, 5118.718470484159, 5165.225216217522, 5301.390340110936, 5466.590005749585, 872.4459967920416, 921.1939552891221, 921.2307723051193, 929.7768535551554, 931.0875342164717, 3544.7518673692593, 3591.874284400086, 3651.3427583034777, 3655.707543396575, 3662.4140137578393, 9488.146066854017, 9885.654002110035, 9950.402975821475, 9963.302568820258, 9984.950075249722, 9477.099675530813, 9662.516874156265, 9760.56077476501, 9784.315097943218, 9786.657466988621, 7335.706571737071, 7379.520331178456, 7471.953926450301, 7481.513701609378, 7486.737964171228, 5121.564667003347, 5125.692458452505, 5246.239295302236, 5312.256284120149, 5364.597655175956, 9034.774130646787, 9092.545372731469, 9105.590248438968, 9175.77060573491, 9187.540622968958, 8612.526311052952, 8933.240674542476, 8947.463152210888, 9165.908340916505, 9177.908220917705, 7064.0, 7296.379807716191, 7307.232740562377, 7314.232005639331, 7314.305141011595, 7559.357454616419, 7614.2766437188375, 7627.656755445907, 7639.121501027453, 7898.763037108896, 7185.712571497291, 7222.205557388788, 7254.0, 7321.63391830417, 7327.487075904635, 6991.230964593992, 7071.82320441993, 7092.432605391659, 7169.641517924188, 7179.892301615376, 6492.188476440445, 6524.2823289439075, 6545.280019197789, 6563.146790917151, 6563.474922006323, 4432.312991486196, 4447.866564003085, 4548.931766023578, 4566.680332376702, 4597.609203217764, 4611.6310695144975, 4672.065586882569, 4693.178693728652, 4694.201985662514, 4731.952680473151], "value": 4236.478331068566, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.1470906488882, 322.4872452800048, 330.23385745071437, 331.2282382049824, 332.19608547315494, 4880.511948805118, 4889.511048895109, 4934.555889969902, 4967.701937883729, 4974.8507544773665, 4591.862244132673, 4678.766061696919, 4703.365045718827, 4715.198416269234, 4830.44449888263, 3717.2008018276065, 3759.0, 3771.076086358832, 3864.80675966202, 3887.7451078374474, 3858.054776579742, 3858.5562660294004, 3866.4586957825927, 3874.0, 3877.573466918651, 3603.6396360364015, 3799.8480060797533, 3848.3842585186376, 3867.1878905429894, 3869.6323849234277, 3754.812259387047, 3760.3419401604883, 3773.8301776419853, 3786.848526058968, 3806.371948628473, 3332.4506752334482, 3340.9331813363838, 3347.0, 3349.531065650811, 3370.561826962504, 4654.301854721785, 4655.673133157034, 4664.48404268614, 4675.696079754805, 4725.243960966229, 4229.471316085489, 4351.0, 4408.20652282589, 4434.556544345572, 4439.0, 5073.771680274396, 5084.669496482754, 5088.643794934356, 5096.949030509684, 5112.41207261166, 3788.5074940257864, 3789.77261364319, 3792.412176112707, 3798.30975216029, 3811.161544460242, 5008.749562521896, 5229.398619158807, 5246.239295302198, 5260.579153667697, 5328.653637513588, 3726.677029654488, 3743.0642339415144, 3757.830897609614, 3762.473253744478, 3770.886873393748, 3762.887113386603, 3804.6956243500454, 3825.923481530354, 3834.2331533693373, 3840.327942610032, 4236.173946080485, 4332.4801023877335, 4359.847405340798, 4362.8909277267685, 4384.4519435070615, 5001.399832020108, 5013.674111182761, 5046.318746969186, 5052.34319538465, 5101.7704203310195, 1282.8262140141853, 1317.22942078883, 1328.7276108397755, 1332.9800052999212, 1341.6645838540364, 935.9859602105973, 946.6781294359951, 966.2704657983178, 974.3082411487923, 984.2913102566141, 4454.331850222493, 4463.598276155161, 4473.351364052194, 4476.283794592915, 4483.215437298525, 4990.650654454226, 5125.743712814345, 5144.405234377355, 5155.664881782672, 5175.81687262331, 4382.603482301242, 4432.423784907941, 4437.2251099560435, 4449.28811390176, 4464.2187617166865, 2725.6456660634385, 2734.7128551502205, 2744.6294750208867, 2751.2984189032068, 2752.1698070783104, 4700.542831722177, 4849.2968519565175, 4865.513448655106, 4885.6335774817035, 4906.2640603908985, 5248.895022099613, 5272.551833094153, 5287.947120528823, 5303.257543943851, 5327.227552004938, 4881.585065269492, 4893.339399181065, 4927.384076990376, 4944.802207911715, 4954.455009948902, 4916.566299681681, 5267.71027593486, 5272.130098533699, 5275.657082289639, 5278.709670968134, 5137.691738495702, 5152.845414637567, 5154.819581314636, 5171.767270472913, 5178.6374953752875, 4775.785089670903, 5000.799968001239, 5001.224810154432, 5012.147934851157, 5017.924731128963, 3731.085883958339, 3746.4380342947684, 3758.8872333830027, 3779.5653499847867, 3788.412796016622, 5237.580993520585, 5237.6954140178195, 5246.0, 5250.684958902478, 5256.526912577886, 4900.41810906789, 5043.344365232496, 5085.728567858036, 5101.642884998015, 5108.897822043613, 4861.732604706775, 4950.752462376795, 5050.611081952493, 5054.826424637294, 5061.645684802029, 4949.802007919643, 4951.480094590016, 4985.277134815504, 4986.451490336132, 4994.450610432777, 5043.142665746906, 5047.369078865142, 5079.72061536619, 5080.212567052113, 5094.694318340911, 4864.221724524201, 5006.374203224597, 5022.547970682656, 5050.207176452306, 5062.139436295765], "value": 3857.6676342992487, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.4966421490246, 809.538563019079, 809.8623234050211, 813.8941937548118, 817.5094943034177, 11221.775564488706, 12050.75898482031, 12127.272363658187, 12169.939150304235, 12247.510099596027, 12061.819072713917, 12464.93767531161, 12762.298073605947, 12835.358232088405, 12857.292848893307, 864.9264812490945, 868.2316150207081, 883.2360008592585, 883.7569668341193, 883.9779005524881, 873.1181506678237, 877.7279043496504, 878.1613559051091, 878.2008372381134, 878.2754227762122, 924.6948506992676, 926.6617684545126, 927.6474939522972, 928.3594319919268, 928.6053427293373, 903.0427746588605, 909.0409617853162, 910.9453432794054, 912.4388501071859, 913.5797533134765, 669.1568623534382, 671.8622682350107, 675.2909445082644, 676.6041865508683, 678.3318431345122, 10846.511906964126, 11003.22977391583, 11005.284656497302, 11050.668479945534, 11427.771444571063, 9718.61125554977, 9899.45552994585, 9902.405855648683, 9959.452230127341, 10274.383536987805, 9096.954515227448, 9277.61509720095, 9297.349185557014, 9347.76630584234, 9544.809103817954, 3208.2781374190854, 3254.4955531892356, 3305.8842940496966, 3355.1612096975755, 3358.798472091658, 44062.77968610136, 44078.33882491765, 44109.33835992463, 44773.776131119135, 46907.530924690655, 7128.4539210988, 7139.071920650283, 7311.8537629248185, 7366.742164024234, 7587.924120758722, 13042.804357934638, 13056.0, 13068.411921463558, 13077.542286019943, 13078.476860925646, 6600.2409722882485, 6629.834254143589, 6669.233038200572, 6772.492063095287, 6859.828504287333, 3589.3180295743723, 3626.1841085755495, 3634.945475817865, 3648.1062139775395, 3696.094456858033, 2861.3275880168144, 2947.0864032149675, 2955.674875763664, 2957.7781666374676, 2958.5857979882835, 511.94283803944734, 514.5112143463724, 524.046235850748, 524.9737513124328, 528.0626887275117, 484.95392937670863, 529.9178627312773, 562.6511562831071, 563.1665135599336, 654.3652656922777, 8051.718189863327, 8150.836983260301, 8169.0, 8202.917970820336, 8326.64936307157, 506.43026595080534, 565.4430386069718, 602.4246844263718, 609.7103875659116, 620.8758248350348, 3546.3793836078585, 3701.648343407372, 4149.91700166001, 4155.480564929384, 4175.498940127143, 52114.0, 52770.47229527732, 54115.0, 54172.0, 54191.0, 36875.631243687756, 38054.42918356227, 49272.75363623231, 49422.50577494251, 49792.75103624531, 40116.0, 46116.7694161527, 46341.30488042682, 46481.76759116183, 47337.763311183226, 8559.358048146289, 8633.568321584022, 8771.780705482413, 8802.867956980523, 9028.368014239202, 30043.549346760254, 30687.0, 31156.22109447282, 31415.84292078525, 31701.36597268088, 38533.22933541369, 39617.0, 39746.40380394354, 40021.39967900542, 40202.0, 10957.83563246536, 11136.721581960515, 11201.271917325348, 11320.37737924422, 11851.05191584654, 12107.455164517794, 12196.81704774447, 12227.75544489123, 12330.691732706755, 13102.279374634487, 11569.826452603049, 11892.167548271538, 12207.328596927255, 12288.938555307166, 12451.937740311241, 11076.11391088727, 11476.0, 11700.2979821211, 11753.941230293794, 11999.40002999864, 10469.476526173508, 10780.514876830404, 11027.117830573374, 11121.944390277997, 11170.27393219438, 9575.99308722373, 9774.169195618451, 9840.950795245977, 10060.144887684628, 10109.0, 8438.915610844058, 8595.65617375323, 8813.823723525365, 8814.779630509289, 8824.735257942271], "value": 5923.551019927334, "unit": "score"}], "name": "dromaeo_css", "value": 6449.690875434811, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 113.0, 113.0, 113.0, 115.0, 115.0, 112.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 136.0, 164.0, 227.0, 134.0, 165.0, 152.0, 130.0, 194.0, 131.0], "value": 159.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 162.0, 161.0, 160.0, 161.0, 161.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 154.0, 78.0, 75.0, 76.0, 75.0, 138.0, 78.0], "value": 90.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 150.0, 93.0, 92.0, 92.0, 124.0, 97.0, 92.0, 125.0], "value": 105.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 149.0, 148.0, 147.0, 147.0, 148.0, 146.0, 147.0, 147.0, 145.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 64.0, 65.0, 65.0, 64.0, 63.0, 118.0, 63.0, 63.0], "value": 69.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 63.0, 64.0, 64.0, 64.0, 64.0, 63.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 82.0, 81.0, 83.0, 84.0, 84.0, 84.0, 85.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 139.0, 145.0, 131.0, 129.0, 129.0, 128.0, 141.0, 198.0, 133.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 169.0, 170.0, 171.0, 169.0, 171.0, 172.0, 174.0, 180.0, 168.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0, 67.0, 64.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1583.2, "alertThreshold": 2.0}]} 11:13:04 INFO - Return code: 0 11:13:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:13:04 ERROR - # TBPL SUCCESS # 11:13:04 INFO - Running post-action listener: _resource_record_post_action 11:13:04 INFO - ##### 11:13:04 INFO - ##### Finished run-tests step (success) 11:13:04 INFO - ##### 11:13:04 INFO - Running post-run listener: _resource_record_post_run 11:13:04 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 527233024; Read time: 12; Write time: 870188 11:13:04 INFO - TinderboxPrint: CPU usage
13.1% 11:13:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 11:13:04 INFO - TinderboxPrint: I/O write bytes / time
527,233,024 / 870,188 11:13:04 INFO - TinderboxPrint: CPU idle
8,340.9 (86.9%) 11:13:04 INFO - TinderboxPrint: CPU user
1,211.8 (12.6%) 11:13:04 INFO - TinderboxPrint: Swap in / out
0 / 0 11:13:04 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 236199936; Read time: 0; Write time: 468732 11:13:04 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291033088; Read time: 12; Write time: 401456 11:13:04 INFO - Running post-run listener: _upload_blobber_files 11:13:04 INFO - Blob upload gear active. 11:13:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:13:04 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 11:13:04 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'] 11:13:04 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 11:13:05 INFO - (blobuploader) - INFO - Open directory for files ... 11:13:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:13:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:13:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:06 INFO - (blobuploader) - INFO - Done attempting. 11:13:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:13:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:13:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:10 INFO - (blobuploader) - INFO - Done attempting. 11:13:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:13:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:13:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:11 INFO - (blobuploader) - INFO - Done attempting. 11:13:11 INFO - (blobuploader) - INFO - Iteration through files over. 11:13:11 INFO - Return code: 0 11:13:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:13:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:13:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a0f26c47659499b6810ce3562d80dde2d7c5dc0558da16d8be5f3322c8b4a1da58559ac1b02c354812bce39d8ef27eec2c9a50a73aef5a2e6d110b758cfa4bd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a6a3a9db6d885919585993a5a9cf2c055b38b36e72a648cdc10eb19552217096b9da70b3ca566619fc95a49a78727db19dd9815e4412e797c29b2ad33e16f88d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f698eeb03be37d9316e9ac357ea4f10731bfbbcc2712e3267a575c072c3ae4f47e9df9a0de76440003e92ee7265fbc14dd77e23421794c441d7af5987f0121e2"} 11:13:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:13:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:13:11 INFO - Contents: 11:13:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a0f26c47659499b6810ce3562d80dde2d7c5dc0558da16d8be5f3322c8b4a1da58559ac1b02c354812bce39d8ef27eec2c9a50a73aef5a2e6d110b758cfa4bd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a6a3a9db6d885919585993a5a9cf2c055b38b36e72a648cdc10eb19552217096b9da70b3ca566619fc95a49a78727db19dd9815e4412e797c29b2ad33e16f88d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f698eeb03be37d9316e9ac357ea4f10731bfbbcc2712e3267a575c072c3ae4f47e9df9a0de76440003e92ee7265fbc14dd77e23421794c441d7af5987f0121e2"} 11:13:11 INFO - Running post-run listener: copy_logs_to_upload_dir 11:13:11 INFO - Copying logs to upload dir... 11:13:11 INFO - mkdir: /builds/slave/test/build/upload/logs 11:13:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.383981 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-16 11:13:10.611943) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 11:13:10.615158) ========= 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=aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722 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/a0f26c47659499b6810ce3562d80dde2d7c5dc0558da16d8be5f3322c8b4a1da58559ac1b02c354812bce39d8ef27eec2c9a50a73aef5a2e6d110b758cfa4bd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a6a3a9db6d885919585993a5a9cf2c055b38b36e72a648cdc10eb19552217096b9da70b3ca566619fc95a49a78727db19dd9815e4412e797c29b2ad33e16f88d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f698eeb03be37d9316e9ac357ea4f10731bfbbcc2712e3267a575c072c3ae4f47e9df9a0de76440003e92ee7265fbc14dd77e23421794c441d7af5987f0121e2"} build_url:https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011122 build_url: 'https://queue.taskcluster.net/v1/task/d_UybYtPSJyKInBKrbWH_w/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/a0f26c47659499b6810ce3562d80dde2d7c5dc0558da16d8be5f3322c8b4a1da58559ac1b02c354812bce39d8ef27eec2c9a50a73aef5a2e6d110b758cfa4bd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a6a3a9db6d885919585993a5a9cf2c055b38b36e72a648cdc10eb19552217096b9da70b3ca566619fc95a49a78727db19dd9815e4412e797c29b2ad33e16f88d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f698eeb03be37d9316e9ac357ea4f10731bfbbcc2712e3267a575c072c3ae4f47e9df9a0de76440003e92ee7265fbc14dd77e23421794c441d7af5987f0121e2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 11:13:10.638780) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:13:10.639094) ========= 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=aa52b8aba932b19cc50b5275000001de-1463421091.355489-196707722 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 11:13:10.722376) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 11:13:10.722742) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 11:13:10.723059) ========= ========= Total master_lag: 0.17 =========