builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-054 starttime: 1463491751.43 results: success (0) buildid: 20160517060029 builduid: 53e8e5df137a4867b038295ee7ff428c revision: c480fcee4fc0af1e22f08124937cc7602ed16032 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:11.426621) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:11.427073) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:11.427348) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:11.442897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:11.443178) ========= 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=cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 06:29:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-05-17 06:29:12 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084023 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:11.540519) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:11.540830) ========= 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=cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021450 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:11.590053) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 06:29:11.590392) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c480fcee4fc0af1e22f08124937cc7602ed16032 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c480fcee4fc0af1e22f08124937cc7602ed16032 --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=cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 06:29:12,434 truncating revision to first 12 chars 2016-05-17 06:29:12,434 Setting DEBUG logging. 2016-05-17 06:29:12,434 attempt 1/10 2016-05-17 06:29:12,434 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c480fcee4fc0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 06:29:13,465 unpacking tar archive at: fx-team-c480fcee4fc0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.349962 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 06:29:12.952911) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:12.953232) ========= script_repo_revision: c480fcee4fc0af1e22f08124937cc7602ed16032 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:12.953621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:12.953884) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 06:29:12.963205) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-17 06:29:12.963482) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:29:13 INFO - MultiFileLogger online at 20160517 06:29:13 in /builds/slave/test 06:29:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 06:29:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:29:13 INFO - {'append_to_log': False, 06:29:13 INFO - 'base_work_dir': '/builds/slave/test', 06:29:13 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 06:29:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:29:13 INFO - 'branch': 'Fx-Team-Non-PGO', 06:29:13 INFO - 'buildbot_json_path': 'buildprops.json', 06:29:13 INFO - 'config_files': ('talos/linux_config.py',), 06:29:13 INFO - 'default_actions': ('clobber', 06:29:13 INFO - 'read-buildbot-config', 06:29:13 INFO - 'download-and-extract', 06:29:13 INFO - 'populate-webroot', 06:29:13 INFO - 'create-virtualenv', 06:29:13 INFO - 'install', 06:29:13 INFO - 'run-tests'), 06:29:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:29:13 INFO - 'download_minidump_stackwalk': True, 06:29:13 INFO - 'download_symbols': 'ondemand', 06:29:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:29:13 INFO - 'tooltool.py': '/tools/tooltool.py', 06:29:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:29:13 INFO - '/tools/misc-python/virtualenv.py')}, 06:29:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:29:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:29:13 INFO - 'installer_path': 'installer.exe', 06:29:13 INFO - 'log_level': 'info', 06:29:13 INFO - 'log_name': 'talos', 06:29:13 INFO - 'log_to_console': True, 06:29:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:29:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:29:13 INFO - 'opt_config_files': (), 06:29:13 INFO - 'pip_index': False, 06:29:13 INFO - 'sps_profile': False, 06:29:13 INFO - 'sps_profile_interval': 0, 06:29:13 INFO - 'suite': 'dromaeojs', 06:29:13 INFO - 'system_bits': '32', 06:29:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:29:13 INFO - 'title': 'talos-linux64-ix-054', 06:29:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:29:13 INFO - 'use_talos_json': True, 06:29:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:29:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:29:13 INFO - 'work_dir': 'build'} 06:29:13 INFO - ##### 06:29:13 INFO - ##### Running clobber step. 06:29:13 INFO - ##### 06:29:13 INFO - Running pre-action listener: _resource_record_pre_action 06:29:13 INFO - Running main action method: clobber 06:29:13 INFO - rmtree: /builds/slave/test/build 06:29:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:29:14 INFO - Running post-action listener: _resource_record_post_action 06:29:14 INFO - ##### 06:29:14 INFO - ##### Finished clobber step (success) 06:29:14 INFO - ##### 06:29:14 INFO - ##### 06:29:14 INFO - ##### Running read-buildbot-config step. 06:29:14 INFO - ##### 06:29:14 INFO - Running pre-action listener: _resource_record_pre_action 06:29:14 INFO - Running main action method: read_buildbot_config 06:29:14 INFO - Using buildbot properties: 06:29:14 INFO - { 06:29:14 INFO - "project": "", 06:29:14 INFO - "product": "firefox", 06:29:14 INFO - "script_repo_revision": "production", 06:29:14 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 06:29:14 INFO - "repository": "", 06:29:14 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 06:29:14 INFO - "buildid": "20160517060029", 06:29:14 INFO - "pgo_build": "False", 06:29:14 INFO - "basedir": "/builds/slave/test", 06:29:14 INFO - "buildnumber": 1690, 06:29:14 INFO - "slavename": "talos-linux64-ix-054", 06:29:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:29:14 INFO - "platform": "ubuntu64_hw", 06:29:14 INFO - "scheduler": "tests-fx-team-linux64-talos", 06:29:14 INFO - "branch": "fx-team", 06:29:14 INFO - "repo_path": "integration/fx-team", 06:29:14 INFO - "revision": "c480fcee4fc0af1e22f08124937cc7602ed16032", 06:29:14 INFO - "stage_platform": "linux64", 06:29:14 INFO - "builduid": "53e8e5df137a4867b038295ee7ff428c", 06:29:14 INFO - "slavebuilddir": "test" 06:29:14 INFO - } 06:29:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 06:29:14 INFO - Running post-action listener: _resource_record_post_action 06:29:14 INFO - ##### 06:29:14 INFO - ##### Finished read-buildbot-config step (success) 06:29:14 INFO - ##### 06:29:14 INFO - ##### 06:29:14 INFO - ##### Running download-and-extract step. 06:29:14 INFO - ##### 06:29:14 INFO - Running pre-action listener: _resource_record_pre_action 06:29:14 INFO - Running main action method: download_and_extract 06:29:14 INFO - mkdir: /builds/slave/test/build/tests 06:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:14 INFO - https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:29:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 06:29:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 06:29:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:15 INFO - Downloaded 1361 bytes. 06:29:15 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 06:29:15 INFO - Using the following test package requirements: 06:29:15 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 06:29:15 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:29:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:29:15 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:29:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 06:29:15 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:29:15 INFO - u'jsshell-linux-x86_64.zip'], 06:29:15 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:29:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:29:15 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 06:29:15 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:29:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:29:15 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:29:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:29:15 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:29:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:29:15 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 06:29:15 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:29:15 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:15 INFO - https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:29:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 06:29:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 06:29:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:16 INFO - Downloaded 22448136 bytes. 06:29:16 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'] 06:29:16 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 06:29:17 INFO - Return code: 0 06:29:17 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 06:29: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')]} 06:29:17 INFO - https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:29:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 06:29:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 06:29:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:18 INFO - Downloaded 22448136 bytes. 06:29:18 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'] 06:29:18 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 06:29:19 INFO - Return code: 0 06:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:19 INFO - https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:29:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 06:29:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 06:29:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:21 INFO - Downloaded 10840194 bytes. 06:29:21 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'] 06:29:21 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 06:29:21 INFO - Return code: 0 06:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:21 INFO - https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:29:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 06:29:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 06:29:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rj5daoaaSpayzW5KoldWqQ/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 06:29:24 INFO - Downloaded 54763063 bytes. 06:29:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 06:29:24 INFO - mkdir: /builds/slave/test/properties 06:29:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:29:24 INFO - Writing to file /builds/slave/test/properties/build_url 06:29:24 INFO - Contents: 06:29:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 06:29:25 INFO - Running post-action listener: _resource_record_post_action 06:29:25 INFO - Running post-action listener: set_extra_try_arguments 06:29:25 INFO - ##### 06:29:25 INFO - ##### Finished download-and-extract step (success) 06:29:25 INFO - ##### 06:29:25 INFO - ##### 06:29:25 INFO - ##### Running populate-webroot step. 06:29:25 INFO - ##### 06:29:25 INFO - Running pre-action listener: _resource_record_pre_action 06:29:25 INFO - Running main action method: populate_webroot 06:29:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:29:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:29:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:29:25 INFO - '--tppagecycles', 06:29:25 INFO - '7'], 06:29:25 INFO - 'tests': ['tsvgm']}}, 06:29:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:29:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:29:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:29:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:29:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:29:25 INFO - 'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:29:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:29:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:29:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:29:25 INFO - 'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['damp', 'tps']}, 06:29:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:29:25 INFO - 'tests': ['damp', 'tps']}, 06:29:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['dromaeo_dom']}, 06:29:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:29:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['a11yr', 06:29:25 INFO - 'ts_paint', 06:29:25 INFO - 'tpaint', 06:29:25 INFO - 'sessionrestore', 06:29:25 INFO - 'sessionrestore_no_auto_restore', 06:29:25 INFO - 'tabpaint']}, 06:29:25 INFO - 'other-e10s': {'tests': ['a11yr', 06:29:25 INFO - 'ts_paint', 06:29:25 INFO - 'tpaint', 06:29:25 INFO - 'sessionrestore', 06:29:25 INFO - 'sessionrestore_no_auto_restore', 06:29:25 INFO - 'tabpaint']}, 06:29:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:29:25 INFO - 'ts_paint', 06:29:25 INFO - 'tpaint', 06:29:25 INFO - 'sessionrestore', 06:29:25 INFO - 'sessionrestore_no_auto_restore', 06:29:25 INFO - 'tabpaint']}, 06:29:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:29:25 INFO - 'ts_paint', 06:29:25 INFO - 'tpaint', 06:29:25 INFO - 'sessionrestore', 06:29:25 INFO - 'sessionrestore_no_auto_restore', 06:29:25 INFO - 'tabpaint']}, 06:29:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['a11yr', 06:29:25 INFO - 'ts_paint', 06:29:25 INFO - 'tpaint', 06:29:25 INFO - 'sessionrestore', 06:29:25 INFO - 'sessionrestore_no_auto_restore', 06:29:25 INFO - 'tabpaint']}, 06:29:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['a11yr', 06:29:25 INFO - 'ts_paint', 06:29:25 INFO - 'tpaint', 06:29:25 INFO - 'sessionrestore', 06:29:25 INFO - 'sessionrestore_no_auto_restore', 06:29:25 INFO - 'tabpaint']}, 06:29:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['tsvgx', 06:29:25 INFO - 'tsvgr_opacity', 06:29:25 INFO - 'tart', 06:29:25 INFO - 'tscrollx', 06:29:25 INFO - 'cart']}, 06:29:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:29:25 INFO - 'tsvgr_opacity', 06:29:25 INFO - 'tart', 06:29:25 INFO - 'tscrollx', 06:29:25 INFO - 'cart']}, 06:29:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:29:25 INFO - 'talos_options': ['--disable-e10s'], 06:29:25 INFO - 'tests': ['tp5o']}, 06:29:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:29:25 INFO - 'tests': ['tp5o']}, 06:29:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:29:25 INFO - 'talos_options': ['--disable-e10s', 06:29:25 INFO - '--xperf_path', 06:29:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:29:25 INFO - 'tests': ['tp5n']}, 06:29:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:29:25 INFO - 'talos_options': ['--xperf_path', 06:29:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:29:25 INFO - 'tests': ['tp5n']}}, 06:29:25 INFO - 'talos.zip': {'path': '', 06:29:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:29:25 INFO - Running post-action listener: _resource_record_post_action 06:29:25 INFO - ##### 06:29:25 INFO - ##### Finished populate-webroot step (success) 06:29:25 INFO - ##### 06:29:25 INFO - ##### 06:29:25 INFO - ##### Running create-virtualenv step. 06:29:25 INFO - ##### 06:29:25 INFO - Running pre-action listener: _resource_record_pre_action 06:29:25 INFO - Running main action method: create_virtualenv 06:29:25 INFO - Creating virtualenv /builds/slave/test/build/venv 06:29:25 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 06:29:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:29:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:29:26 INFO - Using real prefix '/usr' 06:29:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:29:26 INFO - Installing distribute.............................................................................................................................................................................................done. 06:29:28 INFO - Installing pip.................done. 06:29:28 INFO - Return code: 0 06:29:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:28 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 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:28 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 06:29:28 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 06:29:28 INFO - Using env: {'DISPLAY': ':0', 06:29:28 INFO - 'HOME': '/home/cltbld', 06:29:28 INFO - 'LANG': 'en_US.UTF-8', 06:29:28 INFO - 'LANGUAGE': 'en_US:en', 06:29:28 INFO - 'LOGNAME': 'cltbld', 06:29:28 INFO - 'MAIL': '/var/mail/cltbld', 06:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:28 INFO - 'MOZ_NO_REMOTE': '1', 06:29:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:28 INFO - 'NO_EM_RESTART': '1', 06:29:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:28 INFO - 'PWD': '/builds/slave/test', 06:29:28 INFO - 'SHELL': '/bin/bash', 06:29:28 INFO - 'SHLVL': '1', 06:29:28 INFO - 'TERM': 'linux', 06:29:28 INFO - 'TMOUT': '86400', 06:29:28 INFO - 'USER': 'cltbld', 06:29:28 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:28 INFO - '_': '/tools/buildbot/bin/python'} 06:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:28 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 06:29:28 INFO - Cleaning up... 06:29:28 INFO - Return code: 0 06:29:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:28 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 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:28 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 06:29:28 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 06:29:28 INFO - Using env: {'DISPLAY': ':0', 06:29:28 INFO - 'HOME': '/home/cltbld', 06:29:28 INFO - 'LANG': 'en_US.UTF-8', 06:29:28 INFO - 'LANGUAGE': 'en_US:en', 06:29:28 INFO - 'LOGNAME': 'cltbld', 06:29:28 INFO - 'MAIL': '/var/mail/cltbld', 06:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:28 INFO - 'MOZ_NO_REMOTE': '1', 06:29:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:28 INFO - 'NO_EM_RESTART': '1', 06:29:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:28 INFO - 'PWD': '/builds/slave/test', 06:29:28 INFO - 'SHELL': '/bin/bash', 06:29:28 INFO - 'SHLVL': '1', 06:29:28 INFO - 'TERM': 'linux', 06:29:28 INFO - 'TMOUT': '86400', 06:29:28 INFO - 'USER': 'cltbld', 06:29:28 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:28 INFO - '_': '/tools/buildbot/bin/python'} 06:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:28 INFO - Downloading/unpacking psutil>=3.1.1 06:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:29:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:29:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:29:30 INFO - Installing collected packages: psutil 06:29:30 INFO - Running setup.py install for psutil 06:29:31 INFO - building 'psutil._psutil_linux' extension 06:29:31 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 06:29:31 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 06:29:31 INFO - building 'psutil._psutil_posix' extension 06:29:31 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 06:29:31 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 06:29:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:29:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:29:31 INFO - Successfully installed psutil 06:29:31 INFO - Cleaning up... 06:29:31 INFO - Return code: 0 06:29:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:29:31 INFO - Using env: {'DISPLAY': ':0', 06:29:31 INFO - 'HOME': '/home/cltbld', 06:29:31 INFO - 'LANG': 'en_US.UTF-8', 06:29:31 INFO - 'LANGUAGE': 'en_US:en', 06:29:31 INFO - 'LOGNAME': 'cltbld', 06:29:31 INFO - 'MAIL': '/var/mail/cltbld', 06:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:31 INFO - 'MOZ_NO_REMOTE': '1', 06:29:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:31 INFO - 'NO_EM_RESTART': '1', 06:29:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:31 INFO - 'PWD': '/builds/slave/test', 06:29:31 INFO - 'SHELL': '/bin/bash', 06:29:31 INFO - 'SHLVL': '1', 06:29:31 INFO - 'TERM': 'linux', 06:29:31 INFO - 'TMOUT': '86400', 06:29:31 INFO - 'USER': 'cltbld', 06:29:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:31 INFO - '_': '/tools/buildbot/bin/python'} 06:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:29:31 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 06:29:31 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 06:29:31 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 06:29:31 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 06:29:31 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 06:29:31 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 06:29:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:29:33 INFO - Installing collected packages: mozsystemmonitor 06:29:33 INFO - Running setup.py install for mozsystemmonitor 06:29:33 INFO - Successfully installed mozsystemmonitor 06:29:33 INFO - Cleaning up... 06:29:33 INFO - Return code: 0 06:29:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:33 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 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:33 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 06:29:33 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 06:29:33 INFO - Using env: {'DISPLAY': ':0', 06:29:33 INFO - 'HOME': '/home/cltbld', 06:29:33 INFO - 'LANG': 'en_US.UTF-8', 06:29:33 INFO - 'LANGUAGE': 'en_US:en', 06:29:33 INFO - 'LOGNAME': 'cltbld', 06:29:33 INFO - 'MAIL': '/var/mail/cltbld', 06:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:33 INFO - 'MOZ_NO_REMOTE': '1', 06:29:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:33 INFO - 'NO_EM_RESTART': '1', 06:29:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:33 INFO - 'PWD': '/builds/slave/test', 06:29:33 INFO - 'SHELL': '/bin/bash', 06:29:33 INFO - 'SHLVL': '1', 06:29:33 INFO - 'TERM': 'linux', 06:29:33 INFO - 'TMOUT': '86400', 06:29:33 INFO - 'USER': 'cltbld', 06:29:33 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:33 INFO - '_': '/tools/buildbot/bin/python'} 06:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:34 INFO - Downloading/unpacking blobuploader==1.2.4 06:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:35 INFO - Downloading blobuploader-1.2.4.tar.gz 06:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:29:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:29:36 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 06:29:36 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 06:29:36 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 06:29:36 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 06:29:36 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 06:29:36 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 06:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:29:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:29:36 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 06:29:36 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 06:29:36 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 06:29:36 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 06:29:36 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 06:29:36 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 06:29:36 INFO - Downloading docopt-0.6.1.tar.gz 06:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:29:36 INFO - Installing collected packages: blobuploader, requests, docopt 06:29:36 INFO - Running setup.py install for blobuploader 06:29:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:29:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:29:36 INFO - Running setup.py install for requests 06:29:37 INFO - Running setup.py install for docopt 06:29:37 INFO - Successfully installed blobuploader requests docopt 06:29:37 INFO - Cleaning up... 06:29:37 INFO - Return code: 0 06:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:37 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 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:37 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 06:29:37 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 06:29:37 INFO - Using env: {'DISPLAY': ':0', 06:29:37 INFO - 'HOME': '/home/cltbld', 06:29:37 INFO - 'LANG': 'en_US.UTF-8', 06:29:37 INFO - 'LANGUAGE': 'en_US:en', 06:29:37 INFO - 'LOGNAME': 'cltbld', 06:29:37 INFO - 'MAIL': '/var/mail/cltbld', 06:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:37 INFO - 'MOZ_NO_REMOTE': '1', 06:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:37 INFO - 'NO_EM_RESTART': '1', 06:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:37 INFO - 'PWD': '/builds/slave/test', 06:29:37 INFO - 'SHELL': '/bin/bash', 06:29:37 INFO - 'SHLVL': '1', 06:29:37 INFO - 'TERM': 'linux', 06:29:37 INFO - 'TMOUT': '86400', 06:29:37 INFO - 'USER': 'cltbld', 06:29:37 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:37 INFO - '_': '/tools/buildbot/bin/python'} 06:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:29:37 INFO - Running setup.py (path:/tmp/pip-68lE9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:29:37 INFO - Running setup.py (path:/tmp/pip-9jUJp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:29:37 INFO - Running setup.py (path:/tmp/pip-GdMnBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:29:37 INFO - Running setup.py (path:/tmp/pip-16oRN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:29:37 INFO - Running setup.py (path:/tmp/pip-s_gBNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:29:37 INFO - Running setup.py (path:/tmp/pip-wBeTND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:29:37 INFO - Running setup.py (path:/tmp/pip-RbZMET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:29:38 INFO - Running setup.py (path:/tmp/pip-f1ldoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:29:38 INFO - Running setup.py (path:/tmp/pip-KGezKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:29:38 INFO - Running setup.py (path:/tmp/pip-DX4Se2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:29:38 INFO - Running setup.py (path:/tmp/pip-5ReJtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:29:38 INFO - Running setup.py (path:/tmp/pip-NejSfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:29:38 INFO - Running setup.py (path:/tmp/pip-swR7H9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:29:38 INFO - Running setup.py (path:/tmp/pip-IcK8bz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:29:38 INFO - Running setup.py (path:/tmp/pip-2GP5L_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:29:38 INFO - Running setup.py (path:/tmp/pip-fOLp4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:29:38 INFO - Running setup.py (path:/tmp/pip-9Obs_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:29:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:29:38 INFO - Running setup.py install for manifestparser 06:29:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:29:38 INFO - Running setup.py install for mozcrash 06:29:38 INFO - Running setup.py install for mozdebug 06:29:39 INFO - Running setup.py install for mozdevice 06:29:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Running setup.py install for mozfile 06:29:39 INFO - Running setup.py install for mozhttpd 06:29:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Running setup.py install for mozinfo 06:29:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Running setup.py install for mozInstall 06:29:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Running setup.py install for mozleak 06:29:39 INFO - Running setup.py install for mozlog 06:29:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Running setup.py install for moznetwork 06:29:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:29:39 INFO - Running setup.py install for mozprocess 06:29:39 INFO - Running setup.py install for mozprofile 06:29:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Running setup.py install for mozrunner 06:29:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Running setup.py install for mozscreenshot 06:29:40 INFO - Running setup.py install for moztest 06:29:40 INFO - Running setup.py install for mozversion 06:29:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:29:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:29:40 INFO - Cleaning up... 06:29:40 INFO - Return code: 0 06:29:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:29:40 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')]} 06:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:40 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')]} 06:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:40 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 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:40 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 06:29:40 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 06:29:40 INFO - Using env: {'DISPLAY': ':0', 06:29:40 INFO - 'HOME': '/home/cltbld', 06:29:40 INFO - 'LANG': 'en_US.UTF-8', 06:29:40 INFO - 'LANGUAGE': 'en_US:en', 06:29:40 INFO - 'LOGNAME': 'cltbld', 06:29:40 INFO - 'MAIL': '/var/mail/cltbld', 06:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:40 INFO - 'MOZ_NO_REMOTE': '1', 06:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:40 INFO - 'NO_EM_RESTART': '1', 06:29:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:40 INFO - 'PWD': '/builds/slave/test', 06:29:40 INFO - 'SHELL': '/bin/bash', 06:29:40 INFO - 'SHLVL': '1', 06:29:40 INFO - 'TERM': 'linux', 06:29:40 INFO - 'TMOUT': '86400', 06:29:40 INFO - 'USER': 'cltbld', 06:29:40 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:40 INFO - '_': '/tools/buildbot/bin/python'} 06:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:29:40 INFO - Running setup.py (path:/tmp/pip-M2r1XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:29:40 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)) 06:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:29:40 INFO - Running setup.py (path:/tmp/pip-vjlu2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:29:40 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)) 06:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:29:40 INFO - Running setup.py (path:/tmp/pip-bRVV1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:29:40 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)) 06:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:29:40 INFO - Running setup.py (path:/tmp/pip-usM5gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:29:41 INFO - Running setup.py (path:/tmp/pip-kcBYFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:29:41 INFO - Running setup.py (path:/tmp/pip-BfbrGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:29:41 INFO - Running setup.py (path:/tmp/pip-8XIMXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:29:41 INFO - Running setup.py (path:/tmp/pip-jjiNsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:29:41 INFO - Running setup.py (path:/tmp/pip-cdcpUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:29:41 INFO - Running setup.py (path:/tmp/pip-YpW6Uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:29:41 INFO - Running setup.py (path:/tmp/pip-Ws234Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:29:41 INFO - Running setup.py (path:/tmp/pip-rmddPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:29:41 INFO - Running setup.py (path:/tmp/pip-w3ZffD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:29:41 INFO - Running setup.py (path:/tmp/pip-ZjREX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:29:41 INFO - Running setup.py (path:/tmp/pip-3q47r3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:29:41 INFO - Running setup.py (path:/tmp/pip-PLOo_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:29:41 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)) 06:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:29:41 INFO - Running setup.py (path:/tmp/pip-8IDKUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:29:41 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)) 06:29:41 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)) 06:29:41 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)) 06:29:41 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)) 06:29:41 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)) 06:29:41 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)) 06:29:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:43 INFO - Downloading blessings-1.6.tar.gz 06:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:29:43 INFO - Installing collected packages: blessings 06:29:43 INFO - Running setup.py install for blessings 06:29:44 INFO - Successfully installed blessings 06:29:44 INFO - Cleaning up... 06:29:44 INFO - Return code: 0 06:29:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:29:44 INFO - Reading from file tmpfile_stdout 06:29:44 INFO - Current package versions: 06:29:44 INFO - argparse == 1.2.1 06:29:44 INFO - blessings == 1.6 06:29:44 INFO - blobuploader == 1.2.4 06:29:44 INFO - docopt == 0.6.1 06:29:44 INFO - manifestparser == 1.1 06:29:44 INFO - mozInstall == 1.12 06:29:44 INFO - mozcrash == 0.17 06:29:44 INFO - mozdebug == 0.1 06:29:44 INFO - mozdevice == 0.48 06:29:44 INFO - mozfile == 1.2 06:29:44 INFO - mozhttpd == 0.7 06:29:44 INFO - mozinfo == 0.9 06:29:44 INFO - mozleak == 0.1 06:29:44 INFO - mozlog == 3.2 06:29:44 INFO - moznetwork == 0.27 06:29:44 INFO - mozprocess == 0.22 06:29:44 INFO - mozprofile == 0.28 06:29:44 INFO - mozrunner == 6.11 06:29:44 INFO - mozscreenshot == 0.1 06:29:44 INFO - mozsystemmonitor == 0.3 06:29:44 INFO - moztest == 0.7 06:29:44 INFO - mozversion == 1.4 06:29:44 INFO - psutil == 3.1.1 06:29:44 INFO - requests == 1.2.3 06:29:44 INFO - wsgiref == 0.1.2 06:29:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:29: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')]} 06:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29: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')]} 06:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:44 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 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:44 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 06:29:44 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 06:29:44 INFO - Using env: {'DISPLAY': ':0', 06:29:44 INFO - 'HOME': '/home/cltbld', 06:29:44 INFO - 'LANG': 'en_US.UTF-8', 06:29:44 INFO - 'LANGUAGE': 'en_US:en', 06:29:44 INFO - 'LOGNAME': 'cltbld', 06:29:44 INFO - 'MAIL': '/var/mail/cltbld', 06:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:44 INFO - 'MOZ_NO_REMOTE': '1', 06:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:44 INFO - 'NO_EM_RESTART': '1', 06:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:44 INFO - 'PWD': '/builds/slave/test', 06:29:44 INFO - 'SHELL': '/bin/bash', 06:29:44 INFO - 'SHLVL': '1', 06:29:44 INFO - 'TERM': 'linux', 06:29:44 INFO - 'TMOUT': '86400', 06:29:44 INFO - 'USER': 'cltbld', 06:29:44 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:44 INFO - '_': '/tools/buildbot/bin/python'} 06:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 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)) 06:29:44 INFO - Cleaning up... 06:29:44 INFO - Return code: 0 06:29:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:29: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')]} 06:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29: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')]} 06:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:29:44 INFO - Using env: {'DISPLAY': ':0', 06:29:44 INFO - 'HOME': '/home/cltbld', 06:29:44 INFO - 'LANG': 'en_US.UTF-8', 06:29:44 INFO - 'LANGUAGE': 'en_US:en', 06:29:44 INFO - 'LOGNAME': 'cltbld', 06:29:44 INFO - 'MAIL': '/var/mail/cltbld', 06:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:44 INFO - 'MOZ_NO_REMOTE': '1', 06:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:44 INFO - 'NO_EM_RESTART': '1', 06:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:44 INFO - 'PWD': '/builds/slave/test', 06:29:44 INFO - 'SHELL': '/bin/bash', 06:29:44 INFO - 'SHLVL': '1', 06:29:44 INFO - 'TERM': 'linux', 06:29:44 INFO - 'TMOUT': '86400', 06:29:44 INFO - 'USER': 'cltbld', 06:29:44 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:44 INFO - '_': '/tools/buildbot/bin/python'} 06:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:44 INFO - Downloading/unpacking jsonschema 06:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:29:46 INFO - Downloading/unpacking functools32 (from jsonschema) 06:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:29:46 INFO - Downloading functools32-3.2.3-2.tar.gz 06:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:29:46 INFO - warning: no files found matching '*.txt' 06:29:46 INFO - no previously-included directories found matching 'build' 06:29:46 INFO - no previously-included directories found matching 'dist' 06:29:46 INFO - no previously-included directories found matching '.git*' 06:29:46 INFO - Installing collected packages: jsonschema, functools32 06:29:46 INFO - Running setup.py install for functools32 06:29:47 INFO - warning: no files found matching '*.txt' 06:29:47 INFO - no previously-included directories found matching 'build' 06:29:47 INFO - no previously-included directories found matching 'dist' 06:29:47 INFO - no previously-included directories found matching '.git*' 06:29:47 INFO - Successfully installed jsonschema functools32 06:29:47 INFO - Cleaning up... 06:29:47 INFO - Return code: 0 06:29:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:29: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')]} 06:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29: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')]} 06:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:29:47 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 0x7fe7d9039f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2341770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', '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 06:29:47 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 06:29:47 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 06:29:47 INFO - Using env: {'DISPLAY': ':0', 06:29:47 INFO - 'HOME': '/home/cltbld', 06:29:47 INFO - 'LANG': 'en_US.UTF-8', 06:29:47 INFO - 'LANGUAGE': 'en_US:en', 06:29:47 INFO - 'LOGNAME': 'cltbld', 06:29:47 INFO - 'MAIL': '/var/mail/cltbld', 06:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:47 INFO - 'MOZ_NO_REMOTE': '1', 06:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:29:47 INFO - 'NO_EM_RESTART': '1', 06:29:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:47 INFO - 'PWD': '/builds/slave/test', 06:29:47 INFO - 'SHELL': '/bin/bash', 06:29:47 INFO - 'SHLVL': '1', 06:29:47 INFO - 'TERM': 'linux', 06:29:47 INFO - 'TMOUT': '86400', 06:29:47 INFO - 'USER': 'cltbld', 06:29:47 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:47 INFO - '_': '/tools/buildbot/bin/python'} 06:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:29:47 INFO - Downloading/unpacking flake8 06:29:47 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 06:29:47 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 06:29:47 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 06:29:47 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 06:29:47 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 06:29:47 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 06:29:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:29:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:29:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 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 06:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:29:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:29:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:29:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:29:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:29:49 INFO - no previously-included directories found matching 'docs/_build' 06:29:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:29:50 INFO - Running setup.py install for pep8 06:29:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:29:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:29:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:29:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:29:50 INFO - no previously-included directories found matching 'docs/_build' 06:29:50 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:29:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:29:50 INFO - Cleaning up... 06:29:50 INFO - Return code: 0 06:29:50 INFO - Running post-action listener: _resource_record_post_action 06:29:50 INFO - Running post-action listener: _start_resource_monitoring 06:29:50 INFO - Starting resource monitoring. 06:29:50 INFO - ##### 06:29:50 INFO - ##### Finished create-virtualenv step (success) 06:29:50 INFO - ##### 06:29:50 INFO - ##### 06:29:50 INFO - ##### Running install step. 06:29:50 INFO - ##### 06:29:50 INFO - Running pre-action listener: _resource_record_pre_action 06:29:50 INFO - Running main action method: install 06:29:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:29:50 INFO - Reading from file tmpfile_stdout 06:29:50 INFO - Detecting whether we're running mozinstall >=1.0... 06:29:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:29:50 INFO - Reading from file tmpfile_stdout 06:29:50 INFO - Output received: 06:29:50 INFO - Usage: mozinstall [options] installer 06:29:50 INFO - Options: 06:29:50 INFO - -h, --help show this help message and exit 06:29:50 INFO - -d DEST, --destination=DEST 06:29:50 INFO - Directory to install application into. [default: 06:29:50 INFO - "/builds/slave/test"] 06:29:50 INFO - --app=APP Application being installed. [default: firefox] 06:29:50 INFO - mkdir: /builds/slave/test/build/application 06:29:50 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'] 06:29:50 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 06:30:06 INFO - Reading from file tmpfile_stdout 06:30:06 INFO - Output received: 06:30:06 INFO - /builds/slave/test/build/application/firefox/firefox 06:30:06 INFO - Running post-action listener: _resource_record_post_action 06:30:06 INFO - ##### 06:30:06 INFO - ##### Finished install step (success) 06:30:06 INFO - ##### 06:30:06 INFO - ##### 06:30:06 INFO - ##### Running run-tests step. 06:30:06 INFO - ##### 06:30:06 INFO - Running pre-action listener: _resource_record_pre_action 06:30:06 INFO - Running main action method: run_tests 06:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:30:06 INFO - Python 2.7.3 06:30:06 INFO - Return code: 0 06:30:06 INFO - grabbing minidump binary from tooltool 06:30:06 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')]} 06:30:06 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 0x23d09c0>, '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 0x23d18c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d1d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:30:06 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 06:30:06 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 06:30:06 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 06:30:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:30:06 INFO - Return code: 0 06:30:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:30:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:30:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:30:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:30:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:30:07 INFO - Return code: 0 06:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 06:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 06:30:07 INFO - Using env: {'DISPLAY': ':0', 06:30:07 INFO - 'HOME': '/home/cltbld', 06:30:07 INFO - 'LANG': 'en_US.UTF-8', 06:30:07 INFO - 'LANGUAGE': 'en_US:en', 06:30:07 INFO - 'LOGNAME': 'cltbld', 06:30:07 INFO - 'MAIL': '/var/mail/cltbld', 06:30:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:30:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:07 INFO - 'MOZ_NO_REMOTE': '1', 06:30:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:07 INFO - 'NO_EM_RESTART': '1', 06:30:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:07 INFO - 'PWD': '/builds/slave/test', 06:30:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:30:07 INFO - 'SHELL': '/bin/bash', 06:30:07 INFO - 'SHLVL': '1', 06:30:07 INFO - 'TERM': 'linux', 06:30:07 INFO - 'TMOUT': '86400', 06:30:07 INFO - 'USER': 'cltbld', 06:30:07 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028', 06:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:07 INFO - '_': '/tools/buildbot/bin/python'} 06:30:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 06:30:07 INFO - mozversion application_buildid: 20160517060029 06:30:07 INFO - mozversion application_changeset: c480fcee4fc0af1e22f08124937cc7602ed16032 06:30:07 INFO - mozversion application_display_name: Nightly 06:30:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:30:07 INFO - mozversion application_name: Firefox 06:30:07 INFO - mozversion application_remotingname: firefox 06:30:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:30:07 INFO - mozversion application_vendor: Mozilla 06:30:07 INFO - mozversion application_version: 49.0a1 06:30:07 INFO - mozversion platform_buildid: 20160517060029 06:30:07 INFO - mozversion platform_changeset: c480fcee4fc0af1e22f08124937cc7602ed16032 06:30:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:30:07 INFO - mozversion platform_version: 49.0a1 06:30:07 INFO - using testdate: 1463491807 06:30:07 INFO - actual date: 1463491807 06:30:07 INFO - starting webserver on 'localhost:38673' 06:30:07 INFO - SUITE-START | Running 2 tests 06:30:07 INFO - TEST-START | dromaeo_css 06:30:07 INFO - operating with platform_type : linux_ 06:30:07 INFO - Initialising browser for dromaeo_css test... 06:30:07 INFO - TEST-INFO | started process 21909 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkH4RLI/profile http://localhost:38673/getInfo.html) 06:30:11 INFO - PROCESS | 21909 | __metrics Screen width/height:1600/1200 06:30:11 INFO - PROCESS | 21909 | colorDepth:24 06:30:11 INFO - PROCESS | 21909 | Browser inner width/height: 1024/697 06:30:11 INFO - PROCESS | 21909 | __metrics 06:30:13 ERROR - PROCESS | 21909 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:30:13 INFO - TEST-INFO | 21909: exit 0 06:30:13 INFO - Browser initialized. 06:30:13 INFO - Running cycle 1/1 for dromaeo_css test... 06:30:13 INFO - TEST-INFO | started process 21991 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkH4RLI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:30:25 INFO - PROCESS | 21991 | RSS: Main: 154148864 06:30:25 INFO - PROCESS | 21991 | 06:31:17 INFO - PROCESS | 21991 | 1463491877986 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] 06:33:29 INFO - PROCESS | 21991 | Cycle 1(1): loaded http://localhost:38673/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38673/tests/dromaeo/cssquery-ext.html) 06:33:30 INFO - PROCESS | 21991 | RSS: Main: 262701056 06:33:30 INFO - PROCESS | 21991 | 06:36:33 INFO - PROCESS | 21991 | Cycle 1(1): loaded http://localhost:38673/tests/dromaeo/cssquery-ext.html (next: http://localhost:38673/tests/dromaeo/cssquery-jquery.html) 06:36:34 INFO - PROCESS | 21991 | RSS: Main: 242794496 06:36:34 INFO - PROCESS | 21991 | 06:39:36 INFO - PROCESS | 21991 | Cycle 1(1): loaded http://localhost:38673/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38673/tests/dromaeo/cssquery-mootools.html) 06:39:36 INFO - PROCESS | 21991 | RSS: Main: 230264832 06:39:36 INFO - PROCESS | 21991 | 06:42:38 INFO - PROCESS | 21991 | Cycle 1(1): loaded http://localhost:38673/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38673/tests/dromaeo/cssquery-prototype.html) 06:42:39 INFO - PROCESS | 21991 | RSS: Main: 238620672 06:42:39 INFO - PROCESS | 21991 | 06:45:42 INFO - PROCESS | 21991 | Cycle 1(1): loaded http://localhost:38673/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38673/tests/dromaeo/cssquery-yui.html) 06:45:43 INFO - PROCESS | 21991 | RSS: Main: 225374208 06:45:43 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | Cycle 1(1): loaded http://localhost:38673/tests/dromaeo/cssquery-yui.html (next: http://localhost:38673/tests/dromaeo/cssquery-dojo.html) 06:48:45 INFO - PROCESS | 21991 | RSS: Main: 268156928 06:48:45 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | __start_tp_report 06:48:45 INFO - PROCESS | 21991 | _x_x_mozilla_page_load 06:48:45 INFO - PROCESS | 21991 | _x_x_mozilla_page_load_details 06:48:45 INFO - PROCESS | 21991 | |i|pagename|runs| 06:48:45 INFO - PROCESS | 21991 | |0;dojo.html;2645.193216069099;2658.0962472759256;2693.3132051326916;2697.986510067449;2704.972950270498;4639.489656137822;4678.719276843388;4705.058988202356;4767.64242681799;4812.254100614406;4723.7165770053825;4772.164871147553;4790;4807.062622788558;4895.020995800836;4287.906583821121;4295.441592592959;4302.376155457457;4320.97839510802;4442.178197033543;4334;4412.227860124482;4441.311596702516;4479.9552004479865;4488.371627972087;4234.915301693949;4382.320740285229;4433.84481543146;4452.866414007585;4454.042380888124;4263.445752052214;4298.957010429887;4305.246581848195;4312.741235525878;4322.027543802619;4264.936025959613;4335.934960975588;4343.131373725236;4345.239583072949;4370.10412865365;4493.326001099861;4647.047355292157;4656.604188643969;4659.976700116512;4679.485256621769;4999.600031997468;5052.46949070346;5053.5957123430035;5066.088104141253;5119.6160287978555;5103.464136265676;5128.205128205134;5133.768980395891;5155.2524883892;5175.275461435402;4633.050224703928;4704;4774.069056534012;4775.808967641325;4780.1873681474235;5169.534741873174;5185.22221666753;5252.212168174804;5255.0540902637895;5259;4980.352554168007;4993.201087825931;4996.800127994912;4999.350084488993;5041.59667226621;4925.335079764186;4974.527419895104;4995.725235112031;5061.417936937226;5122.795088196505;4961.975190124099;4975.90048199034;4984.501549844987;5021.472745361758;5039.571636410946;4973.776180071905;4977.701337919736;4981.402231732143;4985.90028199434;5033.597312215014;4235.703500754979;4272.124214536013;4275.40144379787;4311.913761724748;4351;3727.757695749822;3753.0617345663586;3765.4916586260506;3789.4315852621776;3822.2928758179823;4278.935815962763;4288.292431748727;4290.957090429119;4320.481542214954;4322;4953.182724850431;5015.3730783652045;5020.548150666457;5023.020511000393;5029.019341228425;4296.484421869396;4323.610875021201;4327.82688692455;4331.198728235224;4338.240807858612;4594.19601569724;4594.701344412602;4595.701279416827;4634.837780677727;4634.884127896829;4655.534446555317;4704.811807527661;4783.306420568998;4792.472827988849;4796.25658023007;4993;5017.949820501748;5034.949650503448;5114.64197506171;5117.232415137759;4727.361806156125;4729.9054018920115;4732.5030871759345;4753.191957367187;4785.832495862628;4778.283257511401;4871.5128487152415;4983.526564976321;4985.825496107619;5042.243663450512;4942.357493525818;4977.9751101244265;4995.725235112103;5001.474845141208;5050.444451110448;4829.323894654681;4856.805727770849;4858.17411040117;4862.197737373293;4886.535779101122;4759.5240475953515;4835.01569259478;4862.562369386772;4886.364772579542;4908.312836202863;4871.123197824426;4908.165611845923;4908.7545622719435;4929.088118698052;4932.8766780830765;4973.925391119208;4974.5771609413605;4975.0547395994645;4982.327385802871;5014.799408023784;4719.787609557509;4741.57325840676;4752.596029337545;4758.881027974328;4917.508249175197 06:48:45 INFO - PROCESS | 21991 | |1;ext.html;559.3623269472802;598.3149294058306;600.438589918426;600.9489193418559;614.152469591963;28311;31082;31095.37809243814;31210;31222.21944451387;31301;43193.78403107988;44809;44870;49064.26403603949;7929.92070079299;8080.515169089844;8083.110857805636;8083.676652933876;8084.070331911847;7973.40199485038;8064.758057258292;8071.273585377315;8075.757727268191;8076.434649574503;8072;8096;8103.068147163092;8103.918960810405;8106.351491880665;8012.67949282028;8060.5566693831815;8072.19278072194;8076.8788468173025;8079.272865442138;7917.802054948615;7984.321332686729;7999.800004999864;8006.599670016534;8007.439479236456;40053.39919901204;40187.19625607471;40435.39346909799;40462.19075618471;40609.79695101506;3272;3338.315645292709;3347.2134048498583;3626.9455958160647;3942.448057271984;29974;30313.545296820565;30700.539491907417;30965.22586935322;31908.840455797574;3510.5085288059436;3536.398812201932;3556.893293201182;3685.207680348738;3736.252749450121;248501;250259.99999999817;250405;251580;251634.99999999817;55513.167302490496;56629.43370566324;56700.716496417255;56719.716401418555;56903;33086.66913330884;36277.45583816245;36417.81791091028;38463.807680961414;38841.417378739345;15717.764233536505;15799.684006319809;15828.683426331409;15886.126263055412;15894.443694470638;11881.346525941157;11973.46119424628;11973.76052478963;11978.401079945968;11985.34080625574;19773.60452790965;19888.303909363392;19944.401667949984;19979.800201998085;19990.400287991382;7146.2496437874315;7223.494355395072;7234.529755565872;7296.890546641805;7385.519941203912;857.2541888556871;981.7349315684868;1002.3534820041033;1008.8738907636546;1037.8946422060633;29165.416691666473;29311.413771724445;29453.26366840846;29714;30031.549526757117;997.0926456924165;1003.5534187286734;1029.848663090944;1084.2139448899484;1123.8426620273005;15677.137757423223;15760.448384306494;15849.841501584837;15923.44267950616;16219.594510137342;401279;401674;405871;406164;406558;52878;53233.733831330595;53418.73290633522;53528.73235633797;53580;1132.4677401621225;1169.497116240025;1246.5076294863659;1266.639007882749;1288.0854593238719;28534.57198141988;30639.693603064577;30662.386752265276;30703.54371650218;31252;44055.33916991184;44686.77656611696;44725.329120062575;44818.55181448144;44873.32690009718;300353;307266;307791;307867;307880;22958.770412295664;24233.75766242315;24318.027278908645;26513.204603861916;26964.865175673996;36911.815440922626;39323;44491.555084448744;45723.771381142884;50276.49723502718;36629;38223;38308.40310139385;38761.418578720775;39230.41154382744;38597.22805543929;38891.41662875003;38978.80510597429;39051;39361.80319098386;34798.652013480554;37153.25693486061;38123.80938095292;38417.27122279448;38453.230935381696;31511.369772604878;34474.31051378908;34621.65378346184;35085.82457087698;35227.82386088156;26108.73891261063;26109;26373.208803735917;26448;26563.33591660224 06:48:45 INFO - PROCESS | 21991 | |2;jquery.html;381.66032231314136;383.82919600777655;390.57622479609626;403.5943876404217;405.32513365246865;15395.230238488073;16984.320627174897;17746.64506709865;17777.377791777275;17793.911030444862;11131.443427828617;11240.269382490133;11248.043916267126;11270.549178032868;11784.882151178486;4707.646926480519;4819.831305904294;4867.245576935581;4877.902441951159;4878.731669758162;4606.677532572721;4636.35091087248;4637.443506779191;4638.930416043762;4639.188142075141;4706.529347065283;5148.124818780816;5156.200788877712;5158.632962265003;5161.380634323887;5055.51972562606;5112.667676601009;5118.462561431052;5119.104156772569;5119.3088932994315;4595.586397224266;4904.264360345941;4906.877328066791;4959.950400495985;4961.255811628284;11575.53697852085;11823.88176118245;11850.40747962607;11910.761784764256;12170.148089633729;1845.5017145370675;1874.2034635280088;1923.0294136539046;2046.3963130876427;2226.866388016724;9604.231661467065;10676.733081672943;11748.412579371083;12268.386580670931;13152.473901043946;1733.1074496634244;1750.8074111847686;1774.8047714751367;1790.8030116687155;1797.8741488095839;69289.65355173191;71412.92880606846;71527.92708109383;71678.64160679215;74139.25860741432;9311.813763724824;9526.571304291185;10191.439470829175;10347.895631310577;10478.73803154912;14414.13515189092;14443.927780361031;14491.710165796836;14539.20034398097;14556.927215363856;7575.659095340723;7628.427867909929;7643.579603121771;7747.767566973;7852.489588176751;7340.0457940467395;7389.889151662729;7539.095308563007;7552.622368881534;7559.584222867795;4492.213862574102;4557.156925968707;4574.4968053514085;4680.648951328664;4683.320918466803;1756.7804024496938;1873.531617095726;1909.8376637985648;1926.951826204328;1939.7478327817294;848.6775025490398;886.3263919421287;934.4860326820221;1002.9548670309854;1047.1308813684775;16681.91659041697;16724.081446256896;17709.822901770818;17732.734008989875;17854.125316841804;880.4145243413186;885.5882014862991;918.0727465260136;931;954.4034978138664;9735.561899714388;10020.448875311782;10062.69811905644;10087.49562521871;10125.088742013255;354506;355924;358722;365094;371865;52890;72402.63798681078;72440.63779681068;75060.62469687726;76964.61517692488;916.5325683901125;937.8827646544182;942.5617088054088;955.3742298794301;968.8740463739668;12326.630201093982;15967.920160399124;16055.357785688442;16216.918915405819;16480.42318518846;36354.272914541034;36462.8176859114;36963.63036369709;37272.62727372692;37731.62268377281;49683.751581241864;50080;50154.24768628547;50487.24269136039;50941;7639.770806875803;7701.3068823806125;7853;7918.564478953713;8016.438849280495;8559.486430814171;11474.254173478697;11760.529578816751;11805.70485737829;11894.167408281337;10222.591096356062;10277.84583231267;10590.735231618963;10592;10593.364398136137;6822.761203357859;10226.846597300897;10467.581296748045;10570.524326405177;10585.153187745116;10653.307535010199;10662.73343166427;10678.679639610824;10898.836517452086;10908.94545527299;7697.691392463406;7759.68961241544;7873.173316801883;7953.642086106023;7985.321247693779;9435.622575096919;9895.109440150422;10519.737006574896;10772.407517586607;10837.566497340018 06:48:45 INFO - PROCESS | 21991 | |3;mootools.html;1277.3868543099318;1506.3296832909357;1541.9614509637258;1678.6894424531463;1845.8615603829708;7486.101667799865;7745;7766.0680718313815;7859.506693728187;7920.881186782203;7830.177831327714;7987.760367188994;7992.440529162961;8006.719764808218;8026.879596806052;2204.5370472200857;2235.1282999630157;2237.865728056314;2239.3841693534246;2239.462528993038;2121.607502612014;2142.185969331652;2166.978330216701;2174.5433458973635;2177.3032629558547;2145.9463513412134;2159.8920053997317;2183.159483598818;2185.8142057925015;2195.4182141732454;2069.9482512937147;2086.1446806809136;2091.926782562603;2091.985387087269;2095;2069.4929742213253;2098.5383215692523;2101.8738875667354;2102.4218339956483;2104.2004038465284;7294.963525182393;7440.330370266648;7713.5757533335645;7726.652300646484;7757.069151701832;4477.70894891831;4604.884877878047;4610.3315019322345;4613.95386046142;4617.399738034068;2997.895073672411;3000.1749518882266;3021.2597913511113;3047.238190452387;3066.6166729158854;885.3802338363107;893.3746377535751;907.3421769217331;912.780932576177;917.3028498341242;10143.746406339827;10218.489075546267;10228.07947284748;10249.795004099951;10257.384556926609;3900.1029763154634;3990.2418540477215;4000.7199496035;4050.6556942660204;4056.8377264909072;8251;8262.215089566482;8268.181000180071;8271.421000529905;8291.792705182419;4528.569785870337;4530.546945305443;4534.02518458533;4536.77316134192;4538.364628951884;3739.9252014959548;4137.213929353413;4160.54234034262;4240.639545638655;4280.507741609693;3379.391709492315;3401.149712571857;3471.7743346682387;3489.581250249986;3617.186133120027;975.1516180922642;984.522506584306;987.3384832162468;993.036754348281;1000.3047881722279;836.7029704454952;849.723839752083;896.6458248991743;908.0919080919081;920.9171174594185;5276.551493123127;5356.91964620531;5480.91778623321;5487.478689524489;5606;837.3552364679284;855.9700410485603;896.8968568614692;914.8627705844177;965.8454647256406;3582.0686621478603;3622;3777.1501412182038;3800.9429858552144;3847.6537111660086;9305.348625596278;9578.281628877861;9698.27262955267;9702.563384647709;9707.174890134276;9601.423914565008;9619.759506012406;9619.951900240454;9627.422354658744;9634.518274086267;7233.8553228935125;7417.184109747924;7422.777316680508;7425.85148297031;7427.220141885133;5505.6696598204235;5626.2685850839125;5628.240187574625;5668.773249069991;5835.94164058354;8437.95781021091;8766.693165739169;8851.247643950337;8862.778430539289;8874.334424918028;8376.49741015541;8664.610092545725;8678.479291242546;8775.517346546001;8907.465552066897;6594.934050659624;6786.219584747817;6797.25230224665;7081.610511421921;7122.6438678066925;6900.585964842126;6963.825904352229;7150.96424517874;7405.8148546286775;7409.296116868889;6815.488838337244;6949.861002780017;6959.269276726073;7042.683079261343;7077.044598979278;6806.9319306808275;6843.692033858389;6857.759978400732;6985.860282794214;7013.649317534001;6243.188385509855;6262;6297.433231009231;6355.205599300088;6370.331115232834;4537.931931020971;4802.927956080732;4811.975940120277;4848.539388758062;4881.780319885543;4870.439899411613;4874.780634871368;4877.756112194447;4945.085159245551;4967.652264341461 06:48:45 INFO - PROCESS | 21991 | |4;prototype.html;305.38922155688624;307.1951487103788;310.07751937984483;312.2770785631262;315.14909743692033;4947.208446648537;4963.305137280775;4986.828095397584;4997.625178111638;5036.1438555445575;4843.273508973656;4857.53853383928;4894.388201474816;4902.852914412573;4914.483979182188;3669.6146904575035;3746.850125994957;3793.658570728634;3846.1538461538416;3895.474110995008;3815.294170578452;3873.9806300968457;3876.9338431931305;3878.437626544149;3882.6893848492055;3676.3933950898067;3854;3860.9382419834487;3861.092643228839;3887.8142166639163;3725.105974566112;3822.120912190212;3826.8469261229516;3837.6546110850018;3837.961620383788;3409.573803274591;3414.23179784549;3417.9487307690406;3419.9316013679836;3423.828808559587;4702.976485117552;4728.574428301435;4731.077439899221;4732.550407711262;4737.786799594027;4319;4393.670474714378;4400.317950717644;4437.489688685778;4437.7781110944325;5128.358955130609;5154.561862241714;5159;5170.508801663836;5182.585393168538;3734.5705243896755;3740.3267411865854;3750.3811871041244;3777.7544459610035;3780.8487660493547;5140.460251673558;5321.946780532223;5373.274607927958;5490.286262785852;5498.927709096688;3790.422512691396;3837.136644255076;3839.577646458887;3847.6729477994686;3849.9615003850163;3895.7272990890365;3907.433422153772;3908.4332771747936;3921.431392448136;3936.586658400884;4300.8709738707885;4396.384506169139;4410.184115938561;4413.911721765611;4417.337399390053;5135.87160320995;5148.639595228298;5148.716820574829;5156.9742151289;5165.251038599457;1322.543722415765;1337.6923307639297;1342.3020029584757;1350.4193196925416;1357.6470117769452;874.4490970688425;887.6493784954906;930.5254320296563;952.6379975609224;955.7132860141929;4488.743151917468;4491.236489796743;4498.527654596221;4508.864734057984;4515.525869783691;5195.350581177353;5215.765290561933;5247.291615631917;5296.496832800878;5317.899429725237;4360.319901873028;4381.671374646914;4444.2444784386735;4446.5553444655925;4448.332750087513;2781.2907708534194;2785.261905595019;2787.038471727251;2794.790390720704;2814.887404503838;4937.950750438599;4954.925676114861;4957.537526429712;5000.449950505442;5000.699958002531;5174.97412512935;5391.838244852661;5396.595255355864;5501.587380946365;5524.309461317335;5001.69921835945;5030;5042.596592272683;5068.873278168002;5083.644144909821;5284.867878303073;5294.920576191437;5380.623356365017;5391.622586418913;5411.458854114557;5190.325257716473;5204.34945631796;5259.47405259471;5262.815801446931;5267.209918512254;4999.125153098266;5014.272930425141;5014.598832093497;5018.315201367251;5028.823991160292;3685.262947410475;3687.8893633191046;3720.4419337099653;3733.4026555750947;3748.9690335158048;5195;5376.381716102698;5388.892222155457;5433.1035379161995;5455.972720136374;5127.897442051213;5164.7417629119145;5167.354080739908;5191.428942816212;5222.268882356397;5169.405518365285;5183;5190.688558686491;5199.142141546754;5214.713190774544;4878.853469434609;4913.828016019422;5099.3370861787735;5118.027574760784;5120.719820044988;5066.949330506648;5088.7201203932655;5096.796128154981;5102.413222479462;5113.41195762492;4943.530364615356;5042.949570504395;5066.164082926276;5100.387953445462;5103.3414140403775 06:48:45 INFO - PROCESS | 21991 | |5;yui.html;683.3883674111667;684.7466437418155;685.4584877946422;689.7689274093178;764.9579273139976;12192.756144877108;12808.871911280896;12811.103222774409;12814.231146131238;12820.80768788469;12737.745245095093;12999.480020799158;13007.674808129801;13055.347232638378;13060.216387016762;892.8526793079151;899.4648184330313;905.7509184974152;908.6910450446846;908.7773495493614;674.8953912143626;846.86026805577;889.7464222696533;894.713691618679;900.9639614415415;930.432436213909;930.4603330068578;931.3108299858123;932.2495391210073;932.4451951089092;924.4684306523742;924.7826760711228;925.3430064654103;925.9351845370793;926.235855419275;516.6099594805935;535.130413078747;536.0297860871834;536.0404875273256;669.4242951062083;12907.096503244777;13204.603861884063;13282.933585332108;13290.401931913088;13293.601191964257;12142.635720928387;12375.257484550866;12479.251244925243;12664.430100645493;12731.427085781163;10961.232713710044;11127.332360058423;11188;11240.381779002151;11817.94091029548;4235.2588297048205;4239.682023848224;4284.421603083575;4307.483102027777;4368.235558777233;47747.76126119382;47774.76112619449;50272.49727502715;50405.49594504081;50553.74723126361;10013.749656258506;10162.288639795144;10436.791264174673;10520.421376824348;10529.84205236922;18747.062646867602;18803;18833.246670133314;18835.340763073502;18963.146658400405;6961.373476387048;7043.859122817617;7047.224805271519;7151.320624540765;7216.134063912364;3775.1505911169766;3889.086064774776;3917.8628747994253;3947.92104157921;4004.59954004603;2760.1305588739483;2840.6449193850767;2842.4030953499996;2843.2749648839413;2844.8293102413922;499.7825945713626;508.8727818045489;510.0767610624781;511.5421697580719;519.0345956520869;559.4796838939825;569.7037540478927;572.5391060196498;580.3934888042006;590.5777369181056;7975.441719079725;8048.59757012147;8062.435629505995;8129.918700813035;8230.42387032914;495.43767823519846;528.6775067208999;575.3670961941914;580.7415700013454;584.3280227738135;3709.944350834686;3780.092777733324;3811.485449464287;3814.1036856338737;3830.272248272875;46845.297320540216;48329;48609.78475538069;57944.71027644835;59666;48512.514874851506;49719.00561988812;49725.50274497281;49993.500064999615;50261.74869125631;45298;46310.536894631296;46718.76640616775;49667.50332496629;50816;8054.718084867236;8283.337333013465;8721.956390218009;8838.734837954871;8913.955430223066;30623.693763062085;30968;31478.213044674067;31876.840615796773;31987;38895;40602.39096413497;40846;40952.590474094875;41134.794326028175;11352.432378381212;11420;11515.94242028818;11526.250793698382;11918;11837.881621183678;12156.817647735468;12256.325902075472;12257.509699611915;12521.186122901981;11172.38551879622;11324.943375283401;11572.838015200796;12067.577634783092;12239;8192.590370481572;10773.299735517166;11698.2981021139;11767.117466189899;11958.162928595262;11277.855378206921;11453.770924581295;11550.48022838991;11666.650000499998;11774.234674746114;9766.46284454362;9994.100530951959;10170.542325595217;10303.175745940156;10340.172786177238;9964.202863771026;10363.170946324126;10363.481825908826;10526.631567895087;10563.471826408495 06:48:45 INFO - PROCESS | 21991 | __end_tp_report 06:48:45 INFO - PROCESS | 21991 | __start_cc_report 06:48:45 INFO - PROCESS | 21991 | _x_x_mozilla_cycle_collect,973 06:48:45 INFO - PROCESS | 21991 | __end_cc_report 06:48:45 INFO - PROCESS | 21991 | __startTimestamp1463492925970__endTimestamp 06:48:45 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | ------- Summary: start ------- 06:48:45 INFO - PROCESS | 21991 | Number of tests: 6 06:48:45 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | [#0] dojo.html Cycles:165 Average:4664.52 Median:4780.19 stddev:480.39 (10.0%) stddev-sans-first:455.00 06:48:45 INFO - PROCESS | 21991 | Values: 2645.2 2658.1 2693.3 2698.0 2705.0 4639.5 4678.7 4705.1 4767.6 4812.3 4723.7 4772.2 4790.0 4807.1 4895.0 4287.9 4295.4 4302.4 4321.0 4442.2 4334.0 4412.2 4441.3 4480.0 4488.4 4234.9 4382.3 4433.8 4452.9 4454.0 4263.4 4299.0 4305.2 4312.7 4322.0 4264.9 4335.9 4343.1 4345.2 4370.1 4493.3 4647.0 4656.6 4660.0 4679.5 4999.6 5052.5 5053.6 5066.1 5119.6 5103.5 5128.2 5133.8 5155.3 5175.3 4633.1 4704.0 4774.1 4775.8 4780.2 5169.5 5185.2 5252.2 5255.1 5259.0 4980.4 4993.2 4996.8 4999.4 5041.6 4925.3 4974.5 4995.7 5061.4 5122.8 4962.0 4975.9 4984.5 5021.5 5039.6 4973.8 4977.7 4981.4 4985.9 5033.6 4235.7 4272.1 4275.4 4311.9 4351.0 3727.8 3753.1 3765.5 3789.4 3822.3 4278.9 4288.3 4291.0 4320.5 4322.0 4953.2 5015.4 5020.5 5023.0 5029.0 4296.5 4323.6 4327.8 4331.2 4338.2 4594.2 4594.7 4595.7 4634.8 4634.9 4655.5 4704.8 4783.3 4792.5 4796.3 4993.0 5017.9 5034.9 5114.6 5117.2 4727.4 4729.9 4732.5 4753.2 4785.8 4778.3 4871.5 4983.5 4985.8 5042.2 4942.4 4978.0 4995.7 5001.5 5050.4 4829.3 4856.8 4858.2 4862.2 4886.5 4759.5 4835.0 4862.6 4886.4 4908.3 4871.1 4908.2 4908.8 4929.1 4932.9 4973.9 4974.6 4975.1 4982.3 5014.8 4719.8 4741.6 4752.6 4758.9 4917.5 06:48:45 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | [#1] ext.html Cycles:180 Average:47828.22 Median:20298.51 stddev:85939.13 (423.4%) stddev-sans-first:86106.92 06:48:45 INFO - PROCESS | 21991 | Values: 559.4 598.3 600.4 600.9 614.2 28311.0 31082.0 31095.4 31210.0 31222.2 31301.0 43193.8 44809.0 44870.0 49064.3 7929.9 8080.5 8083.1 8083.7 8084.1 7973.4 8064.8 8071.3 8075.8 8076.4 8072.0 8096.0 8103.1 8103.9 8106.4 8012.7 8060.6 8072.2 8076.9 8079.3 7917.8 7984.3 7999.8 8006.6 8007.4 40053.4 40187.2 40435.4 40462.2 40609.8 3272.0 3338.3 3347.2 3626.9 3942.4 29974.0 30313.5 30700.5 30965.2 31908.8 3510.5 3536.4 3556.9 3685.2 3736.3 248501.0 250260.0 250405.0 251580.0 251635.0 55513.2 56629.4 56700.7 56719.7 56903.0 33086.7 36277.5 36417.8 38463.8 38841.4 15717.8 15799.7 15828.7 15886.1 15894.4 11881.3 11973.5 11973.8 11978.4 11985.3 19773.6 19888.3 19944.4 19979.8 19990.4 7146.2 7223.5 7234.5 7296.9 7385.5 857.3 981.7 1002.4 1008.9 1037.9 29165.4 29311.4 29453.3 29714.0 30031.5 997.1 1003.6 1029.8 1084.2 1123.8 15677.1 15760.4 15849.8 15923.4 16219.6 401279.0 401674.0 405871.0 406164.0 406558.0 52878.0 53233.7 53418.7 53528.7 53580.0 1132.5 1169.5 1246.5 1266.6 1288.1 28534.6 30639.7 30662.4 30703.5 31252.0 44055.3 44686.8 44725.3 44818.6 44873.3 300353.0 307266.0 307791.0 307867.0 307880.0 22958.8 24233.8 24318.0 26513.2 26964.9 36911.8 39323.0 44491.6 45723.8 50276.5 36629.0 38223.0 38308.4 38761.4 39230.4 38597.2 38891.4 38978.8 39051.0 39361.8 34798.7 37153.3 38123.8 38417.3 38453.2 31511.4 34474.3 34621.7 35085.8 35227.8 26108.7 26109.0 26373.2 26448.0 26563.3 06:48:45 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | [#2] jquery.html Cycles:180 Average:23060.27 Median:387.20 stddev:59786.77 (15440.7%) stddev-sans-first:59930.24 06:48:45 INFO - PROCESS | 21991 | Values: 381.7 383.8 390.6 403.6 405.3 15395.2 16984.3 17746.6 17777.4 17793.9 11131.4 11240.3 11248.0 11270.5 11784.9 4707.6 4819.8 4867.2 4877.9 4878.7 4606.7 4636.4 4637.4 4638.9 4639.2 4706.5 5148.1 5156.2 5158.6 5161.4 5055.5 5112.7 5118.5 5119.1 5119.3 4595.6 4904.3 4906.9 4960.0 4961.3 11575.5 11823.9 11850.4 11910.8 12170.1 1845.5 1874.2 1923.0 2046.4 2226.9 9604.2 10676.7 11748.4 12268.4 13152.5 1733.1 1750.8 1774.8 1790.8 1797.9 69289.7 71412.9 71527.9 71678.6 74139.3 9311.8 9526.6 10191.4 10347.9 10478.7 14414.1 14443.9 14491.7 14539.2 14556.9 7575.7 7628.4 7643.6 7747.8 7852.5 7340.0 7389.9 7539.1 7552.6 7559.6 4492.2 4557.2 4574.5 4680.6 4683.3 1756.8 1873.5 1909.8 1927.0 1939.7 848.7 886.3 934.5 1003.0 1047.1 16681.9 16724.1 17709.8 17732.7 17854.1 880.4 885.6 918.1 931.0 954.4 9735.6 10020.4 10062.7 10087.5 10125.1 354506.0 355924.0 358722.0 365094.0 371865.0 52890.0 72402.6 72440.6 75060.6 76964.6 916.5 937.9 942.6 955.4 968.9 12326.6 15967.9 16055.4 16216.9 16480.4 36354.3 36462.8 36963.6 37272.6 37731.6 49683.8 50080.0 50154.2 50487.2 50941.0 7639.8 7701.3 7853.0 7918.6 8016.4 8559.5 11474.3 11760.5 11805.7 11894.2 10222.6 10277.8 10590.7 10592.0 10593.4 6822.8 10226.8 10467.6 10570.5 10585.2 10653.3 10662.7 10678.7 10898.8 10908.9 7697.7 7759.7 7873.2 7953.6 7985.3 9435.6 9895.1 10519.7 10772.4 10837.6 06:48:45 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | [#3] mootools.html Cycles:180 Average:5118.78 Median:6039.57 stddev:2816.60 (46.6%) stddev-sans-first:2809.71 06:48:45 INFO - PROCESS | 21991 | Values: 1277.4 1506.3 1542.0 1678.7 1845.9 7486.1 7745.0 7766.1 7859.5 7920.9 7830.2 7987.8 7992.4 8006.7 8026.9 2204.5 2235.1 2237.9 2239.4 2239.5 2121.6 2142.2 2167.0 2174.5 2177.3 2145.9 2159.9 2183.2 2185.8 2195.4 2069.9 2086.1 2091.9 2092.0 2095.0 2069.5 2098.5 2101.9 2102.4 2104.2 7295.0 7440.3 7713.6 7726.7 7757.1 4477.7 4604.9 4610.3 4614.0 4617.4 2997.9 3000.2 3021.3 3047.2 3066.6 885.4 893.4 907.3 912.8 917.3 10143.7 10218.5 10228.1 10249.8 10257.4 3900.1 3990.2 4000.7 4050.7 4056.8 8251.0 8262.2 8268.2 8271.4 8291.8 4528.6 4530.5 4534.0 4536.8 4538.4 3739.9 4137.2 4160.5 4240.6 4280.5 3379.4 3401.1 3471.8 3489.6 3617.2 975.2 984.5 987.3 993.0 1000.3 836.7 849.7 896.6 908.1 920.9 5276.6 5356.9 5480.9 5487.5 5606.0 837.4 856.0 896.9 914.9 965.8 3582.1 3622.0 3777.2 3800.9 3847.7 9305.3 9578.3 9698.3 9702.6 9707.2 9601.4 9619.8 9620.0 9627.4 9634.5 7233.9 7417.2 7422.8 7425.9 7427.2 5505.7 5626.3 5628.2 5668.8 5835.9 8438.0 8766.7 8851.2 8862.8 8874.3 8376.5 8664.6 8678.5 8775.5 8907.5 6594.9 6786.2 6797.3 7081.6 7122.6 6900.6 6963.8 7151.0 7405.8 7409.3 6815.5 6949.9 6959.3 7042.7 7077.0 6806.9 6843.7 6857.8 6985.9 7013.6 6243.2 6262.0 6297.4 6355.2 6370.3 4537.9 4802.9 4812.0 4848.5 4881.8 4870.4 4874.8 4877.8 4945.1 4967.7 06:48:45 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | [#4] prototype.html Cycles:180 Average:4290.20 Median:4914.16 stddev:1237.79 (25.2%) stddev-sans-first:1204.58 06:48:45 INFO - PROCESS | 21991 | Values: 305.4 307.2 310.1 312.3 315.1 4947.2 4963.3 4986.8 4997.6 5036.1 4843.3 4857.5 4894.4 4902.9 4914.5 3669.6 3746.9 3793.7 3846.2 3895.5 3815.3 3874.0 3876.9 3878.4 3882.7 3676.4 3854.0 3860.9 3861.1 3887.8 3725.1 3822.1 3826.8 3837.7 3838.0 3409.6 3414.2 3417.9 3419.9 3423.8 4703.0 4728.6 4731.1 4732.6 4737.8 4319.0 4393.7 4400.3 4437.5 4437.8 5128.4 5154.6 5159.0 5170.5 5182.6 3734.6 3740.3 3750.4 3777.8 3780.8 5140.5 5321.9 5373.3 5490.3 5498.9 3790.4 3837.1 3839.6 3847.7 3850.0 3895.7 3907.4 3908.4 3921.4 3936.6 4300.9 4396.4 4410.2 4413.9 4417.3 5135.9 5148.6 5148.7 5157.0 5165.3 1322.5 1337.7 1342.3 1350.4 1357.6 874.4 887.6 930.5 952.6 955.7 4488.7 4491.2 4498.5 4508.9 4515.5 5195.4 5215.8 5247.3 5296.5 5317.9 4360.3 4381.7 4444.2 4446.6 4448.3 2781.3 2785.3 2787.0 2794.8 2814.9 4938.0 4954.9 4957.5 5000.4 5000.7 5175.0 5391.8 5396.6 5501.6 5524.3 5001.7 5030.0 5042.6 5068.9 5083.6 5284.9 5294.9 5380.6 5391.6 5411.5 5190.3 5204.3 5259.5 5262.8 5267.2 4999.1 5014.3 5014.6 5018.3 5028.8 3685.3 3687.9 3720.4 3733.4 3749.0 5195.0 5376.4 5388.9 5433.1 5456.0 5127.9 5164.7 5167.4 5191.4 5222.3 5169.4 5183.0 5190.7 5199.1 5214.7 4878.9 4913.8 5099.3 5118.0 5120.7 5066.9 5088.7 5096.8 5102.4 5113.4 4943.5 5042.9 5066.2 5100.4 5103.3 06:48:45 INFO - PROCESS | 21991 | 06:48:45 INFO - PROCESS | 21991 | [#5] yui.html Cycles:180 Average:13501.24 Median:22685.03 stddev:15311.31 (67.5%) stddev-sans-first:15324.01 06:48:45 INFO - PROCESS | 21991 | Values: 683.4 684.7 685.5 689.8 765.0 12192.8 12808.9 12811.1 12814.2 12820.8 12737.7 12999.5 13007.7 13055.3 13060.2 892.9 899.5 905.8 908.7 908.8 674.9 846.9 889.7 894.7 901.0 930.4 930.5 931.3 932.2 932.4 924.5 924.8 925.3 925.9 926.2 516.6 535.1 536.0 536.0 669.4 12907.1 13204.6 13282.9 13290.4 13293.6 12142.6 12375.3 12479.3 12664.4 12731.4 10961.2 11127.3 11188.0 11240.4 11817.9 4235.3 4239.7 4284.4 4307.5 4368.2 47747.8 47774.8 50272.5 50405.5 50553.7 10013.7 10162.3 10436.8 10520.4 10529.8 18747.1 18803.0 18833.2 18835.3 18963.1 6961.4 7043.9 7047.2 7151.3 7216.1 3775.2 3889.1 3917.9 3947.9 4004.6 2760.1 2840.6 2842.4 2843.3 2844.8 499.8 508.9 510.1 511.5 519.0 559.5 569.7 572.5 580.4 590.6 7975.4 8048.6 8062.4 8129.9 8230.4 495.4 528.7 575.4 580.7 584.3 3709.9 3780.1 3811.5 3814.1 3830.3 46845.3 48329.0 48609.8 57944.7 59666.0 48512.5 49719.0 49725.5 49993.5 50261.7 45298.0 46310.5 46718.8 49667.5 50816.0 8054.7 8283.3 8722.0 8838.7 8914.0 30623.7 30968.0 31478.2 31876.8 31987.0 38895.0 40602.4 40846.0 40952.6 41134.8 11352.4 11420.0 11515.9 11526.3 11918.0 11837.9 12156.8 12256.3 12257.5 12521.2 11172.4 11324.9 11572.8 12067.6 12239.0 8192.6 10773.3 11698.3 11767.1 11958.2 11277.9 11453.8 11550.5 11666.7 11774.2 9766.5 9994.1 10170.5 10303.2 10340.2 9964.2 10363.2 10363.5 10526.6 10563.5 06:48:45 INFO - PROCESS | 21991 | -------- Summary: end -------- 06:48:45 INFO - PROCESS | 21991 | 06:48:46 INFO - TEST-INFO | 21991: exit 0 06:48:46 INFO - TEST-OK | dromaeo_css | took 1119219ms 06:48:46 INFO - TEST-START | kraken 06:48:46 INFO - operating with platform_type : linux_ 06:48:46 INFO - Initialising browser for kraken test... 06:48:46 INFO - TEST-INFO | started process 22169 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy0m1ge/profile http://localhost:38673/getInfo.html) 06:48:51 INFO - PROCESS | 22169 | __metrics Screen width/height:1600/1200 06:48:51 INFO - PROCESS | 22169 | colorDepth:24 06:48:51 INFO - PROCESS | 22169 | Browser inner width/height: 1024/697 06:48:51 INFO - PROCESS | 22169 | __metrics 06:48:51 ERROR - PROCESS | 22169 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:48:52 INFO - TEST-INFO | 22169: exit 0 06:48:52 INFO - Browser initialized. 06:48:52 INFO - Running cycle 1/1 for kraken test... 06:48:52 INFO - TEST-INFO | started process 22250 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy0m1ge/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:49:04 INFO - PROCESS | 22250 | RSS: Main: 154476544 06:49:04 INFO - PROCESS | 22250 | 06:49:07 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38673/tests/kraken/driver.html?testName=audio-beat-detection) 06:49:08 INFO - PROCESS | 22250 | RSS: Main: 210518016 06:49:08 INFO - PROCESS | 22250 | 06:49:11 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38673/tests/kraken/driver.html?testName=audio-dft) 06:49:12 INFO - PROCESS | 22250 | RSS: Main: 197894144 06:49:12 INFO - PROCESS | 22250 | 06:49:15 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38673/tests/kraken/driver.html?testName=audio-fft) 06:49:16 INFO - PROCESS | 22250 | RSS: Main: 267075584 06:49:16 INFO - PROCESS | 22250 | 06:49:18 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38673/tests/kraken/driver.html?testName=audio-oscillator) 06:49:18 INFO - PROCESS | 22250 | RSS: Main: 175951872 06:49:18 INFO - PROCESS | 22250 | 06:49:21 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38673/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:49:21 INFO - PROCESS | 22250 | RSS: Main: 179470336 06:49:21 INFO - PROCESS | 22250 | 06:49:25 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38673/tests/kraken/driver.html?testName=imaging-darkroom) 06:49:25 INFO - PROCESS | 22250 | RSS: Main: 216190976 06:49:25 INFO - PROCESS | 22250 | 06:49:29 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38673/tests/kraken/driver.html?testName=imaging-desaturate) 06:49:30 INFO - PROCESS | 22250 | RSS: Main: 213970944 06:49:30 INFO - PROCESS | 22250 | 06:49:33 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38673/tests/kraken/driver.html?testName=json-parse-financial) 06:49:33 INFO - PROCESS | 22250 | RSS: Main: 215560192 06:49:33 INFO - PROCESS | 22250 | 06:49:35 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38673/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:49:36 INFO - PROCESS | 22250 | RSS: Main: 204906496 06:49:36 INFO - PROCESS | 22250 | 06:49:38 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38673/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:49:38 INFO - PROCESS | 22250 | RSS: Main: 206635008 06:49:38 INFO - PROCESS | 22250 | 06:49:40 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38673/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:49:41 INFO - PROCESS | 22250 | RSS: Main: 218832896 06:49:41 INFO - PROCESS | 22250 | 06:49:44 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38673/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:49:45 INFO - PROCESS | 22250 | RSS: Main: 222232576 06:49:45 INFO - PROCESS | 22250 | 06:49:48 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38673/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:49:48 INFO - PROCESS | 22250 | RSS: Main: 220389376 06:49:48 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | Cycle 1(1): loaded http://localhost:38673/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38673/tests/kraken/driver.html?testName=ai-astar) 06:49:50 INFO - PROCESS | 22250 | RSS: Main: 267522048 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | __start_tp_report 06:49:50 INFO - PROCESS | 22250 | _x_x_mozilla_page_load 06:49:50 INFO - PROCESS | 22250 | _x_x_mozilla_page_load_details 06:49:50 INFO - PROCESS | 22250 | |i|pagename|runs| 06:49:50 INFO - PROCESS | 22250 | |0;ai-astar;117;110;115;115;114;114;114;115;114;115 06:49:50 INFO - PROCESS | 22250 | |1;audio-beat-detection;159;130;156;220;140;166;152;133;196;135 06:49:50 INFO - PROCESS | 22250 | |2;audio-dft;164;163;162;162;162;163;162;164;163;161 06:49:50 INFO - PROCESS | 22250 | |3;audio-fft;76;75;75;155;75;75;77;76;131;76 06:49:50 INFO - PROCESS | 22250 | |4;audio-oscillator;93;92;147;93;92;94;134;92;92;125 06:49:50 INFO - PROCESS | 22250 | |5;imaging-gaussian-blur;117;117;116;116;117;116;116;117;117;117 06:49:50 INFO - PROCESS | 22250 | |6;imaging-darkroom;146;146;145;146;146;146;146;145;147;146 06:49:50 INFO - PROCESS | 22250 | |7;imaging-desaturate;94;94;94;95;94;94;94;95;95;94 06:49:50 INFO - PROCESS | 22250 | |8;json-parse-financial;62;63;63;64;64;65;63;115;61;61 06:49:50 INFO - PROCESS | 22250 | |9;json-stringify-tinderbox;64;64;64;64;64;64;64;64;64;69 06:49:50 INFO - PROCESS | 22250 | |10;stanford-crypto-aes;84;83;83;81;85;90;86;85;91;83 06:49:50 INFO - PROCESS | 22250 | |11;stanford-crypto-ccm;134;133;142;127;131;131;130;132;256;141 06:49:50 INFO - PROCESS | 22250 | |12;stanford-crypto-pbkdf2;171;170;172;173;173;174;170;176;167;168 06:49:50 INFO - PROCESS | 22250 | |13;stanford-crypto-sha256-iterative;69;66;67;68;69;65;66;65;65;64 06:49:50 INFO - PROCESS | 22250 | __end_tp_report 06:49:50 INFO - PROCESS | 22250 | __start_cc_report 06:49:50 INFO - PROCESS | 22250 | _x_x_mozilla_cycle_collect,2109 06:49:50 INFO - PROCESS | 22250 | __end_cc_report 06:49:50 INFO - PROCESS | 22250 | __startTimestamp1463492990923__endTimestamp 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | ------- Summary: start ------- 06:49:50 INFO - PROCESS | 22250 | Number of tests: 14 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:1.77 (1.5%) stddev-sans-first:1.58 06:49:50 INFO - PROCESS | 22250 | Values: 117.0 110.0 115.0 115.0 114.0 114.0 114.0 115.0 114.0 115.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#1] audio-beat-detection Cycles:10 Average:158.70 Median:157.50 stddev:29.15 (18.5%) stddev-sans-first:30.92 06:49:50 INFO - PROCESS | 22250 | Values: 159.0 130.0 156.0 220.0 140.0 166.0 152.0 133.0 196.0 135.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#2] audio-dft Cycles:10 Average:162.60 Median:163.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 06:49:50 INFO - PROCESS | 22250 | Values: 164.0 163.0 162.0 162.0 162.0 163.0 162.0 164.0 163.0 161.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#3] audio-fft Cycles:10 Average:89.10 Median:75.50 stddev:28.97 (38.4%) stddev-sans-first:30.34 06:49:50 INFO - PROCESS | 22250 | Values: 76.0 75.0 75.0 155.0 75.0 75.0 77.0 76.0 131.0 76.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#4] audio-oscillator Cycles:10 Average:105.40 Median:92.00 stddev:21.31 (23.2%) stddev-sans-first:22.13 06:49:50 INFO - PROCESS | 22250 | Values: 93.0 92.0 147.0 93.0 92.0 94.0 134.0 92.0 92.0 125.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 06:49:50 INFO - PROCESS | 22250 | Values: 117.0 117.0 116.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 06:49:50 INFO - PROCESS | 22250 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 147.0 146.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 06:49:50 INFO - PROCESS | 22250 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 94.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:63.00 stddev:16.53 (26.2%) stddev-sans-first:17.38 06:49:50 INFO - PROCESS | 22250 | Values: 62.0 63.0 63.0 64.0 64.0 65.0 63.0 115.0 61.0 61.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.00 stddev:1.58 (2.5%) stddev-sans-first:1.67 06:49:50 INFO - PROCESS | 22250 | Values: 64.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 69.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.00 stddev:3.18 (3.7%) stddev-sans-first:3.35 06:49:50 INFO - PROCESS | 22250 | Values: 84.0 83.0 83.0 81.0 85.0 90.0 86.0 85.0 91.0 83.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:133.50 stddev:39.04 (29.2%) stddev-sans-first:41.18 06:49:50 INFO - PROCESS | 22250 | Values: 134.0 133.0 142.0 127.0 131.0 131.0 130.0 132.0 256.0 141.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:172.50 stddev:2.76 (1.6%) stddev-sans-first:2.92 06:49:50 INFO - PROCESS | 22250 | Values: 171.0 170.0 172.0 173.0 173.0 174.0 170.0 176.0 167.0 168.0 06:49:50 INFO - PROCESS | 22250 | 06:49:50 INFO - PROCESS | 22250 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.78 (2.7%) stddev-sans-first:1.62 06:49:50 INFO - PROCESS | 22250 | Values: 69.0 66.0 67.0 68.0 69.0 65.0 66.0 65.0 65.0 64.0 06:49:50 INFO - PROCESS | 22250 | -------- Summary: end -------- 06:49:50 INFO - PROCESS | 22250 | 06:49:51 INFO - TEST-INFO | 22250: exit 0 06:49:51 INFO - TEST-OK | kraken | took 65293ms 06:49:51 INFO - SUITE-END | took 1184s 06:49:52 INFO - Completed test suite (00:19:44) 06:49:52 INFO - javascript benchmark 06:49:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2645.193216069099, 2658.0962472759256, 2693.3132051326916, 2697.986510067449, 2704.972950270498, 4639.489656137822, 4678.719276843388, 4705.058988202356, 4767.64242681799, 4812.254100614406, 4723.7165770053825, 4772.164871147553, 4790.0, 4807.062622788558, 4895.020995800836, 4287.906583821121, 4295.441592592959, 4302.376155457457, 4320.97839510802, 4442.178197033543, 4334.0, 4412.227860124482, 4441.311596702516, 4479.9552004479865, 4488.371627972087, 4234.915301693949, 4382.320740285229, 4433.84481543146, 4452.866414007585, 4454.042380888124, 4263.445752052214, 4298.957010429887, 4305.246581848195, 4312.741235525878, 4322.027543802619, 4264.936025959613, 4335.934960975588, 4343.131373725236, 4345.239583072949, 4370.10412865365, 4493.326001099861, 4647.047355292157, 4656.604188643969, 4659.976700116512, 4679.485256621769, 4999.600031997468, 5052.46949070346, 5053.5957123430035, 5066.088104141253, 5119.6160287978555, 5103.464136265676, 5128.205128205134, 5133.768980395891, 5155.2524883892, 5175.275461435402, 4633.050224703928, 4704.0, 4774.069056534012, 4775.808967641325, 4780.1873681474235, 5169.534741873174, 5185.22221666753, 5252.212168174804, 5255.0540902637895, 5259.0, 4980.352554168007, 4993.201087825931, 4996.800127994912, 4999.350084488993, 5041.59667226621, 4925.335079764186, 4974.527419895104, 4995.725235112031, 5061.417936937226, 5122.795088196505, 4961.975190124099, 4975.90048199034, 4984.501549844987, 5021.472745361758, 5039.571636410946, 4973.776180071905, 4977.701337919736, 4981.402231732143, 4985.90028199434, 5033.597312215014, 4235.703500754979, 4272.124214536013, 4275.40144379787, 4311.913761724748, 4351.0, 3727.757695749822, 3753.0617345663586, 3765.4916586260506, 3789.4315852621776, 3822.2928758179823, 4278.935815962763, 4288.292431748727, 4290.957090429119, 4320.481542214954, 4322.0, 4953.182724850431, 5015.3730783652045, 5020.548150666457, 5023.020511000393, 5029.019341228425, 4296.484421869396, 4323.610875021201, 4327.82688692455, 4331.198728235224, 4338.240807858612, 4594.19601569724, 4594.701344412602, 4595.701279416827, 4634.837780677727, 4634.884127896829, 4655.534446555317, 4704.811807527661, 4783.306420568998, 4792.472827988849, 4796.25658023007, 4993.0, 5017.949820501748, 5034.949650503448, 5114.64197506171, 5117.232415137759, 4727.361806156125, 4729.9054018920115, 4732.5030871759345, 4753.191957367187, 4785.832495862628, 4778.283257511401, 4871.5128487152415, 4983.526564976321, 4985.825496107619, 5042.243663450512, 4942.357493525818, 4977.9751101244265, 4995.725235112103, 5001.474845141208, 5050.444451110448, 4829.323894654681, 4856.805727770849, 4858.17411040117, 4862.197737373293, 4886.535779101122, 4759.5240475953515, 4835.01569259478, 4862.562369386772, 4886.364772579542, 4908.312836202863, 4871.123197824426, 4908.165611845923, 4908.7545622719435, 4929.088118698052, 4932.8766780830765, 4973.925391119208, 4974.5771609413605, 4975.0547395994645, 4982.327385802871, 5014.799408023784, 4719.787609557509, 4741.57325840676, 4752.596029337545, 4758.881027974328, 4917.508249175197], "value": 4634.64230437737, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.3623269472802, 598.3149294058306, 600.438589918426, 600.9489193418559, 614.152469591963, 28311.0, 31082.0, 31095.37809243814, 31210.0, 31222.21944451387, 31301.0, 43193.78403107988, 44809.0, 44870.0, 49064.26403603949, 7929.92070079299, 8080.515169089844, 8083.110857805636, 8083.676652933876, 8084.070331911847, 7973.40199485038, 8064.758057258292, 8071.273585377315, 8075.757727268191, 8076.434649574503, 8072.0, 8096.0, 8103.068147163092, 8103.918960810405, 8106.351491880665, 8012.67949282028, 8060.5566693831815, 8072.19278072194, 8076.8788468173025, 8079.272865442138, 7917.802054948615, 7984.321332686729, 7999.800004999864, 8006.599670016534, 8007.439479236456, 40053.39919901204, 40187.19625607471, 40435.39346909799, 40462.19075618471, 40609.79695101506, 3272.0, 3338.315645292709, 3347.2134048498583, 3626.9455958160647, 3942.448057271984, 29974.0, 30313.545296820565, 30700.539491907417, 30965.22586935322, 31908.840455797574, 3510.5085288059436, 3536.398812201932, 3556.893293201182, 3685.207680348738, 3736.252749450121, 248501.0, 250259.99999999817, 250405.0, 251580.0, 251634.99999999817, 55513.167302490496, 56629.43370566324, 56700.716496417255, 56719.716401418555, 56903.0, 33086.66913330884, 36277.45583816245, 36417.81791091028, 38463.807680961414, 38841.417378739345, 15717.764233536505, 15799.684006319809, 15828.683426331409, 15886.126263055412, 15894.443694470638, 11881.346525941157, 11973.46119424628, 11973.76052478963, 11978.401079945968, 11985.34080625574, 19773.60452790965, 19888.303909363392, 19944.401667949984, 19979.800201998085, 19990.400287991382, 7146.2496437874315, 7223.494355395072, 7234.529755565872, 7296.890546641805, 7385.519941203912, 857.2541888556871, 981.7349315684868, 1002.3534820041033, 1008.8738907636546, 1037.8946422060633, 29165.416691666473, 29311.413771724445, 29453.26366840846, 29714.0, 30031.549526757117, 997.0926456924165, 1003.5534187286734, 1029.848663090944, 1084.2139448899484, 1123.8426620273005, 15677.137757423223, 15760.448384306494, 15849.841501584837, 15923.44267950616, 16219.594510137342, 401279.0, 401674.0, 405871.0, 406164.0, 406558.0, 52878.0, 53233.733831330595, 53418.73290633522, 53528.73235633797, 53580.0, 1132.4677401621225, 1169.497116240025, 1246.5076294863659, 1266.639007882749, 1288.0854593238719, 28534.57198141988, 30639.693603064577, 30662.386752265276, 30703.54371650218, 31252.0, 44055.33916991184, 44686.77656611696, 44725.329120062575, 44818.55181448144, 44873.32690009718, 300353.0, 307266.0, 307791.0, 307867.0, 307880.0, 22958.770412295664, 24233.75766242315, 24318.027278908645, 26513.204603861916, 26964.865175673996, 36911.815440922626, 39323.0, 44491.555084448744, 45723.771381142884, 50276.49723502718, 36629.0, 38223.0, 38308.40310139385, 38761.418578720775, 39230.41154382744, 38597.22805543929, 38891.41662875003, 38978.80510597429, 39051.0, 39361.80319098386, 34798.652013480554, 37153.25693486061, 38123.80938095292, 38417.27122279448, 38453.230935381696, 31511.369772604878, 34474.31051378908, 34621.65378346184, 35085.82457087698, 35227.82386088156, 26108.73891261063, 26109.0, 26373.208803735917, 26448.0, 26563.33591660224], "value": 18037.419428488876, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.66032231314136, 383.82919600777655, 390.57622479609626, 403.5943876404217, 405.32513365246865, 15395.230238488073, 16984.320627174897, 17746.64506709865, 17777.377791777275, 17793.911030444862, 11131.443427828617, 11240.269382490133, 11248.043916267126, 11270.549178032868, 11784.882151178486, 4707.646926480519, 4819.831305904294, 4867.245576935581, 4877.902441951159, 4878.731669758162, 4606.677532572721, 4636.35091087248, 4637.443506779191, 4638.930416043762, 4639.188142075141, 4706.529347065283, 5148.124818780816, 5156.200788877712, 5158.632962265003, 5161.380634323887, 5055.51972562606, 5112.667676601009, 5118.462561431052, 5119.104156772569, 5119.3088932994315, 4595.586397224266, 4904.264360345941, 4906.877328066791, 4959.950400495985, 4961.255811628284, 11575.53697852085, 11823.88176118245, 11850.40747962607, 11910.761784764256, 12170.148089633729, 1845.5017145370675, 1874.2034635280088, 1923.0294136539046, 2046.3963130876427, 2226.866388016724, 9604.231661467065, 10676.733081672943, 11748.412579371083, 12268.386580670931, 13152.473901043946, 1733.1074496634244, 1750.8074111847686, 1774.8047714751367, 1790.8030116687155, 1797.8741488095839, 69289.65355173191, 71412.92880606846, 71527.92708109383, 71678.64160679215, 74139.25860741432, 9311.813763724824, 9526.571304291185, 10191.439470829175, 10347.895631310577, 10478.73803154912, 14414.13515189092, 14443.927780361031, 14491.710165796836, 14539.20034398097, 14556.927215363856, 7575.659095340723, 7628.427867909929, 7643.579603121771, 7747.767566973, 7852.489588176751, 7340.0457940467395, 7389.889151662729, 7539.095308563007, 7552.622368881534, 7559.584222867795, 4492.213862574102, 4557.156925968707, 4574.4968053514085, 4680.648951328664, 4683.320918466803, 1756.7804024496938, 1873.531617095726, 1909.8376637985648, 1926.951826204328, 1939.7478327817294, 848.6775025490398, 886.3263919421287, 934.4860326820221, 1002.9548670309854, 1047.1308813684775, 16681.91659041697, 16724.081446256896, 17709.822901770818, 17732.734008989875, 17854.125316841804, 880.4145243413186, 885.5882014862991, 918.0727465260136, 931.0, 954.4034978138664, 9735.561899714388, 10020.448875311782, 10062.69811905644, 10087.49562521871, 10125.088742013255, 354506.0, 355924.0, 358722.0, 365094.0, 371865.0, 52890.0, 72402.63798681078, 72440.63779681068, 75060.62469687726, 76964.61517692488, 916.5325683901125, 937.8827646544182, 942.5617088054088, 955.3742298794301, 968.8740463739668, 12326.630201093982, 15967.920160399124, 16055.357785688442, 16216.918915405819, 16480.42318518846, 36354.272914541034, 36462.8176859114, 36963.63036369709, 37272.62727372692, 37731.62268377281, 49683.751581241864, 50080.0, 50154.24768628547, 50487.24269136039, 50941.0, 7639.770806875803, 7701.3068823806125, 7853.0, 7918.564478953713, 8016.438849280495, 8559.486430814171, 11474.254173478697, 11760.529578816751, 11805.70485737829, 11894.167408281337, 10222.591096356062, 10277.84583231267, 10590.735231618963, 10592.0, 10593.364398136137, 6822.761203357859, 10226.846597300897, 10467.581296748045, 10570.524326405177, 10585.153187745116, 10653.307535010199, 10662.73343166427, 10678.679639610824, 10898.836517452086, 10908.94545527299, 7697.691392463406, 7759.68961241544, 7873.173316801883, 7953.642086106023, 7985.321247693779, 9435.622575096919, 9895.109440150422, 10519.737006574896, 10772.407517586607, 10837.566497340018], "value": 8056.665061050246, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1277.3868543099318, 1506.3296832909357, 1541.9614509637258, 1678.6894424531463, 1845.8615603829708, 7486.101667799865, 7745.0, 7766.0680718313815, 7859.506693728187, 7920.881186782203, 7830.177831327714, 7987.760367188994, 7992.440529162961, 8006.719764808218, 8026.879596806052, 2204.5370472200857, 2235.1282999630157, 2237.865728056314, 2239.3841693534246, 2239.462528993038, 2121.607502612014, 2142.185969331652, 2166.978330216701, 2174.5433458973635, 2177.3032629558547, 2145.9463513412134, 2159.8920053997317, 2183.159483598818, 2185.8142057925015, 2195.4182141732454, 2069.9482512937147, 2086.1446806809136, 2091.926782562603, 2091.985387087269, 2095.0, 2069.4929742213253, 2098.5383215692523, 2101.8738875667354, 2102.4218339956483, 2104.2004038465284, 7294.963525182393, 7440.330370266648, 7713.5757533335645, 7726.652300646484, 7757.069151701832, 4477.70894891831, 4604.884877878047, 4610.3315019322345, 4613.95386046142, 4617.399738034068, 2997.895073672411, 3000.1749518882266, 3021.2597913511113, 3047.238190452387, 3066.6166729158854, 885.3802338363107, 893.3746377535751, 907.3421769217331, 912.780932576177, 917.3028498341242, 10143.746406339827, 10218.489075546267, 10228.07947284748, 10249.795004099951, 10257.384556926609, 3900.1029763154634, 3990.2418540477215, 4000.7199496035, 4050.6556942660204, 4056.8377264909072, 8251.0, 8262.215089566482, 8268.181000180071, 8271.421000529905, 8291.792705182419, 4528.569785870337, 4530.546945305443, 4534.02518458533, 4536.77316134192, 4538.364628951884, 3739.9252014959548, 4137.213929353413, 4160.54234034262, 4240.639545638655, 4280.507741609693, 3379.391709492315, 3401.149712571857, 3471.7743346682387, 3489.581250249986, 3617.186133120027, 975.1516180922642, 984.522506584306, 987.3384832162468, 993.036754348281, 1000.3047881722279, 836.7029704454952, 849.723839752083, 896.6458248991743, 908.0919080919081, 920.9171174594185, 5276.551493123127, 5356.91964620531, 5480.91778623321, 5487.478689524489, 5606.0, 837.3552364679284, 855.9700410485603, 896.8968568614692, 914.8627705844177, 965.8454647256406, 3582.0686621478603, 3622.0, 3777.1501412182038, 3800.9429858552144, 3847.6537111660086, 9305.348625596278, 9578.281628877861, 9698.27262955267, 9702.563384647709, 9707.174890134276, 9601.423914565008, 9619.759506012406, 9619.951900240454, 9627.422354658744, 9634.518274086267, 7233.8553228935125, 7417.184109747924, 7422.777316680508, 7425.85148297031, 7427.220141885133, 5505.6696598204235, 5626.2685850839125, 5628.240187574625, 5668.773249069991, 5835.94164058354, 8437.95781021091, 8766.693165739169, 8851.247643950337, 8862.778430539289, 8874.334424918028, 8376.49741015541, 8664.610092545725, 8678.479291242546, 8775.517346546001, 8907.465552066897, 6594.934050659624, 6786.219584747817, 6797.25230224665, 7081.610511421921, 7122.6438678066925, 6900.585964842126, 6963.825904352229, 7150.96424517874, 7405.8148546286775, 7409.296116868889, 6815.488838337244, 6949.861002780017, 6959.269276726073, 7042.683079261343, 7077.044598979278, 6806.9319306808275, 6843.692033858389, 6857.759978400732, 6985.860282794214, 7013.649317534001, 6243.188385509855, 6262.0, 6297.433231009231, 6355.205599300088, 6370.331115232834, 4537.931931020971, 4802.927956080732, 4811.975940120277, 4848.539388758062, 4881.780319885543, 4870.439899411613, 4874.780634871368, 4877.756112194447, 4945.085159245551, 4967.652264341461], "value": 4130.742104573658, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.38922155688624, 307.1951487103788, 310.07751937984483, 312.2770785631262, 315.14909743692033, 4947.208446648537, 4963.305137280775, 4986.828095397584, 4997.625178111638, 5036.1438555445575, 4843.273508973656, 4857.53853383928, 4894.388201474816, 4902.852914412573, 4914.483979182188, 3669.6146904575035, 3746.850125994957, 3793.658570728634, 3846.1538461538416, 3895.474110995008, 3815.294170578452, 3873.9806300968457, 3876.9338431931305, 3878.437626544149, 3882.6893848492055, 3676.3933950898067, 3854.0, 3860.9382419834487, 3861.092643228839, 3887.8142166639163, 3725.105974566112, 3822.120912190212, 3826.8469261229516, 3837.6546110850018, 3837.961620383788, 3409.573803274591, 3414.23179784549, 3417.9487307690406, 3419.9316013679836, 3423.828808559587, 4702.976485117552, 4728.574428301435, 4731.077439899221, 4732.550407711262, 4737.786799594027, 4319.0, 4393.670474714378, 4400.317950717644, 4437.489688685778, 4437.7781110944325, 5128.358955130609, 5154.561862241714, 5159.0, 5170.508801663836, 5182.585393168538, 3734.5705243896755, 3740.3267411865854, 3750.3811871041244, 3777.7544459610035, 3780.8487660493547, 5140.460251673558, 5321.946780532223, 5373.274607927958, 5490.286262785852, 5498.927709096688, 3790.422512691396, 3837.136644255076, 3839.577646458887, 3847.6729477994686, 3849.9615003850163, 3895.7272990890365, 3907.433422153772, 3908.4332771747936, 3921.431392448136, 3936.586658400884, 4300.8709738707885, 4396.384506169139, 4410.184115938561, 4413.911721765611, 4417.337399390053, 5135.87160320995, 5148.639595228298, 5148.716820574829, 5156.9742151289, 5165.251038599457, 1322.543722415765, 1337.6923307639297, 1342.3020029584757, 1350.4193196925416, 1357.6470117769452, 874.4490970688425, 887.6493784954906, 930.5254320296563, 952.6379975609224, 955.7132860141929, 4488.743151917468, 4491.236489796743, 4498.527654596221, 4508.864734057984, 4515.525869783691, 5195.350581177353, 5215.765290561933, 5247.291615631917, 5296.496832800878, 5317.899429725237, 4360.319901873028, 4381.671374646914, 4444.2444784386735, 4446.5553444655925, 4448.332750087513, 2781.2907708534194, 2785.261905595019, 2787.038471727251, 2794.790390720704, 2814.887404503838, 4937.950750438599, 4954.925676114861, 4957.537526429712, 5000.449950505442, 5000.699958002531, 5174.97412512935, 5391.838244852661, 5396.595255355864, 5501.587380946365, 5524.309461317335, 5001.69921835945, 5030.0, 5042.596592272683, 5068.873278168002, 5083.644144909821, 5284.867878303073, 5294.920576191437, 5380.623356365017, 5391.622586418913, 5411.458854114557, 5190.325257716473, 5204.34945631796, 5259.47405259471, 5262.815801446931, 5267.209918512254, 4999.125153098266, 5014.272930425141, 5014.598832093497, 5018.315201367251, 5028.823991160292, 3685.262947410475, 3687.8893633191046, 3720.4419337099653, 3733.4026555750947, 3748.9690335158048, 5195.0, 5376.381716102698, 5388.892222155457, 5433.1035379161995, 5455.972720136374, 5127.897442051213, 5164.7417629119145, 5167.354080739908, 5191.428942816212, 5222.268882356397, 5169.405518365285, 5183.0, 5190.688558686491, 5199.142141546754, 5214.713190774544, 4878.853469434609, 4913.828016019422, 5099.3370861787735, 5118.027574760784, 5120.719820044988, 5066.949330506648, 5088.7201203932655, 5096.796128154981, 5102.413222479462, 5113.41195762492, 4943.530364615356, 5042.949570504395, 5066.164082926276, 5100.387953445462, 5103.3414140403775], "value": 3901.506528676601, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.3883674111667, 684.7466437418155, 685.4584877946422, 689.7689274093178, 764.9579273139976, 12192.756144877108, 12808.871911280896, 12811.103222774409, 12814.231146131238, 12820.80768788469, 12737.745245095093, 12999.480020799158, 13007.674808129801, 13055.347232638378, 13060.216387016762, 892.8526793079151, 899.4648184330313, 905.7509184974152, 908.6910450446846, 908.7773495493614, 674.8953912143626, 846.86026805577, 889.7464222696533, 894.713691618679, 900.9639614415415, 930.432436213909, 930.4603330068578, 931.3108299858123, 932.2495391210073, 932.4451951089092, 924.4684306523742, 924.7826760711228, 925.3430064654103, 925.9351845370793, 926.235855419275, 516.6099594805935, 535.130413078747, 536.0297860871834, 536.0404875273256, 669.4242951062083, 12907.096503244777, 13204.603861884063, 13282.933585332108, 13290.401931913088, 13293.601191964257, 12142.635720928387, 12375.257484550866, 12479.251244925243, 12664.430100645493, 12731.427085781163, 10961.232713710044, 11127.332360058423, 11188.0, 11240.381779002151, 11817.94091029548, 4235.2588297048205, 4239.682023848224, 4284.421603083575, 4307.483102027777, 4368.235558777233, 47747.76126119382, 47774.76112619449, 50272.49727502715, 50405.49594504081, 50553.74723126361, 10013.749656258506, 10162.288639795144, 10436.791264174673, 10520.421376824348, 10529.84205236922, 18747.062646867602, 18803.0, 18833.246670133314, 18835.340763073502, 18963.146658400405, 6961.373476387048, 7043.859122817617, 7047.224805271519, 7151.320624540765, 7216.134063912364, 3775.1505911169766, 3889.086064774776, 3917.8628747994253, 3947.92104157921, 4004.59954004603, 2760.1305588739483, 2840.6449193850767, 2842.4030953499996, 2843.2749648839413, 2844.8293102413922, 499.7825945713626, 508.8727818045489, 510.0767610624781, 511.5421697580719, 519.0345956520869, 559.4796838939825, 569.7037540478927, 572.5391060196498, 580.3934888042006, 590.5777369181056, 7975.441719079725, 8048.59757012147, 8062.435629505995, 8129.918700813035, 8230.42387032914, 495.43767823519846, 528.6775067208999, 575.3670961941914, 580.7415700013454, 584.3280227738135, 3709.944350834686, 3780.092777733324, 3811.485449464287, 3814.1036856338737, 3830.272248272875, 46845.297320540216, 48329.0, 48609.78475538069, 57944.71027644835, 59666.0, 48512.514874851506, 49719.00561988812, 49725.50274497281, 49993.500064999615, 50261.74869125631, 45298.0, 46310.536894631296, 46718.76640616775, 49667.50332496629, 50816.0, 8054.718084867236, 8283.337333013465, 8721.956390218009, 8838.734837954871, 8913.955430223066, 30623.693763062085, 30968.0, 31478.213044674067, 31876.840615796773, 31987.0, 38895.0, 40602.39096413497, 40846.0, 40952.590474094875, 41134.794326028175, 11352.432378381212, 11420.0, 11515.94242028818, 11526.250793698382, 11918.0, 11837.881621183678, 12156.817647735468, 12256.325902075472, 12257.509699611915, 12521.186122901981, 11172.38551879622, 11324.943375283401, 11572.838015200796, 12067.577634783092, 12239.0, 8192.590370481572, 10773.299735517166, 11698.2981021139, 11767.117466189899, 11958.162928595262, 11277.855378206921, 11453.770924581295, 11550.48022838991, 11666.650000499998, 11774.234674746114, 9766.46284454362, 9994.100530951959, 10170.542325595217, 10303.175745940156, 10340.172786177238, 9964.202863771026, 10363.170946324126, 10363.481825908826, 10526.631567895087, 10563.471826408495], "value": 6216.601672995804, "unit": "score"}], "name": "dromaeo_css", "value": 6380.697655533749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 110.0, 115.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 130.0, 156.0, 220.0, 140.0, 166.0, 152.0, 133.0, 196.0, 135.0], "value": 158.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 162.0, 162.0, 163.0, 162.0, 164.0, 163.0, 161.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 155.0, 75.0, 75.0, 77.0, 76.0, 131.0, 76.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 147.0, 93.0, 92.0, 94.0, 134.0, 92.0, 92.0, 125.0], "value": 105.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 64.0, 64.0, 65.0, 63.0, 115.0, 61.0, 61.0], "value": 68.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 69.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 81.0, 85.0, 90.0, 86.0, 85.0, 91.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 142.0, 127.0, 131.0, 131.0, 130.0, 132.0, 256.0, 141.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 172.0, 173.0, 173.0, 174.0, 170.0, 176.0, 167.0, 168.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 67.0, 68.0, 69.0, 65.0, 66.0, 65.0, 65.0, 64.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1588.1000000000001, "alertThreshold": 2.0}]} 06:49:52 INFO - Return code: 0 06:49:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:49:52 ERROR - # TBPL SUCCESS # 06:49:52 INFO - Running post-action listener: _resource_record_post_action 06:49:52 INFO - ##### 06:49:52 INFO - ##### Finished run-tests step (success) 06:49:52 INFO - ##### 06:49:52 INFO - Running post-run listener: _resource_record_post_run 06:49:52 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536256512; Read time: 16; Write time: 555360 06:49:52 INFO - TinderboxPrint: CPU usage
13.1% 06:49:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 06:49:52 INFO - TinderboxPrint: I/O write bytes / time
536,256,512 / 555,360 06:49:52 INFO - TinderboxPrint: CPU idle
8,327.7 (86.9%) 06:49:52 INFO - TinderboxPrint: CPU user
1,213.0 (12.7%) 06:49:52 INFO - TinderboxPrint: Swap in / out
0 / 0 06:49:52 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 243302400; Read time: 0; Write time: 374212 06:49:52 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292954112; Read time: 16; Write time: 181148 06:49:52 INFO - Running post-run listener: _upload_blobber_files 06:49:52 INFO - Blob upload gear active. 06:49:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:49:52 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 06:49:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:49:53 INFO - (blobuploader) - INFO - Open directory for files ... 06:49:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:49:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:49:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:54 INFO - (blobuploader) - INFO - Done attempting. 06:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:58 INFO - (blobuploader) - INFO - Done attempting. 06:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:58 INFO - (blobuploader) - INFO - Done attempting. 06:49:58 INFO - (blobuploader) - INFO - Iteration through files over. 06:49:58 INFO - Return code: 0 06:49:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:49:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:49:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b094600a6f74c639244940fe2de8c69053ae3129b70044e6ad8ba6aba3c85b79beffdf10b7711800a4a5ec4b25788418888486d0b6e499e3a784afdace64b644", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4c12bf70c8bd01b8dc8a19b971a1e6e3ef919d1a29fb2b2e1e629b2960c7283d8f32bfcea4343c31b7695e027c0f888daac59008a19641b86f48a1a7e5bf1551", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb63f1c2b6cc42fffebc5143041dc86ee14ae4480e5d1c4b5779f3fcbdb3647a8198fd14d906dd39fc8cb52e8fdf14fe0f1a41186696759744674e767bf7189b"} 06:49:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:49:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:49:58 INFO - Contents: 06:49:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b094600a6f74c639244940fe2de8c69053ae3129b70044e6ad8ba6aba3c85b79beffdf10b7711800a4a5ec4b25788418888486d0b6e499e3a784afdace64b644", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4c12bf70c8bd01b8dc8a19b971a1e6e3ef919d1a29fb2b2e1e629b2960c7283d8f32bfcea4343c31b7695e027c0f888daac59008a19641b86f48a1a7e5bf1551", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb63f1c2b6cc42fffebc5143041dc86ee14ae4480e5d1c4b5779f3fcbdb3647a8198fd14d906dd39fc8cb52e8fdf14fe0f1a41186696759744674e767bf7189b"} 06:49:58 INFO - Running post-run listener: copy_logs_to_upload_dir 06:49:58 INFO - Copying logs to upload dir... 06:49:58 INFO - mkdir: /builds/slave/test/build/upload/logs 06:49:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.048330 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-17 06:49:58.019217) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 06:49:58.022851) ========= 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=cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b094600a6f74c639244940fe2de8c69053ae3129b70044e6ad8ba6aba3c85b79beffdf10b7711800a4a5ec4b25788418888486d0b6e499e3a784afdace64b644", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4c12bf70c8bd01b8dc8a19b971a1e6e3ef919d1a29fb2b2e1e629b2960c7283d8f32bfcea4343c31b7695e027c0f888daac59008a19641b86f48a1a7e5bf1551", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb63f1c2b6cc42fffebc5143041dc86ee14ae4480e5d1c4b5779f3fcbdb3647a8198fd14d906dd39fc8cb52e8fdf14fe0f1a41186696759744674e767bf7189b"} build_url:https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/Rj5daoaaSpayzW5KoldWqQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b094600a6f74c639244940fe2de8c69053ae3129b70044e6ad8ba6aba3c85b79beffdf10b7711800a4a5ec4b25788418888486d0b6e499e3a784afdace64b644", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4c12bf70c8bd01b8dc8a19b971a1e6e3ef919d1a29fb2b2e1e629b2960c7283d8f32bfcea4343c31b7695e027c0f888daac59008a19641b86f48a1a7e5bf1551", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb63f1c2b6cc42fffebc5143041dc86ee14ae4480e5d1c4b5779f3fcbdb3647a8198fd14d906dd39fc8cb52e8fdf14fe0f1a41186696759744674e767bf7189b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 06:49:58.046554) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 06:49:58.046844) ========= 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=cbe409c6de289db6ac707de3000001d4-1463491751.578475-522551028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004386 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 06:49:58.129885) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 06:49:58.130172) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 06:49:58.130452) ========= ========= Total master_lag: 0.15 =========