builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-065 starttime: 1463138680.82 results: success (0) buildid: 20160513030046 builduid: f13e940a58724e47b6786411ede947d8 revision: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:40.819868) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:40.820524) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:40.820891) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:40.872846) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:40.873430) ========= 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=523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-13 04:24:40-- 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% 43.9M=0s 2016-05-13 04:24:41 (43.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.199304 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.125569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.125943) ========= 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=523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.057040 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.197333) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.197697) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 --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=523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-13 04:24:41,270 truncating revision to first 12 chars 2016-05-13 04:24:41,270 Setting DEBUG logging. 2016-05-13 04:24:41,270 attempt 1/10 2016-05-13 04:24:41,270 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5a2deb5a9b09?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 04:24:41,725 unpacking tar archive at: mozilla-central-5a2deb5a9b09/testing/mozharness/ program finished with exit code 0 elapsedTime=0.724405 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.941520) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.941990) ========= script_repo_revision: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.942767) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.943323) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 04:24:41.956040) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-13 04:24:41.956550) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:24:42 INFO - MultiFileLogger online at 20160513 04:24:42 in /builds/slave/test 04:24:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:24:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:24:42 INFO - {'append_to_log': False, 04:24:42 INFO - 'base_work_dir': '/builds/slave/test', 04:24:42 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:24:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:24:42 INFO - 'branch': 'Firefox-Non-PGO', 04:24:42 INFO - 'buildbot_json_path': 'buildprops.json', 04:24:42 INFO - 'config_files': ('talos/linux_config.py',), 04:24:42 INFO - 'default_actions': ('clobber', 04:24:42 INFO - 'read-buildbot-config', 04:24:42 INFO - 'download-and-extract', 04:24:42 INFO - 'populate-webroot', 04:24:42 INFO - 'create-virtualenv', 04:24:42 INFO - 'install', 04:24:42 INFO - 'run-tests'), 04:24:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:24:42 INFO - 'download_minidump_stackwalk': True, 04:24:42 INFO - 'download_symbols': 'ondemand', 04:24:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:24:42 INFO - 'tooltool.py': '/tools/tooltool.py', 04:24:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:24:42 INFO - '/tools/misc-python/virtualenv.py')}, 04:24:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:24:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:24:42 INFO - 'installer_path': 'installer.exe', 04:24:42 INFO - 'log_level': 'info', 04:24:42 INFO - 'log_name': 'talos', 04:24:42 INFO - 'log_to_console': True, 04:24:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:24:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:24:42 INFO - 'opt_config_files': (), 04:24:42 INFO - 'pip_index': False, 04:24:42 INFO - 'sps_profile': False, 04:24:42 INFO - 'sps_profile_interval': 0, 04:24:42 INFO - 'suite': 'dromaeojs', 04:24:42 INFO - 'system_bits': '32', 04:24:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:24:42 INFO - 'title': 'talos-linux64-ix-065', 04:24:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:24:42 INFO - 'use_talos_json': True, 04:24:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:24:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:24:42 INFO - 'work_dir': 'build'} 04:24:42 INFO - ##### 04:24:42 INFO - ##### Running clobber step. 04:24:42 INFO - ##### 04:24:42 INFO - Running pre-action listener: _resource_record_pre_action 04:24:42 INFO - Running main action method: clobber 04:24:42 INFO - rmtree: /builds/slave/test/build 04:24:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:24:44 INFO - Running post-action listener: _resource_record_post_action 04:24:44 INFO - ##### 04:24:44 INFO - ##### Finished clobber step (success) 04:24:44 INFO - ##### 04:24:44 INFO - ##### 04:24:44 INFO - ##### Running read-buildbot-config step. 04:24:44 INFO - ##### 04:24:44 INFO - Running pre-action listener: _resource_record_pre_action 04:24:44 INFO - Running main action method: read_buildbot_config 04:24:44 INFO - Using buildbot properties: 04:24:44 INFO - { 04:24:44 INFO - "project": "", 04:24:44 INFO - "product": "firefox", 04:24:44 INFO - "script_repo_revision": "production", 04:24:44 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:24:44 INFO - "repository": "", 04:24:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 04:24:44 INFO - "buildid": "20160513030046", 04:24:44 INFO - "pgo_build": "False", 04:24:44 INFO - "basedir": "/builds/slave/test", 04:24:44 INFO - "buildnumber": 575, 04:24:44 INFO - "slavename": "talos-linux64-ix-065", 04:24:44 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:24:44 INFO - "platform": "ubuntu64_hw", 04:24:44 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:24:44 INFO - "branch": "mozilla-central", 04:24:44 INFO - "repo_path": "mozilla-central", 04:24:44 INFO - "revision": "5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4", 04:24:44 INFO - "stage_platform": "linux64", 04:24:44 INFO - "builduid": "f13e940a58724e47b6786411ede947d8", 04:24:44 INFO - "slavebuilddir": "test" 04:24:44 INFO - } 04:24:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 04:24:44 INFO - Running post-action listener: _resource_record_post_action 04:24:44 INFO - ##### 04:24:44 INFO - ##### Finished read-buildbot-config step (success) 04:24:44 INFO - ##### 04:24:44 INFO - ##### 04:24:44 INFO - ##### Running download-and-extract step. 04:24:44 INFO - ##### 04:24:44 INFO - Running pre-action listener: _resource_record_pre_action 04:24:44 INFO - Running main action method: download_and_extract 04:24:44 INFO - mkdir: /builds/slave/test/build/tests 04:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:24:44 INFO - https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:24:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:24:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:24:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:44 INFO - Downloaded 1361 bytes. 04:24:44 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:24:44 INFO - Using the following test package requirements: 04:24:44 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 04:24:44 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:24:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:24:44 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:24:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:24:44 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:24:44 INFO - u'jsshell-linux-x86_64.zip'], 04:24:44 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:24:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:24:44 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 04:24:44 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:24:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:24:44 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:24:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:24:44 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:24:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:24:44 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:24:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:24:44 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:24:44 INFO - https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:24:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:24:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:24:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:45 INFO - Downloaded 22334134 bytes. 04:24:45 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'] 04:24:45 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 04:24:47 INFO - Return code: 0 04:24:47 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 04:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:24:47 INFO - https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:24:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:24:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:24:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:52 INFO - Downloaded 22334134 bytes. 04:24:52 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'] 04:24:52 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 04:24:53 INFO - Return code: 0 04:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:24:53 INFO - https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:24:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 04:24:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 04:24:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:54 INFO - Downloaded 10840239 bytes. 04:24:54 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'] 04:24:54 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 04:24:54 INFO - Return code: 0 04:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:24:54 INFO - https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:24:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:24:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:24:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fyj2x_uITkyYZceU9Ejz6w/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 04:24:56 INFO - Downloaded 54730663 bytes. 04:24:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:24:56 INFO - mkdir: /builds/slave/test/properties 04:24:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:24:56 INFO - Writing to file /builds/slave/test/properties/build_url 04:24:56 INFO - Contents: 04:24:56 INFO - build_url:https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:24:57 INFO - Running post-action listener: _resource_record_post_action 04:24:57 INFO - Running post-action listener: set_extra_try_arguments 04:24:57 INFO - ##### 04:24:57 INFO - ##### Finished download-and-extract step (success) 04:24:57 INFO - ##### 04:24:57 INFO - ##### 04:24:57 INFO - ##### Running populate-webroot step. 04:24:57 INFO - ##### 04:24:57 INFO - Running pre-action listener: _resource_record_pre_action 04:24:57 INFO - Running main action method: populate_webroot 04:24:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:24:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:24:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:24:57 INFO - '--tppagecycles', 04:24:57 INFO - '7'], 04:24:57 INFO - 'tests': ['tsvgm']}}, 04:24:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:24:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:24:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:24:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:24:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:57 INFO - 'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:57 INFO - 'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['damp', 'tps']}, 04:24:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:57 INFO - 'tests': ['damp', 'tps']}, 04:24:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['dromaeo_dom']}, 04:24:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:24:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['a11yr', 04:24:57 INFO - 'ts_paint', 04:24:57 INFO - 'tpaint', 04:24:57 INFO - 'sessionrestore', 04:24:57 INFO - 'sessionrestore_no_auto_restore', 04:24:57 INFO - 'tabpaint']}, 04:24:57 INFO - 'other-e10s': {'tests': ['a11yr', 04:24:57 INFO - 'ts_paint', 04:24:57 INFO - 'tpaint', 04:24:57 INFO - 'sessionrestore', 04:24:57 INFO - 'sessionrestore_no_auto_restore', 04:24:57 INFO - 'tabpaint']}, 04:24:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:24:57 INFO - 'ts_paint', 04:24:57 INFO - 'tpaint', 04:24:57 INFO - 'sessionrestore', 04:24:57 INFO - 'sessionrestore_no_auto_restore', 04:24:57 INFO - 'tabpaint']}, 04:24:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:24:57 INFO - 'ts_paint', 04:24:57 INFO - 'tpaint', 04:24:57 INFO - 'sessionrestore', 04:24:57 INFO - 'sessionrestore_no_auto_restore', 04:24:57 INFO - 'tabpaint']}, 04:24:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['a11yr', 04:24:57 INFO - 'ts_paint', 04:24:57 INFO - 'tpaint', 04:24:57 INFO - 'sessionrestore', 04:24:57 INFO - 'sessionrestore_no_auto_restore', 04:24:57 INFO - 'tabpaint']}, 04:24:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['a11yr', 04:24:57 INFO - 'ts_paint', 04:24:57 INFO - 'tpaint', 04:24:57 INFO - 'sessionrestore', 04:24:57 INFO - 'sessionrestore_no_auto_restore', 04:24:57 INFO - 'tabpaint']}, 04:24:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['tsvgx', 04:24:57 INFO - 'tsvgr_opacity', 04:24:57 INFO - 'tart', 04:24:57 INFO - 'tscrollx', 04:24:57 INFO - 'cart']}, 04:24:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:24:57 INFO - 'tsvgr_opacity', 04:24:57 INFO - 'tart', 04:24:57 INFO - 'tscrollx', 04:24:57 INFO - 'cart']}, 04:24:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:57 INFO - 'talos_options': ['--disable-e10s'], 04:24:57 INFO - 'tests': ['tp5o']}, 04:24:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:57 INFO - 'tests': ['tp5o']}, 04:24:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:57 INFO - 'talos_options': ['--disable-e10s', 04:24:57 INFO - '--xperf_path', 04:24:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:57 INFO - 'tests': ['tp5n']}, 04:24:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:57 INFO - 'talos_options': ['--xperf_path', 04:24:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:57 INFO - 'tests': ['tp5n']}}, 04:24:57 INFO - 'talos.zip': {'path': '', 04:24:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:24:57 INFO - Running post-action listener: _resource_record_post_action 04:24:57 INFO - ##### 04:24:57 INFO - ##### Finished populate-webroot step (success) 04:24:57 INFO - ##### 04:24:57 INFO - ##### 04:24:57 INFO - ##### Running create-virtualenv step. 04:24:57 INFO - ##### 04:24:57 INFO - Running pre-action listener: _resource_record_pre_action 04:24:57 INFO - Running main action method: create_virtualenv 04:24:57 INFO - Creating virtualenv /builds/slave/test/build/venv 04:24:57 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 04:24:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:24:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:24:57 INFO - Using real prefix '/usr' 04:24:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:24:58 INFO - Installing distribute.............................................................................................................................................................................................done. 04:25:00 INFO - Installing pip.................done. 04:25:00 INFO - Return code: 0 04:25:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:25:00 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')]} 04:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:00 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')]} 04:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:00 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 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25:00 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 04:25:00 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 04:25:00 INFO - Using env: {'DISPLAY': ':0', 04:25:00 INFO - 'HOME': '/home/cltbld', 04:25:00 INFO - 'LANG': 'en_US.UTF-8', 04:25:00 INFO - 'LANGUAGE': 'en_US:en', 04:25:00 INFO - 'LOGNAME': 'cltbld', 04:25:00 INFO - 'MAIL': '/var/mail/cltbld', 04:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:00 INFO - 'MOZ_NO_REMOTE': '1', 04:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:00 INFO - 'NO_EM_RESTART': '1', 04:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:00 INFO - 'PWD': '/builds/slave/test', 04:25:00 INFO - 'SHELL': '/bin/bash', 04:25:00 INFO - 'SHLVL': '1', 04:25:00 INFO - 'TERM': 'linux', 04:25:00 INFO - 'TMOUT': '86400', 04:25:00 INFO - 'USER': 'cltbld', 04:25:00 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:00 INFO - '_': '/tools/buildbot/bin/python'} 04:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:00 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 04:25:00 INFO - Cleaning up... 04:25:00 INFO - Return code: 0 04:25:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:25:00 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')]} 04:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:00 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')]} 04:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:00 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 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25:00 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 04:25:00 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 04:25:00 INFO - Using env: {'DISPLAY': ':0', 04:25:00 INFO - 'HOME': '/home/cltbld', 04:25:00 INFO - 'LANG': 'en_US.UTF-8', 04:25:00 INFO - 'LANGUAGE': 'en_US:en', 04:25:00 INFO - 'LOGNAME': 'cltbld', 04:25:00 INFO - 'MAIL': '/var/mail/cltbld', 04:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:00 INFO - 'MOZ_NO_REMOTE': '1', 04:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:00 INFO - 'NO_EM_RESTART': '1', 04:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:00 INFO - 'PWD': '/builds/slave/test', 04:25:00 INFO - 'SHELL': '/bin/bash', 04:25:00 INFO - 'SHLVL': '1', 04:25:00 INFO - 'TERM': 'linux', 04:25:00 INFO - 'TMOUT': '86400', 04:25:00 INFO - 'USER': 'cltbld', 04:25:00 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:00 INFO - '_': '/tools/buildbot/bin/python'} 04:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:01 INFO - Downloading/unpacking psutil>=3.1.1 04:25:01 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 04:25:01 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 04:25:01 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 04:25:01 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 04:25:01 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 04:25:01 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 04:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:25:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:03 INFO - Installing collected packages: psutil 04:25:03 INFO - Running setup.py install for psutil 04:25:03 INFO - building 'psutil._psutil_linux' extension 04:25:03 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 04:25:04 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 04:25:04 INFO - building 'psutil._psutil_posix' extension 04:25:04 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 04:25:04 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 04:25:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:04 INFO - Successfully installed psutil 04:25:04 INFO - Cleaning up... 04:25:04 INFO - Return code: 0 04:25:04 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 04:25:04 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')]} 04:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:04 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')]} 04:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:04 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.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25:04 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.1'] in /builds/slave/test/build 04:25:04 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.1 04:25:04 INFO - Using env: {'DISPLAY': ':0', 04:25:04 INFO - 'HOME': '/home/cltbld', 04:25:04 INFO - 'LANG': 'en_US.UTF-8', 04:25:04 INFO - 'LANGUAGE': 'en_US:en', 04:25:04 INFO - 'LOGNAME': 'cltbld', 04:25:04 INFO - 'MAIL': '/var/mail/cltbld', 04:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:04 INFO - 'MOZ_NO_REMOTE': '1', 04:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:04 INFO - 'NO_EM_RESTART': '1', 04:25:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:04 INFO - 'PWD': '/builds/slave/test', 04:25:04 INFO - 'SHELL': '/bin/bash', 04:25:04 INFO - 'SHLVL': '1', 04:25:04 INFO - 'TERM': 'linux', 04:25:04 INFO - 'TMOUT': '86400', 04:25:04 INFO - 'USER': 'cltbld', 04:25:04 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:04 INFO - '_': '/tools/buildbot/bin/python'} 04:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:05 INFO - Downloading/unpacking mozsystemmonitor==0.1 04:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:06 INFO - Downloading mozsystemmonitor-0.1.tar.gz 04:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 04:25:07 INFO - Installing collected packages: mozsystemmonitor 04:25:07 INFO - Running setup.py install for mozsystemmonitor 04:25:07 INFO - Successfully installed mozsystemmonitor 04:25:07 INFO - Cleaning up... 04:25:07 INFO - Return code: 0 04:25:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:07 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 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25:07 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 04:25:07 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 04:25:07 INFO - Using env: {'DISPLAY': ':0', 04:25:07 INFO - 'HOME': '/home/cltbld', 04:25:07 INFO - 'LANG': 'en_US.UTF-8', 04:25:07 INFO - 'LANGUAGE': 'en_US:en', 04:25:07 INFO - 'LOGNAME': 'cltbld', 04:25:07 INFO - 'MAIL': '/var/mail/cltbld', 04:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:07 INFO - 'MOZ_NO_REMOTE': '1', 04:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:07 INFO - 'NO_EM_RESTART': '1', 04:25:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:07 INFO - 'PWD': '/builds/slave/test', 04:25:07 INFO - 'SHELL': '/bin/bash', 04:25:07 INFO - 'SHLVL': '1', 04:25:07 INFO - 'TERM': 'linux', 04:25:07 INFO - 'TMOUT': '86400', 04:25:07 INFO - 'USER': 'cltbld', 04:25:07 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:07 INFO - '_': '/tools/buildbot/bin/python'} 04:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:07 INFO - Downloading/unpacking blobuploader==1.2.4 04:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:09 INFO - Downloading blobuploader-1.2.4.tar.gz 04:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:25:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:25:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:10 INFO - Downloading docopt-0.6.1.tar.gz 04:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:25:10 INFO - Installing collected packages: blobuploader, requests, docopt 04:25:10 INFO - Running setup.py install for blobuploader 04:25:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:25:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:25:10 INFO - Running setup.py install for requests 04:25:10 INFO - Running setup.py install for docopt 04:25:10 INFO - Successfully installed blobuploader requests docopt 04:25:10 INFO - Cleaning up... 04:25:10 INFO - Return code: 0 04:25:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:10 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 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25:10 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 04:25:10 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 04:25:10 INFO - Using env: {'DISPLAY': ':0', 04:25:10 INFO - 'HOME': '/home/cltbld', 04:25:10 INFO - 'LANG': 'en_US.UTF-8', 04:25:10 INFO - 'LANGUAGE': 'en_US:en', 04:25:10 INFO - 'LOGNAME': 'cltbld', 04:25:10 INFO - 'MAIL': '/var/mail/cltbld', 04:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:10 INFO - 'MOZ_NO_REMOTE': '1', 04:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:10 INFO - 'NO_EM_RESTART': '1', 04:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:10 INFO - 'PWD': '/builds/slave/test', 04:25:10 INFO - 'SHELL': '/bin/bash', 04:25:10 INFO - 'SHLVL': '1', 04:25:10 INFO - 'TERM': 'linux', 04:25:10 INFO - 'TMOUT': '86400', 04:25:10 INFO - 'USER': 'cltbld', 04:25:10 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:10 INFO - '_': '/tools/buildbot/bin/python'} 04:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:10 INFO - Running setup.py (path:/tmp/pip-rHVdAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:11 INFO - Running setup.py (path:/tmp/pip-EZcyiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:11 INFO - Running setup.py (path:/tmp/pip-JaIZo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:11 INFO - Running setup.py (path:/tmp/pip-RcYBE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:11 INFO - Running setup.py (path:/tmp/pip-Oijrre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:11 INFO - Running setup.py (path:/tmp/pip-PxVxPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:11 INFO - Running setup.py (path:/tmp/pip-O58K60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:11 INFO - Running setup.py (path:/tmp/pip-P9Y_rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:11 INFO - Running setup.py (path:/tmp/pip-YfyMxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:11 INFO - Running setup.py (path:/tmp/pip-0RJ6GA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:11 INFO - Running setup.py (path:/tmp/pip-46WzlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:11 INFO - Running setup.py (path:/tmp/pip-yB8vcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:11 INFO - Running setup.py (path:/tmp/pip-yaestt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:11 INFO - Running setup.py (path:/tmp/pip-N6afHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:11 INFO - Running setup.py (path:/tmp/pip-8J9vnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:12 INFO - Running setup.py (path:/tmp/pip-oQvyaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:12 INFO - Running setup.py (path:/tmp/pip-dHlJG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:25:12 INFO - Running setup.py install for manifestparser 04:25:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:25:12 INFO - Running setup.py install for mozcrash 04:25:12 INFO - Running setup.py install for mozdebug 04:25:12 INFO - Running setup.py install for mozdevice 04:25:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:25:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:25:12 INFO - Running setup.py install for mozfile 04:25:12 INFO - Running setup.py install for mozhttpd 04:25:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:25:12 INFO - Running setup.py install for mozinfo 04:25:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:25:12 INFO - Running setup.py install for mozInstall 04:25:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Running setup.py install for mozleak 04:25:13 INFO - Running setup.py install for mozlog 04:25:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Running setup.py install for moznetwork 04:25:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Running setup.py install for mozprocess 04:25:13 INFO - Running setup.py install for mozprofile 04:25:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Running setup.py install for mozrunner 04:25:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Running setup.py install for mozscreenshot 04:25:13 INFO - Running setup.py install for moztest 04:25:13 INFO - Running setup.py install for mozversion 04:25:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:25:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:25:13 INFO - Cleaning up... 04:25:13 INFO - Return code: 0 04:25:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:13 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 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25:13 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 04:25:13 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 04:25:13 INFO - Using env: {'DISPLAY': ':0', 04:25:13 INFO - 'HOME': '/home/cltbld', 04:25:13 INFO - 'LANG': 'en_US.UTF-8', 04:25:13 INFO - 'LANGUAGE': 'en_US:en', 04:25:13 INFO - 'LOGNAME': 'cltbld', 04:25:13 INFO - 'MAIL': '/var/mail/cltbld', 04:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:13 INFO - 'MOZ_NO_REMOTE': '1', 04:25:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:13 INFO - 'NO_EM_RESTART': '1', 04:25:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:13 INFO - 'PWD': '/builds/slave/test', 04:25:13 INFO - 'SHELL': '/bin/bash', 04:25:13 INFO - 'SHLVL': '1', 04:25:13 INFO - 'TERM': 'linux', 04:25:13 INFO - 'TMOUT': '86400', 04:25:13 INFO - 'USER': 'cltbld', 04:25:13 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:13 INFO - '_': '/tools/buildbot/bin/python'} 04:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:14 INFO - Running setup.py (path:/tmp/pip-BJZ_J8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:14 INFO - Running setup.py (path:/tmp/pip-h3E8dR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:14 INFO - Running setup.py (path:/tmp/pip-UYew2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:14 INFO - Running setup.py (path:/tmp/pip-IAAjP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:14 INFO - Running setup.py (path:/tmp/pip-jFZlqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:14 INFO - Running setup.py (path:/tmp/pip-0egl3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:14 INFO - Running setup.py (path:/tmp/pip-VzAjuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:14 INFO - Running setup.py (path:/tmp/pip-bEC1GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:14 INFO - Running setup.py (path:/tmp/pip-cq528H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:14 INFO - Running setup.py (path:/tmp/pip-VAQwVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:14 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)) 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:14 INFO - Running setup.py (path:/tmp/pip-sLv3tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:15 INFO - Running setup.py (path:/tmp/pip-ODPkGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:15 INFO - Running setup.py (path:/tmp/pip-Q1RPjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:15 INFO - Running setup.py (path:/tmp/pip-bUG0nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:15 INFO - Running setup.py (path:/tmp/pip-nOvJk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:15 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)) 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:15 INFO - Running setup.py (path:/tmp/pip-HPwtna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:15 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)) 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:15 INFO - Running setup.py (path:/tmp/pip-uq1HNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:15 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)) 04:25:15 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)) 04:25:15 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)) 04:25:15 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)) 04:25:15 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)) 04:25:15 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)) 04:25:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:15 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 04:25:15 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 04:25:15 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 04:25:15 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 04:25:15 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 04:25:15 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 04:25:17 INFO - Downloading blessings-1.6.tar.gz 04:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:25:17 INFO - Installing collected packages: blessings 04:25:17 INFO - Running setup.py install for blessings 04:25:17 INFO - Successfully installed blessings 04:25:17 INFO - Cleaning up... 04:25:17 INFO - Return code: 0 04:25:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:25:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:17 INFO - Reading from file tmpfile_stdout 04:25:17 INFO - Current package versions: 04:25:17 INFO - argparse == 1.2.1 04:25:17 INFO - blessings == 1.6 04:25:17 INFO - blobuploader == 1.2.4 04:25:17 INFO - docopt == 0.6.1 04:25:17 INFO - manifestparser == 1.1 04:25:17 INFO - mozInstall == 1.12 04:25:17 INFO - mozcrash == 0.17 04:25:17 INFO - mozdebug == 0.1 04:25:17 INFO - mozdevice == 0.48 04:25:17 INFO - mozfile == 1.2 04:25:17 INFO - mozhttpd == 0.7 04:25:17 INFO - mozinfo == 0.9 04:25:17 INFO - mozleak == 0.1 04:25:17 INFO - mozlog == 3.2 04:25:17 INFO - moznetwork == 0.27 04:25:17 INFO - mozprocess == 0.22 04:25:17 INFO - mozprofile == 0.28 04:25:17 INFO - mozrunner == 6.11 04:25:17 INFO - mozscreenshot == 0.1 04:25:17 INFO - mozsystemmonitor == 0.1 04:25:17 INFO - moztest == 0.7 04:25:17 INFO - mozversion == 1.4 04:25:17 INFO - psutil == 3.1.1 04:25:17 INFO - requests == 1.2.3 04:25:17 INFO - wsgiref == 0.1.2 04:25:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:17 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 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25:17 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 04:25:17 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 04:25:17 INFO - Using env: {'DISPLAY': ':0', 04:25:17 INFO - 'HOME': '/home/cltbld', 04:25:17 INFO - 'LANG': 'en_US.UTF-8', 04:25:17 INFO - 'LANGUAGE': 'en_US:en', 04:25:17 INFO - 'LOGNAME': 'cltbld', 04:25:17 INFO - 'MAIL': '/var/mail/cltbld', 04:25:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:17 INFO - 'MOZ_NO_REMOTE': '1', 04:25:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:17 INFO - 'NO_EM_RESTART': '1', 04:25:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:17 INFO - 'PWD': '/builds/slave/test', 04:25:17 INFO - 'SHELL': '/bin/bash', 04:25:17 INFO - 'SHLVL': '1', 04:25:17 INFO - 'TERM': 'linux', 04:25:17 INFO - 'TMOUT': '86400', 04:25:17 INFO - 'USER': 'cltbld', 04:25:17 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:17 INFO - '_': '/tools/buildbot/bin/python'} 04:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:25:18 INFO - Cleaning up... 04:25:18 INFO - Return code: 0 04:25:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:25:18 INFO - Using env: {'DISPLAY': ':0', 04:25:18 INFO - 'HOME': '/home/cltbld', 04:25:18 INFO - 'LANG': 'en_US.UTF-8', 04:25:18 INFO - 'LANGUAGE': 'en_US:en', 04:25:18 INFO - 'LOGNAME': 'cltbld', 04:25:18 INFO - 'MAIL': '/var/mail/cltbld', 04:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:18 INFO - 'MOZ_NO_REMOTE': '1', 04:25:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:18 INFO - 'NO_EM_RESTART': '1', 04:25:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:18 INFO - 'PWD': '/builds/slave/test', 04:25:18 INFO - 'SHELL': '/bin/bash', 04:25:18 INFO - 'SHLVL': '1', 04:25:18 INFO - 'TERM': 'linux', 04:25:18 INFO - 'TMOUT': '86400', 04:25:18 INFO - 'USER': 'cltbld', 04:25:18 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:18 INFO - '_': '/tools/buildbot/bin/python'} 04:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:18 INFO - Downloading/unpacking jsonschema 04:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:25:20 INFO - Downloading/unpacking functools32 (from jsonschema) 04:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:20 INFO - Downloading functools32-3.2.3-2.tar.gz 04:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:25:20 INFO - warning: no files found matching '*.txt' 04:25:20 INFO - no previously-included directories found matching 'build' 04:25:20 INFO - no previously-included directories found matching 'dist' 04:25:20 INFO - no previously-included directories found matching '.git*' 04:25:20 INFO - Installing collected packages: jsonschema, functools32 04:25:20 INFO - Running setup.py install for functools32 04:25:20 INFO - warning: no files found matching '*.txt' 04:25:20 INFO - no previously-included directories found matching 'build' 04:25:20 INFO - no previously-included directories found matching 'dist' 04:25:20 INFO - no previously-included directories found matching '.git*' 04:25:20 INFO - Successfully installed jsonschema functools32 04:25:20 INFO - Cleaning up... 04:25:20 INFO - Return code: 0 04:25:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f763abaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', '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 04:25: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', 'flake8'] in /builds/slave/test/build 04:25: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 flake8 04:25:20 INFO - Using env: {'DISPLAY': ':0', 04:25:20 INFO - 'HOME': '/home/cltbld', 04:25:20 INFO - 'LANG': 'en_US.UTF-8', 04:25:20 INFO - 'LANGUAGE': 'en_US:en', 04:25:20 INFO - 'LOGNAME': 'cltbld', 04:25:20 INFO - 'MAIL': '/var/mail/cltbld', 04:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:20 INFO - 'MOZ_NO_REMOTE': '1', 04:25:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:20 INFO - 'NO_EM_RESTART': '1', 04:25:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:20 INFO - 'PWD': '/builds/slave/test', 04:25:20 INFO - 'SHELL': '/bin/bash', 04:25:20 INFO - 'SHLVL': '1', 04:25:20 INFO - 'TERM': 'linux', 04:25:20 INFO - 'TMOUT': '86400', 04:25:20 INFO - 'USER': 'cltbld', 04:25:20 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:20 INFO - '_': '/tools/buildbot/bin/python'} 04:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:21 INFO - Downloading/unpacking flake8 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:25:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:25:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:25:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:25:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:25:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:25:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:25:23 INFO - no previously-included directories found matching 'docs/_build' 04:25:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:25:23 INFO - Running setup.py install for pep8 04:25:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:25:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:25:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:25:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:25:23 INFO - no previously-included directories found matching 'docs/_build' 04:25:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:25:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:25:23 INFO - Cleaning up... 04:25:23 INFO - Return code: 0 04:25:23 INFO - Running post-action listener: _resource_record_post_action 04:25:23 INFO - Running post-action listener: _start_resource_monitoring 04:25:23 INFO - Starting resource monitoring. 04:25:23 INFO - ##### 04:25:23 INFO - ##### Finished create-virtualenv step (success) 04:25:23 INFO - ##### 04:25:23 INFO - ##### 04:25:23 INFO - ##### Running install step. 04:25:23 INFO - ##### 04:25:23 INFO - Running pre-action listener: _resource_record_pre_action 04:25:23 INFO - Running main action method: install 04:25:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:24 INFO - Reading from file tmpfile_stdout 04:25:24 INFO - Detecting whether we're running mozinstall >=1.0... 04:25:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:25:24 INFO - Reading from file tmpfile_stdout 04:25:24 INFO - Output received: 04:25:24 INFO - Usage: mozinstall [options] installer 04:25:24 INFO - Options: 04:25:24 INFO - -h, --help show this help message and exit 04:25:24 INFO - -d DEST, --destination=DEST 04:25:24 INFO - Directory to install application into. [default: 04:25:24 INFO - "/builds/slave/test"] 04:25:24 INFO - --app=APP Application being installed. [default: firefox] 04:25:24 INFO - mkdir: /builds/slave/test/build/application 04:25:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:25:39 INFO - Reading from file tmpfile_stdout 04:25:39 INFO - Output received: 04:25:39 INFO - /builds/slave/test/build/application/firefox/firefox 04:25:39 INFO - Running post-action listener: _resource_record_post_action 04:25:39 INFO - ##### 04:25:39 INFO - ##### Finished install step (success) 04:25:39 INFO - ##### 04:25:39 INFO - ##### 04:25:39 INFO - ##### Running run-tests step. 04:25:39 INFO - ##### 04:25:39 INFO - Running pre-action listener: _resource_record_pre_action 04:25:39 INFO - Running main action method: run_tests 04:25:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:25:39 INFO - Python 2.7.3 04:25:39 INFO - Return code: 0 04:25:39 INFO - grabbing minidump binary from tooltool 04:25: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')]} 04:25:39 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 0x292f9c0>, '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 0x29308c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2930d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:25:39 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 04:25:39 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 04:25:39 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 04:25:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:25:40 INFO - Return code: 0 04:25:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:25:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:25:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:25:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:25:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:25:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:25:41 INFO - Return code: 0 04:25:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 04:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 04:25:41 INFO - Using env: {'DISPLAY': ':0', 04:25:41 INFO - 'HOME': '/home/cltbld', 04:25:41 INFO - 'LANG': 'en_US.UTF-8', 04:25:41 INFO - 'LANGUAGE': 'en_US:en', 04:25:41 INFO - 'LOGNAME': 'cltbld', 04:25:41 INFO - 'MAIL': '/var/mail/cltbld', 04:25:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:41 INFO - 'MOZ_NO_REMOTE': '1', 04:25:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:41 INFO - 'NO_EM_RESTART': '1', 04:25:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:41 INFO - 'PWD': '/builds/slave/test', 04:25:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:41 INFO - 'SHELL': '/bin/bash', 04:25:41 INFO - 'SHLVL': '1', 04:25:41 INFO - 'TERM': 'linux', 04:25:41 INFO - 'TMOUT': '86400', 04:25:41 INFO - 'USER': 'cltbld', 04:25:41 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717', 04:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:41 INFO - '_': '/tools/buildbot/bin/python'} 04:25:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 04:25:41 INFO - mozversion application_buildid: 20160513030046 04:25:41 INFO - mozversion application_changeset: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 04:25:41 INFO - mozversion application_display_name: Nightly 04:25:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:25:41 INFO - mozversion application_name: Firefox 04:25:41 INFO - mozversion application_remotingname: firefox 04:25:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:25:41 INFO - mozversion application_vendor: Mozilla 04:25:41 INFO - mozversion application_version: 49.0a1 04:25:41 INFO - mozversion platform_buildid: 20160513030046 04:25:41 INFO - mozversion platform_changeset: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 04:25:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:25:41 INFO - mozversion platform_version: 49.0a1 04:25:41 INFO - using testdate: 1463138741 04:25:41 INFO - actual date: 1463138741 04:25:41 INFO - starting webserver on 'localhost:45489' 04:25:41 INFO - SUITE-START | Running 2 tests 04:25:41 INFO - TEST-START | dromaeo_css 04:25:41 INFO - operating with platform_type : linux_ 04:25:41 INFO - Initialising browser for dromaeo_css test... 04:25:41 INFO - TEST-INFO | started process 3331 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMRjhh5/profile http://localhost:45489/getInfo.html) 04:25:47 INFO - PROCESS | 3331 | __metrics Screen width/height:1600/1200 04:25:47 INFO - PROCESS | 3331 | colorDepth:24 04:25:47 INFO - PROCESS | 3331 | Browser inner width/height: 1024/697 04:25:47 INFO - PROCESS | 3331 | __metrics 04:25:48 ERROR - PROCESS | 3331 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:25:49 INFO - TEST-INFO | 3331: exit 0 04:25:49 INFO - Browser initialized. 04:25:49 INFO - Running cycle 1/1 for dromaeo_css test... 04:25:49 INFO - TEST-INFO | started process 3417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMRjhh5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:26:01 INFO - PROCESS | 3417 | RSS: Main: 154083328 04:26:01 INFO - PROCESS | 3417 | 04:26:51 INFO - PROCESS | 3417 | 1463138811868 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] 04:29:05 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:45489/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45489/tests/dromaeo/cssquery-ext.html) 04:29:06 INFO - PROCESS | 3417 | RSS: Main: 237785088 04:29:06 INFO - PROCESS | 3417 | 04:32:08 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:45489/tests/dromaeo/cssquery-ext.html (next: http://localhost:45489/tests/dromaeo/cssquery-jquery.html) 04:32:09 INFO - PROCESS | 3417 | RSS: Main: 236515328 04:32:09 INFO - PROCESS | 3417 | 04:35:11 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:45489/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45489/tests/dromaeo/cssquery-mootools.html) 04:35:12 INFO - PROCESS | 3417 | RSS: Main: 245633024 04:35:12 INFO - PROCESS | 3417 | 04:38:14 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:45489/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45489/tests/dromaeo/cssquery-prototype.html) 04:38:14 INFO - PROCESS | 3417 | RSS: Main: 235245568 04:38:14 INFO - PROCESS | 3417 | 04:41:17 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:45489/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45489/tests/dromaeo/cssquery-yui.html) 04:41:18 INFO - PROCESS | 3417 | RSS: Main: 211517440 04:41:18 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:45489/tests/dromaeo/cssquery-yui.html (next: http://localhost:45489/tests/dromaeo/cssquery-dojo.html) 04:44:21 INFO - PROCESS | 3417 | RSS: Main: 272494592 04:44:21 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | __start_tp_report 04:44:21 INFO - PROCESS | 3417 | _x_x_mozilla_page_load 04:44:21 INFO - PROCESS | 3417 | _x_x_mozilla_page_load_details 04:44:21 INFO - PROCESS | 3417 | |i|pagename|runs| 04:44:21 INFO - PROCESS | 3417 | |0;dojo.html;2671.839689618623;2688.9865550672243;2698.7975901807367;2715.2804506805687;2720.605512200731;4704.082703872747;4753.857384278477;4802.8559143225675;4874.63440241982;4915.950840491599;4821.325014497973;4824.758762061901;4842.757862106898;4868.537488938554;4868.610511159108;4370.978145109278;4406.36107764374;4466.263066594008;4522.072975040125;4522.344260082286;4352.434183556134;4399.032212913154;4413.205622987861;4419.204543182226;4470.374147619336;4368.825246990116;4404.66964977628;4406.427164468599;4430.977845110786;4440.533743956903;4423.845165419195;4454.22051141049;4457.821687132511;4496.977515112436;4498.752568608726;4471.374007638933;4477.7984990675495;4483.910321793578;4490.034642551835;4526.140033393644;4633.582977532004;4730.763461826929;4779.761011949424;4789.4971028042255;4791.0178413425165;5252.8686782830355;5284.207368894658;5292.126799078147;5298.496642818965;5307.416184219733;5267.578593712494;5277.498637629419;5278.683279003272;5323.840284791462;5336.092864213093;4756.5481279278065;4798.592119669867;4853.3447984522345;4860.052289803454;4917.975410122926;5382.461753824665;5384.9730751346;5434.375046869661;5437.374701909252;5437.83686489398;4917.360743103445;5142.100132476801;5177.9741101295;5185.144451165515;5191.169412893994;5098.209777484496;5174.974125129425;5186.273921650972;5188.066148093304;5202.947970520247;5124.590032797368;5131.050755610224;5132.204508301219;5132.923006154911;5182.792688292501;5004.574611158019;5068.138416469209;5147.279380886679;5163.690178589297;5176.2494438306985;4363.563643635611;4406.713563618355;4406.779661016936;4415.028693687384;4418.270985287456;3793.867214647529;3804.8097595120134;3815.503984482055;3826.9234615307537;3850.0374906273432;4323.265044942367;4424.225760491901;4443.755593442327;4450.755208463501;4491.034427598017;5193.792248310026;5206.583473322125;5222.582193424517;5250.159974404077;5294.311739473921;4429.490608580055;4462.933056004163;4466.59800617946;4484.820607175741;4504.752238626907;4526.751028693454;4603.769811509478;4656.045510670305;4708.176069187947;4739.289106634033;4843.854684359475;4874.58566021878;4891.951080489292;4904.901901961941;4915.65590408668;5180.948190518046;5184.818531351385;5186.299849520266;5198.298229738937;5204.531592156724;4724.598409135262;4816.8554943351755;4851.975740121276;4852.51474852526;4868.41579010521;5063.443021267584;5083.313752643346;5087.720175390389;5088.211327244283;5114.156164233009;5143.6399452039495;5144.279800827962;5146.588272938231;5158.045761534128;5163.586913046872;4863.683860549195;5002.349694539686;5030.949690503048;5055.115354812967;5069.847904562869;5020.297158397754;5052.671576347526;5057.6459647824295;5086.516780905956;5107.565856902205;4904.730239836843;4998.1503144464805;5032.6980381177245;5074.517920797518;5079.288899554139;5054;5085.92371114441;5113.284140220298;5121.231815227597;5141.2288156776785;5005;5007.749612519432;5018.096742586224;5019.598432125495;5057.69653820772 04:44:21 INFO - PROCESS | 3417 | |1;ext.html;584.9181114643949;598.8652553175534;600.9699515024245;602.4246844263729;611.1443978430194;42609.573904260986;44166.77916610416;44216;48386.75806620971;48456.51543484564;44954.32568511475;45308.77345613275;45332.320015199795;45383.54616453843;45389.092218155616;7529.510581812165;7988.92011079889;8134.877976830352;8138.430309878311;10089.697309080739;9930.602775888992;10247.743806404826;10257.43584102874;10261.794764104714;10263.589456421734;10230.590776368936;10280.074793268604;10282.228832837529;10286.228532860028;10302.433366164858;7615.16233214346;10006;10192.541335639877;10294.17646588271;10298.845517317246;10061.094501494827;10121.493925303705;10134.847977280348;10140.239482038876;10145.594176232942;41358;41669.79165104185;41683.1663366734;41797;41813.58186418127;3301.1747063234193;3487.4768784682246;3687.3731465650903;3777.395616701315;4018.8593399230886;29057;30746.538801917988;31088.222794430094;31571.21071973196;31619.36761264785;3281.6062072551445;3342.9498557521656;3376.4766461198556;3423.3666771647327;3473.89578312651;241166;241456;242285;243008.99999999822;243637;33985.49021764676;34959;60100;60518;60621.09068363978;36403;36537.08657283589;36565.81717091451;36593.634063659025;36651.633483664824;14801.407943682132;14919;14951.775723364159;14952.401903923721;14964.102153870803;10738.248322617343;11475.081993440504;11723;11739.823902641467;11981.58064467757;8396.874046889301;8413.95793021031;8451.830963380698;8489.235968762718;8506.106858779864;3289.243474000993;3289.835508224627;3300.7359411247044;3319.369319829225;3325.152086217999;906.4063038709523;985.6993616946756;985.9852102218473;1028.2082796246998;1035.0839507036328;29841.701582984326;29902;29908.700912990593;29972.850135749617;30125;1024.9692509224735;1060.771934034171;1097.1935627313917;1133.2010932292715;1176.353005846781;15420.558429769622;15742.527724168292;15865.682686346208;16074.919625401799;16149.354025838835;358431;361122;361636;362455;362495;53493.46506534885;54034.189487157724;54299.45700542944;54389.72805136028;54422.45577544253;1151.8560179977503;1267.113020885384;1271.2118486538404;1277.4251586785797;1301.5834932821406;29029.7097029027;30649;30687.846560767055;30706.692933070382;30935.0719478416;44713.10573788571;45360.77319613381;45363.31955020611;45380.319295209934;45478.317825231985;260109;264144;264185;265449;265563;23857.164999224944;24430.144944926844;27332;27879.860600696866;28531.714682853737;43877.56122438735;45283.320750189436;45465;46301.536984629725;46344;37866;38102.98963751212;38355.424668629435;38829.22341553096;38865.222695545366;37759.81120094382;37971.43042854304;38395.23209535849;38438.23123537458;38693.613063869;37197.44203836891;37596.812015939744;38007.619923800405;38143.237135257696;38156;33021.17447063843;33995.320093598486;34368.48447273243;34855.82572087123;34892.82553587216;26175.738242618092;26333.210003699918;26364.604530932436;26387;26463.470730584897 04:44:21 INFO - PROCESS | 3417 | |2;jquery.html;376.4672987722373;378.23218865702614;384.34085543293247;395.6379912380172;396.06331027120865;15973.760393594092;17620;18104.456866294;18347.08264586772;18395;17053.232604532826;17214.39749608764;17819.554511137194;17832.910835445804;17894.463166105004;4811.687240329385;5174.456682048387;5181.378234611851;5202.8179013734525;5231.084560201968;3680.9815950920342;3786.242751449707;3918.608139186073;5882;5941.494972927307;5800.7679692812235;5806.65160090396;5816.069428891378;5816.621919575214;5822.417758224165;5740.024195886688;5794.62334948227;5861.560382971273;5899.941000590004;5936.376680448534;5086.03365360584;5195.740212989335;5208.322918020633;5209.609279304067;5214.426413094557;20718.79281207184;20834.166633334647;21093.367198983902;21110.15559377623;21152.259670911597;1993.0931426201043;2038.4088614301834;2046.079264331057;2054.7842476540045;2109.4093653776968;13077.476900923952;13152.21086734799;13201.40593673287;13221.405036773369;13836.515721949683;1657.2542355939875;1702.2680247493572;1781.9643607127914;1789.3021721528614;1881.3697411367073;45655.54344456546;59218.99999999957;60280;66699.66650166718;70571;9369;9624.711258662252;9745.951270243746;9845.60617575289;9970.900290996997;13270.402831872589;13351.866481335257;13366.198028118344;13384.464621415229;13401.798973015413;7599.886001709979;7671.578063206577;7782.610869456505;7843.7646870592835;7897.960510197528;7672.197832179952;7685.346745526581;7795.3373963212625;7883.36933045355;7965.641546130438;6228.626282423069;6312.305646378894;6329.493640508748;6368.649724265118;6451.741890338438;1601.9839801602068;1696.4656133317965;1745.4501831923158;1770.8583313334902;1777.4489908128521;875.7022612311845;915.0940568836803;918.7657147427363;966.0387914025589;1011.1506334678905;16773.6645267094;17148.571285717706;17691.026993515225;17924.91037544804;18012.189451474715;867.483847110968;897.9281657467518;898.7079199260266;919.8068405634891;929.0848514213494;10002.449865257335;10342.896571034344;10390.532426040847;10428.582856685647;10429.322094063862;370595;376282;379454;379741;379814;43369;68546.314536854;68906.310936891;69095;69096.6545167271;873.7204094689765;972.8200282947678;977.6285011695652;983.3902980152209;998.5356809083811;13369.398377072861;15346.616334591725;15584;16575.336986520404;16587.83412165911;34321.31357372888;34344.31311373709;34541.481877772356;35107.29785404226;35415.468767967985;44868.775656122816;45517.31724024076;45553.772231139956;45655.315170273134;45724.771376144236;7608.657610407434;7809.375249980101;7921.366290696846;7951.681932722858;8011.799705007422;10899.891001089887;11381.487833047368;11922.284662920252;12078.879211207775;12257.632271031884;10901.438162031758;10903.618373356894;10986.230963832455;11099.112071034458;11168.811844357013;7688.6540105696495;10620.787584248426;10685.839712404475;10717.196210284104;10719.946400267949;10928.50821713009;11030.117590592894;11070.169737269576;11106.666799996014;11181.161412894227;7951.244631759973;7997.080335761229;8020;8062.260230179094;8158.388120891075;8128.0246370435925;9824.705258842247;10885.782284354427;11008.559657613927;11092.500837462496 04:44:21 INFO - PROCESS | 3417 | |3;mootools.html;1315.302889468582;1509.4188737336126;1546.5128484527372;1739.4086010756341;1904.8380887624542;7667.961660191698;7987.7603671889865;8003.239692229236;8184.263416292532;8190.836183276331;7992.440529162961;8183.549904755236;8187;8189.1810818918075;8195.836083278331;2196;2198.2196320306302;2219.4340443186966;2220.977790222093;2224.421650370907;2216.6674998750236;2220.844540882139;2289.7023386959672;2290.6678531612906;2293.7247530296386;2176.5320456101936;2190.7042549255807;2212.8008479236864;2221.311393468022;2223.6108680980847;2116.5978464091927;2125.829933605308;2140.882251476168;2146.506303550192;2148.258850696507;2102.5374417628095;2122.8620139691025;2128.435964469417;2130.158587358;2133.178726190428;7936.801579960489;8013.4791238569305;8063.395245356622;8071.677132914677;8235.711750088718;4706.952930470686;4784.808607655689;4799.448063472711;4811.230203167512;4812.205986012304;2935.8532073396245;2942.529195328737;2996.4456575533377;2999.5950546676136;3001.8949336773107;910.41733290694;932.067932067932;970.6408628807316;975.9170470510015;979.745266230778;11125.332480051224;11185.72035699114;11189.888101119048;11218.831717524243;11232.663020109328;3929.135590170158;4013.959860401417;4069.38959156123;4080.143169934347;4145.91708165835;8710.12898710124;8713.782155446164;8731;8731.083236260229;8815;4672.299155126758;4693.108309421199;4701.059788042405;4715.434147902273;4717.834875779331;3708.554973403209;4192.161567686475;4282.571742825755;4344.500382456058;4380.102079073782;3426.691597756164;3452.8273586320583;3487.494313324554;3504.299140171976;3541.256336169382;1029.4852573713144;1047.120418848156;1047.2512153810062;1049.8373350897177;1065.6856227412932;923.4643906534194;928.4083010624512;938.6292414496236;959.4866746290701;972.4554249620236;5367.382750983609;5459.181122831607;5473.507384335349;5501.14732216507;5597.468240517144;410.5114913253219;418.4434701846537;418.9581041895786;427.2885645400436;429.23167530121356;3771.151490914522;3807.276617442677;3848.730588858809;3859.4982652255026;3876;9519.762005949768;9896.802063958681;9993.350432222027;10000.499975001367;10002;9841.114299712917;9848.162906153057;9857.802843943082;9858.359206651545;9858.556364963593;7404.407647388196;7580.3556697681315;7666.310032097026;7697.4226932980255;7704.6918123274445;5554.250176226158;5562.415946325739;5563.833085007456;5612.158176273591;5718.542516598579;9103.362764606414;9219.585118669542;9226.861597075915;9236.72289831306;9253.768655783659;9133.954330228305;9322.673706420243;9323.673671421468;9336.253099752139;9497.810043799223;5962.880742385041;6179.16581261524;6193.349698281616;6408.551401401857;6506.056621789909;7256.7822965311125;7326.120865496175;7507.361874240751;7511.173770885089;7690.538567685957;6856.794296171123;6983.336583024605;6984.476164287597;7080.645967701697;7110.857782844418;6828.556143850634;6832.419244364285;6860.725570977304;6921.7231310747;6930.445564354739;6403.103565500741;6411.0383442484;6468.805935821744;6547.508936829852;6569.9343006569325;4657.767111644336;4663.650226233114;4798.808047678054;4831.492693267297;4844.709317440965;4702.764861756967;4787.880302992314;4806.7836947338155;4870.293807397837;4887.926681099715 04:44:21 INFO - PROCESS | 3417 | |4;prototype.html;305.40903352013856;310.551253438781;310.5543545012905;313.64714695967035;317.4666560178899;4857.902841943168;4865;4877.341558889549;4882.536159064892;4899.804007839691;4759.405074365704;4801.615870730342;4816.566509014188;4840.370751802261;4886.68236564623;3597.712183025352;3719.7210209234418;3727.1986522897573;3732.6267373262726;3740.4763333133383;3686.3364594373;3744.8127593620347;3781.3760729479604;3813.9427908581392;3876.6317199866107;3758.7556808807476;3782.432635104729;3806.7335286529956;3812.8284227209842;3837.194189220267;3530.6998905093096;3726.757760745543;3769.9057523561855;3770.5475342958885;3770.7737535747942;3248.3665685191163;3254.6419893811662;3257.8533965971587;3277.7869438486423;3281.9343613127844;4609.953900460986;4689.226277664181;4691.437027556715;4691.460482044575;4700.600448961843;4266.360045993126;4270.3132262756035;4290.442242508485;4300.397944287803;4311.28863737483;4921.729304888229;4971.90056198874;5089.465606111342;5094.210397388374;5102.36220472441;3566.7681600695873;3576.8926932192076;3582.444721068239;3585.4980302757635;3589.425691889311;5319.9734001330135;5345.278387417688;5350.866228344284;5355.732213389354;5360.72169328034;3521.4189658706537;3666.5233519642275;3686.9815650921573;3730.7388482806487;3732.5147730794824;3715.628437156263;3721.4789929409903;3733.775973441602;3740.869069582606;3751.756135851161;4233.809478573472;4236.364545318165;4242.893927651833;4242.957570424318;4265.232258193493;4837.758112094381;5059.595232381401;5090.77091530882;5101;5113.565346945476;1272.6054922973908;1272.821804947308;1283.5250957145915;1283.722695917568;1285.9099863009687;814.1736137820118;816.506013861617;823.1439303124803;831.3266254334009;841.5581819544666;4306.397104405386;4477.865664030084;4479.61923236522;4488.147252022105;4491.910161796745;5216.504432078947;5245.609913372959;5249.370075590953;5264.710440925709;5285.9735701322015;4501.3022981437925;4506.842260520866;4512.887177820581;4515.155452065178;4518.728876267369;2651.9337016574354;2750.931226719308;2791.6510436195476;2818.732220439055;2823.901163459269;4859.545929305678;4941.678790878654;4952.801887924443;4984.675996060245;4992.825251116194;5193.740312984336;5288.576913846883;5361.544268737123;5385.407605163429;5397;4935.679180853162;4941.901161976741;4952.356193694796;4976.925346119811;4980.153373926441;5253.9211911820585;5256.553192978487;5260.158374660036;5263.157894736823;5274.868128296824;4910.9017819642695;5054.69671819692;5203.505666961632;5222.086134926449;5242.18746094356;4986.551210391082;4994.825181118644;5009.749512524432;5011.5489605935645;5030.6730062547285;3667.339878821838;3674.5774235962135;3679.963200367962;3680.803738784852;3712.554493460802;5059.46875578059;5175.611829112756;5267.78928842842;5276.947230527646;5277.20841873722;5037.269595908646;5038.798448062036;5048.368953880765;5055.216441451581;5070.552024623292;5044.571211447068;5053.534475002208;5060.569851562658;5064.974675126601;5103.21387514367;4740.905181896412;4988.4762099980435;4989.176785830443;5012.1980483122525;5024.547790698854;4865.367502224688;5008.649394542493;5098.934931352808;5134.332536770196;5141.794328226829;5034.672746271481;5039.571636410946;5040.361882388165;5066.201498468138;5071.239314102914 04:44:21 INFO - PROCESS | 3417 | |5;yui.html;768.4198430185211;817.9959100204499;819.978752375395;829.6556928874518;832.4838600067959;11881.168318217728;12161.817572736416;12326.691832704188;12327.568535101273;12329.445174967115;11114.5554177833;11130.276532025413;11135.888641113606;11353.034992025667;11522.769544609124;678.8778019956405;853.7012045783957;862.7541150772015;871.856143736286;877.6313948141764;876.0626130040858;884.8628462588309;885.3713863157166;885.4908427654093;886.7073865624361;921.0191146429065;924.306769922558;925.0887875442684;925.1211349218236;925.7176561148881;710.0733542726714;874.7987962768599;898.7034278688018;920.8848893888264;921.3964853021248;669.2604671837598;669.7990602819135;669.8392385827415;670.276101810044;670.4234358451728;11464.369459679798;11469.885301146966;11476.311421314664;11506.424678766112;11511.424428778528;11034.944825275903;11713.180077394585;12091.516339346415;12101;12647.304398258093;10849.186311026704;10877.292975956616;10938.945305273503;11000.724981875437;11021;4108.876733697994;4158.397032430281;4193;4193.182329445759;4230.386593943861;44354.55645443536;44518.55481445177;44823.103537929055;45657.08685826298;45689.31466028012;8634.222919937236;8652.3510736695;8674.86987695185;8677.913220867837;8742.38803283764;16137.596560085856;16216;16243.431479898147;16273.67452650964;17772;6543.770968016097;6662.6668666566475;6722.428593569503;6768.76309329181;6850;3481.7562770606323;3629.4374371972385;3646.6171052039685;3659.7621154624862;3689.741718079709;2704.4996675615075;2784.2065011472037;2793.25978615667;2799.790015748827;2810.9156725297867;480.03752476284825;503.55183886341183;505.3708133373973;513.5839969624616;513.9100657384943;494.82433736023916;500.51450093409335;672.8486090629569;689.8137502874196;704.3379223529865;7560.697572097164;7992.200779921961;8014.358851291882;8068.354531637455;8102.837943241102;617.7498113264136;619.1981384107592;631.192074145095;644.2172760096505;654.2443477783168;3662;3747.9437808432895;3845.9038524037123;3893.260280546687;3919.941200881989;60130.098048529304;60160;60590;60908.39091609116;60951.69524152351;55374.72312638411;55793.44206557883;55920.80593955388;56973.715131424906;57366.852662946505;42065.15869682589;42617.360739588934;43529.56470435255;50904.49095509072;51763.223551646755;7722.768316950501;7783.338416234457;7807.594632966121;7982.600869956363;8043.758687239392;28394.432111357244;28435.857820711593;30519.237019074702;30862.228444289074;31252;38970.61029389783;39415.80292098521;39449.2110157801;40544.391834123104;40752.59247407607;10677.46612669379;10719.088877445192;10822.458877056273;10909.781804363709;11308.943455282671;11939.403029848647;12041.33772642513;12160.635180944586;12265.448054837376;12687.619371418872;7578.545287282781;9688.951555242178;9902.306838521523;9922.206223502248;9931.900680993387;9411.247100232102;9655;9716.9028309716;9847.409155450696;9966.202703783825;9041.773955650899;9150.450972941644;9286.814263714552;9731.221502279943;9796.118349348593;7904.407169462428;7930.563818990011;7939.166387529457;8016.6392512335915;8313.833723325379;8018.4787988780545;8037.196280371916;8048.315893149148;8304.294134998592;8378.120297368689 04:44:21 INFO - PROCESS | 3417 | __end_tp_report 04:44:21 INFO - PROCESS | 3417 | __start_cc_report 04:44:21 INFO - PROCESS | 3417 | _x_x_mozilla_cycle_collect,993 04:44:21 INFO - PROCESS | 3417 | __end_cc_report 04:44:21 INFO - PROCESS | 3417 | __startTimestamp1463139861485__endTimestamp 04:44:21 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | ------- Summary: start ------- 04:44:21 INFO - PROCESS | 3417 | Number of tests: 6 04:44:21 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | [#0] dojo.html Cycles:165 Average:4789.82 Median:4904.73 stddev:514.35 (10.5%) stddev-sans-first:488.36 04:44:21 INFO - PROCESS | 3417 | Values: 2671.8 2689.0 2698.8 2715.3 2720.6 4704.1 4753.9 4802.9 4874.6 4916.0 4821.3 4824.8 4842.8 4868.5 4868.6 4371.0 4406.4 4466.3 4522.1 4522.3 4352.4 4399.0 4413.2 4419.2 4470.4 4368.8 4404.7 4406.4 4431.0 4440.5 4423.8 4454.2 4457.8 4497.0 4498.8 4471.4 4477.8 4483.9 4490.0 4526.1 4633.6 4730.8 4779.8 4789.5 4791.0 5252.9 5284.2 5292.1 5298.5 5307.4 5267.6 5277.5 5278.7 5323.8 5336.1 4756.5 4798.6 4853.3 4860.1 4918.0 5382.5 5385.0 5434.4 5437.4 5437.8 4917.4 5142.1 5178.0 5185.1 5191.2 5098.2 5175.0 5186.3 5188.1 5202.9 5124.6 5131.1 5132.2 5132.9 5182.8 5004.6 5068.1 5147.3 5163.7 5176.2 4363.6 4406.7 4406.8 4415.0 4418.3 3793.9 3804.8 3815.5 3826.9 3850.0 4323.3 4424.2 4443.8 4450.8 4491.0 5193.8 5206.6 5222.6 5250.2 5294.3 4429.5 4462.9 4466.6 4484.8 4504.8 4526.8 4603.8 4656.0 4708.2 4739.3 4843.9 4874.6 4892.0 4904.9 4915.7 5180.9 5184.8 5186.3 5198.3 5204.5 4724.6 4816.9 4852.0 4852.5 4868.4 5063.4 5083.3 5087.7 5088.2 5114.2 5143.6 5144.3 5146.6 5158.0 5163.6 4863.7 5002.3 5030.9 5055.1 5069.8 5020.3 5052.7 5057.6 5086.5 5107.6 4904.7 4998.2 5032.7 5074.5 5079.3 5054.0 5085.9 5113.3 5121.2 5141.2 5005.0 5007.7 5018.1 5019.6 5057.7 04:44:21 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | [#1] ext.html Cycles:180 Average:45490.58 Median:18680.98 stddev:76983.11 (412.1%) stddev-sans-first:77125.23 04:44:21 INFO - PROCESS | 3417 | Values: 584.9 598.9 601.0 602.4 611.1 42609.6 44166.8 44216.0 48386.8 48456.5 44954.3 45308.8 45332.3 45383.5 45389.1 7529.5 7988.9 8134.9 8138.4 10089.7 9930.6 10247.7 10257.4 10261.8 10263.6 10230.6 10280.1 10282.2 10286.2 10302.4 7615.2 10006.0 10192.5 10294.2 10298.8 10061.1 10121.5 10134.8 10140.2 10145.6 41358.0 41669.8 41683.2 41797.0 41813.6 3301.2 3487.5 3687.4 3777.4 4018.9 29057.0 30746.5 31088.2 31571.2 31619.4 3281.6 3342.9 3376.5 3423.4 3473.9 241166.0 241456.0 242285.0 243009.0 243637.0 33985.5 34959.0 60100.0 60518.0 60621.1 36403.0 36537.1 36565.8 36593.6 36651.6 14801.4 14919.0 14951.8 14952.4 14964.1 10738.2 11475.1 11723.0 11739.8 11981.6 8396.9 8414.0 8451.8 8489.2 8506.1 3289.2 3289.8 3300.7 3319.4 3325.2 906.4 985.7 986.0 1028.2 1035.1 29841.7 29902.0 29908.7 29972.9 30125.0 1025.0 1060.8 1097.2 1133.2 1176.4 15420.6 15742.5 15865.7 16074.9 16149.4 358431.0 361122.0 361636.0 362455.0 362495.0 53493.5 54034.2 54299.5 54389.7 54422.5 1151.9 1267.1 1271.2 1277.4 1301.6 29029.7 30649.0 30687.8 30706.7 30935.1 44713.1 45360.8 45363.3 45380.3 45478.3 260109.0 264144.0 264185.0 265449.0 265563.0 23857.2 24430.1 27332.0 27879.9 28531.7 43877.6 45283.3 45465.0 46301.5 46344.0 37866.0 38103.0 38355.4 38829.2 38865.2 37759.8 37971.4 38395.2 38438.2 38693.6 37197.4 37596.8 38007.6 38143.2 38156.0 33021.2 33995.3 34368.5 34855.8 34892.8 26175.7 26333.2 26364.6 26387.0 26463.5 04:44:21 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | [#2] jquery.html Cycles:180 Average:23465.76 Median:379777.50 stddev:61823.59 (16.3%) stddev-sans-first:61972.72 04:44:21 INFO - PROCESS | 3417 | Values: 376.5 378.2 384.3 395.6 396.1 15973.8 17620.0 18104.5 18347.1 18395.0 17053.2 17214.4 17819.6 17832.9 17894.5 4811.7 5174.5 5181.4 5202.8 5231.1 3681.0 3786.2 3918.6 5882.0 5941.5 5800.8 5806.7 5816.1 5816.6 5822.4 5740.0 5794.6 5861.6 5899.9 5936.4 5086.0 5195.7 5208.3 5209.6 5214.4 20718.8 20834.2 21093.4 21110.2 21152.3 1993.1 2038.4 2046.1 2054.8 2109.4 13077.5 13152.2 13201.4 13221.4 13836.5 1657.3 1702.3 1782.0 1789.3 1881.4 45655.5 59219.0 60280.0 66699.7 70571.0 9369.0 9624.7 9746.0 9845.6 9970.9 13270.4 13351.9 13366.2 13384.5 13401.8 7599.9 7671.6 7782.6 7843.8 7898.0 7672.2 7685.3 7795.3 7883.4 7965.6 6228.6 6312.3 6329.5 6368.6 6451.7 1602.0 1696.5 1745.5 1770.9 1777.4 875.7 915.1 918.8 966.0 1011.2 16773.7 17148.6 17691.0 17924.9 18012.2 867.5 897.9 898.7 919.8 929.1 10002.4 10342.9 10390.5 10428.6 10429.3 370595.0 376282.0 379454.0 379741.0 379814.0 43369.0 68546.3 68906.3 69095.0 69096.7 873.7 972.8 977.6 983.4 998.5 13369.4 15346.6 15584.0 16575.3 16587.8 34321.3 34344.3 34541.5 35107.3 35415.5 44868.8 45517.3 45553.8 45655.3 45724.8 7608.7 7809.4 7921.4 7951.7 8011.8 10899.9 11381.5 11922.3 12078.9 12257.6 10901.4 10903.6 10986.2 11099.1 11168.8 7688.7 10620.8 10685.8 10717.2 10719.9 10928.5 11030.1 11070.2 11106.7 11181.2 7951.2 7997.1 8020.0 8062.3 8158.4 8128.0 9824.7 10885.8 11008.6 11092.5 04:44:21 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | [#3] mootools.html Cycles:180 Average:5240.49 Median:4879.11 stddev:2978.05 (61.0%) stddev-sans-first:2971.79 04:44:21 INFO - PROCESS | 3417 | Values: 1315.3 1509.4 1546.5 1739.4 1904.8 7668.0 7987.8 8003.2 8184.3 8190.8 7992.4 8183.5 8187.0 8189.2 8195.8 2196.0 2198.2 2219.4 2221.0 2224.4 2216.7 2220.8 2289.7 2290.7 2293.7 2176.5 2190.7 2212.8 2221.3 2223.6 2116.6 2125.8 2140.9 2146.5 2148.3 2102.5 2122.9 2128.4 2130.2 2133.2 7936.8 8013.5 8063.4 8071.7 8235.7 4707.0 4784.8 4799.4 4811.2 4812.2 2935.9 2942.5 2996.4 2999.6 3001.9 910.4 932.1 970.6 975.9 979.7 11125.3 11185.7 11189.9 11218.8 11232.7 3929.1 4014.0 4069.4 4080.1 4145.9 8710.1 8713.8 8731.0 8731.1 8815.0 4672.3 4693.1 4701.1 4715.4 4717.8 3708.6 4192.2 4282.6 4344.5 4380.1 3426.7 3452.8 3487.5 3504.3 3541.3 1029.5 1047.1 1047.3 1049.8 1065.7 923.5 928.4 938.6 959.5 972.5 5367.4 5459.2 5473.5 5501.1 5597.5 410.5 418.4 419.0 427.3 429.2 3771.2 3807.3 3848.7 3859.5 3876.0 9519.8 9896.8 9993.4 10000.5 10002.0 9841.1 9848.2 9857.8 9858.4 9858.6 7404.4 7580.4 7666.3 7697.4 7704.7 5554.3 5562.4 5563.8 5612.2 5718.5 9103.4 9219.6 9226.9 9236.7 9253.8 9134.0 9322.7 9323.7 9336.3 9497.8 5962.9 6179.2 6193.3 6408.6 6506.1 7256.8 7326.1 7507.4 7511.2 7690.5 6856.8 6983.3 6984.5 7080.6 7110.9 6828.6 6832.4 6860.7 6921.7 6930.4 6403.1 6411.0 6468.8 6547.5 6569.9 4657.8 4663.7 4798.8 4831.5 4844.7 4702.8 4787.9 4806.8 4870.3 4887.9 04:44:21 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | [#4] prototype.html Cycles:180 Average:4212.57 Median:4849.14 stddev:1235.44 (25.5%) stddev-sans-first:1203.60 04:44:21 INFO - PROCESS | 3417 | Values: 305.4 310.6 310.6 313.6 317.5 4857.9 4865.0 4877.3 4882.5 4899.8 4759.4 4801.6 4816.6 4840.4 4886.7 3597.7 3719.7 3727.2 3732.6 3740.5 3686.3 3744.8 3781.4 3813.9 3876.6 3758.8 3782.4 3806.7 3812.8 3837.2 3530.7 3726.8 3769.9 3770.5 3770.8 3248.4 3254.6 3257.9 3277.8 3281.9 4610.0 4689.2 4691.4 4691.5 4700.6 4266.4 4270.3 4290.4 4300.4 4311.3 4921.7 4971.9 5089.5 5094.2 5102.4 3566.8 3576.9 3582.4 3585.5 3589.4 5320.0 5345.3 5350.9 5355.7 5360.7 3521.4 3666.5 3687.0 3730.7 3732.5 3715.6 3721.5 3733.8 3740.9 3751.8 4233.8 4236.4 4242.9 4243.0 4265.2 4837.8 5059.6 5090.8 5101.0 5113.6 1272.6 1272.8 1283.5 1283.7 1285.9 814.2 816.5 823.1 831.3 841.6 4306.4 4477.9 4479.6 4488.1 4491.9 5216.5 5245.6 5249.4 5264.7 5286.0 4501.3 4506.8 4512.9 4515.2 4518.7 2651.9 2750.9 2791.7 2818.7 2823.9 4859.5 4941.7 4952.8 4984.7 4992.8 5193.7 5288.6 5361.5 5385.4 5397.0 4935.7 4941.9 4952.4 4976.9 4980.2 5253.9 5256.6 5260.2 5263.2 5274.9 4910.9 5054.7 5203.5 5222.1 5242.2 4986.6 4994.8 5009.7 5011.5 5030.7 3667.3 3674.6 3680.0 3680.8 3712.6 5059.5 5175.6 5267.8 5276.9 5277.2 5037.3 5038.8 5048.4 5055.2 5070.6 5044.6 5053.5 5060.6 5065.0 5103.2 4740.9 4988.5 4989.2 5012.2 5024.5 4865.4 5008.6 5098.9 5134.3 5141.8 5034.7 5039.6 5040.4 5066.2 5071.2 04:44:21 INFO - PROCESS | 3417 | 04:44:21 INFO - PROCESS | 3417 | [#5] yui.html Cycles:180 Average:13116.68 Median:57170.28 stddev:16045.45 (28.1%) stddev-sans-first:16063.66 04:44:21 INFO - PROCESS | 3417 | Values: 768.4 818.0 820.0 829.7 832.5 11881.2 12161.8 12326.7 12327.6 12329.4 11114.6 11130.3 11135.9 11353.0 11522.8 678.9 853.7 862.8 871.9 877.6 876.1 884.9 885.4 885.5 886.7 921.0 924.3 925.1 925.1 925.7 710.1 874.8 898.7 920.9 921.4 669.3 669.8 669.8 670.3 670.4 11464.4 11469.9 11476.3 11506.4 11511.4 11034.9 11713.2 12091.5 12101.0 12647.3 10849.2 10877.3 10938.9 11000.7 11021.0 4108.9 4158.4 4193.0 4193.2 4230.4 44354.6 44518.6 44823.1 45657.1 45689.3 8634.2 8652.4 8674.9 8677.9 8742.4 16137.6 16216.0 16243.4 16273.7 17772.0 6543.8 6662.7 6722.4 6768.8 6850.0 3481.8 3629.4 3646.6 3659.8 3689.7 2704.5 2784.2 2793.3 2799.8 2810.9 480.0 503.6 505.4 513.6 513.9 494.8 500.5 672.8 689.8 704.3 7560.7 7992.2 8014.4 8068.4 8102.8 617.7 619.2 631.2 644.2 654.2 3662.0 3747.9 3845.9 3893.3 3919.9 60130.1 60160.0 60590.0 60908.4 60951.7 55374.7 55793.4 55920.8 56973.7 57366.9 42065.2 42617.4 43529.6 50904.5 51763.2 7722.8 7783.3 7807.6 7982.6 8043.8 28394.4 28435.9 30519.2 30862.2 31252.0 38970.6 39415.8 39449.2 40544.4 40752.6 10677.5 10719.1 10822.5 10909.8 11308.9 11939.4 12041.3 12160.6 12265.4 12687.6 7578.5 9689.0 9902.3 9922.2 9931.9 9411.2 9655.0 9716.9 9847.4 9966.2 9041.8 9150.5 9286.8 9731.2 9796.1 7904.4 7930.6 7939.2 8016.6 8313.8 8018.5 8037.2 8048.3 8304.3 8378.1 04:44:21 INFO - PROCESS | 3417 | -------- Summary: end -------- 04:44:21 INFO - PROCESS | 3417 | 04:44:22 INFO - TEST-INFO | 3417: exit 0 04:44:22 INFO - TEST-OK | dromaeo_css | took 1120978ms 04:44:22 INFO - TEST-START | kraken 04:44:22 INFO - operating with platform_type : linux_ 04:44:22 INFO - Initialising browser for kraken test... 04:44:22 INFO - TEST-INFO | started process 3599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsY9j3R/profile http://localhost:45489/getInfo.html) 04:44:26 INFO - PROCESS | 3599 | __metrics Screen width/height:1600/1200 04:44:26 INFO - PROCESS | 3599 | colorDepth:24 04:44:26 INFO - PROCESS | 3599 | Browser inner width/height: 1024/697 04:44:26 INFO - PROCESS | 3599 | __metrics 04:44:27 ERROR - PROCESS | 3599 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:44:28 INFO - TEST-INFO | 3599: exit 0 04:44:28 INFO - Browser initialized. 04:44:28 INFO - Running cycle 1/1 for kraken test... 04:44:28 INFO - TEST-INFO | started process 3681 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsY9j3R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:40 INFO - PROCESS | 3681 | RSS: Main: 153997312 04:44:40 INFO - PROCESS | 3681 | 04:44:43 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45489/tests/kraken/driver.html?testName=audio-beat-detection) 04:44:44 INFO - PROCESS | 3681 | RSS: Main: 209928192 04:44:44 INFO - PROCESS | 3681 | 04:44:47 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45489/tests/kraken/driver.html?testName=audio-dft) 04:44:47 INFO - PROCESS | 3681 | RSS: Main: 196431872 04:44:47 INFO - PROCESS | 3681 | 04:44:51 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45489/tests/kraken/driver.html?testName=audio-fft) 04:44:51 INFO - PROCESS | 3681 | RSS: Main: 266919936 04:44:51 INFO - PROCESS | 3681 | 04:44:53 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45489/tests/kraken/driver.html?testName=audio-oscillator) 04:44:54 INFO - PROCESS | 3681 | RSS: Main: 176189440 04:44:54 INFO - PROCESS | 3681 | 04:44:56 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45489/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:44:57 INFO - PROCESS | 3681 | RSS: Main: 180051968 04:44:57 INFO - PROCESS | 3681 | 04:45:00 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45489/tests/kraken/driver.html?testName=imaging-darkroom) 04:45:01 INFO - PROCESS | 3681 | RSS: Main: 215957504 04:45:01 INFO - PROCESS | 3681 | 04:45:05 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45489/tests/kraken/driver.html?testName=imaging-desaturate) 04:45:05 INFO - PROCESS | 3681 | RSS: Main: 214761472 04:45:05 INFO - PROCESS | 3681 | 04:45:08 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45489/tests/kraken/driver.html?testName=json-parse-financial) 04:45:09 INFO - PROCESS | 3681 | RSS: Main: 215408640 04:45:09 INFO - PROCESS | 3681 | 04:45:11 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45489/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:45:11 INFO - PROCESS | 3681 | RSS: Main: 204017664 04:45:11 INFO - PROCESS | 3681 | 04:45:13 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45489/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:45:14 INFO - PROCESS | 3681 | RSS: Main: 205201408 04:45:14 INFO - PROCESS | 3681 | 04:45:16 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45489/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:45:17 INFO - PROCESS | 3681 | RSS: Main: 216281088 04:45:17 INFO - PROCESS | 3681 | 04:45:20 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45489/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:45:20 INFO - PROCESS | 3681 | RSS: Main: 224403456 04:45:20 INFO - PROCESS | 3681 | 04:45:23 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45489/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:45:24 INFO - PROCESS | 3681 | RSS: Main: 221339648 04:45:24 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | Cycle 1(1): loaded http://localhost:45489/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45489/tests/kraken/driver.html?testName=ai-astar) 04:45:26 INFO - PROCESS | 3681 | RSS: Main: 268607488 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | __start_tp_report 04:45:26 INFO - PROCESS | 3681 | _x_x_mozilla_page_load 04:45:26 INFO - PROCESS | 3681 | _x_x_mozilla_page_load_details 04:45:26 INFO - PROCESS | 3681 | |i|pagename|runs| 04:45:26 INFO - PROCESS | 3681 | |0;ai-astar;112;109;114;114;112;112;113;114;113;112 04:45:26 INFO - PROCESS | 3681 | |1;audio-beat-detection;155;129;172;220;141;159;171;129;197;129 04:45:26 INFO - PROCESS | 3681 | |2;audio-dft;161;163;163;161;161;161;162;161;160;161 04:45:26 INFO - PROCESS | 3681 | |3;audio-fft;75;75;75;147;76;75;76;75;126;74 04:45:26 INFO - PROCESS | 3681 | |4;audio-oscillator;93;93;153;93;92;93;124;92;92;120 04:45:26 INFO - PROCESS | 3681 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;117;117 04:45:26 INFO - PROCESS | 3681 | |6;imaging-darkroom;145;147;145;146;145;146;146;145;146;146 04:45:26 INFO - PROCESS | 3681 | |7;imaging-desaturate;95;94;94;95;94;95;95;95;94;94 04:45:26 INFO - PROCESS | 3681 | |8;json-parse-financial;64;64;65;64;64;65;65;117;62;62 04:45:26 INFO - PROCESS | 3681 | |9;json-stringify-tinderbox;64;66;66;65;65;66;65;66;65;65 04:45:26 INFO - PROCESS | 3681 | |10;stanford-crypto-aes;89;82;82;81;82;83;83;84;83;84 04:45:26 INFO - PROCESS | 3681 | |11;stanford-crypto-ccm;134;128;141;137;128;132;132;132;196;147 04:45:26 INFO - PROCESS | 3681 | |12;stanford-crypto-pbkdf2;169;169;169;167;165;176;168;166;168;167 04:45:26 INFO - PROCESS | 3681 | |13;stanford-crypto-sha256-iterative;69;66;66;66;66;65;66;65;66;68 04:45:26 INFO - PROCESS | 3681 | __end_tp_report 04:45:26 INFO - PROCESS | 3681 | __start_cc_report 04:45:26 INFO - PROCESS | 3681 | _x_x_mozilla_cycle_collect,2085 04:45:26 INFO - PROCESS | 3681 | __end_cc_report 04:45:26 INFO - PROCESS | 3681 | __startTimestamp1463139926430__endTimestamp 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | ------- Summary: start ------- 04:45:26 INFO - PROCESS | 3681 | Number of tests: 14 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:1.51 (1.3%) stddev-sans-first:1.59 04:45:26 INFO - PROCESS | 3681 | Values: 112.0 109.0 114.0 114.0 112.0 112.0 113.0 114.0 113.0 112.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#1] audio-beat-detection Cycles:10 Average:160.20 Median:165.00 stddev:30.74 (18.6%) stddev-sans-first:32.55 04:45:26 INFO - PROCESS | 3681 | Values: 155.0 129.0 172.0 220.0 141.0 159.0 171.0 129.0 197.0 129.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 04:45:26 INFO - PROCESS | 3681 | Values: 161.0 163.0 163.0 161.0 161.0 161.0 162.0 161.0 160.0 161.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#3] audio-fft Cycles:10 Average:87.40 Median:75.00 stddev:26.35 (35.1%) stddev-sans-first:27.57 04:45:26 INFO - PROCESS | 3681 | Values: 75.0 75.0 75.0 147.0 76.0 75.0 76.0 75.0 126.0 74.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#4] audio-oscillator Cycles:10 Average:104.50 Median:92.50 stddev:21.00 (22.7%) stddev-sans-first:21.86 04:45:26 INFO - PROCESS | 3681 | Values: 93.0 93.0 153.0 93.0 92.0 93.0 124.0 92.0 92.0 120.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:45:26 INFO - PROCESS | 3681 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 04:45:26 INFO - PROCESS | 3681 | Values: 145.0 147.0 145.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 04:45:26 INFO - PROCESS | 3681 | Values: 95.0 94.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 94.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#8] json-parse-financial Cycles:10 Average:69.20 Median:64.00 stddev:16.83 (26.3%) stddev-sans-first:17.75 04:45:26 INFO - PROCESS | 3681 | Values: 64.0 64.0 65.0 64.0 64.0 65.0 65.0 117.0 62.0 62.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.50 stddev:0.67 (1.0%) stddev-sans-first:0.53 04:45:26 INFO - PROCESS | 3681 | Values: 64.0 66.0 66.0 65.0 65.0 66.0 65.0 66.0 65.0 65.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.00 stddev:2.21 (2.7%) stddev-sans-first:1.00 04:45:26 INFO - PROCESS | 3681 | Values: 89.0 82.0 82.0 81.0 82.0 83.0 83.0 84.0 83.0 84.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:135.50 stddev:20.29 (15.0%) stddev-sans-first:21.38 04:45:26 INFO - PROCESS | 3681 | Values: 134.0 128.0 141.0 137.0 128.0 132.0 132.0 132.0 196.0 147.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:168.50 stddev:2.99 (1.8%) stddev-sans-first:3.16 04:45:26 INFO - PROCESS | 3681 | Values: 169.0 169.0 169.0 167.0 165.0 176.0 168.0 166.0 168.0 167.0 04:45:26 INFO - PROCESS | 3681 | 04:45:26 INFO - PROCESS | 3681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:1.25 (1.9%) stddev-sans-first:0.87 04:45:26 INFO - PROCESS | 3681 | Values: 69.0 66.0 66.0 66.0 66.0 65.0 66.0 65.0 66.0 68.0 04:45:26 INFO - PROCESS | 3681 | -------- Summary: end -------- 04:45:26 INFO - PROCESS | 3681 | 04:45:28 INFO - TEST-INFO | 3681: exit 0 04:45:28 INFO - TEST-OK | kraken | took 65904ms 04:45:28 INFO - SUITE-END | took 1186s 04:45:28 INFO - Completed test suite (00:19:47) 04:45:28 INFO - javascript benchmark 04:45:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2671.839689618623, 2688.9865550672243, 2698.7975901807367, 2715.2804506805687, 2720.605512200731, 4704.082703872747, 4753.857384278477, 4802.8559143225675, 4874.63440241982, 4915.950840491599, 4821.325014497973, 4824.758762061901, 4842.757862106898, 4868.537488938554, 4868.610511159108, 4370.978145109278, 4406.36107764374, 4466.263066594008, 4522.072975040125, 4522.344260082286, 4352.434183556134, 4399.032212913154, 4413.205622987861, 4419.204543182226, 4470.374147619336, 4368.825246990116, 4404.66964977628, 4406.427164468599, 4430.977845110786, 4440.533743956903, 4423.845165419195, 4454.22051141049, 4457.821687132511, 4496.977515112436, 4498.752568608726, 4471.374007638933, 4477.7984990675495, 4483.910321793578, 4490.034642551835, 4526.140033393644, 4633.582977532004, 4730.763461826929, 4779.761011949424, 4789.4971028042255, 4791.0178413425165, 5252.8686782830355, 5284.207368894658, 5292.126799078147, 5298.496642818965, 5307.416184219733, 5267.578593712494, 5277.498637629419, 5278.683279003272, 5323.840284791462, 5336.092864213093, 4756.5481279278065, 4798.592119669867, 4853.3447984522345, 4860.052289803454, 4917.975410122926, 5382.461753824665, 5384.9730751346, 5434.375046869661, 5437.374701909252, 5437.83686489398, 4917.360743103445, 5142.100132476801, 5177.9741101295, 5185.144451165515, 5191.169412893994, 5098.209777484496, 5174.974125129425, 5186.273921650972, 5188.066148093304, 5202.947970520247, 5124.590032797368, 5131.050755610224, 5132.204508301219, 5132.923006154911, 5182.792688292501, 5004.574611158019, 5068.138416469209, 5147.279380886679, 5163.690178589297, 5176.2494438306985, 4363.563643635611, 4406.713563618355, 4406.779661016936, 4415.028693687384, 4418.270985287456, 3793.867214647529, 3804.8097595120134, 3815.503984482055, 3826.9234615307537, 3850.0374906273432, 4323.265044942367, 4424.225760491901, 4443.755593442327, 4450.755208463501, 4491.034427598017, 5193.792248310026, 5206.583473322125, 5222.582193424517, 5250.159974404077, 5294.311739473921, 4429.490608580055, 4462.933056004163, 4466.59800617946, 4484.820607175741, 4504.752238626907, 4526.751028693454, 4603.769811509478, 4656.045510670305, 4708.176069187947, 4739.289106634033, 4843.854684359475, 4874.58566021878, 4891.951080489292, 4904.901901961941, 4915.65590408668, 5180.948190518046, 5184.818531351385, 5186.299849520266, 5198.298229738937, 5204.531592156724, 4724.598409135262, 4816.8554943351755, 4851.975740121276, 4852.51474852526, 4868.41579010521, 5063.443021267584, 5083.313752643346, 5087.720175390389, 5088.211327244283, 5114.156164233009, 5143.6399452039495, 5144.279800827962, 5146.588272938231, 5158.045761534128, 5163.586913046872, 4863.683860549195, 5002.349694539686, 5030.949690503048, 5055.115354812967, 5069.847904562869, 5020.297158397754, 5052.671576347526, 5057.6459647824295, 5086.516780905956, 5107.565856902205, 4904.730239836843, 4998.1503144464805, 5032.6980381177245, 5074.517920797518, 5079.288899554139, 5054.0, 5085.92371114441, 5113.284140220298, 5121.231815227597, 5141.2288156776785, 5005.0, 5007.749612519432, 5018.096742586224, 5019.598432125495, 5057.69653820772], "value": 4756.394730210606, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.9181114643949, 598.8652553175534, 600.9699515024245, 602.4246844263729, 611.1443978430194, 42609.573904260986, 44166.77916610416, 44216.0, 48386.75806620971, 48456.51543484564, 44954.32568511475, 45308.77345613275, 45332.320015199795, 45383.54616453843, 45389.092218155616, 7529.510581812165, 7988.92011079889, 8134.877976830352, 8138.430309878311, 10089.697309080739, 9930.602775888992, 10247.743806404826, 10257.43584102874, 10261.794764104714, 10263.589456421734, 10230.590776368936, 10280.074793268604, 10282.228832837529, 10286.228532860028, 10302.433366164858, 7615.16233214346, 10006.0, 10192.541335639877, 10294.17646588271, 10298.845517317246, 10061.094501494827, 10121.493925303705, 10134.847977280348, 10140.239482038876, 10145.594176232942, 41358.0, 41669.79165104185, 41683.1663366734, 41797.0, 41813.58186418127, 3301.1747063234193, 3487.4768784682246, 3687.3731465650903, 3777.395616701315, 4018.8593399230886, 29057.0, 30746.538801917988, 31088.222794430094, 31571.21071973196, 31619.36761264785, 3281.6062072551445, 3342.9498557521656, 3376.4766461198556, 3423.3666771647327, 3473.89578312651, 241166.0, 241456.0, 242285.0, 243008.99999999822, 243637.0, 33985.49021764676, 34959.0, 60100.0, 60518.0, 60621.09068363978, 36403.0, 36537.08657283589, 36565.81717091451, 36593.634063659025, 36651.633483664824, 14801.407943682132, 14919.0, 14951.775723364159, 14952.401903923721, 14964.102153870803, 10738.248322617343, 11475.081993440504, 11723.0, 11739.823902641467, 11981.58064467757, 8396.874046889301, 8413.95793021031, 8451.830963380698, 8489.235968762718, 8506.106858779864, 3289.243474000993, 3289.835508224627, 3300.7359411247044, 3319.369319829225, 3325.152086217999, 906.4063038709523, 985.6993616946756, 985.9852102218473, 1028.2082796246998, 1035.0839507036328, 29841.701582984326, 29902.0, 29908.700912990593, 29972.850135749617, 30125.0, 1024.9692509224735, 1060.771934034171, 1097.1935627313917, 1133.2010932292715, 1176.353005846781, 15420.558429769622, 15742.527724168292, 15865.682686346208, 16074.919625401799, 16149.354025838835, 358431.0, 361122.0, 361636.0, 362455.0, 362495.0, 53493.46506534885, 54034.189487157724, 54299.45700542944, 54389.72805136028, 54422.45577544253, 1151.8560179977503, 1267.113020885384, 1271.2118486538404, 1277.4251586785797, 1301.5834932821406, 29029.7097029027, 30649.0, 30687.846560767055, 30706.692933070382, 30935.0719478416, 44713.10573788571, 45360.77319613381, 45363.31955020611, 45380.319295209934, 45478.317825231985, 260109.0, 264144.0, 264185.0, 265449.0, 265563.0, 23857.164999224944, 24430.144944926844, 27332.0, 27879.860600696866, 28531.714682853737, 43877.56122438735, 45283.320750189436, 45465.0, 46301.536984629725, 46344.0, 37866.0, 38102.98963751212, 38355.424668629435, 38829.22341553096, 38865.222695545366, 37759.81120094382, 37971.43042854304, 38395.23209535849, 38438.23123537458, 38693.613063869, 37197.44203836891, 37596.812015939744, 38007.619923800405, 38143.237135257696, 38156.0, 33021.17447063843, 33995.320093598486, 34368.48447273243, 34855.82572087123, 34892.82553587216, 26175.738242618092, 26333.210003699918, 26364.604530932436, 26387.0, 26463.470730584897], "value": 17780.34183356092, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.4672987722373, 378.23218865702614, 384.34085543293247, 395.6379912380172, 396.06331027120865, 15973.760393594092, 17620.0, 18104.456866294, 18347.08264586772, 18395.0, 17053.232604532826, 17214.39749608764, 17819.554511137194, 17832.910835445804, 17894.463166105004, 4811.687240329385, 5174.456682048387, 5181.378234611851, 5202.8179013734525, 5231.084560201968, 3680.9815950920342, 3786.242751449707, 3918.608139186073, 5882.0, 5941.494972927307, 5800.7679692812235, 5806.65160090396, 5816.069428891378, 5816.621919575214, 5822.417758224165, 5740.024195886688, 5794.62334948227, 5861.560382971273, 5899.941000590004, 5936.376680448534, 5086.03365360584, 5195.740212989335, 5208.322918020633, 5209.609279304067, 5214.426413094557, 20718.79281207184, 20834.166633334647, 21093.367198983902, 21110.15559377623, 21152.259670911597, 1993.0931426201043, 2038.4088614301834, 2046.079264331057, 2054.7842476540045, 2109.4093653776968, 13077.476900923952, 13152.21086734799, 13201.40593673287, 13221.405036773369, 13836.515721949683, 1657.2542355939875, 1702.2680247493572, 1781.9643607127914, 1789.3021721528614, 1881.3697411367073, 45655.54344456546, 59218.99999999957, 60280.0, 66699.66650166718, 70571.0, 9369.0, 9624.711258662252, 9745.951270243746, 9845.60617575289, 9970.900290996997, 13270.402831872589, 13351.866481335257, 13366.198028118344, 13384.464621415229, 13401.798973015413, 7599.886001709979, 7671.578063206577, 7782.610869456505, 7843.7646870592835, 7897.960510197528, 7672.197832179952, 7685.346745526581, 7795.3373963212625, 7883.36933045355, 7965.641546130438, 6228.626282423069, 6312.305646378894, 6329.493640508748, 6368.649724265118, 6451.741890338438, 1601.9839801602068, 1696.4656133317965, 1745.4501831923158, 1770.8583313334902, 1777.4489908128521, 875.7022612311845, 915.0940568836803, 918.7657147427363, 966.0387914025589, 1011.1506334678905, 16773.6645267094, 17148.571285717706, 17691.026993515225, 17924.91037544804, 18012.189451474715, 867.483847110968, 897.9281657467518, 898.7079199260266, 919.8068405634891, 929.0848514213494, 10002.449865257335, 10342.896571034344, 10390.532426040847, 10428.582856685647, 10429.322094063862, 370595.0, 376282.0, 379454.0, 379741.0, 379814.0, 43369.0, 68546.314536854, 68906.310936891, 69095.0, 69096.6545167271, 873.7204094689765, 972.8200282947678, 977.6285011695652, 983.3902980152209, 998.5356809083811, 13369.398377072861, 15346.616334591725, 15584.0, 16575.336986520404, 16587.83412165911, 34321.31357372888, 34344.31311373709, 34541.481877772356, 35107.29785404226, 35415.468767967985, 44868.775656122816, 45517.31724024076, 45553.772231139956, 45655.315170273134, 45724.771376144236, 7608.657610407434, 7809.375249980101, 7921.366290696846, 7951.681932722858, 8011.799705007422, 10899.891001089887, 11381.487833047368, 11922.284662920252, 12078.879211207775, 12257.632271031884, 10901.438162031758, 10903.618373356894, 10986.230963832455, 11099.112071034458, 11168.811844357013, 7688.6540105696495, 10620.787584248426, 10685.839712404475, 10717.196210284104, 10719.946400267949, 10928.50821713009, 11030.117590592894, 11070.169737269576, 11106.666799996014, 11181.161412894227, 7951.244631759973, 7997.080335761229, 8020.0, 8062.260230179094, 8158.388120891075, 8128.0246370435925, 9824.705258842247, 10885.782284354427, 11008.559657613927, 11092.500837462496], "value": 8426.892521834929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.302889468582, 1509.4188737336126, 1546.5128484527372, 1739.4086010756341, 1904.8380887624542, 7667.961660191698, 7987.7603671889865, 8003.239692229236, 8184.263416292532, 8190.836183276331, 7992.440529162961, 8183.549904755236, 8187.0, 8189.1810818918075, 8195.836083278331, 2196.0, 2198.2196320306302, 2219.4340443186966, 2220.977790222093, 2224.421650370907, 2216.6674998750236, 2220.844540882139, 2289.7023386959672, 2290.6678531612906, 2293.7247530296386, 2176.5320456101936, 2190.7042549255807, 2212.8008479236864, 2221.311393468022, 2223.6108680980847, 2116.5978464091927, 2125.829933605308, 2140.882251476168, 2146.506303550192, 2148.258850696507, 2102.5374417628095, 2122.8620139691025, 2128.435964469417, 2130.158587358, 2133.178726190428, 7936.801579960489, 8013.4791238569305, 8063.395245356622, 8071.677132914677, 8235.711750088718, 4706.952930470686, 4784.808607655689, 4799.448063472711, 4811.230203167512, 4812.205986012304, 2935.8532073396245, 2942.529195328737, 2996.4456575533377, 2999.5950546676136, 3001.8949336773107, 910.41733290694, 932.067932067932, 970.6408628807316, 975.9170470510015, 979.745266230778, 11125.332480051224, 11185.72035699114, 11189.888101119048, 11218.831717524243, 11232.663020109328, 3929.135590170158, 4013.959860401417, 4069.38959156123, 4080.143169934347, 4145.91708165835, 8710.12898710124, 8713.782155446164, 8731.0, 8731.083236260229, 8815.0, 4672.299155126758, 4693.108309421199, 4701.059788042405, 4715.434147902273, 4717.834875779331, 3708.554973403209, 4192.161567686475, 4282.571742825755, 4344.500382456058, 4380.102079073782, 3426.691597756164, 3452.8273586320583, 3487.494313324554, 3504.299140171976, 3541.256336169382, 1029.4852573713144, 1047.120418848156, 1047.2512153810062, 1049.8373350897177, 1065.6856227412932, 923.4643906534194, 928.4083010624512, 938.6292414496236, 959.4866746290701, 972.4554249620236, 5367.382750983609, 5459.181122831607, 5473.507384335349, 5501.14732216507, 5597.468240517144, 410.5114913253219, 418.4434701846537, 418.9581041895786, 427.2885645400436, 429.23167530121356, 3771.151490914522, 3807.276617442677, 3848.730588858809, 3859.4982652255026, 3876.0, 9519.762005949768, 9896.802063958681, 9993.350432222027, 10000.499975001367, 10002.0, 9841.114299712917, 9848.162906153057, 9857.802843943082, 9858.359206651545, 9858.556364963593, 7404.407647388196, 7580.3556697681315, 7666.310032097026, 7697.4226932980255, 7704.6918123274445, 5554.250176226158, 5562.415946325739, 5563.833085007456, 5612.158176273591, 5718.542516598579, 9103.362764606414, 9219.585118669542, 9226.861597075915, 9236.72289831306, 9253.768655783659, 9133.954330228305, 9322.673706420243, 9323.673671421468, 9336.253099752139, 9497.810043799223, 5962.880742385041, 6179.16581261524, 6193.349698281616, 6408.551401401857, 6506.056621789909, 7256.7822965311125, 7326.120865496175, 7507.361874240751, 7511.173770885089, 7690.538567685957, 6856.794296171123, 6983.336583024605, 6984.476164287597, 7080.645967701697, 7110.857782844418, 6828.556143850634, 6832.419244364285, 6860.725570977304, 6921.7231310747, 6930.445564354739, 6403.103565500741, 6411.0383442484, 6468.805935821744, 6547.508936829852, 6569.9343006569325, 4657.767111644336, 4663.650226233114, 4798.808047678054, 4831.492693267297, 4844.709317440965, 4702.764861756967, 4787.880302992314, 4806.7836947338155, 4870.293807397837, 4887.926681099715], "value": 4139.207216401873, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.40903352013856, 310.551253438781, 310.5543545012905, 313.64714695967035, 317.4666560178899, 4857.902841943168, 4865.0, 4877.341558889549, 4882.536159064892, 4899.804007839691, 4759.405074365704, 4801.615870730342, 4816.566509014188, 4840.370751802261, 4886.68236564623, 3597.712183025352, 3719.7210209234418, 3727.1986522897573, 3732.6267373262726, 3740.4763333133383, 3686.3364594373, 3744.8127593620347, 3781.3760729479604, 3813.9427908581392, 3876.6317199866107, 3758.7556808807476, 3782.432635104729, 3806.7335286529956, 3812.8284227209842, 3837.194189220267, 3530.6998905093096, 3726.757760745543, 3769.9057523561855, 3770.5475342958885, 3770.7737535747942, 3248.3665685191163, 3254.6419893811662, 3257.8533965971587, 3277.7869438486423, 3281.9343613127844, 4609.953900460986, 4689.226277664181, 4691.437027556715, 4691.460482044575, 4700.600448961843, 4266.360045993126, 4270.3132262756035, 4290.442242508485, 4300.397944287803, 4311.28863737483, 4921.729304888229, 4971.90056198874, 5089.465606111342, 5094.210397388374, 5102.36220472441, 3566.7681600695873, 3576.8926932192076, 3582.444721068239, 3585.4980302757635, 3589.425691889311, 5319.9734001330135, 5345.278387417688, 5350.866228344284, 5355.732213389354, 5360.72169328034, 3521.4189658706537, 3666.5233519642275, 3686.9815650921573, 3730.7388482806487, 3732.5147730794824, 3715.628437156263, 3721.4789929409903, 3733.775973441602, 3740.869069582606, 3751.756135851161, 4233.809478573472, 4236.364545318165, 4242.893927651833, 4242.957570424318, 4265.232258193493, 4837.758112094381, 5059.595232381401, 5090.77091530882, 5101.0, 5113.565346945476, 1272.6054922973908, 1272.821804947308, 1283.5250957145915, 1283.722695917568, 1285.9099863009687, 814.1736137820118, 816.506013861617, 823.1439303124803, 831.3266254334009, 841.5581819544666, 4306.397104405386, 4477.865664030084, 4479.61923236522, 4488.147252022105, 4491.910161796745, 5216.504432078947, 5245.609913372959, 5249.370075590953, 5264.710440925709, 5285.9735701322015, 4501.3022981437925, 4506.842260520866, 4512.887177820581, 4515.155452065178, 4518.728876267369, 2651.9337016574354, 2750.931226719308, 2791.6510436195476, 2818.732220439055, 2823.901163459269, 4859.545929305678, 4941.678790878654, 4952.801887924443, 4984.675996060245, 4992.825251116194, 5193.740312984336, 5288.576913846883, 5361.544268737123, 5385.407605163429, 5397.0, 4935.679180853162, 4941.901161976741, 4952.356193694796, 4976.925346119811, 4980.153373926441, 5253.9211911820585, 5256.553192978487, 5260.158374660036, 5263.157894736823, 5274.868128296824, 4910.9017819642695, 5054.69671819692, 5203.505666961632, 5222.086134926449, 5242.18746094356, 4986.551210391082, 4994.825181118644, 5009.749512524432, 5011.5489605935645, 5030.6730062547285, 3667.339878821838, 3674.5774235962135, 3679.963200367962, 3680.803738784852, 3712.554493460802, 5059.46875578059, 5175.611829112756, 5267.78928842842, 5276.947230527646, 5277.20841873722, 5037.269595908646, 5038.798448062036, 5048.368953880765, 5055.216441451581, 5070.552024623292, 5044.571211447068, 5053.534475002208, 5060.569851562658, 5064.974675126601, 5103.21387514367, 4740.905181896412, 4988.4762099980435, 4989.176785830443, 5012.1980483122525, 5024.547790698854, 4865.367502224688, 5008.649394542493, 5098.934931352808, 5134.332536770196, 5141.794328226829, 5034.672746271481, 5039.571636410946, 5040.361882388165, 5066.201498468138, 5071.239314102914], "value": 3820.42217110284, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.4198430185211, 817.9959100204499, 819.978752375395, 829.6556928874518, 832.4838600067959, 11881.168318217728, 12161.817572736416, 12326.691832704188, 12327.568535101273, 12329.445174967115, 11114.5554177833, 11130.276532025413, 11135.888641113606, 11353.034992025667, 11522.769544609124, 678.8778019956405, 853.7012045783957, 862.7541150772015, 871.856143736286, 877.6313948141764, 876.0626130040858, 884.8628462588309, 885.3713863157166, 885.4908427654093, 886.7073865624361, 921.0191146429065, 924.306769922558, 925.0887875442684, 925.1211349218236, 925.7176561148881, 710.0733542726714, 874.7987962768599, 898.7034278688018, 920.8848893888264, 921.3964853021248, 669.2604671837598, 669.7990602819135, 669.8392385827415, 670.276101810044, 670.4234358451728, 11464.369459679798, 11469.885301146966, 11476.311421314664, 11506.424678766112, 11511.424428778528, 11034.944825275903, 11713.180077394585, 12091.516339346415, 12101.0, 12647.304398258093, 10849.186311026704, 10877.292975956616, 10938.945305273503, 11000.724981875437, 11021.0, 4108.876733697994, 4158.397032430281, 4193.0, 4193.182329445759, 4230.386593943861, 44354.55645443536, 44518.55481445177, 44823.103537929055, 45657.08685826298, 45689.31466028012, 8634.222919937236, 8652.3510736695, 8674.86987695185, 8677.913220867837, 8742.38803283764, 16137.596560085856, 16216.0, 16243.431479898147, 16273.67452650964, 17772.0, 6543.770968016097, 6662.6668666566475, 6722.428593569503, 6768.76309329181, 6850.0, 3481.7562770606323, 3629.4374371972385, 3646.6171052039685, 3659.7621154624862, 3689.741718079709, 2704.4996675615075, 2784.2065011472037, 2793.25978615667, 2799.790015748827, 2810.9156725297867, 480.03752476284825, 503.55183886341183, 505.3708133373973, 513.5839969624616, 513.9100657384943, 494.82433736023916, 500.51450093409335, 672.8486090629569, 689.8137502874196, 704.3379223529865, 7560.697572097164, 7992.200779921961, 8014.358851291882, 8068.354531637455, 8102.837943241102, 617.7498113264136, 619.1981384107592, 631.192074145095, 644.2172760096505, 654.2443477783168, 3662.0, 3747.9437808432895, 3845.9038524037123, 3893.260280546687, 3919.941200881989, 60130.098048529304, 60160.0, 60590.0, 60908.39091609116, 60951.69524152351, 55374.72312638411, 55793.44206557883, 55920.80593955388, 56973.715131424906, 57366.852662946505, 42065.15869682589, 42617.360739588934, 43529.56470435255, 50904.49095509072, 51763.223551646755, 7722.768316950501, 7783.338416234457, 7807.594632966121, 7982.600869956363, 8043.758687239392, 28394.432111357244, 28435.857820711593, 30519.237019074702, 30862.228444289074, 31252.0, 38970.61029389783, 39415.80292098521, 39449.2110157801, 40544.391834123104, 40752.59247407607, 10677.46612669379, 10719.088877445192, 10822.458877056273, 10909.781804363709, 11308.943455282671, 11939.403029848647, 12041.33772642513, 12160.635180944586, 12265.448054837376, 12687.619371418872, 7578.545287282781, 9688.951555242178, 9902.306838521523, 9922.206223502248, 9931.900680993387, 9411.247100232102, 9655.0, 9716.9028309716, 9847.409155450696, 9966.202703783825, 9041.773955650899, 9150.450972941644, 9286.814263714552, 9731.221502279943, 9796.118349348593, 7904.407169462428, 7930.563818990011, 7939.166387529457, 8016.6392512335915, 8313.833723325379, 8018.4787988780545, 8037.196280371916, 8048.315893149148, 8304.294134998592, 8378.120297368689], "value": 5955.095161470428, "unit": "score"}], "name": "dromaeo_css", "value": 6374.930463277328, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 114.0, 114.0, 112.0, 112.0, 113.0, 114.0, 113.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 129.0, 172.0, 220.0, 141.0, 159.0, 171.0, 129.0, 197.0, 129.0], "value": 160.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 163.0, 161.0, 161.0, 161.0, 162.0, 161.0, 160.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 147.0, 76.0, 75.0, 76.0, 75.0, 126.0, 74.0], "value": 87.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 153.0, 93.0, 92.0, 93.0, 124.0, 92.0, 92.0, 120.0], "value": 104.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 64.0, 65.0, 65.0, 117.0, 62.0, 62.0], "value": 69.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0, 65.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 82.0, 82.0, 81.0, 82.0, 83.0, 83.0, 84.0, 83.0, 84.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 128.0, 141.0, 137.0, 128.0, 132.0, 132.0, 132.0, 196.0, 147.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 169.0, 167.0, 165.0, 176.0, 168.0, 166.0, 168.0, 167.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 68.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1576.3, "alertThreshold": 2.0}]} 04:45:28 INFO - Return code: 0 04:45:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:45:28 ERROR - # TBPL SUCCESS # 04:45:28 INFO - Running post-action listener: _resource_record_post_action 04:45:28 INFO - ##### 04:45:28 INFO - ##### Finished run-tests step (success) 04:45:28 INFO - ##### 04:45:28 INFO - Running post-run listener: _resource_record_post_run 04:45:28 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 7946240; Write bytes: 467484672; Read time: 1608; Write time: 368752 04:45:28 INFO - TinderboxPrint: CPU usage
13.1% 04:45:28 INFO - TinderboxPrint: I/O read bytes / time
7,946,240 / 1,608 04:45:28 INFO - TinderboxPrint: I/O write bytes / time
467,484,672 / 368,752 04:45:28 INFO - TinderboxPrint: CPU idle
8,352.2 (86.9%) 04:45:28 INFO - TinderboxPrint: CPU user
1,212.8 (12.6%) 04:45:28 INFO - TinderboxPrint: Swap in / out
0 / 0 04:45:28 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 111112192; Read time: 0; Write time: 83348 04:45:28 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 6053888; Write bytes: 356372480; Read time: 1436; Write time: 285404 04:45:29 INFO - Running post-run listener: _upload_blobber_files 04:45:29 INFO - Blob upload gear active. 04:45:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:45:29 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 04:45:29 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:45:29 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:45:29 INFO - (blobuploader) - INFO - Open directory for files ... 04:45:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:45:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:45:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:33 INFO - (blobuploader) - INFO - Done attempting. 04:45:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:45:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:45:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:34 INFO - (blobuploader) - INFO - Done attempting. 04:45:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:45:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:45:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:35 INFO - (blobuploader) - INFO - Done attempting. 04:45:35 INFO - (blobuploader) - INFO - Iteration through files over. 04:45:35 INFO - Return code: 0 04:45:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:45:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:45:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7566cfcfa6f366e50b5577689db0921cb673b2aeb303559bb0a799189cd1688d350a22a7ebc65264ec911462b8549029920eb1e8b62868a6df2387fe476a10dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d49a6c2c9898e584218f229cb073d9c1a16a179bc658497e67e11a2122feba2590f7d484b7cca19f576d427be56fc5270c84b2bf8d5c944a153a0efc53363c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c48f5c1e98ccfd54e5760a8b7198f221fcd475e00bc7685f5fa7a2afcb5615f2da61ca840cca2fc7e60ee6ae32cded374b8bdf526b7134d92db2d4ff7c742b9"} 04:45:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:45:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:45:35 INFO - Contents: 04:45:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7566cfcfa6f366e50b5577689db0921cb673b2aeb303559bb0a799189cd1688d350a22a7ebc65264ec911462b8549029920eb1e8b62868a6df2387fe476a10dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d49a6c2c9898e584218f229cb073d9c1a16a179bc658497e67e11a2122feba2590f7d484b7cca19f576d427be56fc5270c84b2bf8d5c944a153a0efc53363c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c48f5c1e98ccfd54e5760a8b7198f221fcd475e00bc7685f5fa7a2afcb5615f2da61ca840cca2fc7e60ee6ae32cded374b8bdf526b7134d92db2d4ff7c742b9"} 04:45:35 INFO - Running post-run listener: copy_logs_to_upload_dir 04:45:35 INFO - Copying logs to upload dir... 04:45:35 INFO - mkdir: /builds/slave/test/build/upload/logs 04:45:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.316836 ========= master_lag: 0.46 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-13 04:45:35.728827) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 04:45:35.732106) ========= 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=523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7566cfcfa6f366e50b5577689db0921cb673b2aeb303559bb0a799189cd1688d350a22a7ebc65264ec911462b8549029920eb1e8b62868a6df2387fe476a10dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d49a6c2c9898e584218f229cb073d9c1a16a179bc658497e67e11a2122feba2590f7d484b7cca19f576d427be56fc5270c84b2bf8d5c944a153a0efc53363c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c48f5c1e98ccfd54e5760a8b7198f221fcd475e00bc7685f5fa7a2afcb5615f2da61ca840cca2fc7e60ee6ae32cded374b8bdf526b7134d92db2d4ff7c742b9"} build_url:https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009622 build_url: 'https://queue.taskcluster.net/v1/task/fyj2x_uITkyYZceU9Ejz6w/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/Firefox-Non-PGO/sha512/7566cfcfa6f366e50b5577689db0921cb673b2aeb303559bb0a799189cd1688d350a22a7ebc65264ec911462b8549029920eb1e8b62868a6df2387fe476a10dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d49a6c2c9898e584218f229cb073d9c1a16a179bc658497e67e11a2122feba2590f7d484b7cca19f576d427be56fc5270c84b2bf8d5c944a153a0efc53363c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c48f5c1e98ccfd54e5760a8b7198f221fcd475e00bc7685f5fa7a2afcb5615f2da61ca840cca2fc7e60ee6ae32cded374b8bdf526b7134d92db2d4ff7c742b9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 04:45:35.757121) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:45:35.757443) ========= 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=523ca41ca1e0623de333ad52000001bf-1463138238.411554-215720717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:45:35.808021) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 04:45:35.808327) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 04:45:35.808622) ========= ========= Total master_lag: 0.60 =========