builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1464609906.06 results: success (0) buildid: 20160530023021 builduid: ca897c3453e44f00b68e2e96a63ddfd8 revision: be56cb9803a91540033261d76ade0e1f06c4691c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.062307) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.062938) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.063286) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.086515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.086892) ========= 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=20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-30 05:05:06-- 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.2M=0s 2016-05-30 05:05:06 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118875 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.220223) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.220585) ========= 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=20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018647 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.272941) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.273765) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev be56cb9803a91540033261d76ade0e1f06c4691c --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 be56cb9803a91540033261d76ade0e1f06c4691c --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=20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-30 05:05:06,338 truncating revision to first 12 chars 2016-05-30 05:05:06,338 Setting DEBUG logging. 2016-05-30 05:05:06,338 attempt 1/10 2016-05-30 05:05:06,338 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/be56cb9803a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 05:05:06,700 unpacking tar archive at: fx-team-be56cb9803a9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.706323 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.997575) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.998119) ========= script_repo_revision: be56cb9803a91540033261d76ade0e1f06c4691c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.998565) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:06.998900) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 05:05:07.010738) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-05-30 05:05:07.011338) ========= /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=20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:05:07 INFO - MultiFileLogger online at 20160530 05:05:07 in /builds/slave/test 05:05:07 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 05:05:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:07 INFO - {'append_to_log': False, 05:05:07 INFO - 'base_work_dir': '/builds/slave/test', 05:05:07 INFO - 'blob_upload_branch': 'Fx-Team', 05:05:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:07 INFO - 'branch': 'Fx-Team', 05:05:07 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:07 INFO - 'config_files': ('talos/linux_config.py',), 05:05:07 INFO - 'default_actions': ('clobber', 05:05:07 INFO - 'read-buildbot-config', 05:05:07 INFO - 'download-and-extract', 05:05:07 INFO - 'populate-webroot', 05:05:07 INFO - 'create-virtualenv', 05:05:07 INFO - 'install', 05:05:07 INFO - 'run-tests'), 05:05:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:07 INFO - 'download_minidump_stackwalk': True, 05:05:07 INFO - 'download_symbols': 'ondemand', 05:05:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:07 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:07 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:07 INFO - 'installer_path': 'installer.exe', 05:05:07 INFO - 'log_level': 'info', 05:05:07 INFO - 'log_name': 'talos', 05:05:07 INFO - 'log_to_console': True, 05:05:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:05:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:05:07 INFO - 'opt_config_files': (), 05:05:07 INFO - 'pip_index': False, 05:05:07 INFO - 'sps_profile': False, 05:05:07 INFO - 'sps_profile_interval': 0, 05:05:07 INFO - 'suite': 'dromaeojs', 05:05:07 INFO - 'system_bits': '32', 05:05:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:07 INFO - 'title': 'talos-linux64-ix-015', 05:05:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:07 INFO - 'use_talos_json': True, 05:05:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:07 INFO - 'work_dir': 'build'} 05:05:07 INFO - ##### 05:05:07 INFO - ##### Running clobber step. 05:05:07 INFO - ##### 05:05:07 INFO - Running pre-action listener: _resource_record_pre_action 05:05:07 INFO - Running main action method: clobber 05:05:07 INFO - rmtree: /builds/slave/test/build 05:05:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:07 INFO - Running post-action listener: _resource_record_post_action 05:05:07 INFO - ##### 05:05:07 INFO - ##### Finished clobber step (success) 05:05:07 INFO - ##### 05:05:07 INFO - ##### 05:05:07 INFO - ##### Running read-buildbot-config step. 05:05:07 INFO - ##### 05:05:07 INFO - Running pre-action listener: _resource_record_pre_action 05:05:07 INFO - Running main action method: read_buildbot_config 05:05:07 INFO - Using buildbot properties: 05:05:07 INFO - { 05:05:07 INFO - "project": "", 05:05:07 INFO - "product": "firefox", 05:05:07 INFO - "script_repo_revision": "production", 05:05:07 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:05:07 INFO - "repository": "", 05:05:07 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:05:07 INFO - "buildid": "20160530023021", 05:05:07 INFO - "pgo_build": "True", 05:05:07 INFO - "basedir": "/builds/slave/test", 05:05:07 INFO - "buildnumber": 1201, 05:05:07 INFO - "slavename": "talos-linux64-ix-015", 05:05:07 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:05:07 INFO - "platform": "ubuntu64_hw", 05:05:07 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:05:07 INFO - "branch": "fx-team", 05:05:07 INFO - "repo_path": "integration/fx-team", 05:05:07 INFO - "revision": "be56cb9803a91540033261d76ade0e1f06c4691c", 05:05:07 INFO - "stage_platform": "linux64-pgo", 05:05:07 INFO - "builduid": "ca897c3453e44f00b68e2e96a63ddfd8", 05:05:07 INFO - "slavebuilddir": "test" 05:05:07 INFO - } 05:05:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:05:07 INFO - Running post-action listener: _resource_record_post_action 05:05:07 INFO - ##### 05:05:07 INFO - ##### Finished read-buildbot-config step (success) 05:05:07 INFO - ##### 05:05:07 INFO - ##### 05:05:07 INFO - ##### Running download-and-extract step. 05:05:07 INFO - ##### 05:05:07 INFO - Running pre-action listener: _resource_record_pre_action 05:05:07 INFO - Running main action method: download_and_extract 05:05:07 INFO - mkdir: /builds/slave/test/build/tests 05:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:07 INFO - https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:05:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:05:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:05:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:08 INFO - Downloaded 1361 bytes. 05:05:08 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:05:08 INFO - Using the following test package requirements: 05:05:08 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:05:08 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:08 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:05:08 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:08 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:05:08 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:08 INFO - u'jsshell-linux-x86_64.zip'], 05:05:08 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:08 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:05:08 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:05:08 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:08 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:05:08 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:08 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:05:08 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:08 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:05:08 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:08 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:05:08 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:05: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')]} 05:05:08 INFO - https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:05:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:05:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:05:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:09 INFO - Downloaded 24410635 bytes. 05:05:09 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'] 05:05:09 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 05:05:10 INFO - Return code: 0 05:05:10 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 05:05:10 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')]} 05:05:10 INFO - https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:05:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:05:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:05:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:11 INFO - Downloaded 24410635 bytes. 05:05:11 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'] 05:05:11 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 05:05:13 INFO - Return code: 0 05:05:13 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')]} 05:05:13 INFO - https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:05:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:05:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:05:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:15 INFO - Downloaded 10834034 bytes. 05:05:15 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'] 05:05:15 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 05:05:15 INFO - Return code: 0 05:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:15 INFO - https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:05:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:05:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:05:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/To_ebAWCQwqdwhBd9UixRQ/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 05:05:17 INFO - Downloaded 58745715 bytes. 05:05:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:05:17 INFO - mkdir: /builds/slave/test/properties 05:05:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:17 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:17 INFO - Contents: 05:05:17 INFO - build_url:https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:05:18 INFO - Running post-action listener: _resource_record_post_action 05:05:18 INFO - Running post-action listener: set_extra_try_arguments 05:05:18 INFO - ##### 05:05:18 INFO - ##### Finished download-and-extract step (success) 05:05:18 INFO - ##### 05:05:18 INFO - ##### 05:05:18 INFO - ##### Running populate-webroot step. 05:05:18 INFO - ##### 05:05:18 INFO - Running pre-action listener: _resource_record_pre_action 05:05:18 INFO - Running main action method: populate_webroot 05:05:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:18 INFO - '--tppagecycles', 05:05:18 INFO - '7'], 05:05:18 INFO - 'tests': ['tsvgm']}}, 05:05:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:05:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:05:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:18 INFO - 'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:18 INFO - 'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['damp', 'tps']}, 05:05:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:18 INFO - 'tests': ['damp', 'tps']}, 05:05:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['dromaeo_dom']}, 05:05:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['a11yr', 05:05:18 INFO - 'ts_paint', 05:05:18 INFO - 'tpaint', 05:05:18 INFO - 'sessionrestore', 05:05:18 INFO - 'sessionrestore_no_auto_restore', 05:05:18 INFO - 'tabpaint']}, 05:05:18 INFO - 'other-e10s': {'tests': ['a11yr', 05:05:18 INFO - 'ts_paint', 05:05:18 INFO - 'tpaint', 05:05:18 INFO - 'sessionrestore', 05:05:18 INFO - 'sessionrestore_no_auto_restore', 05:05:18 INFO - 'tabpaint']}, 05:05:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:05:18 INFO - 'ts_paint', 05:05:18 INFO - 'tpaint', 05:05:18 INFO - 'sessionrestore', 05:05:18 INFO - 'sessionrestore_no_auto_restore', 05:05:18 INFO - 'tabpaint']}, 05:05:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:05:18 INFO - 'ts_paint', 05:05:18 INFO - 'tpaint', 05:05:18 INFO - 'sessionrestore', 05:05:18 INFO - 'sessionrestore_no_auto_restore', 05:05:18 INFO - 'tabpaint']}, 05:05:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['a11yr', 05:05:18 INFO - 'ts_paint', 05:05:18 INFO - 'tpaint', 05:05:18 INFO - 'sessionrestore', 05:05:18 INFO - 'sessionrestore_no_auto_restore', 05:05:18 INFO - 'tabpaint']}, 05:05:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['a11yr', 05:05:18 INFO - 'ts_paint', 05:05:18 INFO - 'tpaint', 05:05:18 INFO - 'sessionrestore', 05:05:18 INFO - 'sessionrestore_no_auto_restore', 05:05:18 INFO - 'tabpaint']}, 05:05:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['tsvgx', 05:05:18 INFO - 'tsvgr_opacity', 05:05:18 INFO - 'tart', 05:05:18 INFO - 'tscrollx', 05:05:18 INFO - 'cart']}, 05:05:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:05:18 INFO - 'tsvgr_opacity', 05:05:18 INFO - 'tart', 05:05:18 INFO - 'tscrollx', 05:05:18 INFO - 'cart']}, 05:05:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:18 INFO - 'talos_options': ['--disable-e10s'], 05:05:18 INFO - 'tests': ['tp5o']}, 05:05:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:18 INFO - 'tests': ['tp5o']}, 05:05:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:18 INFO - 'talos_options': ['--disable-e10s', 05:05:18 INFO - '--xperf_path', 05:05:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:18 INFO - 'tests': ['tp5n']}, 05:05:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:18 INFO - 'talos_options': ['--xperf_path', 05:05:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:18 INFO - 'tests': ['tp5n']}}, 05:05:18 INFO - 'talos.zip': {'path': '', 05:05:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:18 INFO - Running post-action listener: _resource_record_post_action 05:05:18 INFO - ##### 05:05:18 INFO - ##### Finished populate-webroot step (success) 05:05:18 INFO - ##### 05:05:18 INFO - ##### 05:05:18 INFO - ##### Running create-virtualenv step. 05:05:18 INFO - ##### 05:05:18 INFO - Running pre-action listener: _resource_record_pre_action 05:05:18 INFO - Running main action method: create_virtualenv 05:05:18 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:18 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 05:05:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:18 INFO - Using real prefix '/usr' 05:05:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:18 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:20 INFO - Installing pip.................done. 05:05:20 INFO - Return code: 0 05:05:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05:20 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')]} 05:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:20 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')]} 05:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:20 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 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:20 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 05:05:20 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 05:05:20 INFO - Using env: {'DISPLAY': ':0', 05:05:20 INFO - 'HOME': '/home/cltbld', 05:05:20 INFO - 'LANG': 'en_US.UTF-8', 05:05:20 INFO - 'LANGUAGE': 'en_US:en', 05:05:20 INFO - 'LOGNAME': 'cltbld', 05:05:20 INFO - 'MAIL': '/var/mail/cltbld', 05:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:20 INFO - 'MOZ_NO_REMOTE': '1', 05:05:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:20 INFO - 'NO_EM_RESTART': '1', 05:05:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:20 INFO - 'PWD': '/builds/slave/test', 05:05:20 INFO - 'SHELL': '/bin/bash', 05:05:20 INFO - 'SHLVL': '1', 05:05:20 INFO - 'TERM': 'linux', 05:05:20 INFO - 'TMOUT': '86400', 05:05:20 INFO - 'USER': 'cltbld', 05:05:20 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:20 INFO - '_': '/tools/buildbot/bin/python'} 05:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:20 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 05:05:20 INFO - Cleaning up... 05:05:20 INFO - Return code: 0 05:05:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:05:20 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')]} 05:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:20 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')]} 05:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:20 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 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:20 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 05:05:20 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 05:05:20 INFO - Using env: {'DISPLAY': ':0', 05:05:20 INFO - 'HOME': '/home/cltbld', 05:05:20 INFO - 'LANG': 'en_US.UTF-8', 05:05:20 INFO - 'LANGUAGE': 'en_US:en', 05:05:20 INFO - 'LOGNAME': 'cltbld', 05:05:20 INFO - 'MAIL': '/var/mail/cltbld', 05:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:20 INFO - 'MOZ_NO_REMOTE': '1', 05:05:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:20 INFO - 'NO_EM_RESTART': '1', 05:05:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:20 INFO - 'PWD': '/builds/slave/test', 05:05:20 INFO - 'SHELL': '/bin/bash', 05:05:20 INFO - 'SHLVL': '1', 05:05:20 INFO - 'TERM': 'linux', 05:05:20 INFO - 'TMOUT': '86400', 05:05:20 INFO - 'USER': 'cltbld', 05:05:20 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:20 INFO - '_': '/tools/buildbot/bin/python'} 05:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:21 INFO - Downloading/unpacking psutil>=3.1.1 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:23 INFO - Installing collected packages: psutil 05:05:23 INFO - Running setup.py install for psutil 05:05:23 INFO - building 'psutil._psutil_linux' extension 05:05:23 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 05:05:23 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 05:05:23 INFO - building 'psutil._psutil_posix' extension 05:05:23 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 05:05:23 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 05:05:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:23 INFO - Successfully installed psutil 05:05:23 INFO - Cleaning up... 05:05:23 INFO - Return code: 0 05:05:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:05:23 INFO - Using env: {'DISPLAY': ':0', 05:05:23 INFO - 'HOME': '/home/cltbld', 05:05:23 INFO - 'LANG': 'en_US.UTF-8', 05:05:23 INFO - 'LANGUAGE': 'en_US:en', 05:05:23 INFO - 'LOGNAME': 'cltbld', 05:05:23 INFO - 'MAIL': '/var/mail/cltbld', 05:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:23 INFO - 'MOZ_NO_REMOTE': '1', 05:05:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:23 INFO - 'NO_EM_RESTART': '1', 05:05:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:23 INFO - 'PWD': '/builds/slave/test', 05:05:23 INFO - 'SHELL': '/bin/bash', 05:05:23 INFO - 'SHLVL': '1', 05:05:23 INFO - 'TERM': 'linux', 05:05:23 INFO - 'TMOUT': '86400', 05:05:23 INFO - 'USER': 'cltbld', 05:05:23 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:23 INFO - '_': '/tools/buildbot/bin/python'} 05:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:05:25 INFO - Installing collected packages: mozsystemmonitor 05:05:25 INFO - Running setup.py install for mozsystemmonitor 05:05:25 INFO - Successfully installed mozsystemmonitor 05:05:25 INFO - Cleaning up... 05:05:26 INFO - Return code: 0 05:05:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:26 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 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:26 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 05:05:26 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 05:05:26 INFO - Using env: {'DISPLAY': ':0', 05:05:26 INFO - 'HOME': '/home/cltbld', 05:05:26 INFO - 'LANG': 'en_US.UTF-8', 05:05:26 INFO - 'LANGUAGE': 'en_US:en', 05:05:26 INFO - 'LOGNAME': 'cltbld', 05:05:26 INFO - 'MAIL': '/var/mail/cltbld', 05:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:26 INFO - 'MOZ_NO_REMOTE': '1', 05:05:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:26 INFO - 'NO_EM_RESTART': '1', 05:05:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:26 INFO - 'PWD': '/builds/slave/test', 05:05:26 INFO - 'SHELL': '/bin/bash', 05:05:26 INFO - 'SHLVL': '1', 05:05:26 INFO - 'TERM': 'linux', 05:05:26 INFO - 'TMOUT': '86400', 05:05:26 INFO - 'USER': 'cltbld', 05:05:26 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:26 INFO - '_': '/tools/buildbot/bin/python'} 05:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:28 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:28 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 05:05:28 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 05:05:28 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 05:05:28 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 05:05:28 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 05:05:28 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 05:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:28 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 05:05:28 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 05:05:28 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 05:05:28 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 05:05:28 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 05:05:28 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 05:05:29 INFO - Downloading docopt-0.6.1.tar.gz 05:05:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:29 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:29 INFO - Running setup.py install for blobuploader 05:05:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:29 INFO - Running setup.py install for requests 05:05:29 INFO - Running setup.py install for docopt 05:05:29 INFO - Successfully installed blobuploader requests docopt 05:05:29 INFO - Cleaning up... 05:05:29 INFO - Return code: 0 05:05:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:29 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 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:29 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 05:05:29 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 05:05:29 INFO - Using env: {'DISPLAY': ':0', 05:05:29 INFO - 'HOME': '/home/cltbld', 05:05:29 INFO - 'LANG': 'en_US.UTF-8', 05:05:29 INFO - 'LANGUAGE': 'en_US:en', 05:05:29 INFO - 'LOGNAME': 'cltbld', 05:05:29 INFO - 'MAIL': '/var/mail/cltbld', 05:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:29 INFO - 'MOZ_NO_REMOTE': '1', 05:05:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:29 INFO - 'NO_EM_RESTART': '1', 05:05:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:29 INFO - 'PWD': '/builds/slave/test', 05:05:29 INFO - 'SHELL': '/bin/bash', 05:05:29 INFO - 'SHLVL': '1', 05:05:29 INFO - 'TERM': 'linux', 05:05:29 INFO - 'TMOUT': '86400', 05:05:29 INFO - 'USER': 'cltbld', 05:05:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:29 INFO - '_': '/tools/buildbot/bin/python'} 05:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:30 INFO - Running setup.py (path:/tmp/pip-K8ilAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:30 INFO - Running setup.py (path:/tmp/pip-648Q1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:30 INFO - Running setup.py (path:/tmp/pip-ySVF1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:30 INFO - Running setup.py (path:/tmp/pip-LrHGdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:30 INFO - Running setup.py (path:/tmp/pip-mBeFKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:30 INFO - Running setup.py (path:/tmp/pip-ZP2_Fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:30 INFO - Running setup.py (path:/tmp/pip-bULAxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:30 INFO - Running setup.py (path:/tmp/pip-LRJI_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:30 INFO - Running setup.py (path:/tmp/pip-ezpyxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:30 INFO - Running setup.py (path:/tmp/pip-BPa8GB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:30 INFO - Running setup.py (path:/tmp/pip-DjnXnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:30 INFO - Running setup.py (path:/tmp/pip-EsjWn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:30 INFO - Running setup.py (path:/tmp/pip-UQu5dL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:30 INFO - Running setup.py (path:/tmp/pip-gOvcSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:31 INFO - Running setup.py (path:/tmp/pip-Y1iI_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:31 INFO - Running setup.py (path:/tmp/pip-or1uo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:31 INFO - Running setup.py (path:/tmp/pip-gXGq_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:31 INFO - Running setup.py install for manifestparser 05:05:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:31 INFO - Running setup.py install for mozcrash 05:05:31 INFO - Running setup.py install for mozdebug 05:05:31 INFO - Running setup.py install for mozdevice 05:05:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:31 INFO - Running setup.py install for mozfile 05:05:31 INFO - Running setup.py install for mozhttpd 05:05:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:31 INFO - Running setup.py install for mozinfo 05:05:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:31 INFO - Running setup.py install for mozInstall 05:05:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Running setup.py install for mozleak 05:05:32 INFO - Running setup.py install for mozlog 05:05:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Running setup.py install for moznetwork 05:05:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Running setup.py install for mozprocess 05:05:32 INFO - Running setup.py install for mozprofile 05:05:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Running setup.py install for mozrunner 05:05:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Running setup.py install for mozscreenshot 05:05:32 INFO - Running setup.py install for moztest 05:05:32 INFO - Running setup.py install for mozversion 05:05:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:32 INFO - Cleaning up... 05:05:33 INFO - Return code: 0 05:05:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:33 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')]} 05:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:33 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')]} 05:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:33 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 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:33 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 05:05:33 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 05:05:33 INFO - Using env: {'DISPLAY': ':0', 05:05:33 INFO - 'HOME': '/home/cltbld', 05:05:33 INFO - 'LANG': 'en_US.UTF-8', 05:05:33 INFO - 'LANGUAGE': 'en_US:en', 05:05:33 INFO - 'LOGNAME': 'cltbld', 05:05:33 INFO - 'MAIL': '/var/mail/cltbld', 05:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:33 INFO - 'MOZ_NO_REMOTE': '1', 05:05:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:33 INFO - 'NO_EM_RESTART': '1', 05:05:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:33 INFO - 'PWD': '/builds/slave/test', 05:05:33 INFO - 'SHELL': '/bin/bash', 05:05:33 INFO - 'SHLVL': '1', 05:05:33 INFO - 'TERM': 'linux', 05:05:33 INFO - 'TMOUT': '86400', 05:05:33 INFO - 'USER': 'cltbld', 05:05:33 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:33 INFO - '_': '/tools/buildbot/bin/python'} 05:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:33 INFO - Running setup.py (path:/tmp/pip-COYYhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:33 INFO - Running setup.py (path:/tmp/pip-tDrd_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:33 INFO - Running setup.py (path:/tmp/pip-UviwRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:33 INFO - Running setup.py (path:/tmp/pip-WwjR_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:33 INFO - Running setup.py (path:/tmp/pip-UnWuVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:33 INFO - Running setup.py (path:/tmp/pip-6WKs_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:33 INFO - Running setup.py (path:/tmp/pip-_XUIVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:33 INFO - Running setup.py (path:/tmp/pip-sqD82Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:33 INFO - Running setup.py (path:/tmp/pip-c4JcI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:33 INFO - Running setup.py (path:/tmp/pip-PbkepS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:33 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)) 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:33 INFO - Running setup.py (path:/tmp/pip-937vZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:34 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)) 05:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:34 INFO - Running setup.py (path:/tmp/pip-wNuAE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:34 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)) 05:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:34 INFO - Running setup.py (path:/tmp/pip-4Oe66C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:34 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)) 05:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:34 INFO - Running setup.py (path:/tmp/pip-XE4gqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:34 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)) 05:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:34 INFO - Running setup.py (path:/tmp/pip-mzELc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:34 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)) 05:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:34 INFO - Running setup.py (path:/tmp/pip-LoQkcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:34 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)) 05:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:34 INFO - Running setup.py (path:/tmp/pip-odtmKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:34 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)) 05:05:34 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)) 05:05:34 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)) 05:05:34 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)) 05:05:34 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)) 05:05:34 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)) 05:05:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:34 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 05:05:34 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 05:05:34 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 05:05:34 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 05:05:34 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 05:05:34 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 05:05:36 INFO - Downloading blessings-1.6.tar.gz 05:05:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:36 INFO - Installing collected packages: blessings 05:05:36 INFO - Running setup.py install for blessings 05:05:36 INFO - Successfully installed blessings 05:05:36 INFO - Cleaning up... 05:05:36 INFO - Return code: 0 05:05:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:36 INFO - Reading from file tmpfile_stdout 05:05:36 INFO - Current package versions: 05:05:36 INFO - argparse == 1.2.1 05:05:36 INFO - blessings == 1.6 05:05:36 INFO - blobuploader == 1.2.4 05:05:36 INFO - docopt == 0.6.1 05:05:36 INFO - manifestparser == 1.1 05:05:36 INFO - mozInstall == 1.12 05:05:36 INFO - mozcrash == 0.17 05:05:36 INFO - mozdebug == 0.1 05:05:36 INFO - mozdevice == 0.48 05:05:36 INFO - mozfile == 1.2 05:05:36 INFO - mozhttpd == 0.7 05:05:36 INFO - mozinfo == 0.9 05:05:36 INFO - mozleak == 0.1 05:05:36 INFO - mozlog == 3.2 05:05:36 INFO - moznetwork == 0.27 05:05:36 INFO - mozprocess == 0.22 05:05:36 INFO - mozprofile == 0.28 05:05:36 INFO - mozrunner == 6.11 05:05:36 INFO - mozscreenshot == 0.1 05:05:36 INFO - mozsystemmonitor == 0.3 05:05:36 INFO - moztest == 0.7 05:05:36 INFO - mozversion == 1.4 05:05:36 INFO - psutil == 3.1.1 05:05:36 INFO - requests == 1.2.3 05:05:36 INFO - wsgiref == 0.1.2 05:05:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:36 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')]} 05:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:36 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')]} 05:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:36 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 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:36 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 05:05:36 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 05:05:36 INFO - Using env: {'DISPLAY': ':0', 05:05:36 INFO - 'HOME': '/home/cltbld', 05:05:36 INFO - 'LANG': 'en_US.UTF-8', 05:05:36 INFO - 'LANGUAGE': 'en_US:en', 05:05:36 INFO - 'LOGNAME': 'cltbld', 05:05:36 INFO - 'MAIL': '/var/mail/cltbld', 05:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:36 INFO - 'MOZ_NO_REMOTE': '1', 05:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:36 INFO - 'NO_EM_RESTART': '1', 05:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:36 INFO - 'PWD': '/builds/slave/test', 05:05:36 INFO - 'SHELL': '/bin/bash', 05:05:36 INFO - 'SHLVL': '1', 05:05:36 INFO - 'TERM': 'linux', 05:05:36 INFO - 'TMOUT': '86400', 05:05:36 INFO - 'USER': 'cltbld', 05:05:36 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:36 INFO - '_': '/tools/buildbot/bin/python'} 05:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 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)) 05:05:37 INFO - Cleaning up... 05:05:37 INFO - Return code: 0 05:05:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:37 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 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:37 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 05:05:37 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 05:05:37 INFO - Using env: {'DISPLAY': ':0', 05:05:37 INFO - 'HOME': '/home/cltbld', 05:05:37 INFO - 'LANG': 'en_US.UTF-8', 05:05:37 INFO - 'LANGUAGE': 'en_US:en', 05:05:37 INFO - 'LOGNAME': 'cltbld', 05:05:37 INFO - 'MAIL': '/var/mail/cltbld', 05:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:37 INFO - 'MOZ_NO_REMOTE': '1', 05:05:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:37 INFO - 'NO_EM_RESTART': '1', 05:05:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:37 INFO - 'PWD': '/builds/slave/test', 05:05:37 INFO - 'SHELL': '/bin/bash', 05:05:37 INFO - 'SHLVL': '1', 05:05:37 INFO - 'TERM': 'linux', 05:05:37 INFO - 'TMOUT': '86400', 05:05:37 INFO - 'USER': 'cltbld', 05:05:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:37 INFO - '_': '/tools/buildbot/bin/python'} 05:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:37 INFO - Downloading/unpacking jsonschema 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:39 INFO - Downloading/unpacking functools32 (from jsonschema) 05:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:39 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:39 INFO - warning: no files found matching '*.txt' 05:05:39 INFO - no previously-included directories found matching 'build' 05:05:39 INFO - no previously-included directories found matching 'dist' 05:05:39 INFO - no previously-included directories found matching '.git*' 05:05:39 INFO - Installing collected packages: jsonschema, functools32 05:05:39 INFO - Running setup.py install for functools32 05:05:39 INFO - warning: no files found matching '*.txt' 05:05:39 INFO - no previously-included directories found matching 'build' 05:05:39 INFO - no previously-included directories found matching 'dist' 05:05:39 INFO - no previously-included directories found matching '.git*' 05:05:39 INFO - Successfully installed jsonschema functools32 05:05:39 INFO - Cleaning up... 05:05:39 INFO - Return code: 0 05:05:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6bd7b2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, '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': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', '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 05:05:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:05:39 INFO - Using env: {'DISPLAY': ':0', 05:05:39 INFO - 'HOME': '/home/cltbld', 05:05:39 INFO - 'LANG': 'en_US.UTF-8', 05:05:39 INFO - 'LANGUAGE': 'en_US:en', 05:05:39 INFO - 'LOGNAME': 'cltbld', 05:05:39 INFO - 'MAIL': '/var/mail/cltbld', 05:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:39 INFO - 'MOZ_NO_REMOTE': '1', 05:05:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:39 INFO - 'NO_EM_RESTART': '1', 05:05:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:39 INFO - 'PWD': '/builds/slave/test', 05:05:39 INFO - 'SHELL': '/bin/bash', 05:05:39 INFO - 'SHLVL': '1', 05:05:39 INFO - 'TERM': 'linux', 05:05:39 INFO - 'TMOUT': '86400', 05:05:39 INFO - 'USER': 'cltbld', 05:05:39 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:39 INFO - '_': '/tools/buildbot/bin/python'} 05:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:39 INFO - Downloading/unpacking flake8 05:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:05:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:05:41 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 05:05:41 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 05:05:41 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 05:05:41 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 05:05:41 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 05:05:41 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 05:05:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:05:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:05:41 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 05:05:41 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 05:05:41 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 05:05:41 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 05:05:41 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 05:05:41 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 05:05:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:05:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:05:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:05:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:05:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:05:42 INFO - no previously-included directories found matching 'docs/_build' 05:05:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:05:42 INFO - Running setup.py install for pep8 05:05:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:05:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:05:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:05:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:05:42 INFO - no previously-included directories found matching 'docs/_build' 05:05:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:05:42 INFO - Cleaning up... 05:05:42 INFO - Return code: 0 05:05:42 INFO - Running post-action listener: _resource_record_post_action 05:05:42 INFO - Running post-action listener: _start_resource_monitoring 05:05:42 INFO - Starting resource monitoring. 05:05:42 INFO - ##### 05:05:42 INFO - ##### Finished create-virtualenv step (success) 05:05:42 INFO - ##### 05:05:42 INFO - ##### 05:05:42 INFO - ##### Running install step. 05:05:42 INFO - ##### 05:05:42 INFO - Running pre-action listener: _resource_record_pre_action 05:05:42 INFO - Running main action method: install 05:05:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:42 INFO - Reading from file tmpfile_stdout 05:05:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:05:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:05:43 INFO - Reading from file tmpfile_stdout 05:05:43 INFO - Output received: 05:05:43 INFO - Usage: mozinstall [options] installer 05:05:43 INFO - Options: 05:05:43 INFO - -h, --help show this help message and exit 05:05:43 INFO - -d DEST, --destination=DEST 05:05:43 INFO - Directory to install application into. [default: 05:05:43 INFO - "/builds/slave/test"] 05:05:43 INFO - --app=APP Application being installed. [default: firefox] 05:05:43 INFO - mkdir: /builds/slave/test/build/application 05:05:43 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'] 05:05:43 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 05:05:59 INFO - Reading from file tmpfile_stdout 05:05:59 INFO - Output received: 05:05:59 INFO - /builds/slave/test/build/application/firefox/firefox 05:05:59 INFO - Running post-action listener: _resource_record_post_action 05:05:59 INFO - ##### 05:05:59 INFO - ##### Finished install step (success) 05:05:59 INFO - ##### 05:05:59 INFO - ##### 05:05:59 INFO - ##### Running run-tests step. 05:05:59 INFO - ##### 05:05:59 INFO - Running pre-action listener: _resource_record_pre_action 05:05:59 INFO - Running main action method: run_tests 05:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:59 INFO - Python 2.7.3 05:05:59 INFO - Return code: 0 05:05:59 INFO - grabbing minidump binary from tooltool 05:05:59 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')]} 05:05:59 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 0x19749c0>, '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 0x19758c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:59 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 05:05:59 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 05:05:59 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 05:05:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:59 INFO - Return code: 0 05:05:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:05:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:05:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:06:00 INFO - Return code: 0 05:06:00 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/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 05:06:00 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/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 05:06:00 INFO - Using env: {'DISPLAY': ':0', 05:06:00 INFO - 'HOME': '/home/cltbld', 05:06:00 INFO - 'LANG': 'en_US.UTF-8', 05:06:00 INFO - 'LANGUAGE': 'en_US:en', 05:06:00 INFO - 'LOGNAME': 'cltbld', 05:06:00 INFO - 'MAIL': '/var/mail/cltbld', 05:06:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:00 INFO - 'MOZ_NO_REMOTE': '1', 05:06:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:00 INFO - 'NO_EM_RESTART': '1', 05:06:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:00 INFO - 'PWD': '/builds/slave/test', 05:06:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:00 INFO - 'SHELL': '/bin/bash', 05:06:00 INFO - 'SHLVL': '1', 05:06:00 INFO - 'TERM': 'linux', 05:06:00 INFO - 'TMOUT': '86400', 05:06:00 INFO - 'USER': 'cltbld', 05:06:00 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631', 05:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:00 INFO - '_': '/tools/buildbot/bin/python'} 05:06:00 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/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 05:06:00 INFO - mozversion application_buildid: 20160530023021 05:06:00 INFO - mozversion application_changeset: be56cb9803a91540033261d76ade0e1f06c4691c 05:06:00 INFO - mozversion application_display_name: Nightly 05:06:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:00 INFO - mozversion application_name: Firefox 05:06:00 INFO - mozversion application_remotingname: firefox 05:06:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:06:00 INFO - mozversion application_vendor: Mozilla 05:06:00 INFO - mozversion application_version: 49.0a1 05:06:00 INFO - mozversion platform_buildid: 20160530023021 05:06:00 INFO - mozversion platform_changeset: be56cb9803a91540033261d76ade0e1f06c4691c 05:06:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:06:00 INFO - mozversion platform_version: 49.0a1 05:06:00 INFO - using testdate: 1464609960 05:06:00 INFO - actual date: 1464609960 05:06:00 INFO - starting webserver on 'localhost:46850' 05:06:00 INFO - SUITE-START | Running 2 tests 05:06:00 INFO - TEST-START | dromaeo_css 05:06:00 INFO - operating with platform_type : linux_ 05:06:00 INFO - Initialising browser for dromaeo_css test... 05:06:00 INFO - TEST-INFO | started process 5365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Y38DH/profile http://localhost:46850/getInfo.html) 05:06:04 INFO - PROCESS | 5365 | __metrics Screen width/height:1600/1200 05:06:04 INFO - PROCESS | 5365 | colorDepth:24 05:06:04 INFO - PROCESS | 5365 | Browser inner width/height: 1024/697 05:06:04 INFO - PROCESS | 5365 | __metrics 05:06:05 ERROR - PROCESS | 5365 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:06:06 INFO - TEST-INFO | 5365: exit 0 05:06:06 INFO - Browser initialized. 05:06:06 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:06 INFO - TEST-INFO | started process 5444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Y38DH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:18 INFO - PROCESS | 5444 | RSS: Main: 155058176 05:06:18 INFO - PROCESS | 5444 | 05:07:09 INFO - PROCESS | 5444 | 1464610029550 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] 05:09:17 INFO - PROCESS | 5444 | Cycle 1(1): loaded http://localhost:46850/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46850/tests/dromaeo/cssquery-ext.html) 05:09:18 INFO - PROCESS | 5444 | RSS: Main: 286318592 05:09:18 INFO - PROCESS | 5444 | 05:12:20 INFO - PROCESS | 5444 | Cycle 1(1): loaded http://localhost:46850/tests/dromaeo/cssquery-ext.html (next: http://localhost:46850/tests/dromaeo/cssquery-jquery.html) 05:12:21 INFO - PROCESS | 5444 | RSS: Main: 243023872 05:12:21 INFO - PROCESS | 5444 | 05:15:23 INFO - PROCESS | 5444 | Cycle 1(1): loaded http://localhost:46850/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46850/tests/dromaeo/cssquery-mootools.html) 05:15:23 INFO - PROCESS | 5444 | RSS: Main: 245886976 05:15:23 INFO - PROCESS | 5444 | 05:18:25 INFO - PROCESS | 5444 | Cycle 1(1): loaded http://localhost:46850/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46850/tests/dromaeo/cssquery-prototype.html) 05:18:26 INFO - PROCESS | 5444 | RSS: Main: 245686272 05:18:26 INFO - PROCESS | 5444 | 05:21:29 INFO - PROCESS | 5444 | Cycle 1(1): loaded http://localhost:46850/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46850/tests/dromaeo/cssquery-yui.html) 05:21:29 INFO - PROCESS | 5444 | RSS: Main: 212819968 05:21:29 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | Cycle 1(1): loaded http://localhost:46850/tests/dromaeo/cssquery-yui.html (next: http://localhost:46850/tests/dromaeo/cssquery-dojo.html) 05:24:32 INFO - PROCESS | 5444 | RSS: Main: 303939584 05:24:32 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | __start_tp_report 05:24:32 INFO - PROCESS | 5444 | _x_x_mozilla_page_load 05:24:32 INFO - PROCESS | 5444 | _x_x_mozilla_page_load_details 05:24:32 INFO - PROCESS | 5444 | |i|pagename|runs| 05:24:32 INFO - PROCESS | 5444 | |0;dojo.html;3087.814731116133;3097.1637657832384;3115.049909777518;3137.121605950335;3157.510585859191;5234.398044224915;5308.893822123555;5311.415744268133;5397.379301380342;5412.431694672062;5300.01949639317;5305.0450918834595;5328.920066199001;5341.385740639827;5377.865553361168;4776.665633405663;4779.880502987418;4925.285833554132;4951.900961980758;4958.305837182797;4884.633652476061;4921.015796840628;4935.284383764352;4947.332110165135;4960.503949605047;4723.07899959508;4743.122522333362;4772.379590653228;4831.033793241366;4832.275158726184;4785.282207668843;4785.95214047862;4792.161371759963;4817.9518204818205;4858.344123543312;4766.618670506386;4823.975880120612;4838.540338667856;4846.418429788413;4876.366072410599;5129.8717532062;5195.766190521398;5222.2427747976335;5267.262583238311;5270.973645131749;5636.971815140939;5670.574706896958;5690.772369105231;5751.971240143815;5760.913586296209;5739.512141467981;5741.741621627037;5810.360860305363;5817.970910145422;5819.127130930352;5247.317848679647;5319.335083114611;5425.674459532441;5451.482109199659;5456.48163424478;5821.854453638608;5871.794487192928;5874.9118763218585;5922.378150294243;5922.555808314393;5600.607957443021;5617.971910140505;5669.574781891375;5670;5671.688057156896;5458.808941687022;5657.886842263131;5684.033714268584;5703.62926409782;5757.769689212469;5697.316322041382;5710.7430165642645;5716.056850619601;5720.056190728566;5761.135829625589;5650.463205995424;5684.317881854121;5686.147077938343;5701.2018317435595;5704.942950570524;4210.179015092089;4236.978815105905;4298.355246712956;4323.329883868006;4377.4965878923695;3842.135519508144;3855.3060449118866;3858.6334298241622;3888.4556162137324;3910.237503686754;5351.224072509464;5363.785448582013;5367.597430192673;5371.435999220103;5419.078756611386;5579.2746942897165;5676.262085928803;5700.25896633435;5707.258056452634;5729.9713501432225;5358.866028349323;5369;5377.973110134502;5380.7309634518115;5384.730763461811;4776.379070720784;4785.880352991134;4790.401199850019;4790.904181916342;4829.6860704054125;5745.511631511285;5825.155352473952;5846.093855452412;5872.031114866085;5884.9705751470965;5729.799457018984;5736.741846616996;5748.425157484386;5750.6262092963825;5752.856178595569;5704.258446401941;5894.38108998549;5914.645121292736;5925;5927.8221653350465;5565.582581306499;5584.190292407497;5596.076647353141;5617.578681598815;5620.494155526022;5682.516986056066;5683.744231509675;5686.203931449683;5686.402927692533;5690.971545142248;5413.079776437936;5514.91727624078;5587.664740115606;5609.074502707008;5614.270144881302;5521.723913804374;5547.278853748987;5549.611527193219;5577.414371490935;5604.439556044526;5395.622306438511;5458.235846981346;5471.480209380103;5484.177373394023;5488.9725551371985;5503.7798488061635;5596.160575913483;5603.74783134768;5611.607187496836;5615.915761263666;5340.465953404629;5374.919376209282;5524.06090964529;5524.696141712084;5591.217229587943 05:24:32 INFO - PROCESS | 5444 | |1;ext.html;578.962367446116;624.0794827629246;628.8805127025864;647.2007071266985;660.075893748752;31590.368192636135;43385.34921976169;43858;44031.779841100826;44127.11745765083;47239.76380118095;47833.521664783344;47879.76060119695;47901;47922.52077479224;8844.425112367691;9048.457092574467;9048.502332371718;9055.275577953747;9058.909410905873;8778.253848422892;8932.64269429222;8937.865932011026;8939.061398553156;8943.910560894374;8959.372843900897;8965.820683586324;8968.775780605472;8971.820563588724;8972.730818075435;8804.295656347509;8864.024957254696;8884.644614215456;8889.73330800077;8890.4665720057;8844.336674749355;8955.149260820212;8959.238464730506;8965.327600429993;8968.372213945026;42036.789816050725;42784.57215427837;42823.35764963528;42886.142277154286;42954.57045429537;3464.826758662057;3503.4744788281705;3575.8212089395424;3681.208540163851;3798.4492248623797;33049.17377065569;33295.16762080943;33541.49687754661;34139.829300853584;34186.14534636629;4468.575485328888;4483.08096840147;4483.4843992940905;4487.842925497592;4574.130915126116;357215.2139239313;357258;358257;359435;359644;62339.06491402633;62536.37463625306;62945.68527157335;63034;63041.68479157666;57809.71095144498;63128.684356578844;64645.353546463935;64686.676566616865;70865;17518.29926802939;17633.911830440764;17635.911820440815;17654.382096626556;17738.645227095643;12262.509499619915;12268.141230113984;12362.505499780087;12401.565945191875;12405.069619778373;17628.118594070245;19240.4227873164;19580.782971494184;19660.803391965896;20575.176992920115;7312.23221561739;7330.560166390033;7376.373008294355;7423.62881855905;7522.774316770506;978.505854543451;1052.394872948058;1117.4245263689206;1187.6912002879315;1238.108561835477;33547.4967875482;33567.16082097967;33593.664063359545;33999.660003400146;34503.65496345054;1079.3416016230094;1141.7944769941491;1178.988210117905;1182.5151687807956;1199.9580014699443;17604.38384656531;17628.383006594708;17757.556061098316;17948.910255448638;18008.36970706019;485818;490763;491903;493943;494104;61447.6927615368;61984.07023894645;62095.6895215521;62117.378826212065;62128.689356552924;1288.761579107868;1324.1326930860303;1373.2927542315715;1396.7206558688304;1421.076300404745;33634.15914602154;35025.12437189091;35158.64841351656;35362.646373535936;35416.64583354237;52116.73941630268;52197.73901130622;52658.21012684736;52713.20930186127;52798.73600632126;345989;346370;346505;346611;347327;27053.72946270512;28221.71778282191;28490.43019139564;28945.42109157847;30438.239044024078;49840.252396213364;50740.49259507358;51048.489515104375;51319.74340128276;51709.7414512925;41785.16429671329;42165.367519486615;42931.570684292754;43554.12891742211;43935.340969886114;42001.15997679968;42400.15199695927;42459;42825.571744282155;42896.571034289256;41812.372814408416;42306.36540451834;43298.35052474152;43380.56619433891;43420.56579434165;37415.43876841795;37469;37949.620503794606;38473;38731.22537549177;28987.275318117216;29045.85477072601;29312.706872931853;29399.55900661449;29429.705702943553 05:24:32 INFO - PROCESS | 5444 | |2;jquery.html;389.3128627971629;393.15275581122654;395.4147861165474;408.12864534219443;413.93997870308806;17887.55281117973;19531.41405757826;19672;19672.409827705156;20752;17906.552336191602;18794.436166915013;18800.811991880077;19292.228310867584;19670.901645491787;5059.139946209135;5193.870153246171;5234.136367499358;5256.369235691723;5309.681419114846;5035.471275516054;5052.570531504826;5053.343065401511;5073.594112470993;5087.7710503027265;5154.587632989352;5161.561267292266;5377.247185394048;5680.431956804307;5691.03252447085;5488.506034456918;5658.34928983166;5665.263515742948;5702.144678298247;5713.6000479966415;5505.229267902497;5519.089350257203;5525.640833345819;5538.778448862081;5540.473655002768;13243.271620060892;13530.729385412236;13794.448222071105;13807.861921380758;13834.861651383459;2122.3102491690106;2172.532905425325;2271.534335461243;2295.185209250709;2357.2221167014845;9705.417674939526;10346.896531034743;10907.781844363148;11440.027597654209;14652.853471465362;1890.7258447525032;1899.1074195128267;1907.4563749331373;1914.6457905287427;1984.603079384129;84404;87930;90174.5491272546;90285.77713782882;90416.54791726066;10825.350478971286;11135.72160695989;11231.66305010851;11264.774704505864;11284.774304513863;15331.61670958235;15646.295916683777;15653.530394087968;15661.903666743447;15667;8210.507369557845;8303.252707256375;8352.958235208785;8515.446495977742;8515.86980314547;7956.124826269106;8022;8065.153158918347;8116.472429292077;8214.219649133323;4681.344611754357;4781.187198176319;4786.425628924551;4831.661783675109;4907.411110666742;1850.5743678953647;1861.8417434517946;1951.3170390363543;1967.911443985024;1993.8504612154147;946.6355453150449;948.7960088580988;958.3914214473859;966.1739212972807;1068.631322193842;18105.637887242447;19059.80940190608;19065.80934190668;19174.520636984187;19203.231870725016;953.2374100719396;963.1861077389594;1016.7966406718538;1039.153090231459;1056.6829951014665;10149.188064954933;10428.322159059637;10674.899440428646;10843.240973131962;11264.774704505864;452587;460397;463786;474632;475981;96521.517392414;98618.04088441479;102210.4226352074;102799.48600256951;103285.48357258165;1026.5585798106886;1074.785042991405;1077.5582011375297;1113.8273567597034;1120.7422292872684;17204.655906882042;17278.22247998818;17666.116694165496;17816.27558662007;17950.730739039187;43210.35184472173;43982;44113.33829992617;45001.54998450105;45701.77149114366;60995;61603.69198153981;62257.37742622697;63956.3604363969;64039;8406.87389689143;8475.915240847513;8506.957465212634;8514.020887598006;8720;9472.715818525456;12357;12672.683182920133;12954.805677915027;13004.154729942524;11317.434128293717;11368;11763.47064382122;11880.82178767302;11892.227005244631;11267.661970140909;11536.134789890624;11576.421178941187;11704.531818727155;12048.457819397972;11411.543538258376;11464.656060318204;12015.45930433115;12164.844339787705;12237.510499579917;8128.593570321578;8185.304249138651;8223.09545949933;8524;8543.786405339917;11418.543258269909;11708.648740537797;11819;11852.466639001093;12034.217775844541 05:24:32 INFO - PROCESS | 5444 | |3;mootools.html;1410.3159967415809;1460.6348412896775;1527.350875877752;1691.687037897989;1858.3588661911635;8686.13138686131;9043.909560904398;9045.592948317326;9306.441613503177;9321.254299656028;8992.595333210005;9191.77020574486;9227.907720922789;9230.261579073675;9234.769130771734;2060.268604645352;2300.1834348806183;2397.041183526586;2413.9396515087133;2442.352776514216;2373.3947843299934;2384.5350156719446;2386.5107652931106;2390.2590197038885;2390.474095698952;2318.6088346991755;2381.868997205153;2382.2376839411395;2382.4401265702545;2383.9046438142454;2044.100595737878;2319.802816760569;2389.689340385756;2393.0906255622754;2393.497365553236;2234.340869443518;2247.831412644058;2260.5139894922672;2265.0147185973988;2279.8290128240446;9177.311701622404;9235.353525253235;9240.214581760558;9242.168204861595;9245.72262832116;5049.267856160836;5135.050015747099;5153.2270159476;5160.329157209539;5167.896642067175;3358.1604598850286;3475.131217195701;3519.5072689823446;3543.0965103898598;3555.1112221944513;1122.81473556863;1130.8021096308114;1144.067584918289;1155.1451925575013;1174.4597485156837;12578.874211257862;12589.622311330766;12596.496140154382;12607.180533265308;12624.24254544721;4594.264917613166;4703.3885594872445;4731.195696731564;4742.5731684148595;4772.284157376422;10562.049415552638;10570.682879513626;10572.471376431147;10579.15366770672;10587.629432969808;4906.852794416173;5291.576673866081;5342.679439233659;5353.411124776271;5356.3572371315695;4687.460941991714;4784.497627749106;4787.928181077286;4850.272459131159;4852.0295940811975;4093.8771836844944;4137.710360274752;4139.627433530996;4150.730202536825;4205.663546916239;1267.6767424306743;1279.5457612547411;1287.0218633838351;1296.1315918334737;1296.5397283964057;937.6765016069445;940.4968341937031;947.1617618407759;955.2214944820089;991.9752006199902;5851.765929362863;5931.911021334683;5975.300760130041;6044.123602077674;6072.726727297282;421.8693900347038;423.7753990385039;433.772269558478;434.24224727850105;439.984600538986;3986.9601303986587;4041;4083.4691490106097;4097.467329247179;4118.75287482746;11342.943285283685;11554.248973816842;11619.419029048513;11720.648380548597;11721;11390.544378224778;11471.655850324503;11586.826197607043;11639.894210050032;11643.708907277385;8172.713955011546;8456.619452124669;8496.66013359471;8598.742037738877;8652.740417787476;6122.785702500391;6339.080833279057;6402.391772781578;6413.679316034273;6469.2560355557835;10473.214508912015;10639.734006649585;10705.464726763475;10754.89245107539;10826.891731082904;10865.293755905841;10998;11017.889821102008;11021.724456888642;11034.944825276143;7417.221191774786;7565.2812982766545;7666.310032097138;7883.881741773764;8010.759677209693;9030.774230644287;9042.683506077256;9136.63453461854;9145.817083658156;9147.222486088756;8071.192880711882;8093.150219226896;8093.271605555528;8231.670733170846;8407.369447291356;8012.919870801217;8074.555899425588;8115.350771938349;8160.918390816253;8170.632321545426;6799.796006119825;6803.285655006351;6839.794806155824;6972.477064220102;6983.9650801745665;5173.301604283524;5193.92209116856;5240.633155679066;5258.158694608845;5289.9471005291;5184.896302074013;5276.182191760283;5296.6292359536;5348.893022139613;5395.271638328775 05:24:32 INFO - PROCESS | 5444 | |4;prototype.html;326.8316816839328;332.44315771083427;336.8517852145057;345.8236299487261;349.1028057891219;5150.2532132840715;5254.238135470355;5308.893822123555;5350.919736203955;5408.891822163554;5266.157414813631;5294.364676238847;5319.734013299339;5321.148616221396;5325.4142044375185;4158.896027599311;4188.204241194178;4189.874303770892;4202.70581059326;4210.368444733299;3968.7420317679403;3992.900177495557;4199.223143718406;4215.894602634928;4230.598093181158;4121.711480196387;4138.544760076389;4145.730527515717;4148.813303401339;4162.521310049353;4149.273877071516;4162.271602469556;4167.91664166715;4168.353905144692;4172.77049762263;3450.7404797248823;3728.328900797855;3729.589745128034;3731.328360895038;3744.101415660249;5090.701871022902;5093.617978651616;5110.897782044338;5112.849608838019;5122.3853137623355;4669.089527542131;4694.812207511695;4710.057988402334;4719.646026548023;4722.858314250544;5349.7057661828585;5475.972620136914;5493.093639549469;5504;5512.724363781835;3939.0940083780893;3958.9802050989847;3993.7803420811842;4003.6178473808036;4005.439238506611;5722.340521248833;5751.798687045934;5755.712214389264;5783.768649254067;5797.594168408213;3945.763254204757;4063.6952228582973;4072.450219220427;4072.470578824734;4075.8777236682945;4107.609777071233;4163.335465302209;4166.0626359068965;4182.623563879265;4193.958060419418;4743.980044290494;4768.594669453066;4777.713337199779;4784.210605250164;4795.544423279783;5500.669959802424;5564.527015203753;5582.162675598612;5660.320761508563;5676.0918253080135;1398.1820634928476;1401.2223216115153;1401.2293238718664;1402.7475054490085;1413.342795600051;872.7905302727299;879.2722300679196;880.3089574683843;914.4056363363867;914.491089701997;4663.950611112473;4745.691530050535;4778.402699662543;4783.851875549913;4822.831200907952;5024.397072351268;5048.495150484996;5077.543021128116;5083.491650834961;5162.535371816554;4756.524347565285;4760.809567617256;4768.690035147704;4778.856634300976;4919.631027672939;3027.9697203027686;3052.4200401923563;3067.4018566379787;3073.631164260303;3074.83088430134;5961;5961.612495187799;5963.314218864886;6007.399260074045;6029.276486821609;5644.266245388073;5932.902413053599;5937.7921772737745;5977.312609049928;5984.371640977634;5932.703364831741;6011.6994150293185;6021.8558473889825;6022.156898034279;6028.427299406461;5635.520980716685;5839.912401313899;5853.297604287342;5863.794767183128;5864.794732184353;5752.68360240174;5782;5784.843031393654;5791.565632577489;5803;5982.820515384546;5983.551233657406;5990.850228744316;6007.579469437273;6009.158717779427;3999.0804413882083;4036.273470775289;4111.403846442189;4112.157007813451;4112.506499219993;5837.701698540312;5844.12338149281;5850.912236316543;5853.209816674695;5861.413858614104;5508.641938274;5708.488265163481;5712.485876271156;5719.685417302088;5723.7424315905055;5607.158926161109;5651.57613179005;5670.432956704297;5690.9430905690415;5701.287339082614;5518;5539.25220095282;5547.472990065938;5596;5603.579731520071;5537.889242215214;5582.720863956867;5682.857928551819;5700.572457065654;5722.570807189561;5531.944680553143;5542.2795036644975;5610.8316750495915;5620.606557540933;5625.88748225025 05:24:32 INFO - PROCESS | 5444 | |5;yui.html;746.1978373248758;856.2507805670039;860.8708693695945;866.7919699272175;872.8516152254116;13606.931965340173;14275.71448571028;14307.928460357683;14315.069520481175;14338.068025578343;13291.335433228349;14554.636134096627;14605;14628.926855365735;14776.926115369408;778.4434129597337;782.9412794040442;789.6880732110812;790.0124843945068;790.7271991163068;797.2346547314585;800.7277525641281;801.9478733882279;802.5585927739749;803.148662417839;844.7845799321195;845.0282175498165;845.5349557743248;847.5677404523707;847.8728190771403;839.0351096239374;844.0757370679097;845.5687599324358;845.602566793606;848.0247715127591;611.0376157551874;615.6121643364696;615.6583096381506;615.7875532941173;616.6731632234922;12586.18189817659;12614.180078294974;12620.936895315464;12625.494980200781;14445.638859028504;13625.25061121638;13688.452461901612;13689.520866769715;13746.93126534371;13934.93032534841;12828.294443805587;12888.291143987077;12947.48210071596;13000.219986800823;13064.412101455457;4359.673024523174;4452.28763397858;4459.44256967879;4490.057088011522;4490.820367185309;61547.384526154616;62510;63292.272345250414;63413.68293158551;63701;10993.780124397466;11023.503942322615;11041.503132358903;11159.386233757059;11224.382658953837;19345.90327048356;19397.03014849252;19402.223911043682;19451.22195112208;19471.610567788564;7969.72105976288;8023.077346105156;8023.679052837938;8155.062167850773;8187.222213889671;4510.526394728507;4514.25514790056;4595.724256544551;4717.693349932243;4735.644826638016;3567.1973805893467;3721.3859713147026;3724.2365315110337;3724.2923844470006;3739.08392443853;651.1957732200729;663.3200969006807;665.9101021362054;671.4427025568864;673.3333999340617;752.8005078654161;765.5712800831441;793.7856778669727;801.1907972947249;802.4743792815715;9512.904870951203;9533.952330238442;9682.261821047292;9927.106560409598;10074.194064474825;621.5897507644933;688.784295718058;727.9008696867662;729.0667945030264;787.22851605427;4958.305837182797;5204.4535323791215;5229.712365819841;5372.946270537323;5381.9461805382225;77613.61193193997;78086;78323;79237.20762792413;80349.59825200836;69262.65368673124;69591;69664;70271.64864175646;73001.26998729944;60556.394436055074;61049.08426373608;61948.69025654843;63108;64406.355936440035;9749.610015599297;9945.204383649147;10090.445025523666;10138.746531336776;10371.896281037092;40242.19515609613;40307.59692403038;40831;41164.382534261415;41246.38130428106;49876.62428113756;54675.72662136664;54902;55288.44711552994;56797.71601141968;13138.277394743382;13191.274479903697;13418.664533386744;13456.46174153023;13791.172529648253;14064.648383790487;14345.329421818904;14429.20639364845;14491.710165796836;14514.927425362806;14033.298335083;14229.14625122496;14250.786238206643;15193.316300766272;15284.388624454898;13981.161130332213;14146.363413646204;14252.515120517177;14426.134431934117;14667.633309167357;14110.929445352707;14188.787168192279;14497.420103195755;14915.105093694414;14930.701385972003;11050.502727377127;11376.374299413612;11386.316820990767;11421.828672570084;11504.017910686563;10863.728406789893;10927.726806829893;11352.772944540899;11607.76784464289;11649.359285239394 05:24:32 INFO - PROCESS | 5444 | __end_tp_report 05:24:32 INFO - PROCESS | 5444 | __start_cc_report 05:24:32 INFO - PROCESS | 5444 | _x_x_mozilla_cycle_collect,1071 05:24:32 INFO - PROCESS | 5444 | __end_cc_report 05:24:32 INFO - PROCESS | 5444 | __startTimestamp1464611072304__endTimestamp 05:24:32 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | ------- Summary: start ------- 05:24:32 INFO - PROCESS | 5444 | Number of tests: 6 05:24:32 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | [#0] dojo.html Cycles:165 Average:5285.57 Median:5484.18 stddev:604.57 (11.0%) stddev-sans-first:581.33 05:24:32 INFO - PROCESS | 5444 | Values: 3087.8 3097.2 3115.0 3137.1 3157.5 5234.4 5308.9 5311.4 5397.4 5412.4 5300.0 5305.0 5328.9 5341.4 5377.9 4776.7 4779.9 4925.3 4951.9 4958.3 4884.6 4921.0 4935.3 4947.3 4960.5 4723.1 4743.1 4772.4 4831.0 4832.3 4785.3 4786.0 4792.2 4818.0 4858.3 4766.6 4824.0 4838.5 4846.4 4876.4 5129.9 5195.8 5222.2 5267.3 5271.0 5637.0 5670.6 5690.8 5752.0 5760.9 5739.5 5741.7 5810.4 5818.0 5819.1 5247.3 5319.3 5425.7 5451.5 5456.5 5821.9 5871.8 5874.9 5922.4 5922.6 5600.6 5618.0 5669.6 5670.0 5671.7 5458.8 5657.9 5684.0 5703.6 5757.8 5697.3 5710.7 5716.1 5720.1 5761.1 5650.5 5684.3 5686.1 5701.2 5704.9 4210.2 4237.0 4298.4 4323.3 4377.5 3842.1 3855.3 3858.6 3888.5 3910.2 5351.2 5363.8 5367.6 5371.4 5419.1 5579.3 5676.3 5700.3 5707.3 5730.0 5358.9 5369.0 5378.0 5380.7 5384.7 4776.4 4785.9 4790.4 4790.9 4829.7 5745.5 5825.2 5846.1 5872.0 5885.0 5729.8 5736.7 5748.4 5750.6 5752.9 5704.3 5894.4 5914.6 5925.0 5927.8 5565.6 5584.2 5596.1 5617.6 5620.5 5682.5 5683.7 5686.2 5686.4 5691.0 5413.1 5514.9 5587.7 5609.1 5614.3 5521.7 5547.3 5549.6 5577.4 5604.4 5395.6 5458.2 5471.5 5484.2 5489.0 5503.8 5596.2 5603.7 5611.6 5615.9 5340.5 5374.9 5524.1 5524.7 5591.2 05:24:32 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | [#1] ext.html Cycles:180 Average:57736.96 Median:42855.86 stddev:106594.45 (248.7%) stddev-sans-first:106807.09 05:24:32 INFO - PROCESS | 5444 | Values: 579.0 624.1 628.9 647.2 660.1 31590.4 43385.3 43858.0 44031.8 44127.1 47239.8 47833.5 47879.8 47901.0 47922.5 8844.4 9048.5 9048.5 9055.3 9058.9 8778.3 8932.6 8937.9 8939.1 8943.9 8959.4 8965.8 8968.8 8971.8 8972.7 8804.3 8864.0 8884.6 8889.7 8890.5 8844.3 8955.1 8959.2 8965.3 8968.4 42036.8 42784.6 42823.4 42886.1 42954.6 3464.8 3503.5 3575.8 3681.2 3798.4 33049.2 33295.2 33541.5 34139.8 34186.1 4468.6 4483.1 4483.5 4487.8 4574.1 357215.2 357258.0 358257.0 359435.0 359644.0 62339.1 62536.4 62945.7 63034.0 63041.7 57809.7 63128.7 64645.4 64686.7 70865.0 17518.3 17633.9 17635.9 17654.4 17738.6 12262.5 12268.1 12362.5 12401.6 12405.1 17628.1 19240.4 19580.8 19660.8 20575.2 7312.2 7330.6 7376.4 7423.6 7522.8 978.5 1052.4 1117.4 1187.7 1238.1 33547.5 33567.2 33593.7 33999.7 34503.7 1079.3 1141.8 1179.0 1182.5 1200.0 17604.4 17628.4 17757.6 17948.9 18008.4 485818.0 490763.0 491903.0 493943.0 494104.0 61447.7 61984.1 62095.7 62117.4 62128.7 1288.8 1324.1 1373.3 1396.7 1421.1 33634.2 35025.1 35158.6 35362.6 35416.6 52116.7 52197.7 52658.2 52713.2 52798.7 345989.0 346370.0 346505.0 346611.0 347327.0 27053.7 28221.7 28490.4 28945.4 30438.2 49840.3 50740.5 51048.5 51319.7 51709.7 41785.2 42165.4 42931.6 43554.1 43935.3 42001.2 42400.2 42459.0 42825.6 42896.6 41812.4 42306.4 43298.4 43380.6 43420.6 37415.4 37469.0 37949.6 38473.0 38731.2 28987.3 29045.9 29312.7 29399.6 29429.7 05:24:32 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | [#2] jquery.html Cycles:180 Average:28684.46 Median:2147.42 stddev:77385.28 (3603.6%) stddev-sans-first:77573.20 05:24:32 INFO - PROCESS | 5444 | Values: 389.3 393.2 395.4 408.1 413.9 17887.6 19531.4 19672.0 19672.4 20752.0 17906.6 18794.4 18800.8 19292.2 19670.9 5059.1 5193.9 5234.1 5256.4 5309.7 5035.5 5052.6 5053.3 5073.6 5087.8 5154.6 5161.6 5377.2 5680.4 5691.0 5488.5 5658.3 5665.3 5702.1 5713.6 5505.2 5519.1 5525.6 5538.8 5540.5 13243.3 13530.7 13794.4 13807.9 13834.9 2122.3 2172.5 2271.5 2295.2 2357.2 9705.4 10346.9 10907.8 11440.0 14652.9 1890.7 1899.1 1907.5 1914.6 1984.6 84404.0 87930.0 90174.5 90285.8 90416.5 10825.4 11135.7 11231.7 11264.8 11284.8 15331.6 15646.3 15653.5 15661.9 15667.0 8210.5 8303.3 8353.0 8515.4 8515.9 7956.1 8022.0 8065.2 8116.5 8214.2 4681.3 4781.2 4786.4 4831.7 4907.4 1850.6 1861.8 1951.3 1967.9 1993.9 946.6 948.8 958.4 966.2 1068.6 18105.6 19059.8 19065.8 19174.5 19203.2 953.2 963.2 1016.8 1039.2 1056.7 10149.2 10428.3 10674.9 10843.2 11264.8 452587.0 460397.0 463786.0 474632.0 475981.0 96521.5 98618.0 102210.4 102799.5 103285.5 1026.6 1074.8 1077.6 1113.8 1120.7 17204.7 17278.2 17666.1 17816.3 17950.7 43210.4 43982.0 44113.3 45001.5 45701.8 60995.0 61603.7 62257.4 63956.4 64039.0 8406.9 8475.9 8507.0 8514.0 8720.0 9472.7 12357.0 12672.7 12954.8 13004.2 11317.4 11368.0 11763.5 11880.8 11892.2 11267.7 11536.1 11576.4 11704.5 12048.5 11411.5 11464.7 12015.5 12164.8 12237.5 8128.6 8185.3 8223.1 8524.0 8543.8 11418.5 11708.6 11819.0 11852.5 12034.2 05:24:32 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | [#3] mootools.html Cycles:180 Average:5983.48 Median:4640.86 stddev:3507.76 (75.6%) stddev-sans-first:3500.76 05:24:32 INFO - PROCESS | 5444 | Values: 1410.3 1460.6 1527.4 1691.7 1858.4 8686.1 9043.9 9045.6 9306.4 9321.3 8992.6 9191.8 9227.9 9230.3 9234.8 2060.3 2300.2 2397.0 2413.9 2442.4 2373.4 2384.5 2386.5 2390.3 2390.5 2318.6 2381.9 2382.2 2382.4 2383.9 2044.1 2319.8 2389.7 2393.1 2393.5 2234.3 2247.8 2260.5 2265.0 2279.8 9177.3 9235.4 9240.2 9242.2 9245.7 5049.3 5135.1 5153.2 5160.3 5167.9 3358.2 3475.1 3519.5 3543.1 3555.1 1122.8 1130.8 1144.1 1155.1 1174.5 12578.9 12589.6 12596.5 12607.2 12624.2 4594.3 4703.4 4731.2 4742.6 4772.3 10562.0 10570.7 10572.5 10579.2 10587.6 4906.9 5291.6 5342.7 5353.4 5356.4 4687.5 4784.5 4787.9 4850.3 4852.0 4093.9 4137.7 4139.6 4150.7 4205.7 1267.7 1279.5 1287.0 1296.1 1296.5 937.7 940.5 947.2 955.2 992.0 5851.8 5931.9 5975.3 6044.1 6072.7 421.9 423.8 433.8 434.2 440.0 3987.0 4041.0 4083.5 4097.5 4118.8 11342.9 11554.2 11619.4 11720.6 11721.0 11390.5 11471.7 11586.8 11639.9 11643.7 8172.7 8456.6 8496.7 8598.7 8652.7 6122.8 6339.1 6402.4 6413.7 6469.3 10473.2 10639.7 10705.5 10754.9 10826.9 10865.3 10998.0 11017.9 11021.7 11034.9 7417.2 7565.3 7666.3 7883.9 8010.8 9030.8 9042.7 9136.6 9145.8 9147.2 8071.2 8093.2 8093.3 8231.7 8407.4 8012.9 8074.6 8115.4 8160.9 8170.6 6799.8 6803.3 6839.8 6972.5 6984.0 5173.3 5193.9 5240.6 5258.2 5289.9 5184.9 5276.2 5296.6 5348.9 5395.3 05:24:32 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | [#4] prototype.html Cycles:180 Average:4676.39 Median:5208.39 stddev:1389.13 (26.7%) stddev-sans-first:1354.12 05:24:32 INFO - PROCESS | 5444 | Values: 326.8 332.4 336.9 345.8 349.1 5150.3 5254.2 5308.9 5350.9 5408.9 5266.2 5294.4 5319.7 5321.1 5325.4 4158.9 4188.2 4189.9 4202.7 4210.4 3968.7 3992.9 4199.2 4215.9 4230.6 4121.7 4138.5 4145.7 4148.8 4162.5 4149.3 4162.3 4167.9 4168.4 4172.8 3450.7 3728.3 3729.6 3731.3 3744.1 5090.7 5093.6 5110.9 5112.8 5122.4 4669.1 4694.8 4710.1 4719.6 4722.9 5349.7 5476.0 5493.1 5504.0 5512.7 3939.1 3959.0 3993.8 4003.6 4005.4 5722.3 5751.8 5755.7 5783.8 5797.6 3945.8 4063.7 4072.5 4072.5 4075.9 4107.6 4163.3 4166.1 4182.6 4194.0 4744.0 4768.6 4777.7 4784.2 4795.5 5500.7 5564.5 5582.2 5660.3 5676.1 1398.2 1401.2 1401.2 1402.7 1413.3 872.8 879.3 880.3 914.4 914.5 4664.0 4745.7 4778.4 4783.9 4822.8 5024.4 5048.5 5077.5 5083.5 5162.5 4756.5 4760.8 4768.7 4778.9 4919.6 3028.0 3052.4 3067.4 3073.6 3074.8 5961.0 5961.6 5963.3 6007.4 6029.3 5644.3 5932.9 5937.8 5977.3 5984.4 5932.7 6011.7 6021.9 6022.2 6028.4 5635.5 5839.9 5853.3 5863.8 5864.8 5752.7 5782.0 5784.8 5791.6 5803.0 5982.8 5983.6 5990.9 6007.6 6009.2 3999.1 4036.3 4111.4 4112.2 4112.5 5837.7 5844.1 5850.9 5853.2 5861.4 5508.6 5708.5 5712.5 5719.7 5723.7 5607.2 5651.6 5670.4 5690.9 5701.3 5518.0 5539.3 5547.5 5596.0 5603.6 5537.9 5582.7 5682.9 5700.6 5722.6 5531.9 5542.3 5610.8 5620.6 5625.9 05:24:32 INFO - PROCESS | 5444 | 05:24:32 INFO - PROCESS | 5444 | [#5] yui.html Cycles:180 Average:17070.39 Median:4554.99 stddev:21244.56 (466.4%) stddev-sans-first:21268.80 05:24:32 INFO - PROCESS | 5444 | Values: 746.2 856.3 860.9 866.8 872.9 13606.9 14275.7 14307.9 14315.1 14338.1 13291.3 14554.6 14605.0 14628.9 14776.9 778.4 782.9 789.7 790.0 790.7 797.2 800.7 801.9 802.6 803.1 844.8 845.0 845.5 847.6 847.9 839.0 844.1 845.6 845.6 848.0 611.0 615.6 615.7 615.8 616.7 12586.2 12614.2 12620.9 12625.5 14445.6 13625.3 13688.5 13689.5 13746.9 13934.9 12828.3 12888.3 12947.5 13000.2 13064.4 4359.7 4452.3 4459.4 4490.1 4490.8 61547.4 62510.0 63292.3 63413.7 63701.0 10993.8 11023.5 11041.5 11159.4 11224.4 19345.9 19397.0 19402.2 19451.2 19471.6 7969.7 8023.1 8023.7 8155.1 8187.2 4510.5 4514.3 4595.7 4717.7 4735.6 3567.2 3721.4 3724.2 3724.3 3739.1 651.2 663.3 665.9 671.4 673.3 752.8 765.6 793.8 801.2 802.5 9512.9 9534.0 9682.3 9927.1 10074.2 621.6 688.8 727.9 729.1 787.2 4958.3 5204.5 5229.7 5372.9 5381.9 77613.6 78086.0 78323.0 79237.2 80349.6 69262.7 69591.0 69664.0 70271.6 73001.3 60556.4 61049.1 61948.7 63108.0 64406.4 9749.6 9945.2 10090.4 10138.7 10371.9 40242.2 40307.6 40831.0 41164.4 41246.4 49876.6 54675.7 54902.0 55288.4 56797.7 13138.3 13191.3 13418.7 13456.5 13791.2 14064.6 14345.3 14429.2 14491.7 14514.9 14033.3 14229.1 14250.8 15193.3 15284.4 13981.2 14146.4 14252.5 14426.1 14667.6 14110.9 14188.8 14497.4 14915.1 14930.7 11050.5 11376.4 11386.3 11421.8 11504.0 10863.7 10927.7 11352.8 11607.8 11649.4 05:24:32 INFO - PROCESS | 5444 | -------- Summary: end -------- 05:24:32 INFO - PROCESS | 5444 | 05:24:33 INFO - TEST-INFO | 5444: exit 0 05:24:33 INFO - TEST-OK | dromaeo_css | took 1112140ms 05:24:33 INFO - TEST-START | kraken 05:24:33 INFO - operating with platform_type : linux_ 05:24:33 INFO - Initialising browser for kraken test... 05:24:33 INFO - TEST-INFO | started process 5631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVifres/profile http://localhost:46850/getInfo.html) 05:24:36 INFO - PROCESS | 5631 | __metrics Screen width/height:1600/1200 05:24:36 INFO - PROCESS | 5631 | colorDepth:24 05:24:36 INFO - PROCESS | 5631 | Browser inner width/height: 1024/697 05:24:36 INFO - PROCESS | 5631 | __metrics 05:24:37 ERROR - PROCESS | 5631 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:24:38 INFO - TEST-INFO | 5631: exit 0 05:24:38 INFO - Browser initialized. 05:24:38 INFO - Running cycle 1/1 for kraken test... 05:24:38 INFO - TEST-INFO | started process 5712 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVifres/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:50 INFO - PROCESS | 5712 | RSS: Main: 156086272 05:24:50 INFO - PROCESS | 5712 | 05:24:53 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46850/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:53 INFO - PROCESS | 5712 | RSS: Main: 214384640 05:24:53 INFO - PROCESS | 5712 | 05:24:56 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46850/tests/kraken/driver.html?testName=audio-dft) 05:24:57 INFO - PROCESS | 5712 | RSS: Main: 199061504 05:24:57 INFO - PROCESS | 5712 | 05:25:00 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46850/tests/kraken/driver.html?testName=audio-fft) 05:25:00 INFO - PROCESS | 5712 | RSS: Main: 268025856 05:25:00 INFO - PROCESS | 5712 | 05:25:03 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46850/tests/kraken/driver.html?testName=audio-oscillator) 05:25:03 INFO - PROCESS | 5712 | RSS: Main: 178970624 05:25:03 INFO - PROCESS | 5712 | 05:25:05 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46850/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:06 INFO - PROCESS | 5712 | RSS: Main: 183005184 05:25:06 INFO - PROCESS | 5712 | 05:25:09 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46850/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:09 INFO - PROCESS | 5712 | RSS: Main: 218083328 05:25:09 INFO - PROCESS | 5712 | 05:25:13 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46850/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:13 INFO - PROCESS | 5712 | RSS: Main: 219975680 05:25:13 INFO - PROCESS | 5712 | 05:25:16 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46850/tests/kraken/driver.html?testName=json-parse-financial) 05:25:17 INFO - PROCESS | 5712 | RSS: Main: 220372992 05:25:17 INFO - PROCESS | 5712 | 05:25:18 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46850/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:19 INFO - PROCESS | 5712 | RSS: Main: 208650240 05:25:19 INFO - PROCESS | 5712 | 05:25:21 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46850/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:21 INFO - PROCESS | 5712 | RSS: Main: 210878464 05:25:21 INFO - PROCESS | 5712 | 05:25:23 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46850/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:24 INFO - PROCESS | 5712 | RSS: Main: 217350144 05:25:24 INFO - PROCESS | 5712 | 05:25:27 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46850/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:25:27 INFO - PROCESS | 5712 | RSS: Main: 221720576 05:25:27 INFO - PROCESS | 5712 | 05:25:30 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46850/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:30 INFO - PROCESS | 5712 | RSS: Main: 221073408 05:25:30 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | Cycle 1(1): loaded http://localhost:46850/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46850/tests/kraken/driver.html?testName=ai-astar) 05:25:32 INFO - PROCESS | 5712 | RSS: Main: 263786496 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | __start_tp_report 05:25:32 INFO - PROCESS | 5712 | _x_x_mozilla_page_load 05:25:32 INFO - PROCESS | 5712 | _x_x_mozilla_page_load_details 05:25:32 INFO - PROCESS | 5712 | |i|pagename|runs| 05:25:32 INFO - PROCESS | 5712 | |0;ai-astar;110;106;111;110;109;110;109;110;111;109 05:25:32 INFO - PROCESS | 5712 | |1;audio-beat-detection;148;131;126;178;124;128;160;132;162;133 05:25:32 INFO - PROCESS | 5712 | |2;audio-dft;161;160;161;160;160;160;160;159;162;161 05:25:32 INFO - PROCESS | 5712 | |3;audio-fft;75;75;75;122;75;75;73;75;97;75 05:25:32 INFO - PROCESS | 5712 | |4;audio-oscillator;83;82;111;83;84;82;111;83;83;123 05:25:32 INFO - PROCESS | 5712 | |5;imaging-gaussian-blur;118;118;117;118;116;117;118;117;116;117 05:25:32 INFO - PROCESS | 5712 | |6;imaging-darkroom;147;146;145;145;146;146;146;146;145;147 05:25:32 INFO - PROCESS | 5712 | |7;imaging-desaturate;95;95;95;94;95;95;94;95;95;94 05:25:32 INFO - PROCESS | 5712 | |8;json-parse-financial;53;52;53;52;53;54;56;59;53;54 05:25:32 INFO - PROCESS | 5712 | |9;json-stringify-tinderbox;61;63;62;62;63;63;62;62;62;62 05:25:32 INFO - PROCESS | 5712 | |10;stanford-crypto-aes;86;80;78;80;90;80;81;81;131;87 05:25:32 INFO - PROCESS | 5712 | |11;stanford-crypto-ccm;120;125;120;127;127;126;122;123;122;149 05:25:32 INFO - PROCESS | 5712 | |12;stanford-crypto-pbkdf2;160;165;162;159;167;166;164;163;157;158 05:25:32 INFO - PROCESS | 5712 | |13;stanford-crypto-sha256-iterative;63;63;63;64;62;61;61;61;62;64 05:25:32 INFO - PROCESS | 5712 | __end_tp_report 05:25:32 INFO - PROCESS | 5712 | __start_cc_report 05:25:32 INFO - PROCESS | 5712 | _x_x_mozilla_cycle_collect,1599 05:25:32 INFO - PROCESS | 5712 | __end_cc_report 05:25:32 INFO - PROCESS | 5712 | __startTimestamp1464611132891__endTimestamp 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | ------- Summary: start ------- 05:25:32 INFO - PROCESS | 5712 | Number of tests: 14 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:1.43 (1.3%) stddev-sans-first:1.51 05:25:32 INFO - PROCESS | 5712 | Values: 110.0 106.0 111.0 110.0 109.0 110.0 109.0 110.0 111.0 109.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:140.50 stddev:18.66 (13.3%) stddev-sans-first:19.67 05:25:32 INFO - PROCESS | 5712 | Values: 148.0 131.0 126.0 178.0 124.0 128.0 160.0 132.0 162.0 133.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 05:25:32 INFO - PROCESS | 5712 | Values: 161.0 160.0 161.0 160.0 160.0 160.0 160.0 159.0 162.0 161.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.00 stddev:15.80 (21.1%) stddev-sans-first:16.58 05:25:32 INFO - PROCESS | 5712 | Values: 75.0 75.0 75.0 122.0 75.0 75.0 73.0 75.0 97.0 75.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:83.00 stddev:15.88 (19.1%) stddev-sans-first:16.46 05:25:32 INFO - PROCESS | 5712 | Values: 83.0 82.0 111.0 83.0 84.0 82.0 111.0 83.0 83.0 123.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.50 stddev:0.79 (0.7%) stddev-sans-first:0.78 05:25:32 INFO - PROCESS | 5712 | Values: 118.0 118.0 117.0 118.0 116.0 117.0 118.0 117.0 116.0 117.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 05:25:32 INFO - PROCESS | 5712 | Values: 147.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 145.0 147.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 05:25:32 INFO - PROCESS | 5712 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 94.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:2.13 (4.0%) stddev-sans-first:2.24 05:25:32 INFO - PROCESS | 5712 | Values: 53.0 52.0 53.0 52.0 53.0 54.0 56.0 59.0 53.0 54.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 05:25:32 INFO - PROCESS | 5712 | Values: 61.0 63.0 62.0 62.0 63.0 63.0 62.0 62.0 62.0 62.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:81.00 stddev:15.79 (19.5%) stddev-sans-first:16.74 05:25:32 INFO - PROCESS | 5712 | Values: 86.0 80.0 78.0 80.0 90.0 80.0 81.0 81.0 131.0 87.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:125.50 stddev:8.46 (6.7%) stddev-sans-first:8.69 05:25:32 INFO - PROCESS | 5712 | Values: 120.0 125.0 120.0 127.0 127.0 126.0 122.0 123.0 122.0 149.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:163.50 stddev:3.48 (2.1%) stddev-sans-first:3.61 05:25:32 INFO - PROCESS | 5712 | Values: 160.0 165.0 162.0 159.0 167.0 166.0 164.0 163.0 157.0 158.0 05:25:32 INFO - PROCESS | 5712 | 05:25:32 INFO - PROCESS | 5712 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:1.17 (1.9%) stddev-sans-first:1.22 05:25:32 INFO - PROCESS | 5712 | Values: 63.0 63.0 63.0 64.0 62.0 61.0 61.0 61.0 62.0 64.0 05:25:32 INFO - PROCESS | 5712 | -------- Summary: end -------- 05:25:32 INFO - PROCESS | 5712 | 05:25:33 INFO - TEST-INFO | 5712: exit 0 05:25:33 INFO - TEST-OK | kraken | took 60849ms 05:25:33 INFO - SUITE-END | took 1172s 05:25:34 INFO - Completed test suite (00:19:33) 05:25:34 INFO - javascript benchmark 05:25:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3087.814731116133, 3097.1637657832384, 3115.049909777518, 3137.121605950335, 3157.510585859191, 5234.398044224915, 5308.893822123555, 5311.415744268133, 5397.379301380342, 5412.431694672062, 5300.01949639317, 5305.0450918834595, 5328.920066199001, 5341.385740639827, 5377.865553361168, 4776.665633405663, 4779.880502987418, 4925.285833554132, 4951.900961980758, 4958.305837182797, 4884.633652476061, 4921.015796840628, 4935.284383764352, 4947.332110165135, 4960.503949605047, 4723.07899959508, 4743.122522333362, 4772.379590653228, 4831.033793241366, 4832.275158726184, 4785.282207668843, 4785.95214047862, 4792.161371759963, 4817.9518204818205, 4858.344123543312, 4766.618670506386, 4823.975880120612, 4838.540338667856, 4846.418429788413, 4876.366072410599, 5129.8717532062, 5195.766190521398, 5222.2427747976335, 5267.262583238311, 5270.973645131749, 5636.971815140939, 5670.574706896958, 5690.772369105231, 5751.971240143815, 5760.913586296209, 5739.512141467981, 5741.741621627037, 5810.360860305363, 5817.970910145422, 5819.127130930352, 5247.317848679647, 5319.335083114611, 5425.674459532441, 5451.482109199659, 5456.48163424478, 5821.854453638608, 5871.794487192928, 5874.9118763218585, 5922.378150294243, 5922.555808314393, 5600.607957443021, 5617.971910140505, 5669.574781891375, 5670.0, 5671.688057156896, 5458.808941687022, 5657.886842263131, 5684.033714268584, 5703.62926409782, 5757.769689212469, 5697.316322041382, 5710.7430165642645, 5716.056850619601, 5720.056190728566, 5761.135829625589, 5650.463205995424, 5684.317881854121, 5686.147077938343, 5701.2018317435595, 5704.942950570524, 4210.179015092089, 4236.978815105905, 4298.355246712956, 4323.329883868006, 4377.4965878923695, 3842.135519508144, 3855.3060449118866, 3858.6334298241622, 3888.4556162137324, 3910.237503686754, 5351.224072509464, 5363.785448582013, 5367.597430192673, 5371.435999220103, 5419.078756611386, 5579.2746942897165, 5676.262085928803, 5700.25896633435, 5707.258056452634, 5729.9713501432225, 5358.866028349323, 5369.0, 5377.973110134502, 5380.7309634518115, 5384.730763461811, 4776.379070720784, 4785.880352991134, 4790.401199850019, 4790.904181916342, 4829.6860704054125, 5745.511631511285, 5825.155352473952, 5846.093855452412, 5872.031114866085, 5884.9705751470965, 5729.799457018984, 5736.741846616996, 5748.425157484386, 5750.6262092963825, 5752.856178595569, 5704.258446401941, 5894.38108998549, 5914.645121292736, 5925.0, 5927.8221653350465, 5565.582581306499, 5584.190292407497, 5596.076647353141, 5617.578681598815, 5620.494155526022, 5682.516986056066, 5683.744231509675, 5686.203931449683, 5686.402927692533, 5690.971545142248, 5413.079776437936, 5514.91727624078, 5587.664740115606, 5609.074502707008, 5614.270144881302, 5521.723913804374, 5547.278853748987, 5549.611527193219, 5577.414371490935, 5604.439556044526, 5395.622306438511, 5458.235846981346, 5471.480209380103, 5484.177373394023, 5488.9725551371985, 5503.7798488061635, 5596.160575913483, 5603.74783134768, 5611.607187496836, 5615.915761263666, 5340.465953404629, 5374.919376209282, 5524.06090964529, 5524.696141712084, 5591.217229587943], "value": 5244.766996923947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.962367446116, 624.0794827629246, 628.8805127025864, 647.2007071266985, 660.075893748752, 31590.368192636135, 43385.34921976169, 43858.0, 44031.779841100826, 44127.11745765083, 47239.76380118095, 47833.521664783344, 47879.76060119695, 47901.0, 47922.52077479224, 8844.425112367691, 9048.457092574467, 9048.502332371718, 9055.275577953747, 9058.909410905873, 8778.253848422892, 8932.64269429222, 8937.865932011026, 8939.061398553156, 8943.910560894374, 8959.372843900897, 8965.820683586324, 8968.775780605472, 8971.820563588724, 8972.730818075435, 8804.295656347509, 8864.024957254696, 8884.644614215456, 8889.73330800077, 8890.4665720057, 8844.336674749355, 8955.149260820212, 8959.238464730506, 8965.327600429993, 8968.372213945026, 42036.789816050725, 42784.57215427837, 42823.35764963528, 42886.142277154286, 42954.57045429537, 3464.826758662057, 3503.4744788281705, 3575.8212089395424, 3681.208540163851, 3798.4492248623797, 33049.17377065569, 33295.16762080943, 33541.49687754661, 34139.829300853584, 34186.14534636629, 4468.575485328888, 4483.08096840147, 4483.4843992940905, 4487.842925497592, 4574.130915126116, 357215.2139239313, 357258.0, 358257.0, 359435.0, 359644.0, 62339.06491402633, 62536.37463625306, 62945.68527157335, 63034.0, 63041.68479157666, 57809.71095144498, 63128.684356578844, 64645.353546463935, 64686.676566616865, 70865.0, 17518.29926802939, 17633.911830440764, 17635.911820440815, 17654.382096626556, 17738.645227095643, 12262.509499619915, 12268.141230113984, 12362.505499780087, 12401.565945191875, 12405.069619778373, 17628.118594070245, 19240.4227873164, 19580.782971494184, 19660.803391965896, 20575.176992920115, 7312.23221561739, 7330.560166390033, 7376.373008294355, 7423.62881855905, 7522.774316770506, 978.505854543451, 1052.394872948058, 1117.4245263689206, 1187.6912002879315, 1238.108561835477, 33547.4967875482, 33567.16082097967, 33593.664063359545, 33999.660003400146, 34503.65496345054, 1079.3416016230094, 1141.7944769941491, 1178.988210117905, 1182.5151687807956, 1199.9580014699443, 17604.38384656531, 17628.383006594708, 17757.556061098316, 17948.910255448638, 18008.36970706019, 485818.0, 490763.0, 491903.0, 493943.0, 494104.0, 61447.6927615368, 61984.07023894645, 62095.6895215521, 62117.378826212065, 62128.689356552924, 1288.761579107868, 1324.1326930860303, 1373.2927542315715, 1396.7206558688304, 1421.076300404745, 33634.15914602154, 35025.12437189091, 35158.64841351656, 35362.646373535936, 35416.64583354237, 52116.73941630268, 52197.73901130622, 52658.21012684736, 52713.20930186127, 52798.73600632126, 345989.0, 346370.0, 346505.0, 346611.0, 347327.0, 27053.72946270512, 28221.71778282191, 28490.43019139564, 28945.42109157847, 30438.239044024078, 49840.252396213364, 50740.49259507358, 51048.489515104375, 51319.74340128276, 51709.7414512925, 41785.16429671329, 42165.367519486615, 42931.570684292754, 43554.12891742211, 43935.340969886114, 42001.15997679968, 42400.15199695927, 42459.0, 42825.571744282155, 42896.571034289256, 41812.372814408416, 42306.36540451834, 43298.35052474152, 43380.56619433891, 43420.56579434165, 37415.43876841795, 37469.0, 37949.620503794606, 38473.0, 38731.22537549177, 28987.275318117216, 29045.85477072601, 29312.706872931853, 29399.55900661449, 29429.705702943553], "value": 20580.45420048389, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.3128627971629, 393.15275581122654, 395.4147861165474, 408.12864534219443, 413.93997870308806, 17887.55281117973, 19531.41405757826, 19672.0, 19672.409827705156, 20752.0, 17906.552336191602, 18794.436166915013, 18800.811991880077, 19292.228310867584, 19670.901645491787, 5059.139946209135, 5193.870153246171, 5234.136367499358, 5256.369235691723, 5309.681419114846, 5035.471275516054, 5052.570531504826, 5053.343065401511, 5073.594112470993, 5087.7710503027265, 5154.587632989352, 5161.561267292266, 5377.247185394048, 5680.431956804307, 5691.03252447085, 5488.506034456918, 5658.34928983166, 5665.263515742948, 5702.144678298247, 5713.6000479966415, 5505.229267902497, 5519.089350257203, 5525.640833345819, 5538.778448862081, 5540.473655002768, 13243.271620060892, 13530.729385412236, 13794.448222071105, 13807.861921380758, 13834.861651383459, 2122.3102491690106, 2172.532905425325, 2271.534335461243, 2295.185209250709, 2357.2221167014845, 9705.417674939526, 10346.896531034743, 10907.781844363148, 11440.027597654209, 14652.853471465362, 1890.7258447525032, 1899.1074195128267, 1907.4563749331373, 1914.6457905287427, 1984.603079384129, 84404.0, 87930.0, 90174.5491272546, 90285.77713782882, 90416.54791726066, 10825.350478971286, 11135.72160695989, 11231.66305010851, 11264.774704505864, 11284.774304513863, 15331.61670958235, 15646.295916683777, 15653.530394087968, 15661.903666743447, 15667.0, 8210.507369557845, 8303.252707256375, 8352.958235208785, 8515.446495977742, 8515.86980314547, 7956.124826269106, 8022.0, 8065.153158918347, 8116.472429292077, 8214.219649133323, 4681.344611754357, 4781.187198176319, 4786.425628924551, 4831.661783675109, 4907.411110666742, 1850.5743678953647, 1861.8417434517946, 1951.3170390363543, 1967.911443985024, 1993.8504612154147, 946.6355453150449, 948.7960088580988, 958.3914214473859, 966.1739212972807, 1068.631322193842, 18105.637887242447, 19059.80940190608, 19065.80934190668, 19174.520636984187, 19203.231870725016, 953.2374100719396, 963.1861077389594, 1016.7966406718538, 1039.153090231459, 1056.6829951014665, 10149.188064954933, 10428.322159059637, 10674.899440428646, 10843.240973131962, 11264.774704505864, 452587.0, 460397.0, 463786.0, 474632.0, 475981.0, 96521.517392414, 98618.04088441479, 102210.4226352074, 102799.48600256951, 103285.48357258165, 1026.5585798106886, 1074.785042991405, 1077.5582011375297, 1113.8273567597034, 1120.7422292872684, 17204.655906882042, 17278.22247998818, 17666.116694165496, 17816.27558662007, 17950.730739039187, 43210.35184472173, 43982.0, 44113.33829992617, 45001.54998450105, 45701.77149114366, 60995.0, 61603.69198153981, 62257.37742622697, 63956.3604363969, 64039.0, 8406.87389689143, 8475.915240847513, 8506.957465212634, 8514.020887598006, 8720.0, 9472.715818525456, 12357.0, 12672.683182920133, 12954.805677915027, 13004.154729942524, 11317.434128293717, 11368.0, 11763.47064382122, 11880.82178767302, 11892.227005244631, 11267.661970140909, 11536.134789890624, 11576.421178941187, 11704.531818727155, 12048.457819397972, 11411.543538258376, 11464.656060318204, 12015.45930433115, 12164.844339787705, 12237.510499579917, 8128.593570321578, 8185.304249138651, 8223.09545949933, 8524.0, 8543.786405339917, 11418.543258269909, 11708.648740537797, 11819.0, 11852.466639001093, 12034.217775844541], "value": 9103.369206081425, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.3159967415809, 1460.6348412896775, 1527.350875877752, 1691.687037897989, 1858.3588661911635, 8686.13138686131, 9043.909560904398, 9045.592948317326, 9306.441613503177, 9321.254299656028, 8992.595333210005, 9191.77020574486, 9227.907720922789, 9230.261579073675, 9234.769130771734, 2060.268604645352, 2300.1834348806183, 2397.041183526586, 2413.9396515087133, 2442.352776514216, 2373.3947843299934, 2384.5350156719446, 2386.5107652931106, 2390.2590197038885, 2390.474095698952, 2318.6088346991755, 2381.868997205153, 2382.2376839411395, 2382.4401265702545, 2383.9046438142454, 2044.100595737878, 2319.802816760569, 2389.689340385756, 2393.0906255622754, 2393.497365553236, 2234.340869443518, 2247.831412644058, 2260.5139894922672, 2265.0147185973988, 2279.8290128240446, 9177.311701622404, 9235.353525253235, 9240.214581760558, 9242.168204861595, 9245.72262832116, 5049.267856160836, 5135.050015747099, 5153.2270159476, 5160.329157209539, 5167.896642067175, 3358.1604598850286, 3475.131217195701, 3519.5072689823446, 3543.0965103898598, 3555.1112221944513, 1122.81473556863, 1130.8021096308114, 1144.067584918289, 1155.1451925575013, 1174.4597485156837, 12578.874211257862, 12589.622311330766, 12596.496140154382, 12607.180533265308, 12624.24254544721, 4594.264917613166, 4703.3885594872445, 4731.195696731564, 4742.5731684148595, 4772.284157376422, 10562.049415552638, 10570.682879513626, 10572.471376431147, 10579.15366770672, 10587.629432969808, 4906.852794416173, 5291.576673866081, 5342.679439233659, 5353.411124776271, 5356.3572371315695, 4687.460941991714, 4784.497627749106, 4787.928181077286, 4850.272459131159, 4852.0295940811975, 4093.8771836844944, 4137.710360274752, 4139.627433530996, 4150.730202536825, 4205.663546916239, 1267.6767424306743, 1279.5457612547411, 1287.0218633838351, 1296.1315918334737, 1296.5397283964057, 937.6765016069445, 940.4968341937031, 947.1617618407759, 955.2214944820089, 991.9752006199902, 5851.765929362863, 5931.911021334683, 5975.300760130041, 6044.123602077674, 6072.726727297282, 421.8693900347038, 423.7753990385039, 433.772269558478, 434.24224727850105, 439.984600538986, 3986.9601303986587, 4041.0, 4083.4691490106097, 4097.467329247179, 4118.75287482746, 11342.943285283685, 11554.248973816842, 11619.419029048513, 11720.648380548597, 11721.0, 11390.544378224778, 11471.655850324503, 11586.826197607043, 11639.894210050032, 11643.708907277385, 8172.713955011546, 8456.619452124669, 8496.66013359471, 8598.742037738877, 8652.740417787476, 6122.785702500391, 6339.080833279057, 6402.391772781578, 6413.679316034273, 6469.2560355557835, 10473.214508912015, 10639.734006649585, 10705.464726763475, 10754.89245107539, 10826.891731082904, 10865.293755905841, 10998.0, 11017.889821102008, 11021.724456888642, 11034.944825276143, 7417.221191774786, 7565.2812982766545, 7666.310032097138, 7883.881741773764, 8010.759677209693, 9030.774230644287, 9042.683506077256, 9136.63453461854, 9145.817083658156, 9147.222486088756, 8071.192880711882, 8093.150219226896, 8093.271605555528, 8231.670733170846, 8407.369447291356, 8012.919870801217, 8074.555899425588, 8115.350771938349, 8160.918390816253, 8170.632321545426, 6799.796006119825, 6803.285655006351, 6839.794806155824, 6972.477064220102, 6983.9650801745665, 5173.301604283524, 5193.92209116856, 5240.633155679066, 5258.158694608845, 5289.9471005291, 5184.896302074013, 5276.182191760283, 5296.6292359536, 5348.893022139613, 5395.271638328775], "value": 4652.157713050215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.8316816839328, 332.44315771083427, 336.8517852145057, 345.8236299487261, 349.1028057891219, 5150.2532132840715, 5254.238135470355, 5308.893822123555, 5350.919736203955, 5408.891822163554, 5266.157414813631, 5294.364676238847, 5319.734013299339, 5321.148616221396, 5325.4142044375185, 4158.896027599311, 4188.204241194178, 4189.874303770892, 4202.70581059326, 4210.368444733299, 3968.7420317679403, 3992.900177495557, 4199.223143718406, 4215.894602634928, 4230.598093181158, 4121.711480196387, 4138.544760076389, 4145.730527515717, 4148.813303401339, 4162.521310049353, 4149.273877071516, 4162.271602469556, 4167.91664166715, 4168.353905144692, 4172.77049762263, 3450.7404797248823, 3728.328900797855, 3729.589745128034, 3731.328360895038, 3744.101415660249, 5090.701871022902, 5093.617978651616, 5110.897782044338, 5112.849608838019, 5122.3853137623355, 4669.089527542131, 4694.812207511695, 4710.057988402334, 4719.646026548023, 4722.858314250544, 5349.7057661828585, 5475.972620136914, 5493.093639549469, 5504.0, 5512.724363781835, 3939.0940083780893, 3958.9802050989847, 3993.7803420811842, 4003.6178473808036, 4005.439238506611, 5722.340521248833, 5751.798687045934, 5755.712214389264, 5783.768649254067, 5797.594168408213, 3945.763254204757, 4063.6952228582973, 4072.450219220427, 4072.470578824734, 4075.8777236682945, 4107.609777071233, 4163.335465302209, 4166.0626359068965, 4182.623563879265, 4193.958060419418, 4743.980044290494, 4768.594669453066, 4777.713337199779, 4784.210605250164, 4795.544423279783, 5500.669959802424, 5564.527015203753, 5582.162675598612, 5660.320761508563, 5676.0918253080135, 1398.1820634928476, 1401.2223216115153, 1401.2293238718664, 1402.7475054490085, 1413.342795600051, 872.7905302727299, 879.2722300679196, 880.3089574683843, 914.4056363363867, 914.491089701997, 4663.950611112473, 4745.691530050535, 4778.402699662543, 4783.851875549913, 4822.831200907952, 5024.397072351268, 5048.495150484996, 5077.543021128116, 5083.491650834961, 5162.535371816554, 4756.524347565285, 4760.809567617256, 4768.690035147704, 4778.856634300976, 4919.631027672939, 3027.9697203027686, 3052.4200401923563, 3067.4018566379787, 3073.631164260303, 3074.83088430134, 5961.0, 5961.612495187799, 5963.314218864886, 6007.399260074045, 6029.276486821609, 5644.266245388073, 5932.902413053599, 5937.7921772737745, 5977.312609049928, 5984.371640977634, 5932.703364831741, 6011.6994150293185, 6021.8558473889825, 6022.156898034279, 6028.427299406461, 5635.520980716685, 5839.912401313899, 5853.297604287342, 5863.794767183128, 5864.794732184353, 5752.68360240174, 5782.0, 5784.843031393654, 5791.565632577489, 5803.0, 5982.820515384546, 5983.551233657406, 5990.850228744316, 6007.579469437273, 6009.158717779427, 3999.0804413882083, 4036.273470775289, 4111.403846442189, 4112.157007813451, 4112.506499219993, 5837.701698540312, 5844.12338149281, 5850.912236316543, 5853.209816674695, 5861.413858614104, 5508.641938274, 5708.488265163481, 5712.485876271156, 5719.685417302088, 5723.7424315905055, 5607.158926161109, 5651.57613179005, 5670.432956704297, 5690.9430905690415, 5701.287339082614, 5518.0, 5539.25220095282, 5547.472990065938, 5596.0, 5603.579731520071, 5537.889242215214, 5582.720863956867, 5682.857928551819, 5700.572457065654, 5722.570807189561, 5531.944680553143, 5542.2795036644975, 5610.8316750495915, 5620.606557540933, 5625.88748225025], "value": 4232.241518620126, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.1978373248758, 856.2507805670039, 860.8708693695945, 866.7919699272175, 872.8516152254116, 13606.931965340173, 14275.71448571028, 14307.928460357683, 14315.069520481175, 14338.068025578343, 13291.335433228349, 14554.636134096627, 14605.0, 14628.926855365735, 14776.926115369408, 778.4434129597337, 782.9412794040442, 789.6880732110812, 790.0124843945068, 790.7271991163068, 797.2346547314585, 800.7277525641281, 801.9478733882279, 802.5585927739749, 803.148662417839, 844.7845799321195, 845.0282175498165, 845.5349557743248, 847.5677404523707, 847.8728190771403, 839.0351096239374, 844.0757370679097, 845.5687599324358, 845.602566793606, 848.0247715127591, 611.0376157551874, 615.6121643364696, 615.6583096381506, 615.7875532941173, 616.6731632234922, 12586.18189817659, 12614.180078294974, 12620.936895315464, 12625.494980200781, 14445.638859028504, 13625.25061121638, 13688.452461901612, 13689.520866769715, 13746.93126534371, 13934.93032534841, 12828.294443805587, 12888.291143987077, 12947.48210071596, 13000.219986800823, 13064.412101455457, 4359.673024523174, 4452.28763397858, 4459.44256967879, 4490.057088011522, 4490.820367185309, 61547.384526154616, 62510.0, 63292.272345250414, 63413.68293158551, 63701.0, 10993.780124397466, 11023.503942322615, 11041.503132358903, 11159.386233757059, 11224.382658953837, 19345.90327048356, 19397.03014849252, 19402.223911043682, 19451.22195112208, 19471.610567788564, 7969.72105976288, 8023.077346105156, 8023.679052837938, 8155.062167850773, 8187.222213889671, 4510.526394728507, 4514.25514790056, 4595.724256544551, 4717.693349932243, 4735.644826638016, 3567.1973805893467, 3721.3859713147026, 3724.2365315110337, 3724.2923844470006, 3739.08392443853, 651.1957732200729, 663.3200969006807, 665.9101021362054, 671.4427025568864, 673.3333999340617, 752.8005078654161, 765.5712800831441, 793.7856778669727, 801.1907972947249, 802.4743792815715, 9512.904870951203, 9533.952330238442, 9682.261821047292, 9927.106560409598, 10074.194064474825, 621.5897507644933, 688.784295718058, 727.9008696867662, 729.0667945030264, 787.22851605427, 4958.305837182797, 5204.4535323791215, 5229.712365819841, 5372.946270537323, 5381.9461805382225, 77613.61193193997, 78086.0, 78323.0, 79237.20762792413, 80349.59825200836, 69262.65368673124, 69591.0, 69664.0, 70271.64864175646, 73001.26998729944, 60556.394436055074, 61049.08426373608, 61948.69025654843, 63108.0, 64406.355936440035, 9749.610015599297, 9945.204383649147, 10090.445025523666, 10138.746531336776, 10371.896281037092, 40242.19515609613, 40307.59692403038, 40831.0, 41164.382534261415, 41246.38130428106, 49876.62428113756, 54675.72662136664, 54902.0, 55288.44711552994, 56797.71601141968, 13138.277394743382, 13191.274479903697, 13418.664533386744, 13456.46174153023, 13791.172529648253, 14064.648383790487, 14345.329421818904, 14429.20639364845, 14491.710165796836, 14514.927425362806, 14033.298335083, 14229.14625122496, 14250.786238206643, 15193.316300766272, 15284.388624454898, 13981.161130332213, 14146.363413646204, 14252.515120517177, 14426.134431934117, 14667.633309167357, 14110.929445352707, 14188.787168192279, 14497.420103195755, 14915.105093694414, 14930.701385972003, 11050.502727377127, 11376.374299413612, 11386.316820990767, 11421.828672570084, 11504.017910686563, 10863.728406789893, 10927.726806829893, 11352.772944540899, 11607.76784464289, 11649.359285239394], "value": 7260.976129422562, "unit": "score"}], "name": "dromaeo_css", "value": 7210.103990891998, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 111.0, 110.0, 109.0, 110.0, 109.0, 110.0, 111.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 131.0, 126.0, 178.0, 124.0, 128.0, 160.0, 132.0, 162.0, 133.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 160.0, 159.0, 162.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 122.0, 75.0, 75.0, 73.0, 75.0, 97.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 111.0, 83.0, 84.0, 82.0, 111.0, 83.0, 83.0, 123.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 116.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 52.0, 53.0, 54.0, 56.0, 59.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 62.0, 63.0, 63.0, 62.0, 62.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 78.0, 80.0, 90.0, 80.0, 81.0, 81.0, 131.0, 87.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 125.0, 120.0, 127.0, 127.0, 126.0, 122.0, 123.0, 122.0, 149.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 162.0, 159.0, 167.0, 166.0, 164.0, 163.0, 157.0, 158.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 64.0, 62.0, 61.0, 61.0, 61.0, 62.0, 64.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1498.2, "alertThreshold": 2.0}]} 05:25:34 INFO - Return code: 0 05:25:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:25:34 ERROR - # TBPL SUCCESS # 05:25:34 INFO - Running post-action listener: _resource_record_post_action 05:25:34 INFO - ##### 05:25:34 INFO - ##### Finished run-tests step (success) 05:25:34 INFO - ##### 05:25:34 INFO - Running post-run listener: _resource_record_post_run 05:25:34 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 532480; Write bytes: 369319936; Read time: 88; Write time: 245708 05:25:34 INFO - TinderboxPrint: CPU usage
13.1% 05:25:34 INFO - TinderboxPrint: I/O read bytes / time
532,480 / 88 05:25:34 INFO - TinderboxPrint: I/O write bytes / time
369,319,936 / 245,708 05:25:34 INFO - TinderboxPrint: CPU idle
8,263.1 (86.9%) 05:25:34 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 05:25:34 INFO - TinderboxPrint: Swap in / out
0 / 0 05:25:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 81977344; Read time: 0; Write time: 55868 05:25:34 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 532480; Write bytes: 287342592; Read time: 88; Write time: 189840 05:25:34 INFO - Running post-run listener: _upload_blobber_files 05:25:34 INFO - Blob upload gear active. 05:25:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:34 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 05:25:34 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'] 05:25:34 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 05:25:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:25:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:39 INFO - (blobuploader) - INFO - Done attempting. 05:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:40 INFO - (blobuploader) - INFO - Done attempting. 05:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:41 INFO - (blobuploader) - INFO - Done attempting. 05:25:41 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:41 INFO - Return code: 0 05:25:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/611ce16a7f9e9ca739c24750dae586e3bdc61de13e438e54417d102592068717e2d4d50cf4062e4775884282c40d6a1cc1b50d40aa73a40d3dca463e351529f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a027c6ed40e8e7069e347191db431c639ea77aec40eb27188bb36ab888e051cf47e2682cf0683d22f333c6de349046ea89b4fc64c484f8d0d0525487ef9bd4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/394e256536944e7c56f0c1ef5e0b14155026ef27181daa18cad3d03c0196349e56b5c966f0585de7b75d3a5ac3fece1bc95948aa0a992b7b0747b819e9a48cc8"} 05:25:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:41 INFO - Contents: 05:25:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/611ce16a7f9e9ca739c24750dae586e3bdc61de13e438e54417d102592068717e2d4d50cf4062e4775884282c40d6a1cc1b50d40aa73a40d3dca463e351529f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a027c6ed40e8e7069e347191db431c639ea77aec40eb27188bb36ab888e051cf47e2682cf0683d22f333c6de349046ea89b4fc64c484f8d0d0525487ef9bd4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/394e256536944e7c56f0c1ef5e0b14155026ef27181daa18cad3d03c0196349e56b5c966f0585de7b75d3a5ac3fece1bc95948aa0a992b7b0747b819e9a48cc8"} 05:25:41 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:41 INFO - Copying logs to upload dir... 05:25:41 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.505931 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-05-30 05:25:41.540441) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 05:25:41.544252) ========= 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=20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631 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/611ce16a7f9e9ca739c24750dae586e3bdc61de13e438e54417d102592068717e2d4d50cf4062e4775884282c40d6a1cc1b50d40aa73a40d3dca463e351529f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a027c6ed40e8e7069e347191db431c639ea77aec40eb27188bb36ab888e051cf47e2682cf0683d22f333c6de349046ea89b4fc64c484f8d0d0525487ef9bd4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/394e256536944e7c56f0c1ef5e0b14155026ef27181daa18cad3d03c0196349e56b5c966f0585de7b75d3a5ac3fece1bc95948aa0a992b7b0747b819e9a48cc8"} build_url:https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011493 build_url: 'https://queue.taskcluster.net/v1/task/To_ebAWCQwqdwhBd9UixRQ/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/611ce16a7f9e9ca739c24750dae586e3bdc61de13e438e54417d102592068717e2d4d50cf4062e4775884282c40d6a1cc1b50d40aa73a40d3dca463e351529f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a027c6ed40e8e7069e347191db431c639ea77aec40eb27188bb36ab888e051cf47e2682cf0683d22f333c6de349046ea89b4fc64c484f8d0d0525487ef9bd4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/394e256536944e7c56f0c1ef5e0b14155026ef27181daa18cad3d03c0196349e56b5c966f0585de7b75d3a5ac3fece1bc95948aa0a992b7b0747b819e9a48cc8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 05:25:41.571584) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:25:41.571964) ========= 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=20e666a3d4cfbb71505a330e000001b4-1464609905.344524-1676951631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:25:41.657134) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 05:25:41.657536) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 05:25:41.657975) ========= ========= Total master_lag: 0.19 =========