builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1463638645.98 results: success (0) buildid: 20160518203028 builduid: a7a0ccee54854a178af8d048424ec083 revision: eb54f97ddfd410d426df5989a8ddd962d97bee44 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:25.982710) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:25.983152) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:25.983430) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:26.004341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:26.004675) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-18 23:17:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-05-18 23:17:26 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124640 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:26.143326) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:26.146679) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020785 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:26.196848) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 23:17:26.197228) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eb54f97ddfd410d426df5989a8ddd962d97bee44 --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 eb54f97ddfd410d426df5989a8ddd962d97bee44 --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=934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-18 23:17:26,401 truncating revision to first 12 chars 2016-05-18 23:17:26,402 Setting DEBUG logging. 2016-05-18 23:17:26,402 attempt 1/10 2016-05-18 23:17:26,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eb54f97ddfd4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 23:17:27,260 unpacking tar archive at: fx-team-eb54f97ddfd4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.157105 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 23:17:27.370619) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:27.370918) ========= script_repo_revision: eb54f97ddfd410d426df5989a8ddd962d97bee44 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:27.371309) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:27.371589) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 23:17:27.382580) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-05-18 23:17:27.382864) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:17:27 INFO - MultiFileLogger online at 20160518 23:17:27 in /builds/slave/test 23:17:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:17:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:17:27 INFO - {'append_to_log': False, 23:17:27 INFO - 'base_work_dir': '/builds/slave/test', 23:17:27 INFO - 'blob_upload_branch': 'Fx-Team', 23:17:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:17:27 INFO - 'branch': 'Fx-Team', 23:17:27 INFO - 'buildbot_json_path': 'buildprops.json', 23:17:27 INFO - 'config_files': ('talos/linux_config.py',), 23:17:27 INFO - 'default_actions': ('clobber', 23:17:27 INFO - 'read-buildbot-config', 23:17:27 INFO - 'download-and-extract', 23:17:27 INFO - 'populate-webroot', 23:17:27 INFO - 'create-virtualenv', 23:17:27 INFO - 'install', 23:17:27 INFO - 'run-tests'), 23:17:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:17:27 INFO - 'download_minidump_stackwalk': True, 23:17:27 INFO - 'download_symbols': 'ondemand', 23:17:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:17:27 INFO - 'tooltool.py': '/tools/tooltool.py', 23:17:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:17:27 INFO - '/tools/misc-python/virtualenv.py')}, 23:17:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:17:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:17:27 INFO - 'installer_path': 'installer.exe', 23:17:27 INFO - 'log_level': 'info', 23:17:27 INFO - 'log_name': 'talos', 23:17:27 INFO - 'log_to_console': True, 23:17:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:17:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:17:27 INFO - 'opt_config_files': (), 23:17:27 INFO - 'pip_index': False, 23:17:27 INFO - 'sps_profile': False, 23:17:27 INFO - 'sps_profile_interval': 0, 23:17:27 INFO - 'suite': 'dromaeojs', 23:17:27 INFO - 'system_bits': '32', 23:17:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:17:27 INFO - 'title': 'talos-linux64-ix-057', 23:17:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:17:27 INFO - 'use_talos_json': True, 23:17:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:17:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:17:27 INFO - 'work_dir': 'build'} 23:17:27 INFO - ##### 23:17:27 INFO - ##### Running clobber step. 23:17:27 INFO - ##### 23:17:27 INFO - Running pre-action listener: _resource_record_pre_action 23:17:27 INFO - Running main action method: clobber 23:17:27 INFO - rmtree: /builds/slave/test/build 23:17:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:17:28 INFO - Running post-action listener: _resource_record_post_action 23:17:28 INFO - ##### 23:17:28 INFO - ##### Finished clobber step (success) 23:17:28 INFO - ##### 23:17:28 INFO - ##### 23:17:28 INFO - ##### Running read-buildbot-config step. 23:17:28 INFO - ##### 23:17:28 INFO - Running pre-action listener: _resource_record_pre_action 23:17:28 INFO - Running main action method: read_buildbot_config 23:17:28 INFO - Using buildbot properties: 23:17:28 INFO - { 23:17:28 INFO - "project": "", 23:17:28 INFO - "product": "firefox", 23:17:28 INFO - "script_repo_revision": "production", 23:17:28 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:17:28 INFO - "repository": "", 23:17:28 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:17:28 INFO - "buildid": "20160518203028", 23:17:28 INFO - "pgo_build": "True", 23:17:28 INFO - "basedir": "/builds/slave/test", 23:17:28 INFO - "buildnumber": 698, 23:17:28 INFO - "slavename": "talos-linux64-ix-057", 23:17:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:17:28 INFO - "platform": "ubuntu64_hw", 23:17:28 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:17:28 INFO - "branch": "fx-team", 23:17:28 INFO - "repo_path": "integration/fx-team", 23:17:28 INFO - "revision": "eb54f97ddfd410d426df5989a8ddd962d97bee44", 23:17:28 INFO - "stage_platform": "linux64-pgo", 23:17:28 INFO - "builduid": "a7a0ccee54854a178af8d048424ec083", 23:17:28 INFO - "slavebuilddir": "test" 23:17:28 INFO - } 23:17:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:17:28 INFO - Running post-action listener: _resource_record_post_action 23:17:28 INFO - ##### 23:17:28 INFO - ##### Finished read-buildbot-config step (success) 23:17:28 INFO - ##### 23:17:28 INFO - ##### 23:17:28 INFO - ##### Running download-and-extract step. 23:17:28 INFO - ##### 23:17:28 INFO - Running pre-action listener: _resource_record_pre_action 23:17:28 INFO - Running main action method: download_and_extract 23:17:28 INFO - mkdir: /builds/slave/test/build/tests 23:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:28 INFO - https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:17:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:29 INFO - Downloaded 1361 bytes. 23:17:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:29 INFO - Using the following test package requirements: 23:17:29 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:17:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:17:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:29 INFO - u'jsshell-linux-x86_64.zip'], 23:17:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:17:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:17:29 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:17:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:17:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:17:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:17: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')]} 23:17:29 INFO - https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:17:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:36 INFO - Downloaded 24386200 bytes. 23:17:36 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'] 23:17:36 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 23:17:37 INFO - Return code: 0 23:17:37 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 23:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:37 INFO - https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:17:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:51 INFO - Downloaded 24386200 bytes. 23:17:51 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'] 23:17:51 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 23:17:52 INFO - Return code: 0 23:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:52 INFO - https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:17:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:17:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:17:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:53 INFO - Downloaded 10840194 bytes. 23:17:53 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'] 23:17:53 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 23:17:53 INFO - Return code: 0 23:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:53 INFO - https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:17:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:17:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:17:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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 23:17:58 INFO - Downloaded 58423860 bytes. 23:17:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:17:58 INFO - mkdir: /builds/slave/test/properties 23:17:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:17:58 INFO - Writing to file /builds/slave/test/properties/build_url 23:17:58 INFO - Contents: 23:17:58 INFO - build_url:https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:17:59 INFO - Running post-action listener: _resource_record_post_action 23:17:59 INFO - Running post-action listener: set_extra_try_arguments 23:17:59 INFO - ##### 23:17:59 INFO - ##### Finished download-and-extract step (success) 23:17:59 INFO - ##### 23:17:59 INFO - ##### 23:17:59 INFO - ##### Running populate-webroot step. 23:17:59 INFO - ##### 23:17:59 INFO - Running pre-action listener: _resource_record_pre_action 23:17:59 INFO - Running main action method: populate_webroot 23:17:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:17:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:17:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:17:59 INFO - '--tppagecycles', 23:17:59 INFO - '7'], 23:17:59 INFO - 'tests': ['tsvgm']}}, 23:17:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:17:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:17:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:17:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:17:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:59 INFO - 'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:17:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:17:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:59 INFO - 'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['damp', 'tps']}, 23:17:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:59 INFO - 'tests': ['damp', 'tps']}, 23:17:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['dromaeo_dom']}, 23:17:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:17:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['a11yr', 23:17:59 INFO - 'ts_paint', 23:17:59 INFO - 'tpaint', 23:17:59 INFO - 'sessionrestore', 23:17:59 INFO - 'sessionrestore_no_auto_restore', 23:17:59 INFO - 'tabpaint']}, 23:17:59 INFO - 'other-e10s': {'tests': ['a11yr', 23:17:59 INFO - 'ts_paint', 23:17:59 INFO - 'tpaint', 23:17:59 INFO - 'sessionrestore', 23:17:59 INFO - 'sessionrestore_no_auto_restore', 23:17:59 INFO - 'tabpaint']}, 23:17:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:17:59 INFO - 'ts_paint', 23:17:59 INFO - 'tpaint', 23:17:59 INFO - 'sessionrestore', 23:17:59 INFO - 'sessionrestore_no_auto_restore', 23:17:59 INFO - 'tabpaint']}, 23:17:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:17:59 INFO - 'ts_paint', 23:17:59 INFO - 'tpaint', 23:17:59 INFO - 'sessionrestore', 23:17:59 INFO - 'sessionrestore_no_auto_restore', 23:17:59 INFO - 'tabpaint']}, 23:17:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['a11yr', 23:17:59 INFO - 'ts_paint', 23:17:59 INFO - 'tpaint', 23:17:59 INFO - 'sessionrestore', 23:17:59 INFO - 'sessionrestore_no_auto_restore', 23:17:59 INFO - 'tabpaint']}, 23:17:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['a11yr', 23:17:59 INFO - 'ts_paint', 23:17:59 INFO - 'tpaint', 23:17:59 INFO - 'sessionrestore', 23:17:59 INFO - 'sessionrestore_no_auto_restore', 23:17:59 INFO - 'tabpaint']}, 23:17:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['tsvgx', 23:17:59 INFO - 'tsvgr_opacity', 23:17:59 INFO - 'tart', 23:17:59 INFO - 'tscrollx', 23:17:59 INFO - 'cart']}, 23:17:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:17:59 INFO - 'tsvgr_opacity', 23:17:59 INFO - 'tart', 23:17:59 INFO - 'tscrollx', 23:17:59 INFO - 'cart']}, 23:17:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:59 INFO - 'talos_options': ['--disable-e10s'], 23:17:59 INFO - 'tests': ['tp5o']}, 23:17:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:59 INFO - 'tests': ['tp5o']}, 23:17:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:59 INFO - 'talos_options': ['--disable-e10s', 23:17:59 INFO - '--xperf_path', 23:17:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:17:59 INFO - 'tests': ['tp5n']}, 23:17:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:59 INFO - 'talos_options': ['--xperf_path', 23:17:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:17:59 INFO - 'tests': ['tp5n']}}, 23:17:59 INFO - 'talos.zip': {'path': '', 23:17:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:17:59 INFO - Running post-action listener: _resource_record_post_action 23:17:59 INFO - ##### 23:17:59 INFO - ##### Finished populate-webroot step (success) 23:17:59 INFO - ##### 23:17:59 INFO - ##### 23:17:59 INFO - ##### Running create-virtualenv step. 23:17:59 INFO - ##### 23:17:59 INFO - Running pre-action listener: _resource_record_pre_action 23:17:59 INFO - Running main action method: create_virtualenv 23:17:59 INFO - Creating virtualenv /builds/slave/test/build/venv 23:17:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:17:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:18:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:18:00 INFO - Using real prefix '/usr' 23:18:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:18:00 INFO - Installing distribute.............................................................................................................................................................................................done. 23:18:02 INFO - Installing pip.................done. 23:18:02 INFO - Return code: 0 23:18:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:02 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 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:18:02 INFO - Using env: {'DISPLAY': ':0', 23:18:02 INFO - 'HOME': '/home/cltbld', 23:18:02 INFO - 'LANG': 'en_US.UTF-8', 23:18:02 INFO - 'LANGUAGE': 'en_US:en', 23:18:02 INFO - 'LOGNAME': 'cltbld', 23:18:02 INFO - 'MAIL': '/var/mail/cltbld', 23:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:02 INFO - 'MOZ_NO_REMOTE': '1', 23:18:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:02 INFO - 'NO_EM_RESTART': '1', 23:18:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:02 INFO - 'PWD': '/builds/slave/test', 23:18:02 INFO - 'SHELL': '/bin/bash', 23:18:02 INFO - 'SHLVL': '1', 23:18:02 INFO - 'TERM': 'linux', 23:18:02 INFO - 'TMOUT': '86400', 23:18:02 INFO - 'USER': 'cltbld', 23:18:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:02 INFO - '_': '/tools/buildbot/bin/python'} 23:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:18:02 INFO - Cleaning up... 23:18:02 INFO - Return code: 0 23:18:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:02 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 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:18:02 INFO - Using env: {'DISPLAY': ':0', 23:18:02 INFO - 'HOME': '/home/cltbld', 23:18:02 INFO - 'LANG': 'en_US.UTF-8', 23:18:02 INFO - 'LANGUAGE': 'en_US:en', 23:18:02 INFO - 'LOGNAME': 'cltbld', 23:18:02 INFO - 'MAIL': '/var/mail/cltbld', 23:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:02 INFO - 'MOZ_NO_REMOTE': '1', 23:18:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:02 INFO - 'NO_EM_RESTART': '1', 23:18:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:02 INFO - 'PWD': '/builds/slave/test', 23:18:02 INFO - 'SHELL': '/bin/bash', 23:18:02 INFO - 'SHLVL': '1', 23:18:02 INFO - 'TERM': 'linux', 23:18:02 INFO - 'TMOUT': '86400', 23:18:02 INFO - 'USER': 'cltbld', 23:18:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:02 INFO - '_': '/tools/buildbot/bin/python'} 23:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:02 INFO - Downloading/unpacking psutil>=3.1.1 23:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:18:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:18:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:18:05 INFO - Installing collected packages: psutil 23:18:05 INFO - Running setup.py install for psutil 23:18:05 INFO - building 'psutil._psutil_linux' extension 23:18:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 23:18:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:18:05 INFO - building 'psutil._psutil_posix' extension 23:18:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:18:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:18:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:18:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:18:05 INFO - Successfully installed psutil 23:18:05 INFO - Cleaning up... 23:18:05 INFO - Return code: 0 23:18:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:18:05 INFO - Using env: {'DISPLAY': ':0', 23:18:05 INFO - 'HOME': '/home/cltbld', 23:18:05 INFO - 'LANG': 'en_US.UTF-8', 23:18:05 INFO - 'LANGUAGE': 'en_US:en', 23:18:05 INFO - 'LOGNAME': 'cltbld', 23:18:05 INFO - 'MAIL': '/var/mail/cltbld', 23:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:05 INFO - 'MOZ_NO_REMOTE': '1', 23:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:05 INFO - 'NO_EM_RESTART': '1', 23:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:05 INFO - 'PWD': '/builds/slave/test', 23:18:05 INFO - 'SHELL': '/bin/bash', 23:18:05 INFO - 'SHLVL': '1', 23:18:05 INFO - 'TERM': 'linux', 23:18:05 INFO - 'TMOUT': '86400', 23:18:05 INFO - 'USER': 'cltbld', 23:18:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:05 INFO - '_': '/tools/buildbot/bin/python'} 23:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:18:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:18:07 INFO - Installing collected packages: mozsystemmonitor 23:18:07 INFO - Running setup.py install for mozsystemmonitor 23:18:07 INFO - Successfully installed mozsystemmonitor 23:18:07 INFO - Cleaning up... 23:18:08 INFO - Return code: 0 23:18:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:18:08 INFO - Using env: {'DISPLAY': ':0', 23:18:08 INFO - 'HOME': '/home/cltbld', 23:18:08 INFO - 'LANG': 'en_US.UTF-8', 23:18:08 INFO - 'LANGUAGE': 'en_US:en', 23:18:08 INFO - 'LOGNAME': 'cltbld', 23:18:08 INFO - 'MAIL': '/var/mail/cltbld', 23:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:08 INFO - 'MOZ_NO_REMOTE': '1', 23:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:08 INFO - 'NO_EM_RESTART': '1', 23:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:08 INFO - 'PWD': '/builds/slave/test', 23:18:08 INFO - 'SHELL': '/bin/bash', 23:18:08 INFO - 'SHLVL': '1', 23:18:08 INFO - 'TERM': 'linux', 23:18:08 INFO - 'TMOUT': '86400', 23:18:08 INFO - 'USER': 'cltbld', 23:18:08 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:08 INFO - '_': '/tools/buildbot/bin/python'} 23:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:08 INFO - Downloading/unpacking blobuploader==1.2.4 23:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:10 INFO - Downloading blobuploader-1.2.4.tar.gz 23:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:18:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:18:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:10 INFO - Downloading docopt-0.6.1.tar.gz 23:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:18:10 INFO - Installing collected packages: blobuploader, requests, docopt 23:18:10 INFO - Running setup.py install for blobuploader 23:18:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:18:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:18:11 INFO - Running setup.py install for requests 23:18:11 INFO - Running setup.py install for docopt 23:18:11 INFO - Successfully installed blobuploader requests docopt 23:18:11 INFO - Cleaning up... 23:18:11 INFO - Return code: 0 23:18:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:11 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 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:18:11 INFO - Using env: {'DISPLAY': ':0', 23:18:11 INFO - 'HOME': '/home/cltbld', 23:18:11 INFO - 'LANG': 'en_US.UTF-8', 23:18:11 INFO - 'LANGUAGE': 'en_US:en', 23:18:11 INFO - 'LOGNAME': 'cltbld', 23:18:11 INFO - 'MAIL': '/var/mail/cltbld', 23:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:11 INFO - 'MOZ_NO_REMOTE': '1', 23:18:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:11 INFO - 'NO_EM_RESTART': '1', 23:18:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:11 INFO - 'PWD': '/builds/slave/test', 23:18:11 INFO - 'SHELL': '/bin/bash', 23:18:11 INFO - 'SHLVL': '1', 23:18:11 INFO - 'TERM': 'linux', 23:18:11 INFO - 'TMOUT': '86400', 23:18:11 INFO - 'USER': 'cltbld', 23:18:11 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:11 INFO - '_': '/tools/buildbot/bin/python'} 23:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:18:11 INFO - Running setup.py (path:/tmp/pip-xhCd20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:18:11 INFO - Running setup.py (path:/tmp/pip-hq5AXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:18:11 INFO - Running setup.py (path:/tmp/pip-Ow67ON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:18:11 INFO - Running setup.py (path:/tmp/pip-7hgI7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:18:12 INFO - Running setup.py (path:/tmp/pip-4MpV6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:18:12 INFO - Running setup.py (path:/tmp/pip-fDCBO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:18:12 INFO - Running setup.py (path:/tmp/pip-ZRZRXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:18:12 INFO - Running setup.py (path:/tmp/pip-qjXh1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:18:12 INFO - Running setup.py (path:/tmp/pip-csusX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:18:12 INFO - Running setup.py (path:/tmp/pip-DUjxjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:18:12 INFO - Running setup.py (path:/tmp/pip-dxU5DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:18:12 INFO - Running setup.py (path:/tmp/pip-G5auva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:18:12 INFO - Running setup.py (path:/tmp/pip-ZHmHCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:18:12 INFO - Running setup.py (path:/tmp/pip-6AcLXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:12 INFO - Running setup.py (path:/tmp/pip-ISzZAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:18:12 INFO - Running setup.py (path:/tmp/pip-PBGGgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:18:12 INFO - Running setup.py (path:/tmp/pip-tzM82G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:18:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:18:12 INFO - Running setup.py install for manifestparser 23:18:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Running setup.py install for mozcrash 23:18:13 INFO - Running setup.py install for mozdebug 23:18:13 INFO - Running setup.py install for mozdevice 23:18:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Running setup.py install for mozfile 23:18:13 INFO - Running setup.py install for mozhttpd 23:18:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Running setup.py install for mozinfo 23:18:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Running setup.py install for mozInstall 23:18:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Running setup.py install for mozleak 23:18:13 INFO - Running setup.py install for mozlog 23:18:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:18:13 INFO - Running setup.py install for moznetwork 23:18:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:18:14 INFO - Running setup.py install for mozprocess 23:18:14 INFO - Running setup.py install for mozprofile 23:18:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:18:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:18:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:18:14 INFO - Running setup.py install for mozrunner 23:18:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:18:14 INFO - Running setup.py install for mozscreenshot 23:18:14 INFO - Running setup.py install for moztest 23:18:14 INFO - Running setup.py install for mozversion 23:18:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:18:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:18:14 INFO - Cleaning up... 23:18:14 INFO - Return code: 0 23:18:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:14 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 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:18:14 INFO - Using env: {'DISPLAY': ':0', 23:18:14 INFO - 'HOME': '/home/cltbld', 23:18:14 INFO - 'LANG': 'en_US.UTF-8', 23:18:14 INFO - 'LANGUAGE': 'en_US:en', 23:18:14 INFO - 'LOGNAME': 'cltbld', 23:18:14 INFO - 'MAIL': '/var/mail/cltbld', 23:18:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:14 INFO - 'MOZ_NO_REMOTE': '1', 23:18:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:14 INFO - 'NO_EM_RESTART': '1', 23:18:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:14 INFO - 'PWD': '/builds/slave/test', 23:18:14 INFO - 'SHELL': '/bin/bash', 23:18:14 INFO - 'SHLVL': '1', 23:18:14 INFO - 'TERM': 'linux', 23:18:14 INFO - 'TMOUT': '86400', 23:18:14 INFO - 'USER': 'cltbld', 23:18:14 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:14 INFO - '_': '/tools/buildbot/bin/python'} 23:18:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:18:14 INFO - Running setup.py (path:/tmp/pip-HwQc3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:18:15 INFO - Running setup.py (path:/tmp/pip-uhRRnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:18:15 INFO - Running setup.py (path:/tmp/pip-IK_LCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:18:15 INFO - Running setup.py (path:/tmp/pip-rl0ygj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:18:15 INFO - Running setup.py (path:/tmp/pip-mLn8t_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:18:15 INFO - Running setup.py (path:/tmp/pip-52f0d0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:18:15 INFO - Running setup.py (path:/tmp/pip-GmskGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:18:15 INFO - Running setup.py (path:/tmp/pip-692tmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:18:15 INFO - Running setup.py (path:/tmp/pip-r6o_RQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:18:15 INFO - Running setup.py (path:/tmp/pip-bDWYSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:18:15 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)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:18:15 INFO - Running setup.py (path:/tmp/pip-9jo25k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:18:15 INFO - Running setup.py (path:/tmp/pip-sz6x6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:18:15 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)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:18:15 INFO - Running setup.py (path:/tmp/pip-93vkxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:18:15 INFO - Running setup.py (path:/tmp/pip-TmsMZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:18:15 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)) 23:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:15 INFO - Running setup.py (path:/tmp/pip-lcevEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:18:16 INFO - Running setup.py (path:/tmp/pip-QIdY45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:18:16 INFO - Running setup.py (path:/tmp/pip-ZsVuJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:18 INFO - Downloading blessings-1.6.tar.gz 23:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:18:18 INFO - Installing collected packages: blessings 23:18:18 INFO - Running setup.py install for blessings 23:18:18 INFO - Successfully installed blessings 23:18:18 INFO - Cleaning up... 23:18:18 INFO - Return code: 0 23:18:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:18 INFO - Reading from file tmpfile_stdout 23:18:18 INFO - Current package versions: 23:18:18 INFO - argparse == 1.2.1 23:18:18 INFO - blessings == 1.6 23:18:18 INFO - blobuploader == 1.2.4 23:18:18 INFO - docopt == 0.6.1 23:18:18 INFO - manifestparser == 1.1 23:18:18 INFO - mozInstall == 1.12 23:18:18 INFO - mozcrash == 0.17 23:18:18 INFO - mozdebug == 0.1 23:18:18 INFO - mozdevice == 0.48 23:18:18 INFO - mozfile == 1.2 23:18:18 INFO - mozhttpd == 0.7 23:18:18 INFO - mozinfo == 0.9 23:18:18 INFO - mozleak == 0.1 23:18:18 INFO - mozlog == 3.2 23:18:18 INFO - moznetwork == 0.27 23:18:18 INFO - mozprocess == 0.22 23:18:18 INFO - mozprofile == 0.28 23:18:18 INFO - mozrunner == 6.11 23:18:18 INFO - mozscreenshot == 0.1 23:18:18 INFO - mozsystemmonitor == 0.3 23:18:18 INFO - moztest == 0.7 23:18:18 INFO - mozversion == 1.4 23:18:18 INFO - psutil == 3.1.1 23:18:18 INFO - requests == 1.2.3 23:18:18 INFO - wsgiref == 0.1.2 23:18:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:18 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 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:18:18 INFO - Using env: {'DISPLAY': ':0', 23:18:18 INFO - 'HOME': '/home/cltbld', 23:18:18 INFO - 'LANG': 'en_US.UTF-8', 23:18:18 INFO - 'LANGUAGE': 'en_US:en', 23:18:18 INFO - 'LOGNAME': 'cltbld', 23:18:18 INFO - 'MAIL': '/var/mail/cltbld', 23:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:18 INFO - 'MOZ_NO_REMOTE': '1', 23:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:18 INFO - 'NO_EM_RESTART': '1', 23:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:18 INFO - 'PWD': '/builds/slave/test', 23:18:18 INFO - 'SHELL': '/bin/bash', 23:18:18 INFO - 'SHLVL': '1', 23:18:18 INFO - 'TERM': 'linux', 23:18:18 INFO - 'TMOUT': '86400', 23:18:18 INFO - 'USER': 'cltbld', 23:18:18 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:18 INFO - '_': '/tools/buildbot/bin/python'} 23:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:18:18 INFO - Cleaning up... 23:18:18 INFO - Return code: 0 23:18:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:18:18 INFO - Using env: {'DISPLAY': ':0', 23:18:18 INFO - 'HOME': '/home/cltbld', 23:18:18 INFO - 'LANG': 'en_US.UTF-8', 23:18:18 INFO - 'LANGUAGE': 'en_US:en', 23:18:18 INFO - 'LOGNAME': 'cltbld', 23:18:18 INFO - 'MAIL': '/var/mail/cltbld', 23:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:18 INFO - 'MOZ_NO_REMOTE': '1', 23:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:18 INFO - 'NO_EM_RESTART': '1', 23:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:18 INFO - 'PWD': '/builds/slave/test', 23:18:18 INFO - 'SHELL': '/bin/bash', 23:18:18 INFO - 'SHLVL': '1', 23:18:18 INFO - 'TERM': 'linux', 23:18:18 INFO - 'TMOUT': '86400', 23:18:18 INFO - 'USER': 'cltbld', 23:18:18 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:18 INFO - '_': '/tools/buildbot/bin/python'} 23:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:18 INFO - Downloading/unpacking jsonschema 23:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:18:20 INFO - Downloading/unpacking functools32 (from jsonschema) 23:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:21 INFO - Downloading functools32-3.2.3-2.tar.gz 23:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:18:21 INFO - warning: no files found matching '*.txt' 23:18:21 INFO - no previously-included directories found matching 'build' 23:18:21 INFO - no previously-included directories found matching 'dist' 23:18:21 INFO - no previously-included directories found matching '.git*' 23:18:21 INFO - Installing collected packages: jsonschema, functools32 23:18:21 INFO - Running setup.py install for functools32 23:18:21 INFO - warning: no files found matching '*.txt' 23:18:21 INFO - no previously-included directories found matching 'build' 23:18:21 INFO - no previously-included directories found matching 'dist' 23:18:21 INFO - no previously-included directories found matching '.git*' 23:18:21 INFO - Successfully installed jsonschema functools32 23:18:21 INFO - Cleaning up... 23:18:21 INFO - Return code: 0 23:18:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99ce2fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, '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': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:18:21 INFO - Using env: {'DISPLAY': ':0', 23:18:21 INFO - 'HOME': '/home/cltbld', 23:18:21 INFO - 'LANG': 'en_US.UTF-8', 23:18:21 INFO - 'LANGUAGE': 'en_US:en', 23:18:21 INFO - 'LOGNAME': 'cltbld', 23:18:21 INFO - 'MAIL': '/var/mail/cltbld', 23:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:21 INFO - 'MOZ_NO_REMOTE': '1', 23:18:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:21 INFO - 'NO_EM_RESTART': '1', 23:18:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:21 INFO - 'PWD': '/builds/slave/test', 23:18:21 INFO - 'SHELL': '/bin/bash', 23:18:21 INFO - 'SHLVL': '1', 23:18:21 INFO - 'TERM': 'linux', 23:18:21 INFO - 'TMOUT': '86400', 23:18:21 INFO - 'USER': 'cltbld', 23:18:21 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:21 INFO - '_': '/tools/buildbot/bin/python'} 23:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:21 INFO - Downloading/unpacking flake8 23:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:18:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:18:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:18:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:24 INFO - no previously-included directories found matching 'docs/_build' 23:18:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:18:24 INFO - Running setup.py install for pep8 23:18:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:24 INFO - no previously-included directories found matching 'docs/_build' 23:18:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:18:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:18:24 INFO - Cleaning up... 23:18:24 INFO - Return code: 0 23:18:24 INFO - Running post-action listener: _resource_record_post_action 23:18:24 INFO - Running post-action listener: _start_resource_monitoring 23:18:24 INFO - Starting resource monitoring. 23:18:24 INFO - ##### 23:18:24 INFO - ##### Finished create-virtualenv step (success) 23:18:24 INFO - ##### 23:18:24 INFO - ##### 23:18:24 INFO - ##### Running install step. 23:18:24 INFO - ##### 23:18:24 INFO - Running pre-action listener: _resource_record_pre_action 23:18:24 INFO - Running main action method: install 23:18:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:24 INFO - Reading from file tmpfile_stdout 23:18:24 INFO - Detecting whether we're running mozinstall >=1.0... 23:18:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:18:24 INFO - Reading from file tmpfile_stdout 23:18:24 INFO - Output received: 23:18:24 INFO - Usage: mozinstall [options] installer 23:18:24 INFO - Options: 23:18:24 INFO - -h, --help show this help message and exit 23:18:24 INFO - -d DEST, --destination=DEST 23:18:24 INFO - Directory to install application into. [default: 23:18:24 INFO - "/builds/slave/test"] 23:18:24 INFO - --app=APP Application being installed. [default: firefox] 23:18:24 INFO - mkdir: /builds/slave/test/build/application 23:18:24 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'] 23:18:24 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 23:18:41 INFO - Reading from file tmpfile_stdout 23:18:41 INFO - Output received: 23:18:41 INFO - /builds/slave/test/build/application/firefox/firefox 23:18:41 INFO - Running post-action listener: _resource_record_post_action 23:18:41 INFO - ##### 23:18:41 INFO - ##### Finished install step (success) 23:18:41 INFO - ##### 23:18:41 INFO - ##### 23:18:41 INFO - ##### Running run-tests step. 23:18:41 INFO - ##### 23:18:41 INFO - Running pre-action listener: _resource_record_pre_action 23:18:41 INFO - Running main action method: run_tests 23:18:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:18:41 INFO - Python 2.7.3 23:18:41 INFO - Return code: 0 23:18:41 INFO - grabbing minidump binary from tooltool 23:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:41 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 0x2a3f9c0>, '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 0x2a408c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a40d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:18:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:18:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 23:18:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:18:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:18:41 INFO - Return code: 0 23:18:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:18:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:18:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:18:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:18:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:18:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:18:42 INFO - Return code: 0 23:18:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:18:42 INFO - Using env: {'DISPLAY': ':0', 23:18:42 INFO - 'HOME': '/home/cltbld', 23:18:42 INFO - 'LANG': 'en_US.UTF-8', 23:18:42 INFO - 'LANGUAGE': 'en_US:en', 23:18:42 INFO - 'LOGNAME': 'cltbld', 23:18:42 INFO - 'MAIL': '/var/mail/cltbld', 23:18:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:18:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:42 INFO - 'MOZ_NO_REMOTE': '1', 23:18:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:18:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:42 INFO - 'NO_EM_RESTART': '1', 23:18:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:42 INFO - 'PWD': '/builds/slave/test', 23:18:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:18:42 INFO - 'SHELL': '/bin/bash', 23:18:42 INFO - 'SHLVL': '1', 23:18:42 INFO - 'TERM': 'linux', 23:18:42 INFO - 'TMOUT': '86400', 23:18:42 INFO - 'USER': 'cltbld', 23:18:42 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665', 23:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:42 INFO - '_': '/tools/buildbot/bin/python'} 23:18:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:18:42 INFO - mozversion application_buildid: 20160518203028 23:18:42 INFO - mozversion application_changeset: eb54f97ddfd410d426df5989a8ddd962d97bee44 23:18:42 INFO - mozversion application_display_name: Nightly 23:18:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:18:42 INFO - mozversion application_name: Firefox 23:18:42 INFO - mozversion application_remotingname: firefox 23:18:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:18:42 INFO - mozversion application_vendor: Mozilla 23:18:42 INFO - mozversion application_version: 49.0a1 23:18:42 INFO - mozversion platform_buildid: 20160518203028 23:18:42 INFO - mozversion platform_changeset: eb54f97ddfd410d426df5989a8ddd962d97bee44 23:18:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:18:42 INFO - mozversion platform_version: 49.0a1 23:18:42 INFO - using testdate: 1463638722 23:18:42 INFO - actual date: 1463638722 23:18:42 INFO - starting webserver on 'localhost:60502' 23:18:42 INFO - SUITE-START | Running 2 tests 23:18:42 INFO - TEST-START | dromaeo_css 23:18:42 INFO - operating with platform_type : linux_ 23:18:42 INFO - Initialising browser for dromaeo_css test... 23:18:42 INFO - TEST-INFO | started process 817 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6G4UHn/profile http://localhost:60502/getInfo.html) 23:18:46 INFO - PROCESS | 817 | __metrics Screen width/height:1600/1200 23:18:46 INFO - PROCESS | 817 | colorDepth:24 23:18:46 INFO - PROCESS | 817 | Browser inner width/height: 1024/697 23:18:46 INFO - PROCESS | 817 | __metrics 23:18:48 ERROR - PROCESS | 817 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:18:48 INFO - TEST-INFO | 817: exit 0 23:18:48 INFO - Browser initialized. 23:18:48 INFO - Running cycle 1/1 for dromaeo_css test... 23:18:48 INFO - TEST-INFO | started process 904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6G4UHn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:19:00 INFO - PROCESS | 904 | RSS: Main: 152129536 23:19:00 INFO - PROCESS | 904 | 23:19:51 INFO - PROCESS | 904 | 1463638791897 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:21:59 INFO - PROCESS | 904 | Cycle 1(1): loaded http://localhost:60502/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60502/tests/dromaeo/cssquery-ext.html) 23:22:00 INFO - PROCESS | 904 | RSS: Main: 262647808 23:22:00 INFO - PROCESS | 904 | 23:25:03 INFO - PROCESS | 904 | Cycle 1(1): loaded http://localhost:60502/tests/dromaeo/cssquery-ext.html (next: http://localhost:60502/tests/dromaeo/cssquery-jquery.html) 23:25:03 INFO - PROCESS | 904 | RSS: Main: 236650496 23:25:03 INFO - PROCESS | 904 | 23:28:05 INFO - PROCESS | 904 | Cycle 1(1): loaded http://localhost:60502/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60502/tests/dromaeo/cssquery-mootools.html) 23:28:06 INFO - PROCESS | 904 | RSS: Main: 240783360 23:28:06 INFO - PROCESS | 904 | 23:31:08 INFO - PROCESS | 904 | Cycle 1(1): loaded http://localhost:60502/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60502/tests/dromaeo/cssquery-prototype.html) 23:31:08 INFO - PROCESS | 904 | RSS: Main: 233660416 23:31:08 INFO - PROCESS | 904 | 23:34:11 INFO - PROCESS | 904 | Cycle 1(1): loaded http://localhost:60502/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60502/tests/dromaeo/cssquery-yui.html) 23:34:12 INFO - PROCESS | 904 | RSS: Main: 226148352 23:34:12 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | Cycle 1(1): loaded http://localhost:60502/tests/dromaeo/cssquery-yui.html (next: http://localhost:60502/tests/dromaeo/cssquery-dojo.html) 23:37:14 INFO - PROCESS | 904 | RSS: Main: 282587136 23:37:14 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | __start_tp_report 23:37:14 INFO - PROCESS | 904 | _x_x_mozilla_page_load 23:37:14 INFO - PROCESS | 904 | _x_x_mozilla_page_load_details 23:37:14 INFO - PROCESS | 904 | |i|pagename|runs| 23:37:14 INFO - PROCESS | 904 | |0;dojo.html;3160.1467603746987;3164.3987642347965;3172.984135079325;3191.0586377018776;3204.0387883634903;5196.0647083524955;5291.1798671205925;5440.4287549807295;5538.944610553894;5568.582356323277;5485;5504.064309067458;5537.916931246034;5600.551955843533;5646.378898321192;4978.551930326279;5033.194688849785;5070.492950704936;5073.391193056838;5247.973760131213;5149.304843846089;5169.069567477853;5201.68789872607;5217.139172036609;5226.111561034633;4924;4950.455449900508;4974.452810190858;4992.051510213084;4993.101241776479;5003.049420610108;5012.974935125338;5073.518015788494;5081.618878584121;5127.307813445174;4907.631927605444;5032.345795046657;5047.7476126193915;5057.418396884332;5064.215046667772;5475.917861232045;5527.889442211133;5539.196816461631;5632.915506267409;5632.9436705632825;6030.216071910623;6034.637921724667;6069.605475644069;6072.36240194782;6078.452939235446;5845;5949.851253718692;5969.61197522164;5976.223090998143;6009.188759517453;5606.131049687305;5610.66336019836;5643.633163844337;5664.291963504562;5667.093265077568;6116.393990316224;6144.969275153596;6145.600535965148;6159.076138579249;6161.661108639068;5742.08126699726;5744.167095771173;5804.013317735995;5805.216295800097;5948.315943666447;5836.970815145897;5839.240898683144;5841.912371314434;5855.209546711139;5855.414458554111;5836.474717275466;5840.561957853093;5845.09401042839;5845.298564172326;5845.590808643439;5700.087985922232;5844.795432159854;5864.120381942743;5894.498967587803;5926.644401335933;4432.82268709248;4489.079738653568;4494.73031618104;4503.977480112579;4536.500984891659;3909.843606255775;3971.4439978403047;4000.3599424092004;4032.8790136295956;4063.7358571692744;5558.638688485316;5563.499285064364;5566.832995010156;5589.832305030855;5600.719964001783;5945.316288626776;5948.940510594839;5949.791757288474;5954.225950626391;5974.044152935509;5377.354717433933;5587.05020146576;5591.049521581341;5593.216949627055;5616.073347897633;5121.436641969381;5171.146760784503;5178.766955487012;5180.326557547494;5207.973960130175;6211.844703882439;6213.627182369072;6216.564840461215;6220.377962203744;6225.844353891099;5800.941990580209;5801.332846722681;5818.50542703858;6057.818265452043;6073.210482637405;6015.097735339734;6040.6677632730625;6041;6043.7884674036195;6082.36135205797;5687.090065589486;5735.254416925773;5740.598158128891;5743.4543718346695;5749.741261643152;5723.341815691082;5842.737076831468;5983.34183239852;6017.217761691128;6050.45545900871;5909.91135132982;5913.201717768218;5917.5857689961285;5921.052631578927;5924.911126333194;5858.8242352729485;5898.498627616701;5920.970395148169;5923.703814809329;5933.050711886078;5499.835004949858;5626.718664066862;5643.830685079454;5674.517665998436;5710.314762228559;5828.6211396259105;5860.179574859438;5879.235699359056;5899.498542623754;5903.822885313448;5445.646033007842;5709.885802283848;5712.7714891403875;5713.400092990176;5723.828285151452 23:37:14 INFO - PROCESS | 904 | |1;ext.html;594.8126340202836;617.9783707570234;639.8656282180744;645.6287634610101;657.1358663357681;31344.52983205251;43658;44965.55034449654;45154;49190.50809491904;46533.7673311633;46830.29754553684;46861.0627787444;46907.53092469074;47000.294995575096;8895.421797583153;9063.093690630918;9079.682211122612;9080.727578172633;9092.136247056518;8865.512396818172;8957.865632015524;9000.8649870252;9007.009228984807;9018.278537716968;8997.460152390877;9045.3668243223;9069.954650226773;9097.909020909805;9104.271658267322;8973.46159230445;9024.639014439415;9032.638694452215;9034.186923176945;9040.819183616291;8922.821543569156;9014.188723014895;9019.954900225523;9020.548972551413;9023.503707296097;42471.57528424707;42709.145817083794;42795.144097118195;42821.1435771286;42898;3737.9439308410397;3738.9439158412647;3760.0035990462275;3854.9421758673643;4012.6990475714438;32315.83842080798;32883.83558082218;33095;33103.50344744831;33972.660273397196;4267;4297.39836422901;4329.264025115738;4340.934885976713;4351.9564804352185;354516;354745;356360;356936;357562;59852;59985.70007149936;60208;60346.396536034954;60351.698241508515;37017.62982370196;37817.62182378196;37890;64101;64311.35688643147;15757.369705211893;15873.761893571605;15875;15883.126428046337;15922.442714504938;11820.290782553075;11885.821712674317;11906.642800715992;11923.463444145034;11923.940380298043;20285.290014849408;20473.385798426072;20513.897430512752;20551.897240513703;20577.279795207094;7410.5183163094225;7441.590712510864;7617.124030736536;7772.611369431505;7774.494657847335;987.7333120057688;1054.7257712994676;1094.141099237095;1106.8893110688866;1208.3173007250875;32823;33987.66012339894;34117.31765364728;34510;34761.478577821355;1242.2670624331688;1243.3534562027694;1244.6826059354808;1245.315076707807;1263.1031967303134;17848.196831142628;17876.55308617269;18133.818661813475;18209.544761380806;18269.086545672664;534368;535483;538646;539040;540027;60206;60638.39361606416;60705;60767.39232607706;60892.39107608956;1312.6390242683337;1382.4124746982693;1396.5670642100981;1433.0971487962724;1437.73401920645;33201.667983320825;34324.485132722526;34771.826140870144;34821;34842.65157348394;50844.74577627088;51368.22947655863;51413.48586514087;51451.742741287555;51462.48537514727;369064;374717;375098;375479;375775;25942.870285648452;28673;28764.712352876202;29368.55947160837;30498.695013049586;49245;49452.258216127506;49864.501354987435;50595.2410713847;51073.23390149076;42288.577114229694;42721.78639106784;42930.78534607307;43299.35050974301;44329.77835110804;42422.575774241865;42670.35994460023;42701;42797.78601107099;43254;43018;43275.35086973761;43808.342874856266;44631;44743.776281118386;36974.8151259242;39208;39397.60602393939;39693.404598931615;40056.599434005286;29141.56287655729;29443.263918401353;29933.85033074908;30030.549541756453;30146.84926575353 23:37:14 INFO - PROCESS | 904 | |2;jquery.html;404.6317850755812;406.5020350071163;411.8249743858859;413.06646977830115;418.45182810518224;13977.930110349434;18423.63152736946;19477.70783438246;20101.396958091264;20126.39620811378;13812.585622431343;18492.445226643187;18521.259149634032;18626.627467450642;19683.606327873433;5593.804216852411;5762.164486149506;5778.4799368056865;5779.884402311951;5781.421857814206;5704;5734.311882574076;5774.913376299359;5789.334226563957;5791.652500849941;4140.0891803803115;4336.609705126538;4950.702957822542;6335.524835637322;6341.809745707613;6337.493000559944;6388.361163883645;6407.103005579223;6442.259140198867;6452.193475815523;5551.306086739158;5620.662760234358;5651.943480565224;5688.288963879515;5692.089265717506;22670.886645566665;22691.659625105636;23145;23571.502901344225;23840.40398990039;2357.3752955466816;2422;2704.9594256086175;2814.31049392898;3179.538966849817;13647.181169129784;14198.432062717478;14210.715785684331;14438.639034024129;14610.853891461162;1932.4106147624968;2030.9390718278476;2050.1696812790697;2080.7191029211012;2100.24391219161;78441.60779196124;82619;83002;83034.58482707608;83912.58043709742;10490.370577765358;10770.845830833865;10939.453027348602;10946.288491248202;11014.724631884266;15279.465218717292;15374.538763837103;15458.38166473351;15469.5359139226;15506.224688765516;7823.569703666353;7894.526328420313;7999.240072193133;8008.7597372078935;8023.358131349478;7416.962915185498;7425.405967522585;7437.256274372628;7515.210902855121;7522.059742532184;5960.165576819249;6507.6746162692625;6727.865442691216;6736.696848641823;6948.235694073648;1850.7779066511835;1924.9903750481437;1985.9404217873198;2014.6575082236054;2033.4306394209646;992.731962370156;1062.9734256643646;1087.4127970895627;1123.7471568896933;1143.4340001699213;19724.90137549303;20452.386428406873;20759.481012974797;21202.469938251357;21370.679439808417;1070.5610699613278;1070.577122036791;1082.4154956323498;1112.1825458288151;1183.420124139183;11224.831627525593;11620.14184538755;11690.824637630443;11823.349715765715;11846.289222646668;453986;456323;500839;501126;504682;91477;92404;92433.53783231041;93195.53402232945;93630;1048.9580416783397;1055.2191378379944;1062.1449732965036;1063.0857462582328;1085.67972448128;18165.54586135357;18252.17865196043;18399.908000459913;18614.720779188592;18753.24987000559;35383.46924796182;40170;40339;40685.1862962745;40789.79605102074;60810.655413952656;61470;61735.073973891325;62276;62972;8747.781305467413;8945.686900958435;8985.775355616162;9032.86450703227;9241.983381828128;8964.820703586021;12759.61721148367;12951.805722913976;13059.151155174883;13622.182669039888;11521;11986.220895641756;12063.336516491678;12152.635420937386;12336.568220112253;7778.688852445839;8068.62833318349;11481.942590286995;11740.236884602473;11975.461104250155;12058.939705301418;12108.51565937388;12161.209521381083;12195.939020304842;12418.37908104573;8827.382083254111;8850.336224783296;9041.18629323364;9181.357304988855;9348.906510935076;9076.636934522545;11960.820587691365;11977.820332695192;12091.879081209076;12199.329036903055 23:37:14 INFO - PROCESS | 904 | |3;mootools.html;1393.8118354022217;1532.3564103076706;1559.7192505349037;1733.211388818087;1845.289563518045;8641.91358086419;9020.90979090209;9050.954745226281;9328.766780830483;9340.626374944994;9083.454992700443;9279.721608351743;9286.814263714721;9289.953550232256;9290.721278361643;2113.524456997171;2280.8745518996448;2283.6460348645987;2328.0687724910085;2394.3774618599205;2363.3737059679197;2371.158238825218;2376.298991797415;2376.916807911715;2378.0725517048363;2320.269115228706;2367.621180611103;2372.063035101134;2373.027058905849;2377.1442280778906;2063.793620637939;2309.445733024079;2389.7729715676983;2390.175389490623;2391.8564886106888;2363.1256435118944;2384.9403764906015;2384.9880750596135;2396.053558844264;2431.2098567965304;8921.509316987564;9002.144796244347;9003.054679258648;9007.594658240396;9012.324075694349;5268.078086334915;5413.079776438015;5415.756290966877;5419.268398766156;5419.918701219485;3440.55272814535;3463.0822831949554;3491.0574144980715;3607.7474576779637;3625.909352266188;1092.9016388525072;1104.5471356743697;1117.7149826794116;1119.8096323624923;1144.8339990701302;12735.490580376774;12928.676783080498;12951.740965180643;12957;12967.416466259041;4613.95386046142;4723.267893476516;4782.0435912817575;4831.227003679394;4832.710037397767;11006.614768483065;11015.504302306415;11033.779324413626;11038.834417483744;11049.226554141133;4915.680480768739;5392.137258038695;5396.676199428047;5399.055165346049;5399.811006614828;4791.089692958397;4954.3063971044085;4955.132851751001;4991.07665081961;4995.600351971833;4112.753234805921;4148.170365926827;4167.708260421802;4359.433273674402;4359.585839345257;1229.692576855786;1266.4174479739422;1268.9746205075846;1272.1985149356033;1297.1503665099376;945.9054094590485;961.245422343457;964.2864280432569;965.5268918230016;969.8399764038995;5708.457696518824;5743.677243034993;5807.245058142415;5850.678212698257;5965.582409231312;447.4384647267658;449.9325101234775;452.8777230147842;457.5927424592116;459.1803630519507;3916.823742931575;3986.9003274917777;4001.199760047944;4005.819738111734;4073.9796301018896;10706.304090234109;11414.771704566028;11446.713832154262;11510.769784604261;11533.250338727956;10932.726681832859;11131.554737810558;11221.60724374643;11226.270292430965;11244.100471962223;8610;8621.568921554022;8657.826843463095;8739.694110706221;8740.125987401336;5585.218069470358;5724.141378793214;5728.828135155952;5756.971215143897;5777.133429985536;10030.648927287803;10290.639827605997;10381.273310868166;10387.220958427997;10393.116585090353;10452.947735261276;10648.680539583826;10722.839157412802;10813.837792432962;10891.18316126278;7434.7769566913075;7860.371170306247;7984.960075199587;8056.476329038828;8073.636686349246;9023.233025192669;9031.819363612822;9102.726918192464;9114.40756350835;9116.817663646823;7879.960600196962;7996.480228785111;8052.758417247492;8061.758147255592;8085.797855053671;7889.881651775343;8019.238172373615;8075.798105047421;8228.42401031922;8299.917000829915;7442.367398771165;7453;7477.364424024019;7480.70077196906;7577.772666820004;5011.072951503984;5074.6194035448225;5087.796488140433;5117.2068329409;5130.076586214517;5101.54086132235;5108.616853736059;5141.897162056663;5153.278541004337;5209.192575150858 23:37:14 INFO - PROCESS | 904 | |4;prototype.html;337.71800546543636;342.766918495423;347.0577382406766;354.06879905847615;354.6595268542198;5425.647332923357;5474.452554744528;5494.725263736817;5497.835064948053;5505.724713764311;5313.760880760366;5366.195070739391;5366.275552800371;5367.389783065075;5393.2449457075945;4166.958330416703;4271.466066741657;4277.529471758104;4297.796616947252;4304.461942257218;4251.957480425187;4268.1890440816305;4272.764997925113;4274.102438487921;4275.957240427587;4049.3925911113274;4254.3618457231505;4262.509811371701;4269.060806622553;4286.742795432268;3791.374423220165;4115.485564304462;4140.468026830062;4157.251694694938;4158.480189976253;3760.5863355030924;3764.381316034104;3777.9055523611855;3780.4707340972286;3781.6974642028845;5135.460776618439;5245.318108645852;5258.342707161605;5270.446603106657;5283.841484755463;4607.216773148572;4627.861164165047;4794.880127996793;4894.339264199323;4896.2410826321975;5558.527525160366;5578.107502799533;5583.0738395253575;5593.692346920918;5594.412586678381;4053.43251944728;4063.79681015948;4066.146109317047;4071.409645601401;4073.368627862686;5532.972335138339;5637.8590535236945;5699.914501282526;5780.971095144498;5903;4211.431456753299;4224.619784219373;4226.17589570037;4239.682023848224;4245.596668316505;4298.290782020931;4305.375720520507;4309.224339618899;4309.762963037063;4309.762963037063;4531.297648864432;4819.204831202812;4830.468648448667;4857.611391088704;4864.537868902448;5630.634008789498;5645;5672.347680016851;5675.943240567624;5680.6591604503865;1443.9422423102958;1464.2385959301316;1467.8532146785235;1471.9558413247619;1472.5655931500232;903.9457632541937;941.6139382853135;943.2265542255421;951.1962391778935;953.9618415263451;4822.95177048232;4867.586255168279;4898.652870460581;4962.900741985212;4990.451050384455;5003.199488081889;5061.545150601955;5174.922376164361;5243.4232234454175;5256.211568264791;4565.543445655408;4825.5174482551465;4962.280469331927;4993.80024799004;5003.249512573143;2717.578775289833;2788.2257154131767;2789.5676170193246;2792.3298408381843;2801.8458984755625;5267.683938963674;5279.709615971082;5301.363836339664;5333.679979201261;5357.4106848246665;5169.819056333085;5182.53357197854;5569.693666848281;5982.551308651781;5992.910106348409;5965.403459654;5965.612235204698;5966.224390829165;5966.284045914518;6009.849753756191;5620.831375058755;5845.327787304344;5910.551914780906;5918.585699001029;5930.436608522184;5752.367239603556;5752.884942301214;5761.39505351932;5767.019606666793;5773.53811695055;5707.086866101404;5859.794907178229;6015.157877897185;6032.849178770566;6048.667323297261;4150.8547200847825;4153.709240353146;4164.604362585549;4173.874783756492;4176.477940257468;5701.543876489954;5749.770009199585;5758.452946970031;5811.709414529172;5852.5317974561085;5622;5634.85912852182;5635.112237400353;5648.305508347397;5658.547316214611;5652.180433836988;5659.858503537444;5716.399778023248;5716.999525083177;5729.341125770423;5337.973310133424;5538.169274608841;5583.720813959367;5591.664500130005;5599.9720001401365;5689.77240910359;5690.857728556819;5699.173619825019;5702.885942281048;5710.371859095413;5272.261883336259;5625.774969001194;5646.3506696728755;5650.886982260414;5653.660780353192 23:37:14 INFO - PROCESS | 904 | |5;yui.html;805.7381351060906;837.9622916968738;846.4751853850613;846.6697987784765;847.7499137754365;14245.501407450742;14937.551873443814;14962.700745985101;14979.625509362259;14999.950003499745;14053.859461405384;14153.71692566148;14481.78277325841;14522.12867227967;14548.418063277482;698.769406095987;839.9412041157107;846.9788255293636;851.953142577158;853.4580541356253;827.6275675945849;851.6508231625035;852.2159613155911;854.1415877043562;854.3549620036863;875.1511034296753;876.7106854737921;877.9736607901773;878.2973621103124;878.3412440669498;719.3561762222781;748.9363404110657;879.7096958003843;880.828238493494;882.2853488674162;549.2694716027713;620.4911972182812;639.6513899924553;640.3948268885905;640.3948268885905;12941.805872911913;12949.028822838325;12952.222866628032;12965.675858103528;13029.218246905217;12445.875541244563;12447.253164810141;12592.244465332109;12701.428435720414;12750.681232969157;11435.428228588537;11472.31166130035;11788.469518871585;11793.469293881795;12052.035837133008;4528.773561321921;4561.429821272341;4571.3600095986585;4579.107074120548;4589.701669391479;51418.48581514174;56800.43199567993;63369.68315158441;64035;64104.358956410775;10593.894061059445;10807.405592692478;10827.458627068616;10884.510197041152;10891.891081089247;19708.605827883363;20141.19435222604;20149.899250503655;20174.798252017586;20180.69728954067;8207.753767386988;8262.876056859152;8305.750827475185;8515.872261916076;8522.275606573387;4855.6601037927;4887.755612219375;4888.268156424592;4982.053409852117;5029.220470827027;3556.822158892045;3627.4921510988484;3661.835217415223;3670.981645091811;3673.1368128489785;694.746417557598;703.0894990986583;704.5526090932295;715.7924201981367;716.0333549707852;638.8179368879847;651.7360469010058;742.2169611060275;750.7372419653078;755.1127425275389;9137.497437640994;9552.61789528425;9567.330286879851;9622.566984485715;9651.469169195761;739.456499472887;744.9851002980018;755.9962200189074;758.0600055930606;759.8474337744254;4262.616364527207;4440.533743956838;4482.977585112054;4500.437445319335;4522.547745225517;69793;70234;70375.88872333839;71157;71563;60491.39508604946;61666.383336166065;63178.36821631725;63593.36406635874;63700.36299636944;56847.43152568587;56898.7155064222;56921.715391423604;57029;57672.42327576754;9135.360524763468;9287.303452241244;9398.107179817905;9459.887542567481;9471.573779179816;37505.81247093747;37849.62150378571;39019.804900975316;39072.6092739069;39212.215755684156;50497.747511263675;52290;52681.473185267656;52787;53094.46905530895;11559.884401155881;11804.17370784037;11953.402329883646;12962.935185324013;13107.082504224612;13563.728725425633;14005.5098071567;14125.646858828612;14196.716065678835;14210.857891420954;13852.445902163801;14381.7842732357;14549.272536373352;14587.489437869624;14628;13087.803682944954;13389.129706569041;13847.930760346133;13936.512222072179;14341;13445.327733613476;13755.38100785487;14144.717105657623;14306.856931430553;14362.712745744817;9818.36180648284;9937.403755774676;10167.133687076404;10493.1333023598;10819.729506762395;10693.572257109628;10867.836982445111;11125.666230013112;11630.825537616773;11714.88285117172 23:37:14 INFO - PROCESS | 904 | __end_tp_report 23:37:14 INFO - PROCESS | 904 | __start_cc_report 23:37:14 INFO - PROCESS | 904 | _x_x_mozilla_cycle_collect,858 23:37:14 INFO - PROCESS | 904 | __end_cc_report 23:37:14 INFO - PROCESS | 904 | __startTimestamp1463639834640__endTimestamp 23:37:14 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | ------- Summary: start ------- 23:37:14 INFO - PROCESS | 904 | Number of tests: 6 23:37:14 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | [#0] dojo.html Cycles:165 Average:5506.61 Median:5709.89 stddev:635.41 (11.1%) stddev-sans-first:610.11 23:37:14 INFO - PROCESS | 904 | Values: 3160.1 3164.4 3173.0 3191.1 3204.0 5196.1 5291.2 5440.4 5538.9 5568.6 5485.0 5504.1 5537.9 5600.6 5646.4 4978.6 5033.2 5070.5 5073.4 5248.0 5149.3 5169.1 5201.7 5217.1 5226.1 4924.0 4950.5 4974.5 4992.1 4993.1 5003.0 5013.0 5073.5 5081.6 5127.3 4907.6 5032.3 5047.7 5057.4 5064.2 5475.9 5527.9 5539.2 5632.9 5632.9 6030.2 6034.6 6069.6 6072.4 6078.5 5845.0 5949.9 5969.6 5976.2 6009.2 5606.1 5610.7 5643.6 5664.3 5667.1 6116.4 6145.0 6145.6 6159.1 6161.7 5742.1 5744.2 5804.0 5805.2 5948.3 5837.0 5839.2 5841.9 5855.2 5855.4 5836.5 5840.6 5845.1 5845.3 5845.6 5700.1 5844.8 5864.1 5894.5 5926.6 4432.8 4489.1 4494.7 4504.0 4536.5 3909.8 3971.4 4000.4 4032.9 4063.7 5558.6 5563.5 5566.8 5589.8 5600.7 5945.3 5948.9 5949.8 5954.2 5974.0 5377.4 5587.1 5591.0 5593.2 5616.1 5121.4 5171.1 5178.8 5180.3 5208.0 6211.8 6213.6 6216.6 6220.4 6225.8 5800.9 5801.3 5818.5 6057.8 6073.2 6015.1 6040.7 6041.0 6043.8 6082.4 5687.1 5735.3 5740.6 5743.5 5749.7 5723.3 5842.7 5983.3 6017.2 6050.5 5909.9 5913.2 5917.6 5921.1 5924.9 5858.8 5898.5 5921.0 5923.7 5933.1 5499.8 5626.7 5643.8 5674.5 5710.3 5828.6 5860.2 5879.2 5899.5 5903.8 5445.6 5709.9 5712.8 5713.4 5723.8 23:37:14 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | [#1] ext.html Cycles:180 Average:59183.48 Median:42758.47 stddev:113874.93 (266.3%) stddev-sans-first:114109.41 23:37:14 INFO - PROCESS | 904 | Values: 594.8 618.0 639.9 645.6 657.1 31344.5 43658.0 44965.6 45154.0 49190.5 46533.8 46830.3 46861.1 46907.5 47000.3 8895.4 9063.1 9079.7 9080.7 9092.1 8865.5 8957.9 9000.9 9007.0 9018.3 8997.5 9045.4 9070.0 9097.9 9104.3 8973.5 9024.6 9032.6 9034.2 9040.8 8922.8 9014.2 9020.0 9020.5 9023.5 42471.6 42709.1 42795.1 42821.1 42898.0 3737.9 3738.9 3760.0 3854.9 4012.7 32315.8 32883.8 33095.0 33103.5 33972.7 4267.0 4297.4 4329.3 4340.9 4352.0 354516.0 354745.0 356360.0 356936.0 357562.0 59852.0 59985.7 60208.0 60346.4 60351.7 37017.6 37817.6 37890.0 64101.0 64311.4 15757.4 15873.8 15875.0 15883.1 15922.4 11820.3 11885.8 11906.6 11923.5 11923.9 20285.3 20473.4 20513.9 20551.9 20577.3 7410.5 7441.6 7617.1 7772.6 7774.5 987.7 1054.7 1094.1 1106.9 1208.3 32823.0 33987.7 34117.3 34510.0 34761.5 1242.3 1243.4 1244.7 1245.3 1263.1 17848.2 17876.6 18133.8 18209.5 18269.1 534368.0 535483.0 538646.0 539040.0 540027.0 60206.0 60638.4 60705.0 60767.4 60892.4 1312.6 1382.4 1396.6 1433.1 1437.7 33201.7 34324.5 34771.8 34821.0 34842.7 50844.7 51368.2 51413.5 51451.7 51462.5 369064.0 374717.0 375098.0 375479.0 375775.0 25942.9 28673.0 28764.7 29368.6 30498.7 49245.0 49452.3 49864.5 50595.2 51073.2 42288.6 42721.8 42930.8 43299.4 44329.8 42422.6 42670.4 42701.0 42797.8 43254.0 43018.0 43275.4 43808.3 44631.0 44743.8 36974.8 39208.0 39397.6 39693.4 40056.6 29141.6 29443.3 29933.9 30030.5 30146.8 23:37:14 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | [#2] jquery.html Cycles:180 Average:29097.93 Median:13131.20 stddev:79859.28 (608.2%) stddev-sans-first:80054.24 23:37:14 INFO - PROCESS | 904 | Values: 404.6 406.5 411.8 413.1 418.5 13977.9 18423.6 19477.7 20101.4 20126.4 13812.6 18492.4 18521.3 18626.6 19683.6 5593.8 5762.2 5778.5 5779.9 5781.4 5704.0 5734.3 5774.9 5789.3 5791.7 4140.1 4336.6 4950.7 6335.5 6341.8 6337.5 6388.4 6407.1 6442.3 6452.2 5551.3 5620.7 5651.9 5688.3 5692.1 22670.9 22691.7 23145.0 23571.5 23840.4 2357.4 2422.0 2705.0 2814.3 3179.5 13647.2 14198.4 14210.7 14438.6 14610.9 1932.4 2030.9 2050.2 2080.7 2100.2 78441.6 82619.0 83002.0 83034.6 83912.6 10490.4 10770.8 10939.5 10946.3 11014.7 15279.5 15374.5 15458.4 15469.5 15506.2 7823.6 7894.5 7999.2 8008.8 8023.4 7417.0 7425.4 7437.3 7515.2 7522.1 5960.2 6507.7 6727.9 6736.7 6948.2 1850.8 1925.0 1985.9 2014.7 2033.4 992.7 1063.0 1087.4 1123.7 1143.4 19724.9 20452.4 20759.5 21202.5 21370.7 1070.6 1070.6 1082.4 1112.2 1183.4 11224.8 11620.1 11690.8 11823.3 11846.3 453986.0 456323.0 500839.0 501126.0 504682.0 91477.0 92404.0 92433.5 93195.5 93630.0 1049.0 1055.2 1062.1 1063.1 1085.7 18165.5 18252.2 18399.9 18614.7 18753.2 35383.5 40170.0 40339.0 40685.2 40789.8 60810.7 61470.0 61735.1 62276.0 62972.0 8747.8 8945.7 8985.8 9032.9 9242.0 8964.8 12759.6 12951.8 13059.2 13622.2 11521.0 11986.2 12063.3 12152.6 12336.6 7778.7 8068.6 11481.9 11740.2 11975.5 12058.9 12108.5 12161.2 12195.9 12418.4 8827.4 8850.3 9041.2 9181.4 9348.9 9076.6 11960.8 11977.8 12091.9 12199.3 23:37:14 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | [#3] mootools.html Cycles:180 Average:5971.68 Median:4668.61 stddev:3488.72 (74.7%) stddev-sans-first:3481.55 23:37:14 INFO - PROCESS | 904 | Values: 1393.8 1532.4 1559.7 1733.2 1845.3 8641.9 9020.9 9051.0 9328.8 9340.6 9083.5 9279.7 9286.8 9290.0 9290.7 2113.5 2280.9 2283.6 2328.1 2394.4 2363.4 2371.2 2376.3 2376.9 2378.1 2320.3 2367.6 2372.1 2373.0 2377.1 2063.8 2309.4 2389.8 2390.2 2391.9 2363.1 2384.9 2385.0 2396.1 2431.2 8921.5 9002.1 9003.1 9007.6 9012.3 5268.1 5413.1 5415.8 5419.3 5419.9 3440.6 3463.1 3491.1 3607.7 3625.9 1092.9 1104.5 1117.7 1119.8 1144.8 12735.5 12928.7 12951.7 12957.0 12967.4 4614.0 4723.3 4782.0 4831.2 4832.7 11006.6 11015.5 11033.8 11038.8 11049.2 4915.7 5392.1 5396.7 5399.1 5399.8 4791.1 4954.3 4955.1 4991.1 4995.6 4112.8 4148.2 4167.7 4359.4 4359.6 1229.7 1266.4 1269.0 1272.2 1297.2 945.9 961.2 964.3 965.5 969.8 5708.5 5743.7 5807.2 5850.7 5965.6 447.4 449.9 452.9 457.6 459.2 3916.8 3986.9 4001.2 4005.8 4074.0 10706.3 11414.8 11446.7 11510.8 11533.3 10932.7 11131.6 11221.6 11226.3 11244.1 8610.0 8621.6 8657.8 8739.7 8740.1 5585.2 5724.1 5728.8 5757.0 5777.1 10030.6 10290.6 10381.3 10387.2 10393.1 10452.9 10648.7 10722.8 10813.8 10891.2 7434.8 7860.4 7985.0 8056.5 8073.6 9023.2 9031.8 9102.7 9114.4 9116.8 7880.0 7996.5 8052.8 8061.8 8085.8 7889.9 8019.2 8075.8 8228.4 8299.9 7442.4 7453.0 7477.4 7480.7 7577.8 5011.1 5074.6 5087.8 5117.2 5130.1 5101.5 5108.6 5141.9 5153.3 5209.2 23:37:14 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | [#4] prototype.html Cycles:180 Average:4686.38 Median:5269.07 stddev:1368.41 (26.0%) stddev-sans-first:1332.76 23:37:14 INFO - PROCESS | 904 | Values: 337.7 342.8 347.1 354.1 354.7 5425.6 5474.5 5494.7 5497.8 5505.7 5313.8 5366.2 5366.3 5367.4 5393.2 4167.0 4271.5 4277.5 4297.8 4304.5 4252.0 4268.2 4272.8 4274.1 4276.0 4049.4 4254.4 4262.5 4269.1 4286.7 3791.4 4115.5 4140.5 4157.3 4158.5 3760.6 3764.4 3777.9 3780.5 3781.7 5135.5 5245.3 5258.3 5270.4 5283.8 4607.2 4627.9 4794.9 4894.3 4896.2 5558.5 5578.1 5583.1 5593.7 5594.4 4053.4 4063.8 4066.1 4071.4 4073.4 5533.0 5637.9 5699.9 5781.0 5903.0 4211.4 4224.6 4226.2 4239.7 4245.6 4298.3 4305.4 4309.2 4309.8 4309.8 4531.3 4819.2 4830.5 4857.6 4864.5 5630.6 5645.0 5672.3 5675.9 5680.7 1443.9 1464.2 1467.9 1472.0 1472.6 903.9 941.6 943.2 951.2 954.0 4823.0 4867.6 4898.7 4962.9 4990.5 5003.2 5061.5 5174.9 5243.4 5256.2 4565.5 4825.5 4962.3 4993.8 5003.2 2717.6 2788.2 2789.6 2792.3 2801.8 5267.7 5279.7 5301.4 5333.7 5357.4 5169.8 5182.5 5569.7 5982.6 5992.9 5965.4 5965.6 5966.2 5966.3 6009.8 5620.8 5845.3 5910.6 5918.6 5930.4 5752.4 5752.9 5761.4 5767.0 5773.5 5707.1 5859.8 6015.2 6032.8 6048.7 4150.9 4153.7 4164.6 4173.9 4176.5 5701.5 5749.8 5758.5 5811.7 5852.5 5622.0 5634.9 5635.1 5648.3 5658.5 5652.2 5659.9 5716.4 5717.0 5729.3 5338.0 5538.2 5583.7 5591.7 5600.0 5689.8 5690.9 5699.2 5702.9 5710.4 5272.3 5625.8 5646.4 5650.9 5653.7 23:37:14 INFO - PROCESS | 904 | 23:37:14 INFO - PROCESS | 904 | [#5] yui.html Cycles:180 Average:16109.47 Median:4935.16 stddev:19464.22 (394.4%) stddev-sans-first:19484.89 23:37:14 INFO - PROCESS | 904 | Values: 805.7 838.0 846.5 846.7 847.7 14245.5 14937.6 14962.7 14979.6 15000.0 14053.9 14153.7 14481.8 14522.1 14548.4 698.8 839.9 847.0 852.0 853.5 827.6 851.7 852.2 854.1 854.4 875.2 876.7 878.0 878.3 878.3 719.4 748.9 879.7 880.8 882.3 549.3 620.5 639.7 640.4 640.4 12941.8 12949.0 12952.2 12965.7 13029.2 12445.9 12447.3 12592.2 12701.4 12750.7 11435.4 11472.3 11788.5 11793.5 12052.0 4528.8 4561.4 4571.4 4579.1 4589.7 51418.5 56800.4 63369.7 64035.0 64104.4 10593.9 10807.4 10827.5 10884.5 10891.9 19708.6 20141.2 20149.9 20174.8 20180.7 8207.8 8262.9 8305.8 8515.9 8522.3 4855.7 4887.8 4888.3 4982.1 5029.2 3556.8 3627.5 3661.8 3671.0 3673.1 694.7 703.1 704.6 715.8 716.0 638.8 651.7 742.2 750.7 755.1 9137.5 9552.6 9567.3 9622.6 9651.5 739.5 745.0 756.0 758.1 759.8 4262.6 4440.5 4483.0 4500.4 4522.5 69793.0 70234.0 70375.9 71157.0 71563.0 60491.4 61666.4 63178.4 63593.4 63700.4 56847.4 56898.7 56921.7 57029.0 57672.4 9135.4 9287.3 9398.1 9459.9 9471.6 37505.8 37849.6 39019.8 39072.6 39212.2 50497.7 52290.0 52681.5 52787.0 53094.5 11559.9 11804.2 11953.4 12962.9 13107.1 13563.7 14005.5 14125.6 14196.7 14210.9 13852.4 14381.8 14549.3 14587.5 14628.0 13087.8 13389.1 13847.9 13936.5 14341.0 13445.3 13755.4 14144.7 14306.9 14362.7 9818.4 9937.4 10167.1 10493.1 10819.7 10693.6 10867.8 11125.7 11630.8 11714.9 23:37:14 INFO - PROCESS | 904 | -------- Summary: end -------- 23:37:14 INFO - PROCESS | 904 | 23:37:15 INFO - TEST-INFO | 904: exit 0 23:37:15 INFO - TEST-OK | dromaeo_css | took 1112777ms 23:37:15 INFO - TEST-START | kraken 23:37:15 INFO - operating with platform_type : linux_ 23:37:15 INFO - Initialising browser for kraken test... 23:37:15 INFO - TEST-INFO | started process 1095 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8_2QQt/profile http://localhost:60502/getInfo.html) 23:37:19 INFO - PROCESS | 1095 | __metrics Screen width/height:1600/1200 23:37:19 INFO - PROCESS | 1095 | colorDepth:24 23:37:19 INFO - PROCESS | 1095 | Browser inner width/height: 1024/697 23:37:19 INFO - PROCESS | 1095 | __metrics 23:37:20 ERROR - PROCESS | 1095 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:37:21 INFO - TEST-INFO | 1095: exit 0 23:37:21 INFO - Browser initialized. 23:37:21 INFO - Running cycle 1/1 for kraken test... 23:37:21 INFO - TEST-INFO | started process 1240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8_2QQt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:37:33 INFO - PROCESS | 1240 | RSS: Main: 153493504 23:37:33 INFO - PROCESS | 1240 | 23:37:35 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60502/tests/kraken/driver.html?testName=audio-beat-detection) 23:37:36 INFO - PROCESS | 1240 | RSS: Main: 209289216 23:37:36 INFO - PROCESS | 1240 | 23:37:39 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60502/tests/kraken/driver.html?testName=audio-dft) 23:37:39 INFO - PROCESS | 1240 | RSS: Main: 198385664 23:37:39 INFO - PROCESS | 1240 | 23:37:43 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60502/tests/kraken/driver.html?testName=audio-fft) 23:37:43 INFO - PROCESS | 1240 | RSS: Main: 265568256 23:37:43 INFO - PROCESS | 1240 | 23:37:45 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60502/tests/kraken/driver.html?testName=audio-oscillator) 23:37:46 INFO - PROCESS | 1240 | RSS: Main: 175001600 23:37:46 INFO - PROCESS | 1240 | 23:37:48 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60502/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:37:49 INFO - PROCESS | 1240 | RSS: Main: 179245056 23:37:49 INFO - PROCESS | 1240 | 23:37:52 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60502/tests/kraken/driver.html?testName=imaging-darkroom) 23:37:53 INFO - PROCESS | 1240 | RSS: Main: 214900736 23:37:53 INFO - PROCESS | 1240 | 23:37:56 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60502/tests/kraken/driver.html?testName=imaging-desaturate) 23:37:57 INFO - PROCESS | 1240 | RSS: Main: 213250048 23:37:57 INFO - PROCESS | 1240 | 23:38:00 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60502/tests/kraken/driver.html?testName=json-parse-financial) 23:38:01 INFO - PROCESS | 1240 | RSS: Main: 213848064 23:38:01 INFO - PROCESS | 1240 | 23:38:02 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60502/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:38:03 INFO - PROCESS | 1240 | RSS: Main: 202928128 23:38:03 INFO - PROCESS | 1240 | 23:38:05 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60502/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:38:05 INFO - PROCESS | 1240 | RSS: Main: 207204352 23:38:05 INFO - PROCESS | 1240 | 23:38:07 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60502/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:38:08 INFO - PROCESS | 1240 | RSS: Main: 218607616 23:38:08 INFO - PROCESS | 1240 | 23:38:11 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:38:11 INFO - PROCESS | 1240 | RSS: Main: 223277056 23:38:11 INFO - PROCESS | 1240 | 23:38:14 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:38:15 INFO - PROCESS | 1240 | RSS: Main: 220995584 23:38:15 INFO - PROCESS | 1240 | 23:38:16 INFO - PROCESS | 1240 | Cycle 1(1): loaded http://localhost:60502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60502/tests/kraken/driver.html?testName=ai-astar) 23:38:17 INFO - PROCESS | 1240 | RSS: Main: 267034624 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | __start_tp_report 23:38:17 INFO - PROCESS | 1240 | _x_x_mozilla_page_load 23:38:17 INFO - PROCESS | 1240 | _x_x_mozilla_page_load_details 23:38:17 INFO - PROCESS | 1240 | |i|pagename|runs| 23:38:17 INFO - PROCESS | 1240 | |0;ai-astar;112;104;110;109;108;108;108;109;107;110 23:38:17 INFO - PROCESS | 1240 | |1;audio-beat-detection;164;124;148;210;126;155;146;132;199;123 23:38:17 INFO - PROCESS | 1240 | |2;audio-dft;159;161;159;158;161;161;160;161;161;161 23:38:17 INFO - PROCESS | 1240 | |3;audio-fft;75;74;76;157;75;72;76;74;142;75 23:38:17 INFO - PROCESS | 1240 | |4;audio-oscillator;91;92;145;93;93;92;129;92;92;121 23:38:17 INFO - PROCESS | 1240 | |5;imaging-gaussian-blur;117;117;117;116;117;118;116;116;117;117 23:38:17 INFO - PROCESS | 1240 | |6;imaging-darkroom;145;146;145;145;145;146;145;145;146;146 23:38:17 INFO - PROCESS | 1240 | |7;imaging-desaturate;95;96;94;95;95;95;95;94;95;95 23:38:17 INFO - PROCESS | 1240 | |8;json-parse-financial;55;55;55;53;55;54;54;113;52;52 23:38:17 INFO - PROCESS | 1240 | |9;json-stringify-tinderbox;60;59;59;59;59;60;60;59;59;60 23:38:17 INFO - PROCESS | 1240 | |10;stanford-crypto-aes;77;79;83;77;77;79;80;79;78;80 23:38:17 INFO - PROCESS | 1240 | |11;stanford-crypto-ccm;123;121;132;124;117;116;124;119;198;123 23:38:17 INFO - PROCESS | 1240 | |12;stanford-crypto-pbkdf2;161;159;161;157;159;156;160;158;163;155 23:38:17 INFO - PROCESS | 1240 | |13;stanford-crypto-sha256-iterative;66;65;62;62;61;60;62;67;60;66 23:38:17 INFO - PROCESS | 1240 | __end_tp_report 23:38:17 INFO - PROCESS | 1240 | __start_cc_report 23:38:17 INFO - PROCESS | 1240 | _x_x_mozilla_cycle_collect,1882 23:38:17 INFO - PROCESS | 1240 | __end_cc_report 23:38:17 INFO - PROCESS | 1240 | __startTimestamp1463639897045__endTimestamp 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | ------- Summary: start ------- 23:38:17 INFO - PROCESS | 1240 | Number of tests: 14 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.00 stddev:2.12 (1.9%) stddev-sans-first:1.83 23:38:17 INFO - PROCESS | 1240 | Values: 112.0 104.0 110.0 109.0 108.0 108.0 108.0 109.0 107.0 110.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:151.50 stddev:30.68 (20.3%) stddev-sans-first:32.27 23:38:17 INFO - PROCESS | 1240 | Values: 164.0 124.0 148.0 210.0 126.0 155.0 146.0 132.0 199.0 123.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#2] audio-dft Cycles:10 Average:160.20 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.12 23:38:17 INFO - PROCESS | 1240 | Values: 159.0 161.0 159.0 158.0 161.0 161.0 160.0 161.0 161.0 161.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#3] audio-fft Cycles:10 Average:89.60 Median:75.00 stddev:31.79 (42.4%) stddev-sans-first:33.27 23:38:17 INFO - PROCESS | 1240 | Values: 75.0 74.0 76.0 157.0 75.0 72.0 76.0 74.0 142.0 75.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#4] audio-oscillator Cycles:10 Average:104.00 Median:92.00 stddev:19.95 (21.7%) stddev-sans-first:20.60 23:38:17 INFO - PROCESS | 1240 | Values: 91.0 92.0 145.0 93.0 93.0 92.0 129.0 92.0 92.0 121.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 23:38:17 INFO - PROCESS | 1240 | Values: 117.0 117.0 117.0 116.0 117.0 118.0 116.0 116.0 117.0 117.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:38:17 INFO - PROCESS | 1240 | Values: 145.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 146.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 23:38:17 INFO - PROCESS | 1240 | Values: 95.0 96.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:54.50 stddev:18.73 (34.4%) stddev-sans-first:19.79 23:38:17 INFO - PROCESS | 1240 | Values: 55.0 55.0 55.0 53.0 55.0 54.0 54.0 113.0 52.0 52.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 23:38:17 INFO - PROCESS | 1240 | Values: 60.0 59.0 59.0 59.0 59.0 60.0 60.0 59.0 59.0 60.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:79.00 stddev:1.85 (2.3%) stddev-sans-first:1.83 23:38:17 INFO - PROCESS | 1240 | Values: 77.0 79.0 83.0 77.0 77.0 79.0 80.0 79.0 78.0 80.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:123.50 stddev:24.41 (19.8%) stddev-sans-first:25.77 23:38:17 INFO - PROCESS | 1240 | Values: 123.0 121.0 132.0 124.0 117.0 116.0 124.0 119.0 198.0 123.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.90 Median:159.50 stddev:2.47 (1.5%) stddev-sans-first:2.50 23:38:17 INFO - PROCESS | 1240 | Values: 161.0 159.0 161.0 157.0 159.0 156.0 160.0 158.0 163.0 155.0 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - PROCESS | 1240 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.50 stddev:2.64 (4.2%) stddev-sans-first:2.59 23:38:17 INFO - PROCESS | 1240 | Values: 66.0 65.0 62.0 62.0 61.0 60.0 62.0 67.0 60.0 66.0 23:38:17 INFO - PROCESS | 1240 | -------- Summary: end -------- 23:38:17 INFO - PROCESS | 1240 | 23:38:17 INFO - TEST-INFO | 1240: exit 0 23:38:18 INFO - TEST-OK | kraken | took 62543ms 23:38:18 INFO - SUITE-END | took 1175s 23:38:18 INFO - Completed test suite (00:19:35) 23:38:18 INFO - javascript benchmark 23:38:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.1467603746987, 3164.3987642347965, 3172.984135079325, 3191.0586377018776, 3204.0387883634903, 5196.0647083524955, 5291.1798671205925, 5440.4287549807295, 5538.944610553894, 5568.582356323277, 5485.0, 5504.064309067458, 5537.916931246034, 5600.551955843533, 5646.378898321192, 4978.551930326279, 5033.194688849785, 5070.492950704936, 5073.391193056838, 5247.973760131213, 5149.304843846089, 5169.069567477853, 5201.68789872607, 5217.139172036609, 5226.111561034633, 4924.0, 4950.455449900508, 4974.452810190858, 4992.051510213084, 4993.101241776479, 5003.049420610108, 5012.974935125338, 5073.518015788494, 5081.618878584121, 5127.307813445174, 4907.631927605444, 5032.345795046657, 5047.7476126193915, 5057.418396884332, 5064.215046667772, 5475.917861232045, 5527.889442211133, 5539.196816461631, 5632.915506267409, 5632.9436705632825, 6030.216071910623, 6034.637921724667, 6069.605475644069, 6072.36240194782, 6078.452939235446, 5845.0, 5949.851253718692, 5969.61197522164, 5976.223090998143, 6009.188759517453, 5606.131049687305, 5610.66336019836, 5643.633163844337, 5664.291963504562, 5667.093265077568, 6116.393990316224, 6144.969275153596, 6145.600535965148, 6159.076138579249, 6161.661108639068, 5742.08126699726, 5744.167095771173, 5804.013317735995, 5805.216295800097, 5948.315943666447, 5836.970815145897, 5839.240898683144, 5841.912371314434, 5855.209546711139, 5855.414458554111, 5836.474717275466, 5840.561957853093, 5845.09401042839, 5845.298564172326, 5845.590808643439, 5700.087985922232, 5844.795432159854, 5864.120381942743, 5894.498967587803, 5926.644401335933, 4432.82268709248, 4489.079738653568, 4494.73031618104, 4503.977480112579, 4536.500984891659, 3909.843606255775, 3971.4439978403047, 4000.3599424092004, 4032.8790136295956, 4063.7358571692744, 5558.638688485316, 5563.499285064364, 5566.832995010156, 5589.832305030855, 5600.719964001783, 5945.316288626776, 5948.940510594839, 5949.791757288474, 5954.225950626391, 5974.044152935509, 5377.354717433933, 5587.05020146576, 5591.049521581341, 5593.216949627055, 5616.073347897633, 5121.436641969381, 5171.146760784503, 5178.766955487012, 5180.326557547494, 5207.973960130175, 6211.844703882439, 6213.627182369072, 6216.564840461215, 6220.377962203744, 6225.844353891099, 5800.941990580209, 5801.332846722681, 5818.50542703858, 6057.818265452043, 6073.210482637405, 6015.097735339734, 6040.6677632730625, 6041.0, 6043.7884674036195, 6082.36135205797, 5687.090065589486, 5735.254416925773, 5740.598158128891, 5743.4543718346695, 5749.741261643152, 5723.341815691082, 5842.737076831468, 5983.34183239852, 6017.217761691128, 6050.45545900871, 5909.91135132982, 5913.201717768218, 5917.5857689961285, 5921.052631578927, 5924.911126333194, 5858.8242352729485, 5898.498627616701, 5920.970395148169, 5923.703814809329, 5933.050711886078, 5499.835004949858, 5626.718664066862, 5643.830685079454, 5674.517665998436, 5710.314762228559, 5828.6211396259105, 5860.179574859438, 5879.235699359056, 5899.498542623754, 5903.822885313448, 5445.646033007842, 5709.885802283848, 5712.7714891403875, 5713.400092990176, 5723.828285151452], "value": 5462.9224047624075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.8126340202836, 617.9783707570234, 639.8656282180744, 645.6287634610101, 657.1358663357681, 31344.52983205251, 43658.0, 44965.55034449654, 45154.0, 49190.50809491904, 46533.7673311633, 46830.29754553684, 46861.0627787444, 46907.53092469074, 47000.294995575096, 8895.421797583153, 9063.093690630918, 9079.682211122612, 9080.727578172633, 9092.136247056518, 8865.512396818172, 8957.865632015524, 9000.8649870252, 9007.009228984807, 9018.278537716968, 8997.460152390877, 9045.3668243223, 9069.954650226773, 9097.909020909805, 9104.271658267322, 8973.46159230445, 9024.639014439415, 9032.638694452215, 9034.186923176945, 9040.819183616291, 8922.821543569156, 9014.188723014895, 9019.954900225523, 9020.548972551413, 9023.503707296097, 42471.57528424707, 42709.145817083794, 42795.144097118195, 42821.1435771286, 42898.0, 3737.9439308410397, 3738.9439158412647, 3760.0035990462275, 3854.9421758673643, 4012.6990475714438, 32315.83842080798, 32883.83558082218, 33095.0, 33103.50344744831, 33972.660273397196, 4267.0, 4297.39836422901, 4329.264025115738, 4340.934885976713, 4351.9564804352185, 354516.0, 354745.0, 356360.0, 356936.0, 357562.0, 59852.0, 59985.70007149936, 60208.0, 60346.396536034954, 60351.698241508515, 37017.62982370196, 37817.62182378196, 37890.0, 64101.0, 64311.35688643147, 15757.369705211893, 15873.761893571605, 15875.0, 15883.126428046337, 15922.442714504938, 11820.290782553075, 11885.821712674317, 11906.642800715992, 11923.463444145034, 11923.940380298043, 20285.290014849408, 20473.385798426072, 20513.897430512752, 20551.897240513703, 20577.279795207094, 7410.5183163094225, 7441.590712510864, 7617.124030736536, 7772.611369431505, 7774.494657847335, 987.7333120057688, 1054.7257712994676, 1094.141099237095, 1106.8893110688866, 1208.3173007250875, 32823.0, 33987.66012339894, 34117.31765364728, 34510.0, 34761.478577821355, 1242.2670624331688, 1243.3534562027694, 1244.6826059354808, 1245.315076707807, 1263.1031967303134, 17848.196831142628, 17876.55308617269, 18133.818661813475, 18209.544761380806, 18269.086545672664, 534368.0, 535483.0, 538646.0, 539040.0, 540027.0, 60206.0, 60638.39361606416, 60705.0, 60767.39232607706, 60892.39107608956, 1312.6390242683337, 1382.4124746982693, 1396.5670642100981, 1433.0971487962724, 1437.73401920645, 33201.667983320825, 34324.485132722526, 34771.826140870144, 34821.0, 34842.65157348394, 50844.74577627088, 51368.22947655863, 51413.48586514087, 51451.742741287555, 51462.48537514727, 369064.0, 374717.0, 375098.0, 375479.0, 375775.0, 25942.870285648452, 28673.0, 28764.712352876202, 29368.55947160837, 30498.695013049586, 49245.0, 49452.258216127506, 49864.501354987435, 50595.2410713847, 51073.23390149076, 42288.577114229694, 42721.78639106784, 42930.78534607307, 43299.35050974301, 44329.77835110804, 42422.575774241865, 42670.35994460023, 42701.0, 42797.78601107099, 43254.0, 43018.0, 43275.35086973761, 43808.342874856266, 44631.0, 44743.776281118386, 36974.8151259242, 39208.0, 39397.60602393939, 39693.404598931615, 40056.599434005286, 29141.56287655729, 29443.263918401353, 29933.85033074908, 30030.549541756453, 30146.84926575353], "value": 20546.83574853737, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.6317850755812, 406.5020350071163, 411.8249743858859, 413.06646977830115, 418.45182810518224, 13977.930110349434, 18423.63152736946, 19477.70783438246, 20101.396958091264, 20126.39620811378, 13812.585622431343, 18492.445226643187, 18521.259149634032, 18626.627467450642, 19683.606327873433, 5593.804216852411, 5762.164486149506, 5778.4799368056865, 5779.884402311951, 5781.421857814206, 5704.0, 5734.311882574076, 5774.913376299359, 5789.334226563957, 5791.652500849941, 4140.0891803803115, 4336.609705126538, 4950.702957822542, 6335.524835637322, 6341.809745707613, 6337.493000559944, 6388.361163883645, 6407.103005579223, 6442.259140198867, 6452.193475815523, 5551.306086739158, 5620.662760234358, 5651.943480565224, 5688.288963879515, 5692.089265717506, 22670.886645566665, 22691.659625105636, 23145.0, 23571.502901344225, 23840.40398990039, 2357.3752955466816, 2422.0, 2704.9594256086175, 2814.31049392898, 3179.538966849817, 13647.181169129784, 14198.432062717478, 14210.715785684331, 14438.639034024129, 14610.853891461162, 1932.4106147624968, 2030.9390718278476, 2050.1696812790697, 2080.7191029211012, 2100.24391219161, 78441.60779196124, 82619.0, 83002.0, 83034.58482707608, 83912.58043709742, 10490.370577765358, 10770.845830833865, 10939.453027348602, 10946.288491248202, 11014.724631884266, 15279.465218717292, 15374.538763837103, 15458.38166473351, 15469.5359139226, 15506.224688765516, 7823.569703666353, 7894.526328420313, 7999.240072193133, 8008.7597372078935, 8023.358131349478, 7416.962915185498, 7425.405967522585, 7437.256274372628, 7515.210902855121, 7522.059742532184, 5960.165576819249, 6507.6746162692625, 6727.865442691216, 6736.696848641823, 6948.235694073648, 1850.7779066511835, 1924.9903750481437, 1985.9404217873198, 2014.6575082236054, 2033.4306394209646, 992.731962370156, 1062.9734256643646, 1087.4127970895627, 1123.7471568896933, 1143.4340001699213, 19724.90137549303, 20452.386428406873, 20759.481012974797, 21202.469938251357, 21370.679439808417, 1070.5610699613278, 1070.577122036791, 1082.4154956323498, 1112.1825458288151, 1183.420124139183, 11224.831627525593, 11620.14184538755, 11690.824637630443, 11823.349715765715, 11846.289222646668, 453986.0, 456323.0, 500839.0, 501126.0, 504682.0, 91477.0, 92404.0, 92433.53783231041, 93195.53402232945, 93630.0, 1048.9580416783397, 1055.2191378379944, 1062.1449732965036, 1063.0857462582328, 1085.67972448128, 18165.54586135357, 18252.17865196043, 18399.908000459913, 18614.720779188592, 18753.24987000559, 35383.46924796182, 40170.0, 40339.0, 40685.1862962745, 40789.79605102074, 60810.655413952656, 61470.0, 61735.073973891325, 62276.0, 62972.0, 8747.781305467413, 8945.686900958435, 8985.775355616162, 9032.86450703227, 9241.983381828128, 8964.820703586021, 12759.61721148367, 12951.805722913976, 13059.151155174883, 13622.182669039888, 11521.0, 11986.220895641756, 12063.336516491678, 12152.635420937386, 12336.568220112253, 7778.688852445839, 8068.62833318349, 11481.942590286995, 11740.236884602473, 11975.461104250155, 12058.939705301418, 12108.51565937388, 12161.209521381083, 12195.939020304842, 12418.37908104573, 8827.382083254111, 8850.336224783296, 9041.18629323364, 9181.357304988855, 9348.906510935076, 9076.636934522545, 11960.820587691365, 11977.820332695192, 12091.879081209076, 12199.329036903055], "value": 9519.601651847037, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.8118354022217, 1532.3564103076706, 1559.7192505349037, 1733.211388818087, 1845.289563518045, 8641.91358086419, 9020.90979090209, 9050.954745226281, 9328.766780830483, 9340.626374944994, 9083.454992700443, 9279.721608351743, 9286.814263714721, 9289.953550232256, 9290.721278361643, 2113.524456997171, 2280.8745518996448, 2283.6460348645987, 2328.0687724910085, 2394.3774618599205, 2363.3737059679197, 2371.158238825218, 2376.298991797415, 2376.916807911715, 2378.0725517048363, 2320.269115228706, 2367.621180611103, 2372.063035101134, 2373.027058905849, 2377.1442280778906, 2063.793620637939, 2309.445733024079, 2389.7729715676983, 2390.175389490623, 2391.8564886106888, 2363.1256435118944, 2384.9403764906015, 2384.9880750596135, 2396.053558844264, 2431.2098567965304, 8921.509316987564, 9002.144796244347, 9003.054679258648, 9007.594658240396, 9012.324075694349, 5268.078086334915, 5413.079776438015, 5415.756290966877, 5419.268398766156, 5419.918701219485, 3440.55272814535, 3463.0822831949554, 3491.0574144980715, 3607.7474576779637, 3625.909352266188, 1092.9016388525072, 1104.5471356743697, 1117.7149826794116, 1119.8096323624923, 1144.8339990701302, 12735.490580376774, 12928.676783080498, 12951.740965180643, 12957.0, 12967.416466259041, 4613.95386046142, 4723.267893476516, 4782.0435912817575, 4831.227003679394, 4832.710037397767, 11006.614768483065, 11015.504302306415, 11033.779324413626, 11038.834417483744, 11049.226554141133, 4915.680480768739, 5392.137258038695, 5396.676199428047, 5399.055165346049, 5399.811006614828, 4791.089692958397, 4954.3063971044085, 4955.132851751001, 4991.07665081961, 4995.600351971833, 4112.753234805921, 4148.170365926827, 4167.708260421802, 4359.433273674402, 4359.585839345257, 1229.692576855786, 1266.4174479739422, 1268.9746205075846, 1272.1985149356033, 1297.1503665099376, 945.9054094590485, 961.245422343457, 964.2864280432569, 965.5268918230016, 969.8399764038995, 5708.457696518824, 5743.677243034993, 5807.245058142415, 5850.678212698257, 5965.582409231312, 447.4384647267658, 449.9325101234775, 452.8777230147842, 457.5927424592116, 459.1803630519507, 3916.823742931575, 3986.9003274917777, 4001.199760047944, 4005.819738111734, 4073.9796301018896, 10706.304090234109, 11414.771704566028, 11446.713832154262, 11510.769784604261, 11533.250338727956, 10932.726681832859, 11131.554737810558, 11221.60724374643, 11226.270292430965, 11244.100471962223, 8610.0, 8621.568921554022, 8657.826843463095, 8739.694110706221, 8740.125987401336, 5585.218069470358, 5724.141378793214, 5728.828135155952, 5756.971215143897, 5777.133429985536, 10030.648927287803, 10290.639827605997, 10381.273310868166, 10387.220958427997, 10393.116585090353, 10452.947735261276, 10648.680539583826, 10722.839157412802, 10813.837792432962, 10891.18316126278, 7434.7769566913075, 7860.371170306247, 7984.960075199587, 8056.476329038828, 8073.636686349246, 9023.233025192669, 9031.819363612822, 9102.726918192464, 9114.40756350835, 9116.817663646823, 7879.960600196962, 7996.480228785111, 8052.758417247492, 8061.758147255592, 8085.797855053671, 7889.881651775343, 8019.238172373615, 8075.798105047421, 8228.42401031922, 8299.917000829915, 7442.367398771165, 7453.0, 7477.364424024019, 7480.70077196906, 7577.772666820004, 5011.072951503984, 5074.6194035448225, 5087.796488140433, 5117.2068329409, 5130.076586214517, 5101.54086132235, 5108.616853736059, 5141.897162056663, 5153.278541004337, 5209.192575150858], "value": 4654.2940615533635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.71800546543636, 342.766918495423, 347.0577382406766, 354.06879905847615, 354.6595268542198, 5425.647332923357, 5474.452554744528, 5494.725263736817, 5497.835064948053, 5505.724713764311, 5313.760880760366, 5366.195070739391, 5366.275552800371, 5367.389783065075, 5393.2449457075945, 4166.958330416703, 4271.466066741657, 4277.529471758104, 4297.796616947252, 4304.461942257218, 4251.957480425187, 4268.1890440816305, 4272.764997925113, 4274.102438487921, 4275.957240427587, 4049.3925911113274, 4254.3618457231505, 4262.509811371701, 4269.060806622553, 4286.742795432268, 3791.374423220165, 4115.485564304462, 4140.468026830062, 4157.251694694938, 4158.480189976253, 3760.5863355030924, 3764.381316034104, 3777.9055523611855, 3780.4707340972286, 3781.6974642028845, 5135.460776618439, 5245.318108645852, 5258.342707161605, 5270.446603106657, 5283.841484755463, 4607.216773148572, 4627.861164165047, 4794.880127996793, 4894.339264199323, 4896.2410826321975, 5558.527525160366, 5578.107502799533, 5583.0738395253575, 5593.692346920918, 5594.412586678381, 4053.43251944728, 4063.79681015948, 4066.146109317047, 4071.409645601401, 4073.368627862686, 5532.972335138339, 5637.8590535236945, 5699.914501282526, 5780.971095144498, 5903.0, 4211.431456753299, 4224.619784219373, 4226.17589570037, 4239.682023848224, 4245.596668316505, 4298.290782020931, 4305.375720520507, 4309.224339618899, 4309.762963037063, 4309.762963037063, 4531.297648864432, 4819.204831202812, 4830.468648448667, 4857.611391088704, 4864.537868902448, 5630.634008789498, 5645.0, 5672.347680016851, 5675.943240567624, 5680.6591604503865, 1443.9422423102958, 1464.2385959301316, 1467.8532146785235, 1471.9558413247619, 1472.5655931500232, 903.9457632541937, 941.6139382853135, 943.2265542255421, 951.1962391778935, 953.9618415263451, 4822.95177048232, 4867.586255168279, 4898.652870460581, 4962.900741985212, 4990.451050384455, 5003.199488081889, 5061.545150601955, 5174.922376164361, 5243.4232234454175, 5256.211568264791, 4565.543445655408, 4825.5174482551465, 4962.280469331927, 4993.80024799004, 5003.249512573143, 2717.578775289833, 2788.2257154131767, 2789.5676170193246, 2792.3298408381843, 2801.8458984755625, 5267.683938963674, 5279.709615971082, 5301.363836339664, 5333.679979201261, 5357.4106848246665, 5169.819056333085, 5182.53357197854, 5569.693666848281, 5982.551308651781, 5992.910106348409, 5965.403459654, 5965.612235204698, 5966.224390829165, 5966.284045914518, 6009.849753756191, 5620.831375058755, 5845.327787304344, 5910.551914780906, 5918.585699001029, 5930.436608522184, 5752.367239603556, 5752.884942301214, 5761.39505351932, 5767.019606666793, 5773.53811695055, 5707.086866101404, 5859.794907178229, 6015.157877897185, 6032.849178770566, 6048.667323297261, 4150.8547200847825, 4153.709240353146, 4164.604362585549, 4173.874783756492, 4176.477940257468, 5701.543876489954, 5749.770009199585, 5758.452946970031, 5811.709414529172, 5852.5317974561085, 5622.0, 5634.85912852182, 5635.112237400353, 5648.305508347397, 5658.547316214611, 5652.180433836988, 5659.858503537444, 5716.399778023248, 5716.999525083177, 5729.341125770423, 5337.973310133424, 5538.169274608841, 5583.720813959367, 5591.664500130005, 5599.9720001401365, 5689.77240910359, 5690.857728556819, 5699.173619825019, 5702.885942281048, 5710.371859095413, 5272.261883336259, 5625.774969001194, 5646.3506696728755, 5650.886982260414, 5653.660780353192], "value": 4254.201942942687, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.7381351060906, 837.9622916968738, 846.4751853850613, 846.6697987784765, 847.7499137754365, 14245.501407450742, 14937.551873443814, 14962.700745985101, 14979.625509362259, 14999.950003499745, 14053.859461405384, 14153.71692566148, 14481.78277325841, 14522.12867227967, 14548.418063277482, 698.769406095987, 839.9412041157107, 846.9788255293636, 851.953142577158, 853.4580541356253, 827.6275675945849, 851.6508231625035, 852.2159613155911, 854.1415877043562, 854.3549620036863, 875.1511034296753, 876.7106854737921, 877.9736607901773, 878.2973621103124, 878.3412440669498, 719.3561762222781, 748.9363404110657, 879.7096958003843, 880.828238493494, 882.2853488674162, 549.2694716027713, 620.4911972182812, 639.6513899924553, 640.3948268885905, 640.3948268885905, 12941.805872911913, 12949.028822838325, 12952.222866628032, 12965.675858103528, 13029.218246905217, 12445.875541244563, 12447.253164810141, 12592.244465332109, 12701.428435720414, 12750.681232969157, 11435.428228588537, 11472.31166130035, 11788.469518871585, 11793.469293881795, 12052.035837133008, 4528.773561321921, 4561.429821272341, 4571.3600095986585, 4579.107074120548, 4589.701669391479, 51418.48581514174, 56800.43199567993, 63369.68315158441, 64035.0, 64104.358956410775, 10593.894061059445, 10807.405592692478, 10827.458627068616, 10884.510197041152, 10891.891081089247, 19708.605827883363, 20141.19435222604, 20149.899250503655, 20174.798252017586, 20180.69728954067, 8207.753767386988, 8262.876056859152, 8305.750827475185, 8515.872261916076, 8522.275606573387, 4855.6601037927, 4887.755612219375, 4888.268156424592, 4982.053409852117, 5029.220470827027, 3556.822158892045, 3627.4921510988484, 3661.835217415223, 3670.981645091811, 3673.1368128489785, 694.746417557598, 703.0894990986583, 704.5526090932295, 715.7924201981367, 716.0333549707852, 638.8179368879847, 651.7360469010058, 742.2169611060275, 750.7372419653078, 755.1127425275389, 9137.497437640994, 9552.61789528425, 9567.330286879851, 9622.566984485715, 9651.469169195761, 739.456499472887, 744.9851002980018, 755.9962200189074, 758.0600055930606, 759.8474337744254, 4262.616364527207, 4440.533743956838, 4482.977585112054, 4500.437445319335, 4522.547745225517, 69793.0, 70234.0, 70375.88872333839, 71157.0, 71563.0, 60491.39508604946, 61666.383336166065, 63178.36821631725, 63593.36406635874, 63700.36299636944, 56847.43152568587, 56898.7155064222, 56921.715391423604, 57029.0, 57672.42327576754, 9135.360524763468, 9287.303452241244, 9398.107179817905, 9459.887542567481, 9471.573779179816, 37505.81247093747, 37849.62150378571, 39019.804900975316, 39072.6092739069, 39212.215755684156, 50497.747511263675, 52290.0, 52681.473185267656, 52787.0, 53094.46905530895, 11559.884401155881, 11804.17370784037, 11953.402329883646, 12962.935185324013, 13107.082504224612, 13563.728725425633, 14005.5098071567, 14125.646858828612, 14196.716065678835, 14210.857891420954, 13852.445902163801, 14381.7842732357, 14549.272536373352, 14587.489437869624, 14628.0, 13087.803682944954, 13389.129706569041, 13847.930760346133, 13936.512222072179, 14341.0, 13445.327733613476, 13755.38100785487, 14144.717105657623, 14306.856931430553, 14362.712745744817, 9818.36180648284, 9937.403755774676, 10167.133687076404, 10493.1333023598, 10819.729506762395, 10693.572257109628, 10867.836982445111, 11125.666230013112, 11630.825537616773, 11714.88285117172], "value": 7075.188905743839, "unit": "score"}], "name": "dromaeo_css", "value": 7286.864443640276, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 110.0, 109.0, 108.0, 108.0, 108.0, 109.0, 107.0, 110.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 124.0, 148.0, 210.0, 126.0, 155.0, 146.0, 132.0, 199.0, 123.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 159.0, 158.0, 161.0, 161.0, 160.0, 161.0, 161.0, 161.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 157.0, 75.0, 72.0, 76.0, 74.0, 142.0, 75.0], "value": 89.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 145.0, 93.0, 93.0, 92.0, 129.0, 92.0, 92.0, 121.0], "value": 104.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 116.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 53.0, 55.0, 54.0, 54.0, 113.0, 52.0, 52.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 83.0, 77.0, 77.0, 79.0, 80.0, 79.0, 78.0, 80.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 121.0, 132.0, 124.0, 117.0, 116.0, 124.0, 119.0, 198.0, 123.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 161.0, 157.0, 159.0, 156.0, 160.0, 158.0, 163.0, 155.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 62.0, 62.0, 61.0, 60.0, 62.0, 67.0, 60.0, 66.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1521.9, "alertThreshold": 2.0}]} 23:38:18 INFO - Return code: 0 23:38:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:38:18 ERROR - # TBPL SUCCESS # 23:38:18 INFO - Running post-action listener: _resource_record_post_action 23:38:18 INFO - ##### 23:38:18 INFO - ##### Finished run-tests step (success) 23:38:18 INFO - ##### 23:38:18 INFO - Running post-run listener: _resource_record_post_run 23:38:18 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 467214336; Read time: 8; Write time: 339812 23:38:18 INFO - TinderboxPrint: CPU usage
13.1% 23:38:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:38:18 INFO - TinderboxPrint: I/O write bytes / time
467,214,336 / 339,812 23:38:18 INFO - TinderboxPrint: CPU idle
8,270.9 (86.9%) 23:38:18 INFO - TinderboxPrint: CPU user
1,207.1 (12.7%) 23:38:18 INFO - TinderboxPrint: Swap in / out
0 / 0 23:38:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 177618944; Read time: 0; Write time: 128068 23:38:18 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289595392; Read time: 8; Write time: 211744 23:38:18 INFO - Running post-run listener: _upload_blobber_files 23:38:18 INFO - Blob upload gear active. 23:38:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:38:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:38:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:38:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:38:19 INFO - (blobuploader) - INFO - Open directory for files ... 23:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:38:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:20 INFO - (blobuploader) - INFO - Done attempting. 23:38:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:38:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:38:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:24 INFO - (blobuploader) - INFO - Done attempting. 23:38:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:38:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:25 INFO - (blobuploader) - INFO - Done attempting. 23:38:25 INFO - (blobuploader) - INFO - Iteration through files over. 23:38:25 INFO - Return code: 0 23:38:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:38:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:38:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca7f8d13aa7d58d28c52ff74c03729af3eb327837521978e3fea6b43fb5bb614f9d56e110ba47162a2a6ab25912a489e882474eb93343c30867b30696c7fdf28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e2b9ed8c46e559516b872c9697c8b3bc3bf78a1c10c29750ef978c6e62574e00788046295eaa7df4efcabbe5a6f9a6d21853bf2ed02b897dc84b03426b1a0b26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e0b53ed96fe1ed238aab94afc6621d33e5fe9235869640e52c4825b777c8950abd6a35a640b6d970021bd1b292d04f5b1f5bcbeb7d09b2e2dc7e118267cc7680"} 23:38:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:38:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:38:25 INFO - Contents: 23:38:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca7f8d13aa7d58d28c52ff74c03729af3eb327837521978e3fea6b43fb5bb614f9d56e110ba47162a2a6ab25912a489e882474eb93343c30867b30696c7fdf28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e2b9ed8c46e559516b872c9697c8b3bc3bf78a1c10c29750ef978c6e62574e00788046295eaa7df4efcabbe5a6f9a6d21853bf2ed02b897dc84b03426b1a0b26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e0b53ed96fe1ed238aab94afc6621d33e5fe9235869640e52c4825b777c8950abd6a35a640b6d970021bd1b292d04f5b1f5bcbeb7d09b2e2dc7e118267cc7680"} 23:38:25 INFO - Running post-run listener: copy_logs_to_upload_dir 23:38:25 INFO - Copying logs to upload dir... 23:38:25 INFO - mkdir: /builds/slave/test/build/upload/logs 23:38:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.208432 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-05-18 23:38:25.604383) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 23:38:25.608008) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca7f8d13aa7d58d28c52ff74c03729af3eb327837521978e3fea6b43fb5bb614f9d56e110ba47162a2a6ab25912a489e882474eb93343c30867b30696c7fdf28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e2b9ed8c46e559516b872c9697c8b3bc3bf78a1c10c29750ef978c6e62574e00788046295eaa7df4efcabbe5a6f9a6d21853bf2ed02b897dc84b03426b1a0b26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e0b53ed96fe1ed238aab94afc6621d33e5fe9235869640e52c4825b777c8950abd6a35a640b6d970021bd1b292d04f5b1f5bcbeb7d09b2e2dc7e118267cc7680"} build_url:https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011433 build_url: 'https://queue.taskcluster.net/v1/task/fQfq3Fx6T52pCtgZ4di3jQ/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/sha512/ca7f8d13aa7d58d28c52ff74c03729af3eb327837521978e3fea6b43fb5bb614f9d56e110ba47162a2a6ab25912a489e882474eb93343c30867b30696c7fdf28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e2b9ed8c46e559516b872c9697c8b3bc3bf78a1c10c29750ef978c6e62574e00788046295eaa7df4efcabbe5a6f9a6d21853bf2ed02b897dc84b03426b1a0b26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e0b53ed96fe1ed238aab94afc6621d33e5fe9235869640e52c4825b777c8950abd6a35a640b6d970021bd1b292d04f5b1f5bcbeb7d09b2e2dc7e118267cc7680"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 23:38:25.633204) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:38:25.633685) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1463638385.866691-1346481665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:38:25.718917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 23:38:25.719518) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 23:38:25.720110) ========= ========= Total master_lag: 0.17 =========