builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1463379470.76 results: success (0) buildid: 20160515203030 builduid: 02e0608df6234392a70b43c1c3665b4f revision: 7084bd828e4c688daf6078a710d2f267bb52591a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:50.762136) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:50.762584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:50.762872) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:50.830414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:50.830718) ========= 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=5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-15 23:17:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-05-15 23:17:51 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.436902 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:51.293607) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:51.293934) ========= 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=5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.130772 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:51.438042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-15 23:17:51.438628) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7084bd828e4c688daf6078a710d2f267bb52591a --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 7084bd828e4c688daf6078a710d2f267bb52591a --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=5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-15 23:17:51,676 truncating revision to first 12 chars 2016-05-15 23:17:51,676 Setting DEBUG logging. 2016-05-15 23:17:51,676 attempt 1/10 2016-05-15 23:17:51,676 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7084bd828e4c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-15 23:17:52,457 unpacking tar archive at: fx-team-7084bd828e4c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.054780 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-15 23:17:52.506771) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:52.507108) ========= script_repo_revision: 7084bd828e4c688daf6078a710d2f267bb52591a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:52.507524) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:52.507837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-15 23:17:52.516518) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-05-15 23:17:52.516934) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:17:52 INFO - MultiFileLogger online at 20160515 23:17:52 in /builds/slave/test 23:17:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:17:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:17:52 INFO - {'append_to_log': False, 23:17:52 INFO - 'base_work_dir': '/builds/slave/test', 23:17:52 INFO - 'blob_upload_branch': 'Fx-Team', 23:17:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:17:52 INFO - 'branch': 'Fx-Team', 23:17:52 INFO - 'buildbot_json_path': 'buildprops.json', 23:17:52 INFO - 'config_files': ('talos/linux_config.py',), 23:17:52 INFO - 'default_actions': ('clobber', 23:17:52 INFO - 'read-buildbot-config', 23:17:52 INFO - 'download-and-extract', 23:17:52 INFO - 'populate-webroot', 23:17:52 INFO - 'create-virtualenv', 23:17:52 INFO - 'install', 23:17:52 INFO - 'run-tests'), 23:17:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:17:52 INFO - 'download_minidump_stackwalk': True, 23:17:52 INFO - 'download_symbols': 'ondemand', 23:17:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:17:52 INFO - 'tooltool.py': '/tools/tooltool.py', 23:17:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:17:52 INFO - '/tools/misc-python/virtualenv.py')}, 23:17:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:17:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:17:53 INFO - 'installer_path': 'installer.exe', 23:17:53 INFO - 'log_level': 'info', 23:17:53 INFO - 'log_name': 'talos', 23:17:53 INFO - 'log_to_console': True, 23:17:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:17:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:17:53 INFO - 'opt_config_files': (), 23:17:53 INFO - 'pip_index': False, 23:17:53 INFO - 'sps_profile': False, 23:17:53 INFO - 'sps_profile_interval': 0, 23:17:53 INFO - 'suite': 'dromaeojs', 23:17:53 INFO - 'system_bits': '32', 23:17:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:17:53 INFO - 'title': 'talos-linux64-ix-011', 23:17:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:17:53 INFO - 'use_talos_json': True, 23:17:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:17:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:17:53 INFO - 'work_dir': 'build'} 23:17:53 INFO - ##### 23:17:53 INFO - ##### Running clobber step. 23:17:53 INFO - ##### 23:17:53 INFO - Running pre-action listener: _resource_record_pre_action 23:17:53 INFO - Running main action method: clobber 23:17:53 INFO - rmtree: /builds/slave/test/build 23:17:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:17:54 INFO - Running post-action listener: _resource_record_post_action 23:17:54 INFO - ##### 23:17:54 INFO - ##### Finished clobber step (success) 23:17:54 INFO - ##### 23:17:54 INFO - ##### 23:17:54 INFO - ##### Running read-buildbot-config step. 23:17:54 INFO - ##### 23:17:54 INFO - Running pre-action listener: _resource_record_pre_action 23:17:54 INFO - Running main action method: read_buildbot_config 23:17:54 INFO - Using buildbot properties: 23:17:54 INFO - { 23:17:54 INFO - "project": "", 23:17:54 INFO - "product": "firefox", 23:17:54 INFO - "script_repo_revision": "production", 23:17:54 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:17:54 INFO - "repository": "", 23:17:54 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:17:54 INFO - "buildid": "20160515203030", 23:17:54 INFO - "pgo_build": "True", 23:17:54 INFO - "basedir": "/builds/slave/test", 23:17:54 INFO - "buildnumber": 1155, 23:17:54 INFO - "slavename": "talos-linux64-ix-011", 23:17:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:17:54 INFO - "platform": "ubuntu64_hw", 23:17:54 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:17:54 INFO - "branch": "fx-team", 23:17:54 INFO - "repo_path": "integration/fx-team", 23:17:54 INFO - "revision": "7084bd828e4c688daf6078a710d2f267bb52591a", 23:17:54 INFO - "stage_platform": "linux64-pgo", 23:17:54 INFO - "builduid": "02e0608df6234392a70b43c1c3665b4f", 23:17:54 INFO - "slavebuilddir": "test" 23:17:54 INFO - } 23:17:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:17:54 INFO - Running post-action listener: _resource_record_post_action 23:17:54 INFO - ##### 23:17:54 INFO - ##### Finished read-buildbot-config step (success) 23:17:54 INFO - ##### 23:17:54 INFO - ##### 23:17:54 INFO - ##### Running download-and-extract step. 23:17:54 INFO - ##### 23:17:54 INFO - Running pre-action listener: _resource_record_pre_action 23:17:54 INFO - Running main action method: download_and_extract 23:17:54 INFO - mkdir: /builds/slave/test/build/tests 23:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:54 INFO - https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:17:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:17:55 INFO - Downloaded 1361 bytes. 23:17:55 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:17:55 INFO - Using the following test package requirements: 23:17:55 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:55 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:17:55 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:17:55 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:55 INFO - u'jsshell-linux-x86_64.zip'], 23:17:55 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:17:55 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:55 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:17:55 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:17:55 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:17:55 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:17:55 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:55 INFO - https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:17:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:17:56 INFO - Downloaded 24023288 bytes. 23:17:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:17:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:17:58 INFO - Return code: 0 23:17:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:58 INFO - https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:17:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:18:02 INFO - Downloaded 24023288 bytes. 23:18:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:18:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:18:04 INFO - Return code: 0 23:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:04 INFO - https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:18:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:18:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:18:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:18:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:18:06 INFO - Downloaded 10840194 bytes. 23:18:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:18:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:18:07 INFO - Return code: 0 23:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:07 INFO - https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:18:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:18:08 INFO - Downloaded 58394741 bytes. 23:18:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:08 INFO - mkdir: /builds/slave/test/properties 23:18:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:18:08 INFO - Writing to file /builds/slave/test/properties/build_url 23:18:08 INFO - Contents: 23:18:08 INFO - build_url:https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:18:09 INFO - Running post-action listener: _resource_record_post_action 23:18:09 INFO - Running post-action listener: set_extra_try_arguments 23:18:09 INFO - ##### 23:18:09 INFO - ##### Finished download-and-extract step (success) 23:18:09 INFO - ##### 23:18:09 INFO - ##### 23:18:09 INFO - ##### Running populate-webroot step. 23:18:09 INFO - ##### 23:18:09 INFO - Running pre-action listener: _resource_record_pre_action 23:18:09 INFO - Running main action method: populate_webroot 23:18:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:18:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:18:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:18:09 INFO - '--tppagecycles', 23:18:09 INFO - '7'], 23:18:09 INFO - 'tests': ['tsvgm']}}, 23:18:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:18:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:18:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:18:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:18:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:09 INFO - 'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:18:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:18:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:09 INFO - 'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['damp', 'tps']}, 23:18:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:09 INFO - 'tests': ['damp', 'tps']}, 23:18:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['dromaeo_dom']}, 23:18:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:18:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['a11yr', 23:18:09 INFO - 'ts_paint', 23:18:09 INFO - 'tpaint', 23:18:09 INFO - 'sessionrestore', 23:18:09 INFO - 'sessionrestore_no_auto_restore', 23:18:09 INFO - 'tabpaint']}, 23:18:09 INFO - 'other-e10s': {'tests': ['a11yr', 23:18:09 INFO - 'ts_paint', 23:18:09 INFO - 'tpaint', 23:18:09 INFO - 'sessionrestore', 23:18:09 INFO - 'sessionrestore_no_auto_restore', 23:18:09 INFO - 'tabpaint']}, 23:18:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:18:09 INFO - 'ts_paint', 23:18:09 INFO - 'tpaint', 23:18:09 INFO - 'sessionrestore', 23:18:09 INFO - 'sessionrestore_no_auto_restore', 23:18:09 INFO - 'tabpaint']}, 23:18:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:18:09 INFO - 'ts_paint', 23:18:09 INFO - 'tpaint', 23:18:09 INFO - 'sessionrestore', 23:18:09 INFO - 'sessionrestore_no_auto_restore', 23:18:09 INFO - 'tabpaint']}, 23:18:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['a11yr', 23:18:09 INFO - 'ts_paint', 23:18:09 INFO - 'tpaint', 23:18:09 INFO - 'sessionrestore', 23:18:09 INFO - 'sessionrestore_no_auto_restore', 23:18:09 INFO - 'tabpaint']}, 23:18:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['a11yr', 23:18:09 INFO - 'ts_paint', 23:18:09 INFO - 'tpaint', 23:18:09 INFO - 'sessionrestore', 23:18:09 INFO - 'sessionrestore_no_auto_restore', 23:18:09 INFO - 'tabpaint']}, 23:18:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['tsvgx', 23:18:09 INFO - 'tsvgr_opacity', 23:18:09 INFO - 'tart', 23:18:09 INFO - 'tscrollx', 23:18:09 INFO - 'cart']}, 23:18:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:18:09 INFO - 'tsvgr_opacity', 23:18:09 INFO - 'tart', 23:18:09 INFO - 'tscrollx', 23:18:09 INFO - 'cart']}, 23:18:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:09 INFO - 'talos_options': ['--disable-e10s'], 23:18:09 INFO - 'tests': ['tp5o']}, 23:18:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:09 INFO - 'tests': ['tp5o']}, 23:18:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:09 INFO - 'talos_options': ['--disable-e10s', 23:18:09 INFO - '--xperf_path', 23:18:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:18:09 INFO - 'tests': ['tp5n']}, 23:18:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:09 INFO - 'talos_options': ['--xperf_path', 23:18:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:18:09 INFO - 'tests': ['tp5n']}}, 23:18:09 INFO - 'talos.zip': {'path': '', 23:18:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:18:09 INFO - Running post-action listener: _resource_record_post_action 23:18:09 INFO - ##### 23:18:09 INFO - ##### Finished populate-webroot step (success) 23:18:09 INFO - ##### 23:18:09 INFO - ##### 23:18:09 INFO - ##### Running create-virtualenv step. 23:18:09 INFO - ##### 23:18:09 INFO - Running pre-action listener: _resource_record_pre_action 23:18:09 INFO - Running main action method: create_virtualenv 23:18:09 INFO - Creating virtualenv /builds/slave/test/build/venv 23:18:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:18:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:18:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:18:09 INFO - Using real prefix '/usr' 23:18:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:18:10 INFO - Installing distribute.............................................................................................................................................................................................done. 23:18:12 INFO - Installing pip.................done. 23:18:12 INFO - Return code: 0 23:18:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:12 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:18:12 INFO - Using env: {'DISPLAY': ':0', 23:18:12 INFO - 'HOME': '/home/cltbld', 23:18:12 INFO - 'LANG': 'en_US.UTF-8', 23:18:12 INFO - 'LANGUAGE': 'en_US:en', 23:18:12 INFO - 'LOGNAME': 'cltbld', 23:18:12 INFO - 'MAIL': '/var/mail/cltbld', 23:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:12 INFO - 'MOZ_NO_REMOTE': '1', 23:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:12 INFO - 'NO_EM_RESTART': '1', 23:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:12 INFO - 'PWD': '/builds/slave/test', 23:18:12 INFO - 'SHELL': '/bin/bash', 23:18:12 INFO - 'SHLVL': '1', 23:18:12 INFO - 'TERM': 'linux', 23:18:12 INFO - 'TMOUT': '86400', 23:18:12 INFO - 'USER': 'cltbld', 23:18:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:12 INFO - '_': '/tools/buildbot/bin/python'} 23:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:18:13 INFO - Cleaning up... 23:18:13 INFO - Return code: 0 23:18:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:13 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:18:13 INFO - Using env: {'DISPLAY': ':0', 23:18:13 INFO - 'HOME': '/home/cltbld', 23:18:13 INFO - 'LANG': 'en_US.UTF-8', 23:18:13 INFO - 'LANGUAGE': 'en_US:en', 23:18:13 INFO - 'LOGNAME': 'cltbld', 23:18:13 INFO - 'MAIL': '/var/mail/cltbld', 23:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:13 INFO - 'MOZ_NO_REMOTE': '1', 23:18:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:13 INFO - 'NO_EM_RESTART': '1', 23:18:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:13 INFO - 'PWD': '/builds/slave/test', 23:18:13 INFO - 'SHELL': '/bin/bash', 23:18:13 INFO - 'SHLVL': '1', 23:18:13 INFO - 'TERM': 'linux', 23:18:13 INFO - 'TMOUT': '86400', 23:18:13 INFO - 'USER': 'cltbld', 23:18:13 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:13 INFO - '_': '/tools/buildbot/bin/python'} 23:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:13 INFO - Downloading/unpacking psutil>=3.1.1 23:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:18:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:18:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:18:15 INFO - Installing collected packages: psutil 23:18:15 INFO - Running setup.py install for psutil 23:18:15 INFO - building 'psutil._psutil_linux' extension 23:18:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 23:18:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:18:16 INFO - building 'psutil._psutil_posix' extension 23:18:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:18:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:18:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:18:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:18:17 INFO - Successfully installed psutil 23:18:17 INFO - Cleaning up... 23:18:17 INFO - Return code: 0 23:18:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:18: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')]} 23:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18: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')]} 23:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:17 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:18:17 INFO - Using env: {'DISPLAY': ':0', 23:18:17 INFO - 'HOME': '/home/cltbld', 23:18:17 INFO - 'LANG': 'en_US.UTF-8', 23:18:17 INFO - 'LANGUAGE': 'en_US:en', 23:18:17 INFO - 'LOGNAME': 'cltbld', 23:18:17 INFO - 'MAIL': '/var/mail/cltbld', 23:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:17 INFO - 'MOZ_NO_REMOTE': '1', 23:18:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:17 INFO - 'NO_EM_RESTART': '1', 23:18:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:17 INFO - 'PWD': '/builds/slave/test', 23:18:17 INFO - 'SHELL': '/bin/bash', 23:18:17 INFO - 'SHLVL': '1', 23:18:17 INFO - 'TERM': 'linux', 23:18:17 INFO - 'TMOUT': '86400', 23:18:17 INFO - 'USER': 'cltbld', 23:18:17 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:17 INFO - '_': '/tools/buildbot/bin/python'} 23:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:18:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:18:19 INFO - Installing collected packages: mozsystemmonitor 23:18:19 INFO - Running setup.py install for mozsystemmonitor 23:18:19 INFO - Successfully installed mozsystemmonitor 23:18:19 INFO - Cleaning up... 23:18:19 INFO - Return code: 0 23:18:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:18: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')]} 23:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18: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')]} 23:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:19 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:18:19 INFO - Using env: {'DISPLAY': ':0', 23:18:19 INFO - 'HOME': '/home/cltbld', 23:18:19 INFO - 'LANG': 'en_US.UTF-8', 23:18:19 INFO - 'LANGUAGE': 'en_US:en', 23:18:19 INFO - 'LOGNAME': 'cltbld', 23:18:19 INFO - 'MAIL': '/var/mail/cltbld', 23:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:19 INFO - 'MOZ_NO_REMOTE': '1', 23:18:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:19 INFO - 'NO_EM_RESTART': '1', 23:18:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:19 INFO - 'PWD': '/builds/slave/test', 23:18:19 INFO - 'SHELL': '/bin/bash', 23:18:19 INFO - 'SHLVL': '1', 23:18:19 INFO - 'TERM': 'linux', 23:18:19 INFO - 'TMOUT': '86400', 23:18:19 INFO - 'USER': 'cltbld', 23:18:19 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:19 INFO - '_': '/tools/buildbot/bin/python'} 23:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:19 INFO - Downloading/unpacking blobuploader==1.2.4 23:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:21 INFO - Downloading blobuploader-1.2.4.tar.gz 23:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:18:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:18:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:22 INFO - Downloading docopt-0.6.1.tar.gz 23:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:18:22 INFO - Installing collected packages: blobuploader, requests, docopt 23:18:22 INFO - Running setup.py install for blobuploader 23:18:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:18:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:18:22 INFO - Running setup.py install for requests 23:18:22 INFO - Running setup.py install for docopt 23:18:22 INFO - Successfully installed blobuploader requests docopt 23:18:22 INFO - Cleaning up... 23:18:22 INFO - Return code: 0 23:18:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:22 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:18:22 INFO - Using env: {'DISPLAY': ':0', 23:18:22 INFO - 'HOME': '/home/cltbld', 23:18:22 INFO - 'LANG': 'en_US.UTF-8', 23:18:22 INFO - 'LANGUAGE': 'en_US:en', 23:18:22 INFO - 'LOGNAME': 'cltbld', 23:18:22 INFO - 'MAIL': '/var/mail/cltbld', 23:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:22 INFO - 'MOZ_NO_REMOTE': '1', 23:18:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:22 INFO - 'NO_EM_RESTART': '1', 23:18:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:22 INFO - 'PWD': '/builds/slave/test', 23:18:22 INFO - 'SHELL': '/bin/bash', 23:18:22 INFO - 'SHLVL': '1', 23:18:22 INFO - 'TERM': 'linux', 23:18:22 INFO - 'TMOUT': '86400', 23:18:22 INFO - 'USER': 'cltbld', 23:18:22 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:22 INFO - '_': '/tools/buildbot/bin/python'} 23:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:18:23 INFO - Running setup.py (path:/tmp/pip-njiFzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:18:23 INFO - Running setup.py (path:/tmp/pip-ZnUZ_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:18:23 INFO - Running setup.py (path:/tmp/pip-oOrF7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:18:23 INFO - Running setup.py (path:/tmp/pip-pET8lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:18:23 INFO - Running setup.py (path:/tmp/pip-bM3vQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:18:23 INFO - Running setup.py (path:/tmp/pip-yXhDBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:18:23 INFO - Running setup.py (path:/tmp/pip-VAHBcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:18:23 INFO - Running setup.py (path:/tmp/pip-pzu2ET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:18:23 INFO - Running setup.py (path:/tmp/pip-WlFqEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:18:23 INFO - Running setup.py (path:/tmp/pip-o_oOP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:18:23 INFO - Running setup.py (path:/tmp/pip-d8FpRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:18:23 INFO - Running setup.py (path:/tmp/pip-Pc5dD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:18:23 INFO - Running setup.py (path:/tmp/pip-gbDG1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:18:24 INFO - Running setup.py (path:/tmp/pip-A6IaKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:24 INFO - Running setup.py (path:/tmp/pip-qcquug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:18:24 INFO - Running setup.py (path:/tmp/pip-oNQw7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:18:24 INFO - Running setup.py (path:/tmp/pip-iwNEOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:18:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:18:24 INFO - Running setup.py install for manifestparser 23:18:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:18:24 INFO - Running setup.py install for mozcrash 23:18:24 INFO - Running setup.py install for mozdebug 23:18:24 INFO - Running setup.py install for mozdevice 23:18:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:18:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:18:24 INFO - Running setup.py install for mozfile 23:18:24 INFO - Running setup.py install for mozhttpd 23:18:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:18:24 INFO - Running setup.py install for mozinfo 23:18:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Running setup.py install for mozInstall 23:18:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Running setup.py install for mozleak 23:18:25 INFO - Running setup.py install for mozlog 23:18:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Running setup.py install for moznetwork 23:18:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Running setup.py install for mozprocess 23:18:25 INFO - Running setup.py install for mozprofile 23:18:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Running setup.py install for mozrunner 23:18:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:18:25 INFO - Running setup.py install for mozscreenshot 23:18:25 INFO - Running setup.py install for moztest 23:18:26 INFO - Running setup.py install for mozversion 23:18:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:18:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:18:26 INFO - Cleaning up... 23:18:26 INFO - Return code: 0 23:18:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:26 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:18:26 INFO - Using env: {'DISPLAY': ':0', 23:18:26 INFO - 'HOME': '/home/cltbld', 23:18:26 INFO - 'LANG': 'en_US.UTF-8', 23:18:26 INFO - 'LANGUAGE': 'en_US:en', 23:18:26 INFO - 'LOGNAME': 'cltbld', 23:18:26 INFO - 'MAIL': '/var/mail/cltbld', 23:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:26 INFO - 'MOZ_NO_REMOTE': '1', 23:18:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:26 INFO - 'NO_EM_RESTART': '1', 23:18:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:26 INFO - 'PWD': '/builds/slave/test', 23:18:26 INFO - 'SHELL': '/bin/bash', 23:18:26 INFO - 'SHLVL': '1', 23:18:26 INFO - 'TERM': 'linux', 23:18:26 INFO - 'TMOUT': '86400', 23:18:26 INFO - 'USER': 'cltbld', 23:18:26 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:26 INFO - '_': '/tools/buildbot/bin/python'} 23:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:18:26 INFO - Running setup.py (path:/tmp/pip-UqalCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:18:26 INFO - Running setup.py (path:/tmp/pip-d9xBw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:18:26 INFO - Running setup.py (path:/tmp/pip-QYmdlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:18:26 INFO - Running setup.py (path:/tmp/pip-jAJqlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:18:26 INFO - Running setup.py (path:/tmp/pip-8l1ikv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:18:26 INFO - Running setup.py (path:/tmp/pip-aL_W45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:18:26 INFO - Running setup.py (path:/tmp/pip-P4NC3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:18:26 INFO - Running setup.py (path:/tmp/pip-1P_J7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:18:26 INFO - Running setup.py (path:/tmp/pip-PkvLPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:18:27 INFO - Running setup.py (path:/tmp/pip-kpXrGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:18:27 INFO - Running setup.py (path:/tmp/pip-1LOyLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:18:27 INFO - Running setup.py (path:/tmp/pip-AYn5rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:18:27 INFO - Running setup.py (path:/tmp/pip-SOygJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:18:27 INFO - Running setup.py (path:/tmp/pip-HF6x9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:27 INFO - Running setup.py (path:/tmp/pip-hM185R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:18:27 INFO - Running setup.py (path:/tmp/pip-gLJKNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:18:27 INFO - Running setup.py (path:/tmp/pip-GmX1fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:29 INFO - Downloading blessings-1.6.tar.gz 23:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:18:29 INFO - Installing collected packages: blessings 23:18:29 INFO - Running setup.py install for blessings 23:18:29 INFO - Successfully installed blessings 23:18:29 INFO - Cleaning up... 23:18:29 INFO - Return code: 0 23:18:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:18:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:29 INFO - Reading from file tmpfile_stdout 23:18:29 INFO - Current package versions: 23:18:29 INFO - argparse == 1.2.1 23:18:29 INFO - blessings == 1.6 23:18:29 INFO - blobuploader == 1.2.4 23:18:29 INFO - docopt == 0.6.1 23:18:29 INFO - manifestparser == 1.1 23:18:29 INFO - mozInstall == 1.12 23:18:29 INFO - mozcrash == 0.17 23:18:29 INFO - mozdebug == 0.1 23:18:29 INFO - mozdevice == 0.48 23:18:29 INFO - mozfile == 1.2 23:18:29 INFO - mozhttpd == 0.7 23:18:29 INFO - mozinfo == 0.9 23:18:29 INFO - mozleak == 0.1 23:18:29 INFO - mozlog == 3.2 23:18:29 INFO - moznetwork == 0.27 23:18:29 INFO - mozprocess == 0.22 23:18:29 INFO - mozprofile == 0.28 23:18:29 INFO - mozrunner == 6.11 23:18:29 INFO - mozscreenshot == 0.1 23:18:29 INFO - mozsystemmonitor == 0.3 23:18:29 INFO - moztest == 0.7 23:18:29 INFO - mozversion == 1.4 23:18:29 INFO - psutil == 3.1.1 23:18:29 INFO - requests == 1.2.3 23:18:29 INFO - wsgiref == 0.1.2 23:18:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:29 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:18:29 INFO - Using env: {'DISPLAY': ':0', 23:18:29 INFO - 'HOME': '/home/cltbld', 23:18:29 INFO - 'LANG': 'en_US.UTF-8', 23:18:29 INFO - 'LANGUAGE': 'en_US:en', 23:18:29 INFO - 'LOGNAME': 'cltbld', 23:18:29 INFO - 'MAIL': '/var/mail/cltbld', 23:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:29 INFO - 'MOZ_NO_REMOTE': '1', 23:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:29 INFO - 'NO_EM_RESTART': '1', 23:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:29 INFO - 'PWD': '/builds/slave/test', 23:18:29 INFO - 'SHELL': '/bin/bash', 23:18:29 INFO - 'SHLVL': '1', 23:18:29 INFO - 'TERM': 'linux', 23:18:29 INFO - 'TMOUT': '86400', 23:18:29 INFO - 'USER': 'cltbld', 23:18:29 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:29 INFO - '_': '/tools/buildbot/bin/python'} 23:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:18:30 INFO - Cleaning up... 23:18:30 INFO - Return code: 0 23:18:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:30 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:18:30 INFO - Using env: {'DISPLAY': ':0', 23:18:30 INFO - 'HOME': '/home/cltbld', 23:18:30 INFO - 'LANG': 'en_US.UTF-8', 23:18:30 INFO - 'LANGUAGE': 'en_US:en', 23:18:30 INFO - 'LOGNAME': 'cltbld', 23:18:30 INFO - 'MAIL': '/var/mail/cltbld', 23:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:30 INFO - 'MOZ_NO_REMOTE': '1', 23:18:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:30 INFO - 'NO_EM_RESTART': '1', 23:18:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:30 INFO - 'PWD': '/builds/slave/test', 23:18:30 INFO - 'SHELL': '/bin/bash', 23:18:30 INFO - 'SHLVL': '1', 23:18:30 INFO - 'TERM': 'linux', 23:18:30 INFO - 'TMOUT': '86400', 23:18:30 INFO - 'USER': 'cltbld', 23:18:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:30 INFO - '_': '/tools/buildbot/bin/python'} 23:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:30 INFO - Downloading/unpacking jsonschema 23:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:18:32 INFO - Downloading/unpacking functools32 (from jsonschema) 23:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:32 INFO - Downloading functools32-3.2.3-2.tar.gz 23:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:18:32 INFO - warning: no files found matching '*.txt' 23:18:32 INFO - no previously-included directories found matching 'build' 23:18:32 INFO - no previously-included directories found matching 'dist' 23:18:32 INFO - no previously-included directories found matching '.git*' 23:18:32 INFO - Installing collected packages: jsonschema, functools32 23:18:32 INFO - Running setup.py install for functools32 23:18:32 INFO - warning: no files found matching '*.txt' 23:18:32 INFO - no previously-included directories found matching 'build' 23:18:32 INFO - no previously-included directories found matching 'dist' 23:18:32 INFO - no previously-included directories found matching '.git*' 23:18:32 INFO - Successfully installed jsonschema functools32 23:18:32 INFO - Cleaning up... 23:18:32 INFO - Return code: 0 23:18:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:32 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 0x7f4cb2ce8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b42770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, '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': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:18:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:18:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:18:32 INFO - Using env: {'DISPLAY': ':0', 23:18:32 INFO - 'HOME': '/home/cltbld', 23:18:32 INFO - 'LANG': 'en_US.UTF-8', 23:18:32 INFO - 'LANGUAGE': 'en_US:en', 23:18:32 INFO - 'LOGNAME': 'cltbld', 23:18:32 INFO - 'MAIL': '/var/mail/cltbld', 23:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:32 INFO - 'MOZ_NO_REMOTE': '1', 23:18:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:32 INFO - 'NO_EM_RESTART': '1', 23:18:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:32 INFO - 'PWD': '/builds/slave/test', 23:18:32 INFO - 'SHELL': '/bin/bash', 23:18:32 INFO - 'SHLVL': '1', 23:18:32 INFO - 'TERM': 'linux', 23:18:32 INFO - 'TMOUT': '86400', 23:18:32 INFO - 'USER': 'cltbld', 23:18:32 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:32 INFO - '_': '/tools/buildbot/bin/python'} 23:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:32 INFO - Downloading/unpacking flake8 23:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:18:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:18: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 23:18: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 23:18: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 23:18: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 23:18: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 23:18: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 23:18:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:18:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:18:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:35 INFO - no previously-included directories found matching 'docs/_build' 23:18:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:18:35 INFO - Running setup.py install for pep8 23:18:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:35 INFO - no previously-included directories found matching 'docs/_build' 23:18:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:18:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:18:35 INFO - Cleaning up... 23:18:35 INFO - Return code: 0 23:18:35 INFO - Running post-action listener: _resource_record_post_action 23:18:35 INFO - Running post-action listener: _start_resource_monitoring 23:18:35 INFO - Starting resource monitoring. 23:18:35 INFO - ##### 23:18:35 INFO - ##### Finished create-virtualenv step (success) 23:18:35 INFO - ##### 23:18:35 INFO - ##### 23:18:35 INFO - ##### Running install step. 23:18:35 INFO - ##### 23:18:35 INFO - Running pre-action listener: _resource_record_pre_action 23:18:35 INFO - Running main action method: install 23:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:36 INFO - Reading from file tmpfile_stdout 23:18:36 INFO - Detecting whether we're running mozinstall >=1.0... 23:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:18:36 INFO - Reading from file tmpfile_stdout 23:18:36 INFO - Output received: 23:18:36 INFO - Usage: mozinstall [options] installer 23:18:36 INFO - Options: 23:18:36 INFO - -h, --help show this help message and exit 23:18:36 INFO - -d DEST, --destination=DEST 23:18:36 INFO - Directory to install application into. [default: 23:18:36 INFO - "/builds/slave/test"] 23:18:36 INFO - --app=APP Application being installed. [default: firefox] 23:18:36 INFO - mkdir: /builds/slave/test/build/application 23:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:18:52 INFO - Reading from file tmpfile_stdout 23:18:52 INFO - Output received: 23:18:52 INFO - /builds/slave/test/build/application/firefox/firefox 23:18:52 INFO - Running post-action listener: _resource_record_post_action 23:18:52 INFO - ##### 23:18:52 INFO - ##### Finished install step (success) 23:18:52 INFO - ##### 23:18:52 INFO - ##### 23:18:52 INFO - ##### Running run-tests step. 23:18:52 INFO - ##### 23:18:52 INFO - Running pre-action listener: _resource_record_pre_action 23:18:52 INFO - Running main action method: run_tests 23:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:18:52 INFO - Python 2.7.3 23:18:52 INFO - Return code: 0 23:18:52 INFO - grabbing minidump binary from tooltool 23:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:52 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 0x1bd19c0>, '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 0x1bd28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:18:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:18:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 23:18:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:18:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:18:52 INFO - Return code: 0 23:18:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:18:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:18:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:18:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:18:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:18:53 INFO - Return code: 0 23:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:18:53 INFO - Using env: {'DISPLAY': ':0', 23:18:53 INFO - 'HOME': '/home/cltbld', 23:18:53 INFO - 'LANG': 'en_US.UTF-8', 23:18:53 INFO - 'LANGUAGE': 'en_US:en', 23:18:53 INFO - 'LOGNAME': 'cltbld', 23:18:53 INFO - 'MAIL': '/var/mail/cltbld', 23:18:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:18:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:53 INFO - 'MOZ_NO_REMOTE': '1', 23:18:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:53 INFO - 'NO_EM_RESTART': '1', 23:18:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:53 INFO - 'PWD': '/builds/slave/test', 23:18:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:18:53 INFO - 'SHELL': '/bin/bash', 23:18:53 INFO - 'SHLVL': '1', 23:18:53 INFO - 'TERM': 'linux', 23:18:53 INFO - 'TMOUT': '86400', 23:18:53 INFO - 'USER': 'cltbld', 23:18:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679', 23:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:53 INFO - '_': '/tools/buildbot/bin/python'} 23:18:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:18:54 INFO - mozversion application_buildid: 20160515203030 23:18:54 INFO - mozversion application_changeset: 7084bd828e4c688daf6078a710d2f267bb52591a 23:18:54 INFO - mozversion application_display_name: Nightly 23:18:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:18:54 INFO - mozversion application_name: Firefox 23:18:54 INFO - mozversion application_remotingname: firefox 23:18:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:18:54 INFO - mozversion application_vendor: Mozilla 23:18:54 INFO - mozversion application_version: 49.0a1 23:18:54 INFO - mozversion platform_buildid: 20160515203030 23:18:54 INFO - mozversion platform_changeset: 7084bd828e4c688daf6078a710d2f267bb52591a 23:18:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:18:54 INFO - mozversion platform_version: 49.0a1 23:18:54 INFO - using testdate: 1463379534 23:18:54 INFO - actual date: 1463379534 23:18:54 INFO - starting webserver on 'localhost:34713' 23:18:54 INFO - SUITE-START | Running 2 tests 23:18:54 INFO - TEST-START | dromaeo_css 23:18:54 INFO - operating with platform_type : linux_ 23:18:54 INFO - Initialising browser for dromaeo_css test... 23:18:54 INFO - TEST-INFO | started process 3338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE8Hc92/profile http://localhost:34713/getInfo.html) 23:19:02 INFO - PROCESS | 3338 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 23:19:03 INFO - PROCESS | 3338 | __metrics Screen width/height:1600/1200 23:19:03 INFO - PROCESS | 3338 | colorDepth:24 23:19:03 INFO - PROCESS | 3338 | Browser inner width/height: 1024/697 23:19:03 INFO - PROCESS | 3338 | __metrics 23:19:04 ERROR - PROCESS | 3338 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:19:05 INFO - TEST-INFO | 3338: exit 0 23:19:05 INFO - Browser initialized. 23:19:05 INFO - Running cycle 1/1 for dromaeo_css test... 23:19:05 INFO - TEST-INFO | started process 3421 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE8Hc92/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:19:17 INFO - PROCESS | 3421 | RSS: Main: 152084480 23:19:17 INFO - PROCESS | 3421 | 23:20:08 INFO - PROCESS | 3421 | 1463379608567 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:22:17 INFO - PROCESS | 3421 | Cycle 1(1): loaded http://localhost:34713/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34713/tests/dromaeo/cssquery-ext.html) 23:22:18 INFO - PROCESS | 3421 | RSS: Main: 255057920 23:22:18 INFO - PROCESS | 3421 | 23:25:20 INFO - PROCESS | 3421 | Cycle 1(1): loaded http://localhost:34713/tests/dromaeo/cssquery-ext.html (next: http://localhost:34713/tests/dromaeo/cssquery-jquery.html) 23:25:21 INFO - PROCESS | 3421 | RSS: Main: 237060096 23:25:21 INFO - PROCESS | 3421 | 23:28:23 INFO - PROCESS | 3421 | Cycle 1(1): loaded http://localhost:34713/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34713/tests/dromaeo/cssquery-mootools.html) 23:28:23 INFO - PROCESS | 3421 | RSS: Main: 231686144 23:28:23 INFO - PROCESS | 3421 | 23:31:25 INFO - PROCESS | 3421 | Cycle 1(1): loaded http://localhost:34713/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34713/tests/dromaeo/cssquery-prototype.html) 23:31:26 INFO - PROCESS | 3421 | RSS: Main: 231387136 23:31:26 INFO - PROCESS | 3421 | 23:34:28 INFO - PROCESS | 3421 | Cycle 1(1): loaded http://localhost:34713/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34713/tests/dromaeo/cssquery-yui.html) 23:34:29 INFO - PROCESS | 3421 | RSS: Main: 220168192 23:34:29 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | Cycle 1(1): loaded http://localhost:34713/tests/dromaeo/cssquery-yui.html (next: http://localhost:34713/tests/dromaeo/cssquery-dojo.html) 23:37:32 INFO - PROCESS | 3421 | RSS: Main: 277266432 23:37:32 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | __start_tp_report 23:37:32 INFO - PROCESS | 3421 | _x_x_mozilla_page_load 23:37:32 INFO - PROCESS | 3421 | _x_x_mozilla_page_load_details 23:37:32 INFO - PROCESS | 3421 | |i|pagename|runs| 23:37:32 INFO - PROCESS | 3421 | |0;dojo.html;3144.5597616333716;3146;3187.410329089117;3191.218151552869;3205.1987003249187;5186.273921650963;5238.816641417551;5277.762500687469;5369.436209198032;5371.785128594856;5229.8169564065265;5234.7120908350025;5261.579073674096;5318;5329.200619907016;4738.099761045399;4763.166445871976;4900.044491324194;4947.406311242638;4977.6266779991465;4879.658423910328;4888.706677599355;4916.926246106294;4921.532454416824;4939.185034469308;4769.5707386335225;4774.59415949643;4853.490383509751;4857.149998750205;4860.684055536397;4848.733319667417;4850.951490485085;4857.611391088704;4858.125537403266;4900.975495122501;4725.480197178307;4823.421189457287;4831.9275210871865;4839.05638400512;4856.3201151838775;5193.688378697252;5257.08001099806;5294.205869119663;5350.785968561253;5353.812616558441;5730.140478928152;5800.332961709373;5802.883942321172;5806.332271788756;5818.4763371296785;5563.693996830173;5702.8289151325525;5776.393478684761;5799.8550036249435;5801.100829371454;5349.4650534946195;5426.674399536001;5431.918521222225;5435.402105768362;5442.972785136049;5949.494292985145;5955.76176952917;5956.493698035629;5956.493698035715;5957.49361304294;5463.153211252262;5526;5613.101903695389;5724.4561766632105;5732.627379220337;5676.460736230051;5695.914561281584;5696.8860622787315;5732.455416735404;5770.422957704196;5636.60543761941;5682.977064128497;5688.374278829326;5690.9146362804595;5691.345495268014;5450.127979523337;5622.156676498476;5663;5697.658140511582;5699.971500142556;4358.084802191512;4359.760213188305;4361.367601697736;4368.039031413084;4394.978025109918;3887.7278590498395;3910.4720862683744;3914.2562913047;3940.389239667856;3951.5258169019903;5217.399999000164;5321.6540924839765;5323.920141197885;5329.680219186861;5370.812021579304;5658.292713410824;5674.546036317085;5680.573956953245;5685.403032681592;5720.713964301768;5274.498922602346;5407.378151512547;5415;5418.783248670009;5467.343918729778;4805.327254184417;4831.661783675109;4866.635002374836;4869.561739443467;4877.219644856806;5846.123081537803;5863.648181109147;5867.266591676041;5868.031774757117;5869.148973398833;5550.47270509301;5633.605647604629;5645.774169033192;5672.290963629546;5702.37273899879;5887.116932460165;5923.703814809329;5935.258092738408;5944.8811023780145;5966.49284810796;5645.971770141123;5647.915281270866;5649.435056494318;5651.773929042793;5661.348944871228;5516.91724624139;5619.80330688424;5662.348829884451;5692.402297758841;5732.082866741302;5551.2228288040515;5559.694216818114;5565.415631358811;5570.108782594765;5587.050201465842;5540.445955404427;5560.610757246954;5567.331920169606;5571.554275658019;5572.526335261548;5217.191335343028;5357.357117145967;5446.264754258124;5471.179323101566;5474.425185355481;5609.382967873449;5660.660360378391;5666.263385759825;5670.432956704297;5671.376148623731;5454.236406902957;5491;5496.5053145217125;5499;5501.119820828648 23:37:32 INFO - PROCESS | 3421 | |1;ext.html;608.5527137553897;642.8167972127944;655.2530115668139;655.5804285257435;663.8506336074381;43744.781276093614;45560.31659525102;45605.31592026118;45615.315770263434;48288.51711482884;45892.311615325714;46496.76751616246;46524;46692.066158676804;46732.5604067613;8755.649774009047;8937.642494300253;8943.10568943107;8943.95528022359;8947.194752472276;8751.693690720826;8823.691170809023;8837.072107428723;8847.823043539125;8849.292056635453;8829.205371516595;8863;8884;8885.42244754089;8889.73330800077;8796.120387961217;8816.735497935073;8843.867341989842;8848.823023539493;8850.468971861708;8783.560821958941;8809.691660791841;8844.778880527974;8850.955745221232;8860.512671803048;42623.36064959028;42682.57317426848;42845;42845.57154428447;42872;3520.700740437066;3581.4985901973746;3582.444721068239;3607.3687104756564;3665.4135338345736;33111.503327449864;33377;33832.661673383445;33841.15397115092;34268;4092.5498195198506;4135.855245066408;4151.91696166078;4157.127003329305;4183.267928112598;368784;369057;369734;370359;370486;60160.39839601635;60477;60677.39322606806;60754;60931;61379.386206137366;63667.68166159139;64731.352686473474;65000.34999650037;69753.3024669757;16110.75833862493;16237.918810405872;16258.75611865823;16260.59348516273;16266.67466650684;11290.15323850698;11781.882181178078;11812.94093529544;11852.703682407837;11862.703432414259;17522.21150048251;17905.28378864857;17935.910320448314;18442.35451759182;18472.168752406174;5992.880142397127;6019.398060193946;6063.423974722395;6085.148079268906;6178.4439400453275;938.3103418987175;1104.6133853151332;1142.4459137318395;1155.3472288157163;1156.9942150289194;32200.77992200808;33412.66587334096;33560.49659255113;33737.32525349479;34366.82816585901;1098.542204803903;1129.2264798612975;1154.7113221694576;1162.0645380468804;1208.0999655256715;17539.56151096233;17565.824341756674;17619.55951101233;17773;17782.733259001125;499112;500474;502043;504695;505686;60580.69709651423;60734.69632651897;60735;60905.69547152324;60932.69533652304;1285.4858056777364;1405.6556143744847;1431.2485944878813;1435.411481292665;1440.6614445605273;33406.16484587808;34722.826385867906;34814.303713925074;35117;35176;51742.223866641274;51896.74051629718;52359.73820130875;52379.21431178459;52412.21381679201;295273;297291;298255;299416;299518;36352.63647363493;41049.384259235536;42465.575344246165;42721;45183;48394;48758;49211.75394123006;49657;49751;41159.58840411557;41994;42093.78953105337;43075.784621076695;43396.56603433925;41503.58496414997;41719.374209386275;41888.79055604825;42024.789876050425;42456.5754342465;42067;42282.36576451417;42544.5745542553;43182;43334.78332608317;35960.28079438449;37608.811955940044;37992.430113547765;38334;38867.22265554617;28604.570931435628;28653.283667907635;28995;29187.70812291935;29242.70757292485 23:37:32 INFO - PROCESS | 3421 | |2;jquery.html;387.13475382520045;389.2798323102259;393.4825704198978;400.0079802090815;413.54303494638424;14442.927785361086;17338.306467741277;18333.816661833378;19790;19847;15625.687486250268;18254.81745182548;18915.62168756624;19388.51528711783;19929.501762455944;5460.153676180198;5584.385717571064;5600.2159697642155;5637.971810140964;5643.633163844357;4016.9799151004204;4211.978940105295;4214.4099826024385;5552.7223638818095;5802.448767367115;6094.055421409666;6207.317195108534;6211.565190436649;6212.534059945499;6218.533609979269;5971.820845374646;6110.511159107261;6161.568690191689;6229.813105606839;6229.875402491947;4916.975415122937;5425.267588875491;5456.563474921997;5458.4541545845495;5464.617476776627;12410.317432541207;12644.178128421623;12827.551035713705;12843;12862.421191046426;2191.364504293753;2231.8995645196005;2593.1701855406277;2613.516499447608;2652.7214642462454;9646.180074693659;9648.372855764354;9786.363886347435;10146.391216527032;10280.12618927392;1820.1627251464342;1900.4773687235981;1939;1974.5458544534695;2037.3989673046487;79581;81264.59367703124;81696.59151704263;82577.58711206405;83216.16783832145;10461.84307235392;10529.684109476728;10839.078678312275;11096.55613775456;11178.161637877192;15020.549383518512;15141.167235802137;15173.620659483602;15228.771568426482;15241;7742.6128693566225;7916.604169791487;7923;8064.193580642006;8109.75670729879;7728.07263128417;7761.6119194040075;7787.143414224431;7894.368450523944;7896.684132634745;4174.749515029108;4429.800658970354;4503.527129651339;4728.95271047292;4824.445188803263;1944.7666280046485;1987.8409727221788;2004.7894971028124;2012.2030377774904;2019.8586098973224;964.8504481805331;983.7934033852828;985.69936169469;1013.2501948558014;1028.8353863381824;19212.615747685246;19338.129784159748;19525;19529.902350488155;19538.31615893437;963.9252233759308;992.7518120469882;1000.9849852252222;1004.3823048825066;1057.4606950455322;10583;10828.133749300037;10876.61931832398;10890.29213101146;10939.945300273606;369933;375491;376058;461927;463344;65069;84927.57536212403;94437.5278123605;102994;105935.99999999846;841.2722994609619;875.4397185801092;897.4974014551872;898.9011208767031;905.7237542549585;17436.22442625652;17466.21402036886;17583.384581539583;17628.91185544064;17631.11844407749;32686.509702354007;34738;35340;40075.398869016404;40651;48601.75699121623;49678.25482617836;52413.47586524086;54314.728426357615;54546.727266365;7604.505707129019;7846.921530784619;8003.959980200062;8083.19168083187;8200.302974247255;13511.932440337736;13781.173129612256;13922.373493193034;13941.790873136708;13944.4422223114;12219.69450763738;12251.509939602673;12390.070744694003;12477;12604.11771176009;11632.76734465289;11736.823947640622;11757.294562326288;12049.759004820029;12109.939450302692;9215.631374744935;12007.639770806889;12282.201656892274;12372.938135309267;12382;8002.399820013406;8062.677492900453;8114.634841432267;8260.628271727666;8294.419390642597;11478.196526243082;11881.465334059814;12040.458179382123;12051.674315825077;12471 23:37:32 INFO - PROCESS | 3421 | |3;mootools.html;1424.4088703188177;1507.4573153664676;1544.0426935300115;1683.2004797721083;1814.4738025972476;8620.698275560357;8962.686305979292;8972.327075469333;9297.814043719121;9298.209652179572;9010.72967810965;9214.861777073349;9222.21611163052;9222.538873056354;9224.077592240772;2145.227718021515;2173.467500462381;2178.1396348442354;2179.302623160589;2181.018541656253;2115.883626400547;2134.9786502134934;2153.364757396564;2156.590247852903;2156.773538778429;2120.1731324783345;2147.7530084040377;2151.3008272311563;2154.0845140815168;2178.3900507857825;2058.835293176542;2060.062671484471;2060.5157787919825;2063.607914496241;2066.8449866260016;2088.457001179689;2116.100657220675;2121.5438680683574;2122.8832414217213;2126.0539060118253;8675.652973881037;8909.55452227386;9027.277817774562;9073.682421115229;9093.954530227307;5109.4379618241965;5166.889118839467;5186.636935414523;5199.324087868591;5209.374875014984;3210.839458027089;3306.834658267077;3307.1897385140555;3329.250918543333;3338.2488939988307;1074.548689550383;1084.138110202391;1084.7613525024483;1089.1232557790975;1117.9608713695063;12664.620061398173;12714.046446516548;12716.300603466805;12761.106722529426;12771.936140319332;4243.236217480821;4272;4330.610245077958;4333.479982402069;4370.497392799805;9983;10050.246231532665;10069.295149339623;10074.395536267848;10078.042585954323;5142.897142057138;5201.7919283229;5204.739763011834;5205.531502164823;5212.921806172911;4289.828406863753;4314.848980285675;4757.452892917289;4799.568038876518;4874.8050077997195;3784.413415920537;3825.923481530354;3848.13416981177;3872.1674839909556;3997.7801220932547;1051.984220236697;1112.5605385872534;1115.754534002518;1130.6212418839616;1134.2173900008968;896.6278994217457;1012.8784545854397;1013.5135135135176;1028.7685270814156;1035.089121573011;6147.66187859672;6193.38066193377;6232.376762323732;6470.773522926675;6510.08858759774;1032.3496197395743;1040.5941682743735;1058.3385384134915;1072.3458690198972;1114.3536748685906;4070.430139780433;4083.9183216335505;4117.238310912431;4222.092250166229;4330.069035157457;11022.338659680285;11233.94383028096;11236.943815281034;11243.437828108561;11297.604583839691;10798.78402431947;11030.669079927615;11112.833307500394;11134.3876086816;11144.05275551571;8281.37889658278;8626.784330391665;8643.524606146722;8707.259882910024;8711.172438618321;6252.124702541739;6521.34786521363;6601.9009714853355;6608.504362172953;6614.570052946543;10147.695569132939;10530.736731581772;10532.52603632823;10651.30766500175;10749.301295415773;10696.732581685521;10806.081483074027;10816;10830.241883068426;10834.241603087708;7440.181580026301;7874.527528348317;8065.556394398364;8093.635786389509;8340.999080110183;8529.872051919103;8726.869096963677;8732.825343492968;8741.606627701642;8774.034856165945;7902.644381002984;7961.482503637245;8096.190380961857;8145.42981991255;8147.406488635137;7876.369890408868;7978.84042319162;8022.157673444438;8119.269265766109;8162.387820913336;7188.460865435218;7286.052813134259;7294.854102917806;7296.890546641698;7342.082239720035;5298.28473156119;5317.97341013308;5392.379876314274;5405.540529055074;5480.862978425571;5462.989346970823;5559.777608895599;5589.161625756006;5606.327240731139;5639.7180140993605 23:37:32 INFO - PROCESS | 3421 | |4;prototype.html;295.6983876446025;313.8070086896559;314.294409051679;316.21420769388067;320.65209247965953;5364.54401375883;5413.215083812845;5424.321959755031;5452.263944367509;5460.672359658418;5091.592672586194;5177.145770947789;5207.531322181003;5232.398274198459;5373.865653358668;4026.5973402659793;4102.753834769908;4124.855630052949;4134.917301653965;4150.8132134053885;4122.42286079949;4126.731762435447;4130.690198235145;4135.09028013838;4143.461350024508;4021.7385869918508;4077.245709543744;4086.693497987648;4143.067809742799;4176.874693759192;4109.486314210723;4124.1133156371225;4124.6906482013965;4147.668186545069;4164.292070348048;3644.471551625026;3650.142216579102;3653.981730091359;3676.1361080146144;3687.907802304937;5057.893625069377;5130.30740849988;5132.153194722866;5143.742812859342;5148.227765835118;4749.786259618326;4788.425388953313;4791.3531673224015;4796.808127674889;4814.638902082323;5581.190727344512;5582.944170558283;5605.561580712403;5614.943850561483;5638.323401191883;3734.3838266685966;4059.4925634295714;4067.1255680028935;4080.9387859182134;4088.713790034699;5773.451522105435;5791.841631673682;5807.883842323172;5814.389489103625;5840.948629246734;4028.436018957348;4031.6169963853386;4036.57615950321;4051.5745846685672;4056.3307054335705;4108.301588729923;4108.383742438658;4115.6295933365545;4120.434234990685;4130.834766609362;4490.438695163105;4677.415323084614;4710.717356958594;4712.95287047132;4753.191957367256;5604;5634.464725851038;5660.688662123541;5676.943230567642;5696.629719068247;1346.4008516210192;1410.4569740649717;1412.865777751112;1415.8513356097667;1420.155007770375;912.6851236323457;925.2459245714721;935.8362286599818;948.7912659214961;956.3783540698463;4845.63657725672;4856.538628830255;4858.376476702611;4884.998575292057;4911.214205727067;5054.786851155696;5084.034033533617;5086.652037210142;5092.9304845982015;5103.362079740033;4534.9319760203625;4815.494373090775;4866.537678920497;4867.610591152699;4896.828610998598;2688.8841130930837;2718.4834881372476;2730.2218867622632;2754.3389586499097;2792.2042217967783;5199.66202196856;5204.21936709489;5239.332467272848;5252;5258.868528286747;5120.846374608767;5269.657472264291;5279.498447647467;5479.3424789024775;5892.7642894283745;5954.523638108941;5954.55340849438;5956.761729530857;5965.970170149121;6003.6397816131175;5777.306723193244;5784.50831679312;5799.5070419014855;5802.187693722966;5814.651120932757;5572.3034620672415;5715.8571035724435;5731.971340143273;5740.478773125042;5742;5945.494632956247;5948;5974.1934838796205;5978.222831031938;5995.520358371407;3861.5945325740395;4054.432379466818;4059.594040596035;4080.6123418275215;4088.407180958685;5532.723363831873;5674.06377947634;5721.570882183936;5747.41945964863;5755.942440575708;5499.010178167968;5596.9440305598055;5600.383957764561;5600.439956004367;5631.324241091095;5584.609077364708;5590.385057643737;5591.189277554813;5617.241672374177;5632;5234.267202591716;5484;5490.835274941758;5501.614886957875;5505.752241149078;5603.2155498231095;5607.299087614048;5613.46672066153;5617.74720137603;5673;5571.554275657856;5576;5577.462254674032;5577.860553486195;5580.135079062752 23:37:32 INFO - PROCESS | 3421 | |5;yui.html;816.9509829410235;856.3919617071879;862.2799962031703;881.7222574888909;898.5686870302253;15184.164870932094;15636.061836289817;15882.047077175363;15973;16031.438899638515;14174.574762757107;14332.211728354936;14337.784933225983;14383.208923509203;14410.495632652859;686.1491750229729;807.9232472915093;827.7765003449065;839.7061028639988;846.9237768600825;839.290799274612;840.1388576708861;842.321930845669;843.6794018273048;843.9535825529593;883.6995421556669;885.3006125161115;885.5616469847434;886.3441053620306;886.441541828647;725.2928394815066;867.3538214030556;874.4447275979767;888.9111088891124;889.5329951775369;637.2225884420999;641.3906788550848;641.5733537197758;642.3095172689377;642.858571114354;14932.552023439206;14952.626184345478;14980.925095374563;14989.400423983028;14991.32539035746;13587.388567514485;13774.380152893144;13840.515581954684;14021.929890350584;14084.225367604779;11840.407979601072;11933.582324618597;11974.461149248305;12114.394280286038;12246.877531224663;4488.192125417424;4527.932081018787;4532.818687252506;4667.603253723438;4701.412323459567;61499.38500615026;61633;62050;62189.378106219265;62471.375286247014;11176.497057632427;11192.104631629612;11288;11417.828732569018;11475;19840.305589304593;19855.205791768458;19871.205151793765;19884.60230795376;19888.70166947497;7826.882596761053;7940.205979402013;7961.164077771867;8208.179182081743;8247.587620618946;4686.976565117153;4732.2428411454;4741.762911854394;4829.66192366531;4885.096257192431;3389.9152521187166;3513.7364697647777;3521.8767343142868;3541.6635419635095;3543.875964341236;565.2284631478003;626.5394934722975;638.4190386748037;652.1619718661498;687.2886562407983;628.230417738267;671.8186089755739;702.9648517574101;729.05951322793;752.420636110192;10564.524596393181;10742.194335424718;10827.621033263798;10874.510647020903;10901.400422976812;742.688071010177;770.4837758701683;771.3289438188699;788.113372455987;809.2813521867012;4942.258661200777;4945.5964480901575;4957.504249575014;4976.527229913152;5088.567471764867;69844;72089;72351;73659.2634073663;73877;62505.374946249955;63230.36769632245;63429;63434.36565634377;65556;56263.46814393362;56313.43686563164;58905.41094589171;59085.409145907994;59647;9451.432914025181;9526.761830954281;9627.855582166414;9629.470379128936;9766.9023309766;39108.41337379999;39173.02067448223;39189.216215674955;39407.40888886726;39540.60459395484;47124.29313560368;51575.226371603705;53526.73236633792;53953.25584301962;54621.453785461636;13071.940873780575;13117.475300987853;13156.210627362389;13301.334933253105;13425.86574134246;14552.927235363755;14816.481423150139;14974.176420296988;14974.550763477113;14995.550133496012;13802.240876751473;13820.792688109888;13875.236861972688;14009.649758756113;14086.577402677936;12944;13113.40989655487;13255.668608284484;13378;13378;13369.465221391036;13464.865351346361;13474.79787803164;13666.385012674255;13818.447262109805;10846.783064338826;10940.945295273472;10970.725731856768;11193.104551636012;11318.943405283251;10748.193885458779;10977.83533246986;11002.614908478165;11081.39052352096;11119.610813621484 23:37:32 INFO - PROCESS | 3421 | __end_tp_report 23:37:32 INFO - PROCESS | 3421 | __start_cc_report 23:37:32 INFO - PROCESS | 3421 | _x_x_mozilla_cycle_collect,847 23:37:32 INFO - PROCESS | 3421 | __end_cc_report 23:37:32 INFO - PROCESS | 3421 | __startTimestamp1463380652340__endTimestamp 23:37:32 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | ------- Summary: start ------- 23:37:32 INFO - PROCESS | 3421 | Number of tests: 6 23:37:32 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | [#0] dojo.html Cycles:165 Average:5295.71 Median:5474.43 stddev:592.06 (10.8%) stddev-sans-first:569.32 23:37:32 INFO - PROCESS | 3421 | Values: 3144.6 3146.0 3187.4 3191.2 3205.2 5186.3 5238.8 5277.8 5369.4 5371.8 5229.8 5234.7 5261.6 5318.0 5329.2 4738.1 4763.2 4900.0 4947.4 4977.6 4879.7 4888.7 4916.9 4921.5 4939.2 4769.6 4774.6 4853.5 4857.1 4860.7 4848.7 4851.0 4857.6 4858.1 4901.0 4725.5 4823.4 4831.9 4839.1 4856.3 5193.7 5257.1 5294.2 5350.8 5353.8 5730.1 5800.3 5802.9 5806.3 5818.5 5563.7 5702.8 5776.4 5799.9 5801.1 5349.5 5426.7 5431.9 5435.4 5443.0 5949.5 5955.8 5956.5 5956.5 5957.5 5463.2 5526.0 5613.1 5724.5 5732.6 5676.5 5695.9 5696.9 5732.5 5770.4 5636.6 5683.0 5688.4 5690.9 5691.3 5450.1 5622.2 5663.0 5697.7 5700.0 4358.1 4359.8 4361.4 4368.0 4395.0 3887.7 3910.5 3914.3 3940.4 3951.5 5217.4 5321.7 5323.9 5329.7 5370.8 5658.3 5674.5 5680.6 5685.4 5720.7 5274.5 5407.4 5415.0 5418.8 5467.3 4805.3 4831.7 4866.6 4869.6 4877.2 5846.1 5863.6 5867.3 5868.0 5869.1 5550.5 5633.6 5645.8 5672.3 5702.4 5887.1 5923.7 5935.3 5944.9 5966.5 5646.0 5647.9 5649.4 5651.8 5661.3 5516.9 5619.8 5662.3 5692.4 5732.1 5551.2 5559.7 5565.4 5570.1 5587.1 5540.4 5560.6 5567.3 5571.6 5572.5 5217.2 5357.4 5446.3 5471.2 5474.4 5609.4 5660.7 5666.3 5670.4 5671.4 5454.2 5491.0 5496.5 5499.0 5501.1 23:37:32 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | [#1] ext.html Cycles:180 Average:57100.84 Median:42461.08 stddev:105388.08 (248.2%) stddev-sans-first:105598.37 23:37:32 INFO - PROCESS | 3421 | Values: 608.6 642.8 655.3 655.6 663.9 43744.8 45560.3 45605.3 45615.3 48288.5 45892.3 46496.8 46524.0 46692.1 46732.6 8755.6 8937.6 8943.1 8944.0 8947.2 8751.7 8823.7 8837.1 8847.8 8849.3 8829.2 8863.0 8884.0 8885.4 8889.7 8796.1 8816.7 8843.9 8848.8 8850.5 8783.6 8809.7 8844.8 8851.0 8860.5 42623.4 42682.6 42845.0 42845.6 42872.0 3520.7 3581.5 3582.4 3607.4 3665.4 33111.5 33377.0 33832.7 33841.2 34268.0 4092.5 4135.9 4151.9 4157.1 4183.3 368784.0 369057.0 369734.0 370359.0 370486.0 60160.4 60477.0 60677.4 60754.0 60931.0 61379.4 63667.7 64731.4 65000.3 69753.3 16110.8 16237.9 16258.8 16260.6 16266.7 11290.2 11781.9 11812.9 11852.7 11862.7 17522.2 17905.3 17935.9 18442.4 18472.2 5992.9 6019.4 6063.4 6085.1 6178.4 938.3 1104.6 1142.4 1155.3 1157.0 32200.8 33412.7 33560.5 33737.3 34366.8 1098.5 1129.2 1154.7 1162.1 1208.1 17539.6 17565.8 17619.6 17773.0 17782.7 499112.0 500474.0 502043.0 504695.0 505686.0 60580.7 60734.7 60735.0 60905.7 60932.7 1285.5 1405.7 1431.2 1435.4 1440.7 33406.2 34722.8 34814.3 35117.0 35176.0 51742.2 51896.7 52359.7 52379.2 52412.2 295273.0 297291.0 298255.0 299416.0 299518.0 36352.6 41049.4 42465.6 42721.0 45183.0 48394.0 48758.0 49211.8 49657.0 49751.0 41159.6 41994.0 42093.8 43075.8 43396.6 41503.6 41719.4 41888.8 42024.8 42456.6 42067.0 42282.4 42544.6 43182.0 43334.8 35960.3 37608.8 37992.4 38334.0 38867.2 28604.6 28653.3 28995.0 29187.7 29242.7 23:37:32 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | [#2] jquery.html Cycles:180 Average:26079.26 Median:202636.50 stddev:68389.69 (33.7%) stddev-sans-first:68554.34 23:37:32 INFO - PROCESS | 3421 | Values: 387.1 389.3 393.5 400.0 413.5 14442.9 17338.3 18333.8 19790.0 19847.0 15625.7 18254.8 18915.6 19388.5 19929.5 5460.2 5584.4 5600.2 5638.0 5643.6 4017.0 4212.0 4214.4 5552.7 5802.4 6094.1 6207.3 6211.6 6212.5 6218.5 5971.8 6110.5 6161.6 6229.8 6229.9 4917.0 5425.3 5456.6 5458.5 5464.6 12410.3 12644.2 12827.6 12843.0 12862.4 2191.4 2231.9 2593.2 2613.5 2652.7 9646.2 9648.4 9786.4 10146.4 10280.1 1820.2 1900.5 1939.0 1974.5 2037.4 79581.0 81264.6 81696.6 82577.6 83216.2 10461.8 10529.7 10839.1 11096.6 11178.2 15020.5 15141.2 15173.6 15228.8 15241.0 7742.6 7916.6 7923.0 8064.2 8109.8 7728.1 7761.6 7787.1 7894.4 7896.7 4174.7 4429.8 4503.5 4729.0 4824.4 1944.8 1987.8 2004.8 2012.2 2019.9 964.9 983.8 985.7 1013.3 1028.8 19212.6 19338.1 19525.0 19529.9 19538.3 963.9 992.8 1001.0 1004.4 1057.5 10583.0 10828.1 10876.6 10890.3 10939.9 369933.0 375491.0 376058.0 461927.0 463344.0 65069.0 84927.6 94437.5 102994.0 105936.0 841.3 875.4 897.5 898.9 905.7 17436.2 17466.2 17583.4 17628.9 17631.1 32686.5 34738.0 35340.0 40075.4 40651.0 48601.8 49678.3 52413.5 54314.7 54546.7 7604.5 7846.9 8004.0 8083.2 8200.3 13511.9 13781.2 13922.4 13941.8 13944.4 12219.7 12251.5 12390.1 12477.0 12604.1 11632.8 11736.8 11757.3 12049.8 12109.9 9215.6 12007.6 12282.2 12372.9 12382.0 8002.4 8062.7 8114.6 8260.6 8294.4 11478.2 11881.5 12040.5 12051.7 12471.0 23:37:32 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | [#3] mootools.html Cycles:180 Average:5917.40 Median:4169.67 stddev:3456.96 (82.9%) stddev-sans-first:3450.17 23:37:32 INFO - PROCESS | 3421 | Values: 1424.4 1507.5 1544.0 1683.2 1814.5 8620.7 8962.7 8972.3 9297.8 9298.2 9010.7 9214.9 9222.2 9222.5 9224.1 2145.2 2173.5 2178.1 2179.3 2181.0 2115.9 2135.0 2153.4 2156.6 2156.8 2120.2 2147.8 2151.3 2154.1 2178.4 2058.8 2060.1 2060.5 2063.6 2066.8 2088.5 2116.1 2121.5 2122.9 2126.1 8675.7 8909.6 9027.3 9073.7 9094.0 5109.4 5166.9 5186.6 5199.3 5209.4 3210.8 3306.8 3307.2 3329.3 3338.2 1074.5 1084.1 1084.8 1089.1 1118.0 12664.6 12714.0 12716.3 12761.1 12771.9 4243.2 4272.0 4330.6 4333.5 4370.5 9983.0 10050.2 10069.3 10074.4 10078.0 5142.9 5201.8 5204.7 5205.5 5212.9 4289.8 4314.8 4757.5 4799.6 4874.8 3784.4 3825.9 3848.1 3872.2 3997.8 1052.0 1112.6 1115.8 1130.6 1134.2 896.6 1012.9 1013.5 1028.8 1035.1 6147.7 6193.4 6232.4 6470.8 6510.1 1032.3 1040.6 1058.3 1072.3 1114.4 4070.4 4083.9 4117.2 4222.1 4330.1 11022.3 11233.9 11236.9 11243.4 11297.6 10798.8 11030.7 11112.8 11134.4 11144.1 8281.4 8626.8 8643.5 8707.3 8711.2 6252.1 6521.3 6601.9 6608.5 6614.6 10147.7 10530.7 10532.5 10651.3 10749.3 10696.7 10806.1 10816.0 10830.2 10834.2 7440.2 7874.5 8065.6 8093.6 8341.0 8529.9 8726.9 8732.8 8741.6 8774.0 7902.6 7961.5 8096.2 8145.4 8147.4 7876.4 7978.8 8022.2 8119.3 8162.4 7188.5 7286.1 7294.9 7296.9 7342.1 5298.3 5318.0 5392.4 5405.5 5480.9 5463.0 5559.8 5589.2 5606.3 5639.7 23:37:32 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | [#4] prototype.html Cycles:180 Average:4620.57 Median:5145.99 stddev:1371.08 (26.6%) stddev-sans-first:1335.95 23:37:32 INFO - PROCESS | 3421 | Values: 295.7 313.8 314.3 316.2 320.7 5364.5 5413.2 5424.3 5452.3 5460.7 5091.6 5177.1 5207.5 5232.4 5373.9 4026.6 4102.8 4124.9 4134.9 4150.8 4122.4 4126.7 4130.7 4135.1 4143.5 4021.7 4077.2 4086.7 4143.1 4176.9 4109.5 4124.1 4124.7 4147.7 4164.3 3644.5 3650.1 3654.0 3676.1 3687.9 5057.9 5130.3 5132.2 5143.7 5148.2 4749.8 4788.4 4791.4 4796.8 4814.6 5581.2 5582.9 5605.6 5614.9 5638.3 3734.4 4059.5 4067.1 4080.9 4088.7 5773.5 5791.8 5807.9 5814.4 5840.9 4028.4 4031.6 4036.6 4051.6 4056.3 4108.3 4108.4 4115.6 4120.4 4130.8 4490.4 4677.4 4710.7 4713.0 4753.2 5604.0 5634.5 5660.7 5676.9 5696.6 1346.4 1410.5 1412.9 1415.9 1420.2 912.7 925.2 935.8 948.8 956.4 4845.6 4856.5 4858.4 4885.0 4911.2 5054.8 5084.0 5086.7 5092.9 5103.4 4534.9 4815.5 4866.5 4867.6 4896.8 2688.9 2718.5 2730.2 2754.3 2792.2 5199.7 5204.2 5239.3 5252.0 5258.9 5120.8 5269.7 5279.5 5479.3 5892.8 5954.5 5954.6 5956.8 5966.0 6003.6 5777.3 5784.5 5799.5 5802.2 5814.7 5572.3 5715.9 5732.0 5740.5 5742.0 5945.5 5948.0 5974.2 5978.2 5995.5 3861.6 4054.4 4059.6 4080.6 4088.4 5532.7 5674.1 5721.6 5747.4 5755.9 5499.0 5596.9 5600.4 5600.4 5631.3 5584.6 5590.4 5591.2 5617.2 5632.0 5234.3 5484.0 5490.8 5501.6 5505.8 5603.2 5607.3 5613.5 5617.7 5673.0 5571.6 5576.0 5577.5 5577.9 5580.1 23:37:32 INFO - PROCESS | 3421 | 23:37:32 INFO - PROCESS | 3421 | [#5] yui.html Cycles:180 Average:16489.20 Median:25912.85 stddev:19824.60 (76.5%) stddev-sans-first:19845.28 23:37:32 INFO - PROCESS | 3421 | Values: 817.0 856.4 862.3 881.7 898.6 15184.2 15636.1 15882.0 15973.0 16031.4 14174.6 14332.2 14337.8 14383.2 14410.5 686.1 807.9 827.8 839.7 846.9 839.3 840.1 842.3 843.7 844.0 883.7 885.3 885.6 886.3 886.4 725.3 867.4 874.4 888.9 889.5 637.2 641.4 641.6 642.3 642.9 14932.6 14952.6 14980.9 14989.4 14991.3 13587.4 13774.4 13840.5 14021.9 14084.2 11840.4 11933.6 11974.5 12114.4 12246.9 4488.2 4527.9 4532.8 4667.6 4701.4 61499.4 61633.0 62050.0 62189.4 62471.4 11176.5 11192.1 11288.0 11417.8 11475.0 19840.3 19855.2 19871.2 19884.6 19888.7 7826.9 7940.2 7961.2 8208.2 8247.6 4687.0 4732.2 4741.8 4829.7 4885.1 3389.9 3513.7 3521.9 3541.7 3543.9 565.2 626.5 638.4 652.2 687.3 628.2 671.8 703.0 729.1 752.4 10564.5 10742.2 10827.6 10874.5 10901.4 742.7 770.5 771.3 788.1 809.3 4942.3 4945.6 4957.5 4976.5 5088.6 69844.0 72089.0 72351.0 73659.3 73877.0 62505.4 63230.4 63429.0 63434.4 65556.0 56263.5 56313.4 58905.4 59085.4 59647.0 9451.4 9526.8 9627.9 9629.5 9766.9 39108.4 39173.0 39189.2 39407.4 39540.6 47124.3 51575.2 53526.7 53953.3 54621.5 13071.9 13117.5 13156.2 13301.3 13425.9 14552.9 14816.5 14974.2 14974.6 14995.6 13802.2 13820.8 13875.2 14009.6 14086.6 12944.0 13113.4 13255.7 13378.0 13378.0 13369.5 13464.9 13474.8 13666.4 13818.4 10846.8 10940.9 10970.7 11193.1 11318.9 10748.2 10977.8 11002.6 11081.4 11119.6 23:37:32 INFO - PROCESS | 3421 | -------- Summary: end -------- 23:37:32 INFO - PROCESS | 3421 | 23:37:33 INFO - TEST-INFO | 3421: exit 0 23:37:33 INFO - TEST-OK | dromaeo_css | took 1118910ms 23:37:33 INFO - TEST-START | kraken 23:37:33 INFO - operating with platform_type : linux_ 23:37:33 INFO - Initialising browser for kraken test... 23:37:33 INFO - TEST-INFO | started process 3602 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDBt8UK/profile http://localhost:34713/getInfo.html) 23:37:38 INFO - PROCESS | 3602 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 23:37:38 INFO - PROCESS | 3602 | __metrics Screen width/height:1600/1200 23:37:38 INFO - PROCESS | 3602 | colorDepth:24 23:37:38 INFO - PROCESS | 3602 | Browser inner width/height: 1024/697 23:37:38 INFO - PROCESS | 3602 | __metrics 23:37:39 ERROR - PROCESS | 3602 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:37:40 INFO - TEST-INFO | 3602: exit 0 23:37:40 INFO - Browser initialized. 23:37:40 INFO - Running cycle 1/1 for kraken test... 23:37:40 INFO - TEST-INFO | started process 3687 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDBt8UK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:37:52 INFO - PROCESS | 3687 | RSS: Main: 151822336 23:37:52 INFO - PROCESS | 3687 | 23:37:55 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34713/tests/kraken/driver.html?testName=audio-beat-detection) 23:37:56 INFO - PROCESS | 3687 | RSS: Main: 208736256 23:37:56 INFO - PROCESS | 3687 | 23:37:58 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34713/tests/kraken/driver.html?testName=audio-dft) 23:37:59 INFO - PROCESS | 3687 | RSS: Main: 196898816 23:37:59 INFO - PROCESS | 3687 | 23:38:02 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34713/tests/kraken/driver.html?testName=audio-fft) 23:38:03 INFO - PROCESS | 3687 | RSS: Main: 264495104 23:38:03 INFO - PROCESS | 3687 | 23:38:05 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34713/tests/kraken/driver.html?testName=audio-oscillator) 23:38:05 INFO - PROCESS | 3687 | RSS: Main: 174395392 23:38:05 INFO - PROCESS | 3687 | 23:38:08 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34713/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:38:08 INFO - PROCESS | 3687 | RSS: Main: 178061312 23:38:08 INFO - PROCESS | 3687 | 23:38:12 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34713/tests/kraken/driver.html?testName=imaging-darkroom) 23:38:12 INFO - PROCESS | 3687 | RSS: Main: 214441984 23:38:12 INFO - PROCESS | 3687 | 23:38:16 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34713/tests/kraken/driver.html?testName=imaging-desaturate) 23:38:16 INFO - PROCESS | 3687 | RSS: Main: 212283392 23:38:16 INFO - PROCESS | 3687 | 23:38:19 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34713/tests/kraken/driver.html?testName=json-parse-financial) 23:38:20 INFO - PROCESS | 3687 | RSS: Main: 213340160 23:38:20 INFO - PROCESS | 3687 | 23:38:22 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34713/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:38:22 INFO - PROCESS | 3687 | RSS: Main: 203423744 23:38:22 INFO - PROCESS | 3687 | 23:38:24 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34713/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:38:25 INFO - PROCESS | 3687 | RSS: Main: 208347136 23:38:25 INFO - PROCESS | 3687 | 23:38:27 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34713/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:38:27 INFO - PROCESS | 3687 | RSS: Main: 215068672 23:38:27 INFO - PROCESS | 3687 | 23:38:30 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34713/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:38:31 INFO - PROCESS | 3687 | RSS: Main: 223408128 23:38:31 INFO - PROCESS | 3687 | 23:38:33 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34713/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:38:34 INFO - PROCESS | 3687 | RSS: Main: 219185152 23:38:34 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | Cycle 1(1): loaded http://localhost:34713/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34713/tests/kraken/driver.html?testName=ai-astar) 23:38:36 INFO - PROCESS | 3687 | RSS: Main: 266055680 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | __start_tp_report 23:38:36 INFO - PROCESS | 3687 | _x_x_mozilla_page_load 23:38:36 INFO - PROCESS | 3687 | _x_x_mozilla_page_load_details 23:38:36 INFO - PROCESS | 3687 | |i|pagename|runs| 23:38:36 INFO - PROCESS | 3687 | |0;ai-astar;113;107;110;109;110;110;108;110;110;109 23:38:36 INFO - PROCESS | 3687 | |1;audio-beat-detection;156;125;159;209;137;150;157;124;183;127 23:38:36 INFO - PROCESS | 3687 | |2;audio-dft;159;161;160;161;161;160;162;160;160;160 23:38:36 INFO - PROCESS | 3687 | |3;audio-fft;73;74;74;146;73;72;75;74;137;73 23:38:36 INFO - PROCESS | 3687 | |4;audio-oscillator;91;92;145;94;92;91;119;91;91;125 23:38:36 INFO - PROCESS | 3687 | |5;imaging-gaussian-blur;116;116;116;117;117;116;117;116;117;116 23:38:36 INFO - PROCESS | 3687 | |6;imaging-darkroom;146;146;145;147;146;146;144;146;145;146 23:38:36 INFO - PROCESS | 3687 | |7;imaging-desaturate;95;94;95;95;95;95;95;94;95;96 23:38:36 INFO - PROCESS | 3687 | |8;json-parse-financial;55;54;54;54;55;55;56;105;52;53 23:38:36 INFO - PROCESS | 3687 | |9;json-stringify-tinderbox;58;58;58;58;58;58;59;57;58;59 23:38:36 INFO - PROCESS | 3687 | |10;stanford-crypto-aes;78;77;77;76;81;79;79;78;80;79 23:38:36 INFO - PROCESS | 3687 | |11;stanford-crypto-ccm;123;120;118;120;134;118;117;131;195;124 23:38:36 INFO - PROCESS | 3687 | |12;stanford-crypto-pbkdf2;165;159;159;156;158;161;155;156;158;155 23:38:36 INFO - PROCESS | 3687 | |13;stanford-crypto-sha256-iterative;62;63;62;66;63;61;62;61;61;61 23:38:36 INFO - PROCESS | 3687 | __end_tp_report 23:38:36 INFO - PROCESS | 3687 | __start_cc_report 23:38:36 INFO - PROCESS | 3687 | _x_x_mozilla_cycle_collect,1828 23:38:36 INFO - PROCESS | 3687 | __end_cc_report 23:38:36 INFO - PROCESS | 3687 | __startTimestamp1463380716313__endTimestamp 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | ------- Summary: start ------- 23:38:36 INFO - PROCESS | 3687 | Number of tests: 14 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:1.58 (1.4%) stddev-sans-first:1.09 23:38:36 INFO - PROCESS | 3687 | Values: 113.0 107.0 110.0 109.0 110.0 110.0 108.0 110.0 110.0 109.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:156.50 stddev:27.21 (17.4%) stddev-sans-first:28.83 23:38:36 INFO - PROCESS | 3687 | Values: 156.0 125.0 159.0 209.0 137.0 150.0 157.0 124.0 183.0 127.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.73 23:38:36 INFO - PROCESS | 3687 | Values: 159.0 161.0 160.0 161.0 161.0 160.0 162.0 160.0 160.0 160.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#3] audio-fft Cycles:10 Average:87.10 Median:73.50 stddev:28.76 (39.1%) stddev-sans-first:30.05 23:38:36 INFO - PROCESS | 3687 | Values: 73.0 74.0 74.0 146.0 73.0 72.0 75.0 74.0 137.0 73.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:91.00 stddev:19.44 (21.4%) stddev-sans-first:20.13 23:38:36 INFO - PROCESS | 3687 | Values: 91.0 92.0 145.0 94.0 92.0 91.0 119.0 91.0 91.0 125.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:38:36 INFO - PROCESS | 3687 | Values: 116.0 116.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 23:38:36 INFO - PROCESS | 3687 | Values: 146.0 146.0 145.0 147.0 146.0 146.0 144.0 146.0 145.0 146.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 23:38:36 INFO - PROCESS | 3687 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 96.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:54.50 stddev:16.10 (29.5%) stddev-sans-first:17.00 23:38:36 INFO - PROCESS | 3687 | Values: 55.0 54.0 54.0 54.0 55.0 55.0 56.0 105.0 52.0 53.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:58.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 23:38:36 INFO - PROCESS | 3687 | Values: 58.0 58.0 58.0 58.0 58.0 58.0 59.0 57.0 58.0 59.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:79.00 stddev:1.51 (1.9%) stddev-sans-first:1.59 23:38:36 INFO - PROCESS | 3687 | Values: 78.0 77.0 77.0 76.0 81.0 79.0 79.0 78.0 80.0 79.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#11] stanford-crypto-ccm Cycles:10 Average:130.00 Median:123.50 stddev:23.53 (19.1%) stddev-sans-first:24.82 23:38:36 INFO - PROCESS | 3687 | Values: 123.0 120.0 118.0 120.0 134.0 118.0 117.0 131.0 195.0 124.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.20 Median:158.50 stddev:3.08 (1.9%) stddev-sans-first:2.07 23:38:36 INFO - PROCESS | 3687 | Values: 165.0 159.0 159.0 156.0 158.0 161.0 155.0 156.0 158.0 155.0 23:38:36 INFO - PROCESS | 3687 | 23:38:36 INFO - PROCESS | 3687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.55 (2.5%) stddev-sans-first:1.64 23:38:36 INFO - PROCESS | 3687 | Values: 62.0 63.0 62.0 66.0 63.0 61.0 62.0 61.0 61.0 61.0 23:38:36 INFO - PROCESS | 3687 | -------- Summary: end -------- 23:38:36 INFO - PROCESS | 3687 | 23:38:37 INFO - TEST-INFO | 3687: exit 0 23:38:37 INFO - TEST-OK | kraken | took 64372ms 23:38:37 INFO - SUITE-END | took 1183s 23:38:37 INFO - Completed test suite (00:19:43) 23:38:37 INFO - javascript benchmark 23:38:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.5597616333716, 3146.0, 3187.410329089117, 3191.218151552869, 3205.1987003249187, 5186.273921650963, 5238.816641417551, 5277.762500687469, 5369.436209198032, 5371.785128594856, 5229.8169564065265, 5234.7120908350025, 5261.579073674096, 5318.0, 5329.200619907016, 4738.099761045399, 4763.166445871976, 4900.044491324194, 4947.406311242638, 4977.6266779991465, 4879.658423910328, 4888.706677599355, 4916.926246106294, 4921.532454416824, 4939.185034469308, 4769.5707386335225, 4774.59415949643, 4853.490383509751, 4857.149998750205, 4860.684055536397, 4848.733319667417, 4850.951490485085, 4857.611391088704, 4858.125537403266, 4900.975495122501, 4725.480197178307, 4823.421189457287, 4831.9275210871865, 4839.05638400512, 4856.3201151838775, 5193.688378697252, 5257.08001099806, 5294.205869119663, 5350.785968561253, 5353.812616558441, 5730.140478928152, 5800.332961709373, 5802.883942321172, 5806.332271788756, 5818.4763371296785, 5563.693996830173, 5702.8289151325525, 5776.393478684761, 5799.8550036249435, 5801.100829371454, 5349.4650534946195, 5426.674399536001, 5431.918521222225, 5435.402105768362, 5442.972785136049, 5949.494292985145, 5955.76176952917, 5956.493698035629, 5956.493698035715, 5957.49361304294, 5463.153211252262, 5526.0, 5613.101903695389, 5724.4561766632105, 5732.627379220337, 5676.460736230051, 5695.914561281584, 5696.8860622787315, 5732.455416735404, 5770.422957704196, 5636.60543761941, 5682.977064128497, 5688.374278829326, 5690.9146362804595, 5691.345495268014, 5450.127979523337, 5622.156676498476, 5663.0, 5697.658140511582, 5699.971500142556, 4358.084802191512, 4359.760213188305, 4361.367601697736, 4368.039031413084, 4394.978025109918, 3887.7278590498395, 3910.4720862683744, 3914.2562913047, 3940.389239667856, 3951.5258169019903, 5217.399999000164, 5321.6540924839765, 5323.920141197885, 5329.680219186861, 5370.812021579304, 5658.292713410824, 5674.546036317085, 5680.573956953245, 5685.403032681592, 5720.713964301768, 5274.498922602346, 5407.378151512547, 5415.0, 5418.783248670009, 5467.343918729778, 4805.327254184417, 4831.661783675109, 4866.635002374836, 4869.561739443467, 4877.219644856806, 5846.123081537803, 5863.648181109147, 5867.266591676041, 5868.031774757117, 5869.148973398833, 5550.47270509301, 5633.605647604629, 5645.774169033192, 5672.290963629546, 5702.37273899879, 5887.116932460165, 5923.703814809329, 5935.258092738408, 5944.8811023780145, 5966.49284810796, 5645.971770141123, 5647.915281270866, 5649.435056494318, 5651.773929042793, 5661.348944871228, 5516.91724624139, 5619.80330688424, 5662.348829884451, 5692.402297758841, 5732.082866741302, 5551.2228288040515, 5559.694216818114, 5565.415631358811, 5570.108782594765, 5587.050201465842, 5540.445955404427, 5560.610757246954, 5567.331920169606, 5571.554275658019, 5572.526335261548, 5217.191335343028, 5357.357117145967, 5446.264754258124, 5471.179323101566, 5474.425185355481, 5609.382967873449, 5660.660360378391, 5666.263385759825, 5670.432956704297, 5671.376148623731, 5454.236406902957, 5491.0, 5496.5053145217125, 5499.0, 5501.119820828648], "value": 5257.0171570215725, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.5527137553897, 642.8167972127944, 655.2530115668139, 655.5804285257435, 663.8506336074381, 43744.781276093614, 45560.31659525102, 45605.31592026118, 45615.315770263434, 48288.51711482884, 45892.311615325714, 46496.76751616246, 46524.0, 46692.066158676804, 46732.5604067613, 8755.649774009047, 8937.642494300253, 8943.10568943107, 8943.95528022359, 8947.194752472276, 8751.693690720826, 8823.691170809023, 8837.072107428723, 8847.823043539125, 8849.292056635453, 8829.205371516595, 8863.0, 8884.0, 8885.42244754089, 8889.73330800077, 8796.120387961217, 8816.735497935073, 8843.867341989842, 8848.823023539493, 8850.468971861708, 8783.560821958941, 8809.691660791841, 8844.778880527974, 8850.955745221232, 8860.512671803048, 42623.36064959028, 42682.57317426848, 42845.0, 42845.57154428447, 42872.0, 3520.700740437066, 3581.4985901973746, 3582.444721068239, 3607.3687104756564, 3665.4135338345736, 33111.503327449864, 33377.0, 33832.661673383445, 33841.15397115092, 34268.0, 4092.5498195198506, 4135.855245066408, 4151.91696166078, 4157.127003329305, 4183.267928112598, 368784.0, 369057.0, 369734.0, 370359.0, 370486.0, 60160.39839601635, 60477.0, 60677.39322606806, 60754.0, 60931.0, 61379.386206137366, 63667.68166159139, 64731.352686473474, 65000.34999650037, 69753.3024669757, 16110.75833862493, 16237.918810405872, 16258.75611865823, 16260.59348516273, 16266.67466650684, 11290.15323850698, 11781.882181178078, 11812.94093529544, 11852.703682407837, 11862.703432414259, 17522.21150048251, 17905.28378864857, 17935.910320448314, 18442.35451759182, 18472.168752406174, 5992.880142397127, 6019.398060193946, 6063.423974722395, 6085.148079268906, 6178.4439400453275, 938.3103418987175, 1104.6133853151332, 1142.4459137318395, 1155.3472288157163, 1156.9942150289194, 32200.77992200808, 33412.66587334096, 33560.49659255113, 33737.32525349479, 34366.82816585901, 1098.542204803903, 1129.2264798612975, 1154.7113221694576, 1162.0645380468804, 1208.0999655256715, 17539.56151096233, 17565.824341756674, 17619.55951101233, 17773.0, 17782.733259001125, 499112.0, 500474.0, 502043.0, 504695.0, 505686.0, 60580.69709651423, 60734.69632651897, 60735.0, 60905.69547152324, 60932.69533652304, 1285.4858056777364, 1405.6556143744847, 1431.2485944878813, 1435.411481292665, 1440.6614445605273, 33406.16484587808, 34722.826385867906, 34814.303713925074, 35117.0, 35176.0, 51742.223866641274, 51896.74051629718, 52359.73820130875, 52379.21431178459, 52412.21381679201, 295273.0, 297291.0, 298255.0, 299416.0, 299518.0, 36352.63647363493, 41049.384259235536, 42465.575344246165, 42721.0, 45183.0, 48394.0, 48758.0, 49211.75394123006, 49657.0, 49751.0, 41159.58840411557, 41994.0, 42093.78953105337, 43075.784621076695, 43396.56603433925, 41503.58496414997, 41719.374209386275, 41888.79055604825, 42024.789876050425, 42456.5754342465, 42067.0, 42282.36576451417, 42544.5745542553, 43182.0, 43334.78332608317, 35960.28079438449, 37608.811955940044, 37992.430113547765, 38334.0, 38867.22265554617, 28604.570931435628, 28653.283667907635, 28995.0, 29187.70812291935, 29242.70757292485], "value": 20404.995603399493, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.13475382520045, 389.2798323102259, 393.4825704198978, 400.0079802090815, 413.54303494638424, 14442.927785361086, 17338.306467741277, 18333.816661833378, 19790.0, 19847.0, 15625.687486250268, 18254.81745182548, 18915.62168756624, 19388.51528711783, 19929.501762455944, 5460.153676180198, 5584.385717571064, 5600.2159697642155, 5637.971810140964, 5643.633163844357, 4016.9799151004204, 4211.978940105295, 4214.4099826024385, 5552.7223638818095, 5802.448767367115, 6094.055421409666, 6207.317195108534, 6211.565190436649, 6212.534059945499, 6218.533609979269, 5971.820845374646, 6110.511159107261, 6161.568690191689, 6229.813105606839, 6229.875402491947, 4916.975415122937, 5425.267588875491, 5456.563474921997, 5458.4541545845495, 5464.617476776627, 12410.317432541207, 12644.178128421623, 12827.551035713705, 12843.0, 12862.421191046426, 2191.364504293753, 2231.8995645196005, 2593.1701855406277, 2613.516499447608, 2652.7214642462454, 9646.180074693659, 9648.372855764354, 9786.363886347435, 10146.391216527032, 10280.12618927392, 1820.1627251464342, 1900.4773687235981, 1939.0, 1974.5458544534695, 2037.3989673046487, 79581.0, 81264.59367703124, 81696.59151704263, 82577.58711206405, 83216.16783832145, 10461.84307235392, 10529.684109476728, 10839.078678312275, 11096.55613775456, 11178.161637877192, 15020.549383518512, 15141.167235802137, 15173.620659483602, 15228.771568426482, 15241.0, 7742.6128693566225, 7916.604169791487, 7923.0, 8064.193580642006, 8109.75670729879, 7728.07263128417, 7761.6119194040075, 7787.143414224431, 7894.368450523944, 7896.684132634745, 4174.749515029108, 4429.800658970354, 4503.527129651339, 4728.95271047292, 4824.445188803263, 1944.7666280046485, 1987.8409727221788, 2004.7894971028124, 2012.2030377774904, 2019.8586098973224, 964.8504481805331, 983.7934033852828, 985.69936169469, 1013.2501948558014, 1028.8353863381824, 19212.615747685246, 19338.129784159748, 19525.0, 19529.902350488155, 19538.31615893437, 963.9252233759308, 992.7518120469882, 1000.9849852252222, 1004.3823048825066, 1057.4606950455322, 10583.0, 10828.133749300037, 10876.61931832398, 10890.29213101146, 10939.945300273606, 369933.0, 375491.0, 376058.0, 461927.0, 463344.0, 65069.0, 84927.57536212403, 94437.5278123605, 102994.0, 105935.99999999846, 841.2722994609619, 875.4397185801092, 897.4974014551872, 898.9011208767031, 905.7237542549585, 17436.22442625652, 17466.21402036886, 17583.384581539583, 17628.91185544064, 17631.11844407749, 32686.509702354007, 34738.0, 35340.0, 40075.398869016404, 40651.0, 48601.75699121623, 49678.25482617836, 52413.47586524086, 54314.728426357615, 54546.727266365, 7604.505707129019, 7846.921530784619, 8003.959980200062, 8083.19168083187, 8200.302974247255, 13511.932440337736, 13781.173129612256, 13922.373493193034, 13941.790873136708, 13944.4422223114, 12219.69450763738, 12251.509939602673, 12390.070744694003, 12477.0, 12604.11771176009, 11632.76734465289, 11736.823947640622, 11757.294562326288, 12049.759004820029, 12109.939450302692, 9215.631374744935, 12007.639770806889, 12282.201656892274, 12372.938135309267, 12382.0, 8002.399820013406, 8062.677492900453, 8114.634841432267, 8260.628271727666, 8294.419390642597, 11478.196526243082, 11881.465334059814, 12040.458179382123, 12051.674315825077, 12471.0], "value": 8874.03051699001, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.4088703188177, 1507.4573153664676, 1544.0426935300115, 1683.2004797721083, 1814.4738025972476, 8620.698275560357, 8962.686305979292, 8972.327075469333, 9297.814043719121, 9298.209652179572, 9010.72967810965, 9214.861777073349, 9222.21611163052, 9222.538873056354, 9224.077592240772, 2145.227718021515, 2173.467500462381, 2178.1396348442354, 2179.302623160589, 2181.018541656253, 2115.883626400547, 2134.9786502134934, 2153.364757396564, 2156.590247852903, 2156.773538778429, 2120.1731324783345, 2147.7530084040377, 2151.3008272311563, 2154.0845140815168, 2178.3900507857825, 2058.835293176542, 2060.062671484471, 2060.5157787919825, 2063.607914496241, 2066.8449866260016, 2088.457001179689, 2116.100657220675, 2121.5438680683574, 2122.8832414217213, 2126.0539060118253, 8675.652973881037, 8909.55452227386, 9027.277817774562, 9073.682421115229, 9093.954530227307, 5109.4379618241965, 5166.889118839467, 5186.636935414523, 5199.324087868591, 5209.374875014984, 3210.839458027089, 3306.834658267077, 3307.1897385140555, 3329.250918543333, 3338.2488939988307, 1074.548689550383, 1084.138110202391, 1084.7613525024483, 1089.1232557790975, 1117.9608713695063, 12664.620061398173, 12714.046446516548, 12716.300603466805, 12761.106722529426, 12771.936140319332, 4243.236217480821, 4272.0, 4330.610245077958, 4333.479982402069, 4370.497392799805, 9983.0, 10050.246231532665, 10069.295149339623, 10074.395536267848, 10078.042585954323, 5142.897142057138, 5201.7919283229, 5204.739763011834, 5205.531502164823, 5212.921806172911, 4289.828406863753, 4314.848980285675, 4757.452892917289, 4799.568038876518, 4874.8050077997195, 3784.413415920537, 3825.923481530354, 3848.13416981177, 3872.1674839909556, 3997.7801220932547, 1051.984220236697, 1112.5605385872534, 1115.754534002518, 1130.6212418839616, 1134.2173900008968, 896.6278994217457, 1012.8784545854397, 1013.5135135135176, 1028.7685270814156, 1035.089121573011, 6147.66187859672, 6193.38066193377, 6232.376762323732, 6470.773522926675, 6510.08858759774, 1032.3496197395743, 1040.5941682743735, 1058.3385384134915, 1072.3458690198972, 1114.3536748685906, 4070.430139780433, 4083.9183216335505, 4117.238310912431, 4222.092250166229, 4330.069035157457, 11022.338659680285, 11233.94383028096, 11236.943815281034, 11243.437828108561, 11297.604583839691, 10798.78402431947, 11030.669079927615, 11112.833307500394, 11134.3876086816, 11144.05275551571, 8281.37889658278, 8626.784330391665, 8643.524606146722, 8707.259882910024, 8711.172438618321, 6252.124702541739, 6521.34786521363, 6601.9009714853355, 6608.504362172953, 6614.570052946543, 10147.695569132939, 10530.736731581772, 10532.52603632823, 10651.30766500175, 10749.301295415773, 10696.732581685521, 10806.081483074027, 10816.0, 10830.241883068426, 10834.241603087708, 7440.181580026301, 7874.527528348317, 8065.556394398364, 8093.635786389509, 8340.999080110183, 8529.872051919103, 8726.869096963677, 8732.825343492968, 8741.606627701642, 8774.034856165945, 7902.644381002984, 7961.482503637245, 8096.190380961857, 8145.42981991255, 8147.406488635137, 7876.369890408868, 7978.84042319162, 8022.157673444438, 8119.269265766109, 8162.387820913336, 7188.460865435218, 7286.052813134259, 7294.854102917806, 7296.890546641698, 7342.082239720035, 5298.28473156119, 5317.97341013308, 5392.379876314274, 5405.540529055074, 5480.862978425571, 5462.989346970823, 5559.777608895599, 5589.161625756006, 5606.327240731139, 5639.7180140993605], "value": 4661.994557878134, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.6983876446025, 313.8070086896559, 314.294409051679, 316.21420769388067, 320.65209247965953, 5364.54401375883, 5413.215083812845, 5424.321959755031, 5452.263944367509, 5460.672359658418, 5091.592672586194, 5177.145770947789, 5207.531322181003, 5232.398274198459, 5373.865653358668, 4026.5973402659793, 4102.753834769908, 4124.855630052949, 4134.917301653965, 4150.8132134053885, 4122.42286079949, 4126.731762435447, 4130.690198235145, 4135.09028013838, 4143.461350024508, 4021.7385869918508, 4077.245709543744, 4086.693497987648, 4143.067809742799, 4176.874693759192, 4109.486314210723, 4124.1133156371225, 4124.6906482013965, 4147.668186545069, 4164.292070348048, 3644.471551625026, 3650.142216579102, 3653.981730091359, 3676.1361080146144, 3687.907802304937, 5057.893625069377, 5130.30740849988, 5132.153194722866, 5143.742812859342, 5148.227765835118, 4749.786259618326, 4788.425388953313, 4791.3531673224015, 4796.808127674889, 4814.638902082323, 5581.190727344512, 5582.944170558283, 5605.561580712403, 5614.943850561483, 5638.323401191883, 3734.3838266685966, 4059.4925634295714, 4067.1255680028935, 4080.9387859182134, 4088.713790034699, 5773.451522105435, 5791.841631673682, 5807.883842323172, 5814.389489103625, 5840.948629246734, 4028.436018957348, 4031.6169963853386, 4036.57615950321, 4051.5745846685672, 4056.3307054335705, 4108.301588729923, 4108.383742438658, 4115.6295933365545, 4120.434234990685, 4130.834766609362, 4490.438695163105, 4677.415323084614, 4710.717356958594, 4712.95287047132, 4753.191957367256, 5604.0, 5634.464725851038, 5660.688662123541, 5676.943230567642, 5696.629719068247, 1346.4008516210192, 1410.4569740649717, 1412.865777751112, 1415.8513356097667, 1420.155007770375, 912.6851236323457, 925.2459245714721, 935.8362286599818, 948.7912659214961, 956.3783540698463, 4845.63657725672, 4856.538628830255, 4858.376476702611, 4884.998575292057, 4911.214205727067, 5054.786851155696, 5084.034033533617, 5086.652037210142, 5092.9304845982015, 5103.362079740033, 4534.9319760203625, 4815.494373090775, 4866.537678920497, 4867.610591152699, 4896.828610998598, 2688.8841130930837, 2718.4834881372476, 2730.2218867622632, 2754.3389586499097, 2792.2042217967783, 5199.66202196856, 5204.21936709489, 5239.332467272848, 5252.0, 5258.868528286747, 5120.846374608767, 5269.657472264291, 5279.498447647467, 5479.3424789024775, 5892.7642894283745, 5954.523638108941, 5954.55340849438, 5956.761729530857, 5965.970170149121, 6003.6397816131175, 5777.306723193244, 5784.50831679312, 5799.5070419014855, 5802.187693722966, 5814.651120932757, 5572.3034620672415, 5715.8571035724435, 5731.971340143273, 5740.478773125042, 5742.0, 5945.494632956247, 5948.0, 5974.1934838796205, 5978.222831031938, 5995.520358371407, 3861.5945325740395, 4054.432379466818, 4059.594040596035, 4080.6123418275215, 4088.407180958685, 5532.723363831873, 5674.06377947634, 5721.570882183936, 5747.41945964863, 5755.942440575708, 5499.010178167968, 5596.9440305598055, 5600.383957764561, 5600.439956004367, 5631.324241091095, 5584.609077364708, 5590.385057643737, 5591.189277554813, 5617.241672374177, 5632.0, 5234.267202591716, 5484.0, 5490.835274941758, 5501.614886957875, 5505.752241149078, 5603.2155498231095, 5607.299087614048, 5613.46672066153, 5617.74720137603, 5673.0, 5571.554275657856, 5576.0, 5577.462254674032, 5577.860553486195, 5580.135079062752], "value": 4178.64598784475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.9509829410235, 856.3919617071879, 862.2799962031703, 881.7222574888909, 898.5686870302253, 15184.164870932094, 15636.061836289817, 15882.047077175363, 15973.0, 16031.438899638515, 14174.574762757107, 14332.211728354936, 14337.784933225983, 14383.208923509203, 14410.495632652859, 686.1491750229729, 807.9232472915093, 827.7765003449065, 839.7061028639988, 846.9237768600825, 839.290799274612, 840.1388576708861, 842.321930845669, 843.6794018273048, 843.9535825529593, 883.6995421556669, 885.3006125161115, 885.5616469847434, 886.3441053620306, 886.441541828647, 725.2928394815066, 867.3538214030556, 874.4447275979767, 888.9111088891124, 889.5329951775369, 637.2225884420999, 641.3906788550848, 641.5733537197758, 642.3095172689377, 642.858571114354, 14932.552023439206, 14952.626184345478, 14980.925095374563, 14989.400423983028, 14991.32539035746, 13587.388567514485, 13774.380152893144, 13840.515581954684, 14021.929890350584, 14084.225367604779, 11840.407979601072, 11933.582324618597, 11974.461149248305, 12114.394280286038, 12246.877531224663, 4488.192125417424, 4527.932081018787, 4532.818687252506, 4667.603253723438, 4701.412323459567, 61499.38500615026, 61633.0, 62050.0, 62189.378106219265, 62471.375286247014, 11176.497057632427, 11192.104631629612, 11288.0, 11417.828732569018, 11475.0, 19840.305589304593, 19855.205791768458, 19871.205151793765, 19884.60230795376, 19888.70166947497, 7826.882596761053, 7940.205979402013, 7961.164077771867, 8208.179182081743, 8247.587620618946, 4686.976565117153, 4732.2428411454, 4741.762911854394, 4829.66192366531, 4885.096257192431, 3389.9152521187166, 3513.7364697647777, 3521.8767343142868, 3541.6635419635095, 3543.875964341236, 565.2284631478003, 626.5394934722975, 638.4190386748037, 652.1619718661498, 687.2886562407983, 628.230417738267, 671.8186089755739, 702.9648517574101, 729.05951322793, 752.420636110192, 10564.524596393181, 10742.194335424718, 10827.621033263798, 10874.510647020903, 10901.400422976812, 742.688071010177, 770.4837758701683, 771.3289438188699, 788.113372455987, 809.2813521867012, 4942.258661200777, 4945.5964480901575, 4957.504249575014, 4976.527229913152, 5088.567471764867, 69844.0, 72089.0, 72351.0, 73659.2634073663, 73877.0, 62505.374946249955, 63230.36769632245, 63429.0, 63434.36565634377, 65556.0, 56263.46814393362, 56313.43686563164, 58905.41094589171, 59085.409145907994, 59647.0, 9451.432914025181, 9526.761830954281, 9627.855582166414, 9629.470379128936, 9766.9023309766, 39108.41337379999, 39173.02067448223, 39189.216215674955, 39407.40888886726, 39540.60459395484, 47124.29313560368, 51575.226371603705, 53526.73236633792, 53953.25584301962, 54621.453785461636, 13071.940873780575, 13117.475300987853, 13156.210627362389, 13301.334933253105, 13425.86574134246, 14552.927235363755, 14816.481423150139, 14974.176420296988, 14974.550763477113, 14995.550133496012, 13802.240876751473, 13820.792688109888, 13875.236861972688, 14009.649758756113, 14086.577402677936, 12944.0, 13113.40989655487, 13255.668608284484, 13378.0, 13378.0, 13369.465221391036, 13464.865351346361, 13474.79787803164, 13666.385012674255, 13818.447262109805, 10846.783064338826, 10940.945295273472, 10970.725731856768, 11193.104551636012, 11318.943405283251, 10748.193885458779, 10977.83533246986, 11002.614908478165, 11081.39052352096, 11119.610813621484], "value": 7215.0138655470455, "unit": "score"}], "name": "dromaeo_css", "value": 7151.786050184649, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 110.0, 109.0, 110.0, 110.0, 108.0, 110.0, 110.0, 109.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 125.0, 159.0, 209.0, 137.0, 150.0, 157.0, 124.0, 183.0, 127.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 160.0, 161.0, 161.0, 160.0, 162.0, 160.0, 160.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 146.0, 73.0, 72.0, 75.0, 74.0, 137.0, 73.0], "value": 87.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 145.0, 94.0, 92.0, 91.0, 119.0, 91.0, 91.0, 125.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 147.0, 146.0, 146.0, 144.0, 146.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 96.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 55.0, 55.0, 56.0, 105.0, 52.0, 53.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 58.0, 58.0, 59.0, 57.0, 58.0, 59.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 76.0, 81.0, 79.0, 79.0, 78.0, 80.0, 79.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 120.0, 118.0, 120.0, 134.0, 118.0, 117.0, 131.0, 195.0, 124.0], "value": 130.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 159.0, 156.0, 158.0, 161.0, 155.0, 156.0, 158.0, 155.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 66.0, 63.0, 61.0, 62.0, 61.0, 61.0, 61.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1516.1000000000001, "alertThreshold": 2.0}]} 23:38:37 INFO - Return code: 0 23:38:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:38:37 ERROR - # TBPL SUCCESS # 23:38:37 INFO - Running post-action listener: _resource_record_post_action 23:38:37 INFO - ##### 23:38:37 INFO - ##### Finished run-tests step (success) 23:38:37 INFO - ##### 23:38:37 INFO - Running post-run listener: _resource_record_post_run 23:38:37 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 7798784; Write bytes: 494911488; Read time: 1736; Write time: 962300 23:38:37 INFO - TinderboxPrint: CPU usage
13.2% 23:38:37 INFO - TinderboxPrint: I/O read bytes / time
7,798,784 / 1,736 23:38:37 INFO - TinderboxPrint: I/O write bytes / time
494,911,488 / 962,300 23:38:37 INFO - TinderboxPrint: CPU idle
8,321.3 (86.8%) 23:38:37 INFO - TinderboxPrint: CPU user
1,203.8 (12.6%) 23:38:37 INFO - TinderboxPrint: Swap in / out
0 / 0 23:38:37 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 158396416; Read time: 428; Write time: 292464 23:38:37 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 6057984; Write bytes: 334897152; Read time: 1272; Write time: 661652 23:38:38 INFO - Running post-run listener: _upload_blobber_files 23:38:38 INFO - Blob upload gear active. 23:38:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:38:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:38:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:38:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:38:38 INFO - (blobuploader) - INFO - Open directory for files ... 23:38:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:38:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:38:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:39 INFO - (blobuploader) - INFO - Done attempting. 23:38:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:38:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:38:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:40 INFO - (blobuploader) - INFO - Done attempting. 23:38:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:38:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:44 INFO - (blobuploader) - INFO - Done attempting. 23:38:44 INFO - (blobuploader) - INFO - Iteration through files over. 23:38:44 INFO - Return code: 0 23:38:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:38:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:38:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4f224b0d79c33c770fb27fd6dc5127883d079ae262c20667929a08b5e94f4e0eb8368511c934cd53192dc3ab7abd8357c4320f4a5955057cda65b74434f6406", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9dab5c0ac6e77a05635991dc460f3da210e7ac1477cc2efb92cd7a53a20c8eefd642cd25673d74059ec4203f5c95e6cc2bee3a81598aeca517b8a66e2c19b6ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b72c52ab9feecedf1caeb7b9488f680d0fc90ac95ff8a0f641489a3d3f9858bab1e4554b9b0cdf4d24c27bfec9a1939e818c7393a880200a19ecb0b5702bfe52"} 23:38:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:38:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:38:44 INFO - Contents: 23:38:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4f224b0d79c33c770fb27fd6dc5127883d079ae262c20667929a08b5e94f4e0eb8368511c934cd53192dc3ab7abd8357c4320f4a5955057cda65b74434f6406", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9dab5c0ac6e77a05635991dc460f3da210e7ac1477cc2efb92cd7a53a20c8eefd642cd25673d74059ec4203f5c95e6cc2bee3a81598aeca517b8a66e2c19b6ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b72c52ab9feecedf1caeb7b9488f680d0fc90ac95ff8a0f641489a3d3f9858bab1e4554b9b0cdf4d24c27bfec9a1939e818c7393a880200a19ecb0b5702bfe52"} 23:38:44 INFO - Running post-run listener: copy_logs_to_upload_dir 23:38:44 INFO - Copying logs to upload dir... 23:38:44 INFO - mkdir: /builds/slave/test/build/upload/logs 23:38:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.452435 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-05-15 23:38:43.984419) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-15 23:38:43.988263) ========= 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=5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4f224b0d79c33c770fb27fd6dc5127883d079ae262c20667929a08b5e94f4e0eb8368511c934cd53192dc3ab7abd8357c4320f4a5955057cda65b74434f6406", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9dab5c0ac6e77a05635991dc460f3da210e7ac1477cc2efb92cd7a53a20c8eefd642cd25673d74059ec4203f5c95e6cc2bee3a81598aeca517b8a66e2c19b6ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b72c52ab9feecedf1caeb7b9488f680d0fc90ac95ff8a0f641489a3d3f9858bab1e4554b9b0cdf4d24c27bfec9a1939e818c7393a880200a19ecb0b5702bfe52"} build_url:https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011073 build_url: 'https://queue.taskcluster.net/v1/task/E1C1mMrcTU2u2af2NuPgHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4f224b0d79c33c770fb27fd6dc5127883d079ae262c20667929a08b5e94f4e0eb8368511c934cd53192dc3ab7abd8357c4320f4a5955057cda65b74434f6406", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9dab5c0ac6e77a05635991dc460f3da210e7ac1477cc2efb92cd7a53a20c8eefd642cd25673d74059ec4203f5c95e6cc2bee3a81598aeca517b8a66e2c19b6ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b72c52ab9feecedf1caeb7b9488f680d0fc90ac95ff8a0f641489a3d3f9858bab1e4554b9b0cdf4d24c27bfec9a1939e818c7393a880200a19ecb0b5702bfe52"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-15 23:38:44.014836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 23:38:44.015328) ========= 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=5cf47e208febbcd81da23b2e0000026f-1463355130.219953-1820453679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004361 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 23:38:44.096843) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-15 23:38:44.097187) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-15 23:38:44.097553) ========= ========= Total master_lag: 0.16 =========