builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-050 starttime: 1463281780.18 results: success (0) buildid: 20160514173010 builduid: c0ac523baf474938b02204cb6bff301d revision: 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.179802) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.180261) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.180590) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.237933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.238215) ========= 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=6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-14 20:09:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.9M=0s 2016-05-14 20:09:41 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113682 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.495983) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.496390) ========= 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=6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019186 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.550218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:40.553594) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a --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 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a --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=6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-14 20:09:41,504 truncating revision to first 12 chars 2016-05-14 20:09:41,504 Setting DEBUG logging. 2016-05-14 20:09:41,504 attempt 1/10 2016-05-14 20:09:41,504 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/091bc7c572db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 20:09:41,941 unpacking tar archive at: fx-team-091bc7c572db/testing/mozharness/ program finished with exit code 0 elapsedTime=0.723046 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:41.304251) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:41.304725) ========= script_repo_revision: 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:41.305140) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:41.305430) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 20:09:41.316236) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-14 20:09:41.316515) ========= /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=6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:09:42 INFO - MultiFileLogger online at 20160514 20:09:42 in /builds/slave/test 20:09:42 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 20:09:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:09:42 INFO - {'append_to_log': False, 20:09:42 INFO - 'base_work_dir': '/builds/slave/test', 20:09:42 INFO - 'blob_upload_branch': 'Fx-Team', 20:09:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:09:42 INFO - 'branch': 'Fx-Team', 20:09:42 INFO - 'buildbot_json_path': 'buildprops.json', 20:09:42 INFO - 'config_files': ('talos/linux_config.py',), 20:09:42 INFO - 'default_actions': ('clobber', 20:09:42 INFO - 'read-buildbot-config', 20:09:42 INFO - 'download-and-extract', 20:09:42 INFO - 'populate-webroot', 20:09:42 INFO - 'create-virtualenv', 20:09:42 INFO - 'install', 20:09:42 INFO - 'run-tests'), 20:09:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:09:42 INFO - 'download_minidump_stackwalk': True, 20:09:42 INFO - 'download_symbols': 'ondemand', 20:09:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:09:42 INFO - 'tooltool.py': '/tools/tooltool.py', 20:09:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:09:42 INFO - '/tools/misc-python/virtualenv.py')}, 20:09:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:09:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:09:42 INFO - 'installer_path': 'installer.exe', 20:09:42 INFO - 'log_level': 'info', 20:09:42 INFO - 'log_name': 'talos', 20:09:42 INFO - 'log_to_console': True, 20:09:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:09:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:09:42 INFO - 'opt_config_files': (), 20:09:42 INFO - 'pip_index': False, 20:09:42 INFO - 'sps_profile': False, 20:09:42 INFO - 'sps_profile_interval': 0, 20:09:42 INFO - 'suite': 'dromaeojs', 20:09:42 INFO - 'system_bits': '32', 20:09:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:09:42 INFO - 'title': 'talos-linux64-ix-050', 20:09:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:09:42 INFO - 'use_talos_json': True, 20:09:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:09:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:09:42 INFO - 'work_dir': 'build'} 20:09:42 INFO - ##### 20:09:42 INFO - ##### Running clobber step. 20:09:42 INFO - ##### 20:09:42 INFO - Running pre-action listener: _resource_record_pre_action 20:09:42 INFO - Running main action method: clobber 20:09:42 INFO - rmtree: /builds/slave/test/build 20:09:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:09:42 INFO - Running post-action listener: _resource_record_post_action 20:09:42 INFO - ##### 20:09:42 INFO - ##### Finished clobber step (success) 20:09:42 INFO - ##### 20:09:42 INFO - ##### 20:09:42 INFO - ##### Running read-buildbot-config step. 20:09:42 INFO - ##### 20:09:42 INFO - Running pre-action listener: _resource_record_pre_action 20:09:42 INFO - Running main action method: read_buildbot_config 20:09:42 INFO - Using buildbot properties: 20:09:42 INFO - { 20:09:42 INFO - "project": "", 20:09:42 INFO - "product": "firefox", 20:09:42 INFO - "script_repo_revision": "production", 20:09:42 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:09:42 INFO - "repository": "", 20:09:42 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:09:42 INFO - "buildid": "20160514173010", 20:09:42 INFO - "pgo_build": "True", 20:09:42 INFO - "basedir": "/builds/slave/test", 20:09:42 INFO - "buildnumber": 89, 20:09:42 INFO - "slavename": "talos-linux64-ix-050", 20:09:42 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:09:42 INFO - "platform": "ubuntu64_hw", 20:09:42 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:09:42 INFO - "branch": "fx-team", 20:09:42 INFO - "repo_path": "integration/fx-team", 20:09:42 INFO - "revision": "091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a", 20:09:42 INFO - "stage_platform": "linux64-pgo", 20:09:42 INFO - "builduid": "c0ac523baf474938b02204cb6bff301d", 20:09:42 INFO - "slavebuilddir": "test" 20:09:42 INFO - } 20:09:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 20:09:42 INFO - Running post-action listener: _resource_record_post_action 20:09:42 INFO - ##### 20:09:42 INFO - ##### Finished read-buildbot-config step (success) 20:09:42 INFO - ##### 20:09:42 INFO - ##### 20:09:42 INFO - ##### Running download-and-extract step. 20:09:42 INFO - ##### 20:09:42 INFO - Running pre-action listener: _resource_record_pre_action 20:09:42 INFO - Running main action method: download_and_extract 20:09:42 INFO - mkdir: /builds/slave/test/build/tests 20:09:42 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')]} 20:09:42 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:09:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:09:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:09:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:43 INFO - Downloaded 1361 bytes. 20:09:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:09:43 INFO - Using the following test package requirements: 20:09:43 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:09:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:09:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:09:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:43 INFO - u'jsshell-linux-x86_64.zip'], 20:09:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:09:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:09:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:09:43 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:09:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:09:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:09:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:09:43 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')]} 20:09:43 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:09:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:09:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:09:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:44 INFO - Downloaded 24022619 bytes. 20:09:44 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'] 20:09:44 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 20:09:45 INFO - Return code: 0 20:09:45 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 20:09:45 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')]} 20:09:45 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:09:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:09:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:09:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:55 INFO - Downloaded 24022619 bytes. 20:09:55 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'] 20:09:55 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 20:09:56 INFO - Return code: 0 20:09:56 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')]} 20:09:56 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:09:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:09:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:09:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:58 INFO - Downloaded 10840194 bytes. 20:09:58 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'] 20:09:58 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 20:09:58 INFO - Return code: 0 20:09: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')]} 20:09:58 INFO - https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:09:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:09:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:09:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:09:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LMM4aMymSoiMH08uXZZhhQ/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 20:10:00 INFO - Downloaded 58398130 bytes. 20:10:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:10:00 INFO - mkdir: /builds/slave/test/properties 20:10:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:10:00 INFO - Writing to file /builds/slave/test/properties/build_url 20:10:00 INFO - Contents: 20:10:00 INFO - build_url:https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:10:01 INFO - Running post-action listener: _resource_record_post_action 20:10:01 INFO - Running post-action listener: set_extra_try_arguments 20:10:01 INFO - ##### 20:10:01 INFO - ##### Finished download-and-extract step (success) 20:10:01 INFO - ##### 20:10:01 INFO - ##### 20:10:01 INFO - ##### Running populate-webroot step. 20:10:01 INFO - ##### 20:10:01 INFO - Running pre-action listener: _resource_record_pre_action 20:10:01 INFO - Running main action method: populate_webroot 20:10:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:10:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:10:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:10:01 INFO - '--tppagecycles', 20:10:01 INFO - '7'], 20:10:01 INFO - 'tests': ['tsvgm']}}, 20:10:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:10:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:10:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:10:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:10:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:01 INFO - 'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:10:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:10:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:01 INFO - 'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['damp', 'tps']}, 20:10:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:01 INFO - 'tests': ['damp', 'tps']}, 20:10:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['dromaeo_dom']}, 20:10:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:10:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['a11yr', 20:10:01 INFO - 'ts_paint', 20:10:01 INFO - 'tpaint', 20:10:01 INFO - 'sessionrestore', 20:10:01 INFO - 'sessionrestore_no_auto_restore', 20:10:01 INFO - 'tabpaint']}, 20:10:01 INFO - 'other-e10s': {'tests': ['a11yr', 20:10:01 INFO - 'ts_paint', 20:10:01 INFO - 'tpaint', 20:10:01 INFO - 'sessionrestore', 20:10:01 INFO - 'sessionrestore_no_auto_restore', 20:10:01 INFO - 'tabpaint']}, 20:10:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:10:01 INFO - 'ts_paint', 20:10:01 INFO - 'tpaint', 20:10:01 INFO - 'sessionrestore', 20:10:01 INFO - 'sessionrestore_no_auto_restore', 20:10:01 INFO - 'tabpaint']}, 20:10:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:10:01 INFO - 'ts_paint', 20:10:01 INFO - 'tpaint', 20:10:01 INFO - 'sessionrestore', 20:10:01 INFO - 'sessionrestore_no_auto_restore', 20:10:01 INFO - 'tabpaint']}, 20:10:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['a11yr', 20:10:01 INFO - 'ts_paint', 20:10:01 INFO - 'tpaint', 20:10:01 INFO - 'sessionrestore', 20:10:01 INFO - 'sessionrestore_no_auto_restore', 20:10:01 INFO - 'tabpaint']}, 20:10:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['a11yr', 20:10:01 INFO - 'ts_paint', 20:10:01 INFO - 'tpaint', 20:10:01 INFO - 'sessionrestore', 20:10:01 INFO - 'sessionrestore_no_auto_restore', 20:10:01 INFO - 'tabpaint']}, 20:10:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['tsvgx', 20:10:01 INFO - 'tsvgr_opacity', 20:10:01 INFO - 'tart', 20:10:01 INFO - 'tscrollx', 20:10:01 INFO - 'cart']}, 20:10:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:10:01 INFO - 'tsvgr_opacity', 20:10:01 INFO - 'tart', 20:10:01 INFO - 'tscrollx', 20:10:01 INFO - 'cart']}, 20:10:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:01 INFO - 'talos_options': ['--disable-e10s'], 20:10:01 INFO - 'tests': ['tp5o']}, 20:10:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:01 INFO - 'tests': ['tp5o']}, 20:10:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:01 INFO - 'talos_options': ['--disable-e10s', 20:10:01 INFO - '--xperf_path', 20:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:10:01 INFO - 'tests': ['tp5n']}, 20:10:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:01 INFO - 'talos_options': ['--xperf_path', 20:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:10:01 INFO - 'tests': ['tp5n']}}, 20:10:01 INFO - 'talos.zip': {'path': '', 20:10:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:10:01 INFO - Running post-action listener: _resource_record_post_action 20:10:01 INFO - ##### 20:10:01 INFO - ##### Finished populate-webroot step (success) 20:10:01 INFO - ##### 20:10:01 INFO - ##### 20:10:01 INFO - ##### Running create-virtualenv step. 20:10:01 INFO - ##### 20:10:01 INFO - Running pre-action listener: _resource_record_pre_action 20:10:01 INFO - Running main action method: create_virtualenv 20:10:01 INFO - Creating virtualenv /builds/slave/test/build/venv 20:10:01 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 20:10:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:10:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:10:01 INFO - Using real prefix '/usr' 20:10:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:10:02 INFO - Installing distribute.............................................................................................................................................................................................done. 20:10:04 INFO - Installing pip.................done. 20:10:04 INFO - Return code: 0 20:10:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:10:04 INFO - Using env: {'DISPLAY': ':0', 20:10:04 INFO - 'HOME': '/home/cltbld', 20:10:04 INFO - 'LANG': 'en_US.UTF-8', 20:10:04 INFO - 'LANGUAGE': 'en_US:en', 20:10:04 INFO - 'LOGNAME': 'cltbld', 20:10:04 INFO - 'MAIL': '/var/mail/cltbld', 20:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:04 INFO - 'MOZ_NO_REMOTE': '1', 20:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:04 INFO - 'NO_EM_RESTART': '1', 20:10:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:04 INFO - 'PWD': '/builds/slave/test', 20:10:04 INFO - 'SHELL': '/bin/bash', 20:10:04 INFO - 'SHLVL': '1', 20:10:04 INFO - 'TERM': 'linux', 20:10:04 INFO - 'TMOUT': '86400', 20:10:04 INFO - 'USER': 'cltbld', 20:10:04 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:04 INFO - '_': '/tools/buildbot/bin/python'} 20:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:04 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 20:10:04 INFO - Cleaning up... 20:10:04 INFO - Return code: 0 20:10:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:10:04 INFO - Using env: {'DISPLAY': ':0', 20:10:04 INFO - 'HOME': '/home/cltbld', 20:10:04 INFO - 'LANG': 'en_US.UTF-8', 20:10:04 INFO - 'LANGUAGE': 'en_US:en', 20:10:04 INFO - 'LOGNAME': 'cltbld', 20:10:04 INFO - 'MAIL': '/var/mail/cltbld', 20:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:04 INFO - 'MOZ_NO_REMOTE': '1', 20:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:04 INFO - 'NO_EM_RESTART': '1', 20:10:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:04 INFO - 'PWD': '/builds/slave/test', 20:10:04 INFO - 'SHELL': '/bin/bash', 20:10:04 INFO - 'SHLVL': '1', 20:10:04 INFO - 'TERM': 'linux', 20:10:04 INFO - 'TMOUT': '86400', 20:10:04 INFO - 'USER': 'cltbld', 20:10:04 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:04 INFO - '_': '/tools/buildbot/bin/python'} 20:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:04 INFO - Downloading/unpacking psutil>=3.1.1 20:10:04 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 20:10:04 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 20:10:04 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 20:10:04 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 20:10:04 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 20:10:04 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 20:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:10:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:10:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:10:06 INFO - Installing collected packages: psutil 20:10:06 INFO - Running setup.py install for psutil 20:10:06 INFO - building 'psutil._psutil_linux' extension 20:10:06 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 20:10:06 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 20:10:06 INFO - building 'psutil._psutil_posix' extension 20:10:06 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 20:10:07 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 20:10:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:10:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:10:07 INFO - Successfully installed psutil 20:10:07 INFO - Cleaning up... 20:10:07 INFO - Return code: 0 20:10:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:10:07 INFO - Using env: {'DISPLAY': ':0', 20:10:07 INFO - 'HOME': '/home/cltbld', 20:10:07 INFO - 'LANG': 'en_US.UTF-8', 20:10:07 INFO - 'LANGUAGE': 'en_US:en', 20:10:07 INFO - 'LOGNAME': 'cltbld', 20:10:07 INFO - 'MAIL': '/var/mail/cltbld', 20:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:07 INFO - 'MOZ_NO_REMOTE': '1', 20:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:07 INFO - 'NO_EM_RESTART': '1', 20:10:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:07 INFO - 'PWD': '/builds/slave/test', 20:10:07 INFO - 'SHELL': '/bin/bash', 20:10:07 INFO - 'SHLVL': '1', 20:10:07 INFO - 'TERM': 'linux', 20:10:07 INFO - 'TMOUT': '86400', 20:10:07 INFO - 'USER': 'cltbld', 20:10:07 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:07 INFO - '_': '/tools/buildbot/bin/python'} 20:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:10:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:10:09 INFO - Installing collected packages: mozsystemmonitor 20:10:09 INFO - Running setup.py install for mozsystemmonitor 20:10:09 INFO - Successfully installed mozsystemmonitor 20:10:09 INFO - Cleaning up... 20:10:09 INFO - Return code: 0 20:10:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:10:09 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')]} 20:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:09 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')]} 20:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:09 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 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:09 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 20:10:09 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 20:10:09 INFO - Using env: {'DISPLAY': ':0', 20:10:09 INFO - 'HOME': '/home/cltbld', 20:10:09 INFO - 'LANG': 'en_US.UTF-8', 20:10:09 INFO - 'LANGUAGE': 'en_US:en', 20:10:09 INFO - 'LOGNAME': 'cltbld', 20:10:09 INFO - 'MAIL': '/var/mail/cltbld', 20:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:09 INFO - 'MOZ_NO_REMOTE': '1', 20:10:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:09 INFO - 'NO_EM_RESTART': '1', 20:10:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:09 INFO - 'PWD': '/builds/slave/test', 20:10:09 INFO - 'SHELL': '/bin/bash', 20:10:09 INFO - 'SHLVL': '1', 20:10:09 INFO - 'TERM': 'linux', 20:10:09 INFO - 'TMOUT': '86400', 20:10:09 INFO - 'USER': 'cltbld', 20:10:09 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:09 INFO - '_': '/tools/buildbot/bin/python'} 20:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:09 INFO - Downloading/unpacking blobuploader==1.2.4 20:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:11 INFO - Downloading blobuploader-1.2.4.tar.gz 20:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:10:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:10:11 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 20:10:11 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 20:10:11 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 20:10:11 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 20:10:11 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 20:10:11 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 20:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:10:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:10:12 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 20:10:12 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 20:10:12 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 20:10:12 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 20:10:12 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 20:10:12 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 20:10:12 INFO - Downloading docopt-0.6.1.tar.gz 20:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:10:12 INFO - Installing collected packages: blobuploader, requests, docopt 20:10:12 INFO - Running setup.py install for blobuploader 20:10:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:10:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:10:12 INFO - Running setup.py install for requests 20:10:12 INFO - Running setup.py install for docopt 20:10:12 INFO - Successfully installed blobuploader requests docopt 20:10:12 INFO - Cleaning up... 20:10:12 INFO - Return code: 0 20:10:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:12 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 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:12 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 20:10:12 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 20:10:12 INFO - Using env: {'DISPLAY': ':0', 20:10:12 INFO - 'HOME': '/home/cltbld', 20:10:12 INFO - 'LANG': 'en_US.UTF-8', 20:10:12 INFO - 'LANGUAGE': 'en_US:en', 20:10:12 INFO - 'LOGNAME': 'cltbld', 20:10:12 INFO - 'MAIL': '/var/mail/cltbld', 20:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:12 INFO - 'MOZ_NO_REMOTE': '1', 20:10:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:12 INFO - 'NO_EM_RESTART': '1', 20:10:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:12 INFO - 'PWD': '/builds/slave/test', 20:10:12 INFO - 'SHELL': '/bin/bash', 20:10:12 INFO - 'SHLVL': '1', 20:10:12 INFO - 'TERM': 'linux', 20:10:12 INFO - 'TMOUT': '86400', 20:10:12 INFO - 'USER': 'cltbld', 20:10:12 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:12 INFO - '_': '/tools/buildbot/bin/python'} 20:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:10:13 INFO - Running setup.py (path:/tmp/pip-qd28j9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:10:13 INFO - Running setup.py (path:/tmp/pip-RHreL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:10:13 INFO - Running setup.py (path:/tmp/pip-xOjit3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:10:13 INFO - Running setup.py (path:/tmp/pip-P6NI9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:10:13 INFO - Running setup.py (path:/tmp/pip-MPMO_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:10:13 INFO - Running setup.py (path:/tmp/pip-3RR_YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:10:13 INFO - Running setup.py (path:/tmp/pip-APZBJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:10:13 INFO - Running setup.py (path:/tmp/pip-PLoNld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:10:13 INFO - Running setup.py (path:/tmp/pip-gAUzUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:10:13 INFO - Running setup.py (path:/tmp/pip-Ip2yV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:10:13 INFO - Running setup.py (path:/tmp/pip-S4vPuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:10:13 INFO - Running setup.py (path:/tmp/pip-WFJAOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:10:14 INFO - Running setup.py (path:/tmp/pip-F4FqkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:10:14 INFO - Running setup.py (path:/tmp/pip-bkSGp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:14 INFO - Running setup.py (path:/tmp/pip-duz1GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:10:14 INFO - Running setup.py (path:/tmp/pip-0iweZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:10:14 INFO - Running setup.py (path:/tmp/pip-D71agP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:10:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:10:14 INFO - Running setup.py install for manifestparser 20:10:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:10:14 INFO - Running setup.py install for mozcrash 20:10:14 INFO - Running setup.py install for mozdebug 20:10:14 INFO - Running setup.py install for mozdevice 20:10:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:10:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:10:14 INFO - Running setup.py install for mozfile 20:10:14 INFO - Running setup.py install for mozhttpd 20:10:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:10:14 INFO - Running setup.py install for mozinfo 20:10:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Running setup.py install for mozInstall 20:10:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Running setup.py install for mozleak 20:10:15 INFO - Running setup.py install for mozlog 20:10:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Running setup.py install for moznetwork 20:10:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Running setup.py install for mozprocess 20:10:15 INFO - Running setup.py install for mozprofile 20:10:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Running setup.py install for mozrunner 20:10:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:10:15 INFO - Running setup.py install for mozscreenshot 20:10:15 INFO - Running setup.py install for moztest 20:10:16 INFO - Running setup.py install for mozversion 20:10:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:10:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:10:16 INFO - Cleaning up... 20:10:16 INFO - Return code: 0 20:10:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:16 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')]} 20:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:16 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')]} 20:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:16 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 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:16 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 20:10:16 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 20:10:16 INFO - Using env: {'DISPLAY': ':0', 20:10:16 INFO - 'HOME': '/home/cltbld', 20:10:16 INFO - 'LANG': 'en_US.UTF-8', 20:10:16 INFO - 'LANGUAGE': 'en_US:en', 20:10:16 INFO - 'LOGNAME': 'cltbld', 20:10:16 INFO - 'MAIL': '/var/mail/cltbld', 20:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:16 INFO - 'MOZ_NO_REMOTE': '1', 20:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:16 INFO - 'NO_EM_RESTART': '1', 20:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:16 INFO - 'PWD': '/builds/slave/test', 20:10:16 INFO - 'SHELL': '/bin/bash', 20:10:16 INFO - 'SHLVL': '1', 20:10:16 INFO - 'TERM': 'linux', 20:10:16 INFO - 'TMOUT': '86400', 20:10:16 INFO - 'USER': 'cltbld', 20:10:16 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:16 INFO - '_': '/tools/buildbot/bin/python'} 20:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:10:16 INFO - Running setup.py (path:/tmp/pip-aEy50W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:10:16 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)) 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:10:16 INFO - Running setup.py (path:/tmp/pip-LtFUcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:10:16 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)) 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:10:16 INFO - Running setup.py (path:/tmp/pip-Ym8Zqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:10:16 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)) 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:10:16 INFO - Running setup.py (path:/tmp/pip-TeA8br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:10:16 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)) 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:10:16 INFO - Running setup.py (path:/tmp/pip-fzbGZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:10:16 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)) 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:10:16 INFO - Running setup.py (path:/tmp/pip-ZZUwHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:10:16 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)) 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:10:16 INFO - Running setup.py (path:/tmp/pip-sZPBcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:10:16 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)) 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:10:16 INFO - Running setup.py (path:/tmp/pip-gWgmOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:10:16 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)) 20:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:10:16 INFO - Running setup.py (path:/tmp/pip-TR_Djz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:10:17 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)) 20:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:10:17 INFO - Running setup.py (path:/tmp/pip-8vHKVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:10:17 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)) 20:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:10:17 INFO - Running setup.py (path:/tmp/pip-X72DZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:10:17 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)) 20:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:10:17 INFO - Running setup.py (path:/tmp/pip-62VELJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:10:17 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)) 20:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:10:17 INFO - Running setup.py (path:/tmp/pip-wud0u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:10:17 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)) 20:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:10:17 INFO - Running setup.py (path:/tmp/pip-hcEAjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:10:17 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)) 20:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:17 INFO - Running setup.py (path:/tmp/pip-zLm1Tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:17 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)) 20:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:10:17 INFO - Running setup.py (path:/tmp/pip-9tkKi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:10:17 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)) 20:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:10:17 INFO - Running setup.py (path:/tmp/pip-sO12v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:10:17 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)) 20:10:17 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)) 20:10:17 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)) 20:10:17 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)) 20:10:17 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)) 20:10:17 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)) 20:10:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:10: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 20:10: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 20:10: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 20:10: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 20:10: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 20:10: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 20:10:19 INFO - Downloading blessings-1.6.tar.gz 20:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:10:19 INFO - Installing collected packages: blessings 20:10:19 INFO - Running setup.py install for blessings 20:10:19 INFO - Successfully installed blessings 20:10:19 INFO - Cleaning up... 20:10:19 INFO - Return code: 0 20:10:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:19 INFO - Reading from file tmpfile_stdout 20:10:19 INFO - Current package versions: 20:10:19 INFO - argparse == 1.2.1 20:10:19 INFO - blessings == 1.6 20:10:19 INFO - blobuploader == 1.2.4 20:10:19 INFO - docopt == 0.6.1 20:10:19 INFO - manifestparser == 1.1 20:10:19 INFO - mozInstall == 1.12 20:10:19 INFO - mozcrash == 0.17 20:10:19 INFO - mozdebug == 0.1 20:10:19 INFO - mozdevice == 0.48 20:10:19 INFO - mozfile == 1.2 20:10:19 INFO - mozhttpd == 0.7 20:10:19 INFO - mozinfo == 0.9 20:10:19 INFO - mozleak == 0.1 20:10:19 INFO - mozlog == 3.2 20:10:19 INFO - moznetwork == 0.27 20:10:19 INFO - mozprocess == 0.22 20:10:19 INFO - mozprofile == 0.28 20:10:19 INFO - mozrunner == 6.11 20:10:19 INFO - mozscreenshot == 0.1 20:10:19 INFO - mozsystemmonitor == 0.3 20:10:19 INFO - moztest == 0.7 20:10:19 INFO - mozversion == 1.4 20:10:19 INFO - psutil == 3.1.1 20:10:19 INFO - requests == 1.2.3 20:10:19 INFO - wsgiref == 0.1.2 20:10:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:19 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 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:19 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 20:10:19 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 20:10:19 INFO - Using env: {'DISPLAY': ':0', 20:10:19 INFO - 'HOME': '/home/cltbld', 20:10:19 INFO - 'LANG': 'en_US.UTF-8', 20:10:19 INFO - 'LANGUAGE': 'en_US:en', 20:10:19 INFO - 'LOGNAME': 'cltbld', 20:10:19 INFO - 'MAIL': '/var/mail/cltbld', 20:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:19 INFO - 'MOZ_NO_REMOTE': '1', 20:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:19 INFO - 'NO_EM_RESTART': '1', 20:10:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:19 INFO - 'PWD': '/builds/slave/test', 20:10:19 INFO - 'SHELL': '/bin/bash', 20:10:19 INFO - 'SHLVL': '1', 20:10:19 INFO - 'TERM': 'linux', 20:10:19 INFO - 'TMOUT': '86400', 20:10:19 INFO - 'USER': 'cltbld', 20:10:19 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:19 INFO - '_': '/tools/buildbot/bin/python'} 20:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 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)) 20:10:20 INFO - Cleaning up... 20:10:20 INFO - Return code: 0 20:10:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:10:20 INFO - Using env: {'DISPLAY': ':0', 20:10:20 INFO - 'HOME': '/home/cltbld', 20:10:20 INFO - 'LANG': 'en_US.UTF-8', 20:10:20 INFO - 'LANGUAGE': 'en_US:en', 20:10:20 INFO - 'LOGNAME': 'cltbld', 20:10:20 INFO - 'MAIL': '/var/mail/cltbld', 20:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:20 INFO - 'MOZ_NO_REMOTE': '1', 20:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:20 INFO - 'NO_EM_RESTART': '1', 20:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:20 INFO - 'PWD': '/builds/slave/test', 20:10:20 INFO - 'SHELL': '/bin/bash', 20:10:20 INFO - 'SHLVL': '1', 20:10:20 INFO - 'TERM': 'linux', 20:10:20 INFO - 'TMOUT': '86400', 20:10:20 INFO - 'USER': 'cltbld', 20:10:20 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:20 INFO - '_': '/tools/buildbot/bin/python'} 20:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:20 INFO - Downloading/unpacking jsonschema 20:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:10:22 INFO - Downloading/unpacking functools32 (from jsonschema) 20:10: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 20:10: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 20:10: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 20:10: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 20:10: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 20:10: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 20:10:22 INFO - Downloading functools32-3.2.3-2.tar.gz 20:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:10:22 INFO - warning: no files found matching '*.txt' 20:10:22 INFO - no previously-included directories found matching 'build' 20:10:22 INFO - no previously-included directories found matching 'dist' 20:10:22 INFO - no previously-included directories found matching '.git*' 20:10:22 INFO - Installing collected packages: jsonschema, functools32 20:10:22 INFO - Running setup.py install for functools32 20:10:22 INFO - warning: no files found matching '*.txt' 20:10:22 INFO - no previously-included directories found matching 'build' 20:10:22 INFO - no previously-included directories found matching 'dist' 20:10:22 INFO - no previously-included directories found matching '.git*' 20:10:22 INFO - Successfully installed jsonschema functools32 20:10:22 INFO - Cleaning up... 20:10:22 INFO - Return code: 0 20:10:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:22 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 0x7f59a3e13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ca8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1595770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', '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 20:10:22 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 20:10:22 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 20:10:22 INFO - Using env: {'DISPLAY': ':0', 20:10:22 INFO - 'HOME': '/home/cltbld', 20:10:22 INFO - 'LANG': 'en_US.UTF-8', 20:10:22 INFO - 'LANGUAGE': 'en_US:en', 20:10:22 INFO - 'LOGNAME': 'cltbld', 20:10:22 INFO - 'MAIL': '/var/mail/cltbld', 20:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:22 INFO - 'MOZ_NO_REMOTE': '1', 20:10:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:22 INFO - 'NO_EM_RESTART': '1', 20:10:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:22 INFO - 'PWD': '/builds/slave/test', 20:10:22 INFO - 'SHELL': '/bin/bash', 20:10:22 INFO - 'SHLVL': '1', 20:10:22 INFO - 'TERM': 'linux', 20:10:22 INFO - 'TMOUT': '86400', 20:10:22 INFO - 'USER': 'cltbld', 20:10:22 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:22 INFO - '_': '/tools/buildbot/bin/python'} 20:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:22 INFO - Downloading/unpacking flake8 20:10: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 20:10: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 20:10: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 20:10: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 20:10: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 20:10: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 20:10:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:10:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:10:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:10:25 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 20:10:25 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 20:10:25 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 20:10:25 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 20:10:25 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 20:10:25 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 20:10:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:10:25 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 20:10:25 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 20:10:25 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 20:10:25 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 20:10:25 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 20:10:25 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 20:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:10:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:10:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:10:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:10:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:10:25 INFO - no previously-included directories found matching 'docs/_build' 20:10:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:10:25 INFO - Running setup.py install for pep8 20:10:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:10:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:10:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:10:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:10:25 INFO - no previously-included directories found matching 'docs/_build' 20:10:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:10:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:10:25 INFO - Cleaning up... 20:10:25 INFO - Return code: 0 20:10:25 INFO - Running post-action listener: _resource_record_post_action 20:10:25 INFO - Running post-action listener: _start_resource_monitoring 20:10:25 INFO - Starting resource monitoring. 20:10:25 INFO - ##### 20:10:25 INFO - ##### Finished create-virtualenv step (success) 20:10:25 INFO - ##### 20:10:25 INFO - ##### 20:10:25 INFO - ##### Running install step. 20:10:25 INFO - ##### 20:10:25 INFO - Running pre-action listener: _resource_record_pre_action 20:10:25 INFO - Running main action method: install 20:10:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:26 INFO - Reading from file tmpfile_stdout 20:10:26 INFO - Detecting whether we're running mozinstall >=1.0... 20:10:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:10:26 INFO - Reading from file tmpfile_stdout 20:10:26 INFO - Output received: 20:10:26 INFO - Usage: mozinstall [options] installer 20:10:26 INFO - Options: 20:10:26 INFO - -h, --help show this help message and exit 20:10:26 INFO - -d DEST, --destination=DEST 20:10:26 INFO - Directory to install application into. [default: 20:10:26 INFO - "/builds/slave/test"] 20:10:26 INFO - --app=APP Application being installed. [default: firefox] 20:10:26 INFO - mkdir: /builds/slave/test/build/application 20:10:26 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'] 20:10:26 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 20:10:42 INFO - Reading from file tmpfile_stdout 20:10:42 INFO - Output received: 20:10:42 INFO - /builds/slave/test/build/application/firefox/firefox 20:10:42 INFO - Running post-action listener: _resource_record_post_action 20:10:42 INFO - ##### 20:10:42 INFO - ##### Finished install step (success) 20:10:42 INFO - ##### 20:10:42 INFO - ##### 20:10:42 INFO - ##### Running run-tests step. 20:10:42 INFO - ##### 20:10:42 INFO - Running pre-action listener: _resource_record_pre_action 20:10:42 INFO - Running main action method: run_tests 20:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:10:42 INFO - Python 2.7.3 20:10:42 INFO - Return code: 0 20:10:42 INFO - grabbing minidump binary from tooltool 20:10:42 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')]} 20:10:42 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 0x16249c0>, '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 0x16258c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1625d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:10:42 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 20:10:42 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 20:10:42 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 20:10:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:10:42 INFO - Return code: 0 20:10:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:10:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:10:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:10:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:10:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:10:43 INFO - Return code: 0 20:10:43 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/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 20:10:43 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/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 20:10:43 INFO - Using env: {'DISPLAY': ':0', 20:10:43 INFO - 'HOME': '/home/cltbld', 20:10:43 INFO - 'LANG': 'en_US.UTF-8', 20:10:43 INFO - 'LANGUAGE': 'en_US:en', 20:10:43 INFO - 'LOGNAME': 'cltbld', 20:10:43 INFO - 'MAIL': '/var/mail/cltbld', 20:10:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:10:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:43 INFO - 'MOZ_NO_REMOTE': '1', 20:10:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:10:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:43 INFO - 'NO_EM_RESTART': '1', 20:10:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:43 INFO - 'PWD': '/builds/slave/test', 20:10:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:10:43 INFO - 'SHELL': '/bin/bash', 20:10:43 INFO - 'SHLVL': '1', 20:10:43 INFO - 'TERM': 'linux', 20:10:43 INFO - 'TMOUT': '86400', 20:10:43 INFO - 'USER': 'cltbld', 20:10:43 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611', 20:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:43 INFO - '_': '/tools/buildbot/bin/python'} 20:10:43 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/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 20:10:43 INFO - mozversion application_buildid: 20160514173010 20:10:43 INFO - mozversion application_changeset: 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a 20:10:43 INFO - mozversion application_display_name: Nightly 20:10:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:10:43 INFO - mozversion application_name: Firefox 20:10:43 INFO - mozversion application_remotingname: firefox 20:10:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:10:43 INFO - mozversion application_vendor: Mozilla 20:10:43 INFO - mozversion application_version: 49.0a1 20:10:43 INFO - mozversion platform_buildid: 20160514173010 20:10:43 INFO - mozversion platform_changeset: 091bc7c572dbbfcb751c8e0d9c37b36ff0a83a6a 20:10:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:10:43 INFO - mozversion platform_version: 49.0a1 20:10:43 INFO - using testdate: 1463281843 20:10:43 INFO - actual date: 1463281843 20:10:43 INFO - starting webserver on 'localhost:37860' 20:10:43 INFO - SUITE-START | Running 2 tests 20:10:43 INFO - TEST-START | dromaeo_css 20:10:43 INFO - operating with platform_type : linux_ 20:10:43 INFO - Initialising browser for dromaeo_css test... 20:10:43 INFO - TEST-INFO | started process 17391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqhozdZ/profile http://localhost:37860/getInfo.html) 20:10:47 INFO - PROCESS | 17391 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 20:10:47 INFO - PROCESS | 17391 | __metrics Screen width/height:1600/1200 20:10:47 INFO - PROCESS | 17391 | colorDepth:24 20:10:47 INFO - PROCESS | 17391 | Browser inner width/height: 1024/697 20:10:47 INFO - PROCESS | 17391 | __metrics 20:10:48 ERROR - PROCESS | 17391 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:10:49 INFO - TEST-INFO | 17391: exit 0 20:10:49 INFO - Browser initialized. 20:10:49 INFO - Running cycle 1/1 for dromaeo_css test... 20:10:49 INFO - TEST-INFO | started process 17475 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqhozdZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:11:01 INFO - PROCESS | 17475 | RSS: Main: 152592384 20:11:01 INFO - PROCESS | 17475 | 20:11:52 INFO - PROCESS | 17475 | 1463281912620 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] 20:14:00 INFO - PROCESS | 17475 | Cycle 1(1): loaded http://localhost:37860/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37860/tests/dromaeo/cssquery-ext.html) 20:14:01 INFO - PROCESS | 17475 | RSS: Main: 243331072 20:14:01 INFO - PROCESS | 17475 | 20:17:03 INFO - PROCESS | 17475 | Cycle 1(1): loaded http://localhost:37860/tests/dromaeo/cssquery-ext.html (next: http://localhost:37860/tests/dromaeo/cssquery-jquery.html) 20:17:04 INFO - PROCESS | 17475 | RSS: Main: 241319936 20:17:04 INFO - PROCESS | 17475 | 20:20:06 INFO - PROCESS | 17475 | Cycle 1(1): loaded http://localhost:37860/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37860/tests/dromaeo/cssquery-mootools.html) 20:20:07 INFO - PROCESS | 17475 | RSS: Main: 237641728 20:20:07 INFO - PROCESS | 17475 | 20:23:09 INFO - PROCESS | 17475 | Cycle 1(1): loaded http://localhost:37860/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37860/tests/dromaeo/cssquery-prototype.html) 20:23:09 INFO - PROCESS | 17475 | RSS: Main: 236531712 20:23:09 INFO - PROCESS | 17475 | 20:26:12 INFO - PROCESS | 17475 | Cycle 1(1): loaded http://localhost:37860/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37860/tests/dromaeo/cssquery-yui.html) 20:26:12 INFO - PROCESS | 17475 | RSS: Main: 224796672 20:26:12 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | Cycle 1(1): loaded http://localhost:37860/tests/dromaeo/cssquery-yui.html (next: http://localhost:37860/tests/dromaeo/cssquery-dojo.html) 20:29:15 INFO - PROCESS | 17475 | RSS: Main: 274579456 20:29:15 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | __start_tp_report 20:29:15 INFO - PROCESS | 17475 | _x_x_mozilla_page_load 20:29:15 INFO - PROCESS | 17475 | _x_x_mozilla_page_load_details 20:29:15 INFO - PROCESS | 17475 | |i|pagename|runs| 20:29:15 INFO - PROCESS | 17475 | |0;dojo.html;3171.0962375722916;3230.951535726963;3249.951250731239;3271.541984122222;3284.523744057112;5320.866978325535;5372.435894231108;5399.406065332815;5497.477739614736;5511.559075273978;5362.785488580452;5372.57019438445;5461.317335333083;5528.226048353224;5544.16837474379;4819.180739274314;4888.242322440027;4897.828575999841;4902.436219834711;5084.186530155176;4947.6784009039475;5001.074801161779;5037.093323201822;5052.242163675442;5058.848234552969;4733.5976442002475;4791.952080479203;4803.543663351993;4890.413150421937;4907.656464047518;4862.5623693867365;4881.536254055859;4889.657723959324;4890.413150421972;4992.40091189056;4895.8776030599165;4933.654644174909;4943.1102401567705;4945.505449455026;4949.158643030693;5197;5279.5512381447625;5334.333208348957;5347.411784703719;5439.891202175974;5847.356790753014;5854.941450585525;5906.7637294508595;5911.379305172981;5921.200637913869;5891.705414729289;5926.940730592725;5972.074328479092;5984.491318237955;5991.940080599182;5294.602904782157;5445.9727701361635;5519.586031047647;5520.447955204488;5611.354694210137;6061.480733150516;6062.690674164;6064.898402976644;6106.847328816726;6119.938800611937;5635.6618602883955;5838.5329173666005;5847.181394604759;5890.145928840294;5892.381299963527;5708.543316534668;5775.653460792366;5779.942200578024;5822.82531524055;5827;5790.710464476843;5794.739236734357;5801.767929282866;5805.216295800097;5806.274215723035;5694.686792226385;5734.197212390269;5751;5798.971005144947;5811.709414529257;4398.582134697199;4425.579569940851;4427.26950053244;4441.711288766284;4475.194464996267;3889.397143442771;3908.101136738509;3935.665468435215;3948.9012774680864;3959.148783011666;5300.973495132577;5331.653442526223;5455;5506.284183056177;5525.4474552544425;5906.852328691817;5918.940810591925;5919.970400148058;5934.821955341346;5986.551008674367;5528.170774383794;5551.361593416728;5553.444655534414;5558.916616250759;5572.080606699849;4916.311716359713;5009.699418034929;5075.365579302587;5075.441701412841;5078.97460512695;6175.011998080286;6180.103884936749;6193.473554747807;6193.566450348432;6197.597156184834;5908.291005079418;5912.113183022581;5914.811953243879;5915.674637894957;5989.011813050798;5856.882862342814;6084.69576521181;6087.391260873877;6111.348653136637;6122.755089796358;5758.942410575841;5776.8555786104;5791.9131213031;5795.652260864193;5826.970865145647;5899.203607513103;5903.911441328298;5907.350191478848;5914.408559144051;5914.58597898143;5467.644603100785;5686.2607860977805;5689.630174038674;5723.112917497794;5792.420757924174;5760.0783874579865;5760.539156867524;5761.711914404347;5763.135529670583;5765.308163020464;5472.151816468454;5681.346645135863;5682.403347648603;5685.232493613475;5691.658500489983;5809.883802323845;5841.299044114563;5870.7945221917025;5903.7048147593305;5905.261842269716;5707.514861236676;5742.310922689304;5744.5978781486565;5749.453801888814;5800.767969281182 20:29:15 INFO - PROCESS | 17475 | |1;ext.html;636.0173531893224;642.9485641148708;651.8598501322215;659.6767583883899;664.8404382948091;42811;44686.10627787447;44724.77637611811;47585;47591.52408475914;46450.767746161306;47018;47019.294710579285;47047.52952470474;47081.529184708146;8942.508162051068;9088.454692718426;9091.818163636723;9092.090790920887;9094.818103637923;8843.734687959372;8927.910720892773;8935.687250946217;8942.776430589221;8945.418547794405;8967.865482017774;9018.278537716968;9020.233280171193;9025.59384827681;9027.277817774562;8926.37515373924;8946.597403116875;8953.955230223808;8964.14840590143;8972.013078561386;8868.822623547492;8937.865932011026;8946.105389461067;8948.910510894873;8955.238804701608;43001.1399772006;43381.34927976083;43422.348664770056;43475.34786978198;43521.564784352384;3762.360398732195;3896.7467114637457;3949.723519353646;4291.184674911757;4466.017476155241;31118.37763244745;34307.656923430695;34321.14197145091;34408.655913440794;35541.111472213146;4077.6533994610495;4104.671626269921;4128.256913755523;4141.130362623852;4168.645665118469;356378;359456;359980;360011.9999999974;362179;55882.44117558854;66252.33747662559;67283.32716672869;67380;67421.32578674151;54213;72074.63962680259;74926;75108.24891751121;75323.24676753272;16737;16776.161191940355;16850.578735531464;16851.494455166365;16890.831091688924;12091.214071085351;13146.737065258641;13165.210087394787;13179.209247445184;13183.274919879499;20738.896305518676;21377.893110534347;21523.246686365903;21550.137994480046;21656.675149872764;7516;7637.365109037373;7859.449838511364;7873.724419645285;7878.290953814184;993.1707024634367;1082.6156714366443;1091.939943303126;1102.4322473925934;1146.7534480086654;33797.83101084528;34086.48870266948;34284.48573271403;34340.82829585886;34914;1167.3637867362138;1184.6386852010219;1227.030645789834;1236.2925193825492;1268.2580690296095;18016;18042.458726238234;18100;18126.365577204735;18146.63706725858;542962;549378;549778;550576;552314;60868.695656521435;60882.39117608767;61461;61611.383886161464;61772.073418898755;1303.0552849184267;1393.0736060519641;1405.7539930512119;1405.810215620919;1413.8020677105214;30543.847280763453;35099.47350789689;35105.29789404147;35416.64583354133;35666.821665891504;51490.48509514857;51518.19686310961;51907.74046129745;52014.47985520097;52053;317116;320047;320192;320406;320596;37324;42113.36829947492;43666.78166609147;44238.336424953006;44870.551294486635;49261.753691231315;49487.25769113538;49554.25668614901;50038.249426257906;50520.747396262785;42035.579644203164;42343;42747.14505709806;43663.34504982364;43867.341989870816;42155.57844421639;42437.78781106075;42480.5751942489;42862.35706464468;43262.56737432585;42105;42210.15579688451;43055;43302;43336.78331608448;35892.64107358893;36802.26395472129;37622.8118859404;39075;39677.206455871295;29139.271518212216;29435.350818425588;29819;29860;30218.24454388658 20:29:15 INFO - PROCESS | 17475 | |2;jquery.html;402.82678448267245;403.38081045595015;403.8223181800007;412.78328877339374;420.7496539558961;13778.655533611665;18356.541086472844;20022.89988550057;20049.59900801983;20074;14420.639484012878;18765.061746912666;18866.811331886678;19407.51481212967;19568.804311956876;5588.916166257499;5768.019436695772;5787.392323806003;5795.594308398414;5806.419358064181;5689.715514224293;5784.971075144639;5796.275465566804;5796.362400135981;5800.47795698387;4175.457190565222;5951.137085122655;6245.5003599712145;6254.937450625481;6350.079238510413;6256.436920677115;6258.374162583728;6280.999999999977;6337.366263373672;6379.872402551923;5515.172724091378;5612.410696876851;5629.774809007635;5651.8304450866535;5652.434756524356;22575.984080716244;23366.53266934669;23679.7632023681;23787.85909268174;23942;2529.064246228907;2538.2892790018823;2627.356297707055;2736.835789852615;2959.556066590007;7306.488545801796;12937.80593291092;14351.497697580638;14421.206833624146;14432.422703091865;1921.9231230750754;1983.1571582077559;2047.1299697628597;2048.201201531389;2076.2214169686367;73781;75792;76265.61867190684;85003;87917.12082879154;10074.337734273762;10470.633527826642;10796.298240614335;10964.28732132409;10986.121110311156;14709.92645036768;14801.333939972727;14925.328360223815;14971.176585287696;15161.696766064617;8143.389245806469;8262.628181731836;8412.242898139197;8433.325333973391;8496.065432802387;8052;8147.877781833158;8184.508929464251;8230.958845205856;8281;4013.6989725770686;4477.597016268486;4493.662975276868;4668.8132474701315;4841.733704646278;1824.2611742244412;1842.5854182808762;1857.2570971611462;1886.1983656946018;1888.6033932873975;1008.1178968402647;1032.318669678009;1039.7088815131776;1083.1172594335592;1084.9063646181105;19065;19892.60214795696;19917.10373033188;19989.500262493555;20064.59870802576;1043.4626167523732;1045.0020230679684;1052.3528030261334;1067.0769784136667;1081.1729027294127;10155.238357123246;11091;11190.832137517944;11195.4962026709;11203.327800331843;458487;460668;461267;486200;496443;90193.54903225573;91293.54353228191;92254.53872730593;92524.53737731268;93099.53450232705;1025.0876719719456;1028.4240825137947;1052.379096333152;1056.8837427882927;1060.3584831177027;17278.827211728225;17325.74011389805;17539.649207016042;17545.561360965567;17841.197146128197;39765.32590861655;40134.79932600318;40737.38893916534;41162.176756465306;41281.58718412778;54754;56291.43708562862;57983.710081449324;58054.12918806137;58322.12516812166;8504.872426913478;8552.700655477029;8601.838751768431;8611;8653.870191947;10396.792064158522;13287.13633613812;13339.733205335644;13779.724405512034;13796.724065518834;11850.58522951727;12039.75920481603;12138.271703697807;12151.513939442677;12283.69290767738;11719.88280117222;11763.5303580615;12067.974222191213;12135.696607584881;12147.635570932885;12143.878561214628;12316.568920087753;12450.626481205578;12774.936125319315;12848.678783030498;8563.143685631387;8569;8693.478391296543;8713.215810577078;8834.690785822722;9170.495622740813;11232.943835281098;12350.567730129402;12387.442565084413;12823.358832058546 20:29:15 INFO - PROCESS | 17475 | |3;mootools.html;1366.3509832829402;1532.073095777055;1568.6078480379904;1733.505950804021;1867.6638205123072;8553.529555874424;8805.031446540876;8819.735407937756;9207.81584368314;9238.538073096335;8865;9085.727428177166;9094.681686140986;9098.226650734696;9100.226480749145;2078.366098340006;2132.2217390652454;2132.5201829588295;2205.095910676619;2229.476073122815;2271.2618399020303;2330.533893221354;2332.8950197241165;2349;2351.9764802352015;2310.318456055459;2359.315798418457;2365.73976862545;2372;2372.7034120734907;2009.8291645210174;2268.0247493577754;2299.1033496936207;2302.7582103879017;2304.1129165271323;2344.6717459555675;2385.105585405473;2386.940326491834;2387.7850993410675;2388.259639511757;8909.198172164472;8967.237784788302;8969.506677132755;8970.775730606787;8972.730818075468;5282.841514754564;5432.864178395532;5439.619226654136;5446.183072539111;5446.291982042309;3228.4673028950197;3268.6404495505476;3423.07576954221;3437.9484307735406;3441.2429265561536;1104.8563686720756;1124.2636073371955;1140.7604403075363;1142.4287856071965;1165.5163107310457;12910.09629325957;12942.482300707961;12948.935255323757;12961.351932403437;12973.351332433434;4795.376601041842;4893.828715994923;4908.730019848943;4910.8772280693265;4976.676516026447;10750.94624526888;10811.891881081247;10816;10820.89179108199;10832.837507437394;5456.045192091368;5468.808591699271;5472.370677372153;5475.206095116186;5476.096444086761;4342.913141737147;4400.185965596375;4941.876453088702;4950.18321976877;5053.292539044537;4001.1797581495725;4054.412110243998;4118.073433477503;4142.523609784853;4304.526502084768;1265.9113862029753;1287.0797379873436;1300.1419063418098;1311.7114234868313;1311.9737605247842;1030.5568605499557;1042.9165666746642;1046.398320965448;1055.1084333737979;1067.2475904487317;5957.791477298274;6003.969980150158;6115.694215289218;6182.783602573978;6315.526335524854;450.04141179287905;454.32986345140654;460.580871407018;462.115049679866;462.26269100784197;4185.455890734185;4372.081862808782;4392.165488557164;4430;4430.224710675619;10981.560737570568;11419.60031398897;11424.371659558805;11437.885621143849;11475.598354057567;10723.142148628247;11089.722756931142;11097.1677124216;11325.377104259345;11429.371384573762;8418.747437576883;8495.32037437016;8541.103184165697;8553;8555.27280181179;6166.630002199882;6384.489240860813;6444.677766111582;6452.387023232785;6459.838504037437;10090.192784577363;10347.482625868526;10352.741181470223;10383.376997380181;10487.161027117965;10619.36283822973;10705.785884282426;10708.946455267675;10710.357378557;10737.46312684347;7271.200167981412;7360.04319438491;8040.517568945882;8075.394345423999;8217.342612591097;8957.686480973134;9087.863682044906;9111.772205694911;9115.863262050942;9120.954395227982;7801.102873169658;7892.250236227549;7993.440459167802;8024.719134830253;8170.387220958333;7737.767866964;7867.724629637935;7906.683732650629;7915.208479152038;7996.120426753168;7263.309985551364;7274.96362518184;7321.084864391951;7362.7423040193335;7430.814229644302;5268.262443258023;5407.324084489439;5442.673439593637;5564.638298510584;5664.065429204135;5453.727313634223;5464.480874316933;5491.945080549143;5653.4170432279025;5700.543956483554 20:29:15 INFO - PROCESS | 17475 | |4;prototype.html;331.568960351543;332.4000179675686;338.11414095070916;341.6463959801607;346.4941185868632;5256.1590145576665;5279.472052794718;5288;5308.416074231832;5316.059638232046;5240.764165612547;5254.92117618236;5258.815941442051;5261.316028916245;5272.288241087452;3910.843566257346;4014.293925081843;4015.5984401559904;4076.1847630473876;4111.917761644765;4050.7037747920676;4085.754854708712;4095.3242714952;4130.678182981447;4130.896727581804;4048.1903619276263;4056.533498647649;4068.677679754077;4106.28140075487;4113.444684967536;3901.882943511699;3965.2267807777353;4060;4070.4097905803787;4087.7956102195067;3613.421852503613;3639.490471334015;3644.1254099016046;3645.54430696163;3650.507181530486;4943.802247910079;5062.139436295838;5082.415522214956;5085.389753229636;5138.820141295074;4506.188886000518;4706.44687253209;4729.266963620645;4739.739314337745;4750.738709370983;5490.968341713552;5515.751791169367;5517.78608706084;5549.889002219973;5558.527525160366;4054.472918520603;4067.572904845008;4073.572274911151;4077.2660921034203;4077.796110194508;5490.450954904518;5635.5773317001385;5654.688992105433;5666.773329066832;5803.129530570406;4077.1437998020156;4087.58832288945;4089.7546147230664;4090.1206240658403;4106.938395924063;4135.855245066408;4152.273352163359;4155.667546596325;4161.481059413292;4168.166366726667;4549.7497637629585;4681.344611754357;4721.315409265637;4727.95272047282;4764.642077008036;5480.616356854982;5499.257600223918;5511.200875873056;5525.530329921921;5535.972320138373;1436.8778653814543;1452.7820826876052;1453.9854601454063;1460.7443697353133;1460.7516722157259;917.3074328881652;946.5409276500892;956.7751578379215;972.7713987212999;982.7444864335324;5208.9739551302755;5250.868728281824;5263.421023687391;5268.2088090049565;5291.814786482455;5537.8061767837935;5548.14003829399;5567.610267281332;5585.497305242467;5620.718964051863;4992.675476094042;5219.765110570033;5267.367915850046;5281.867953301121;5283.207518872123;2932.736053755172;2956.2757124504633;2957.46765582193;2959.1801042358793;2964.4219377221234;5789.768409263666;5800.245968024215;5803.883922321615;5804.187413762077;5813.680247586423;5516;5788.189653448437;5798.449147330997;5807.448292412214;5809.157672137601;5863.941360586425;5921.697226610139;5934.7922822701;5948.9773477018025;5982.461578457785;5481.561475081904;5698.1737648041635;5716.79991200306;5758.683272420057;5783.971080144573;5649.6327738696855;5666.206731057573;5680.573956953328;5685.886282274414;5737.815512390664;5895.29256489224;5906.165040780364;5936.28764548257;5963.075723262901;5983.282006159115;3852.5745474174737;4069.348904175318;4114.547399786081;4121.91756164881;4125.711200215956;5660.132156388464;5762.481376676119;5782.306123265235;5786.42135786418;5786.94213057864;5372.892542149057;5521.309836270466;5534.7509362078;5558.749856256397;5628.521575666115;5515.7865269640615;5547.306586676666;5550;5551.361593416808;5564.610477266552;5303.496167864047;5336.466353364633;5444.230625046955;5444.428335024765;5461.504637032223;5595.63628364155;5608.46719561641;5626.43735626434;5627.971860140673;5637.210790489253;5500.80747173847;5510.972445137749;5511.58663100261;5512.779488820563;5528.308961379827 20:29:15 INFO - PROCESS | 17475 | |5;yui.html;760.1945379723157;769.3960241210643;868.6829307302835;880.0231742765529;885.017630430222;14404.711905761878;14834.777478337806;14850.628734281634;15074.698506029887;15192.620184495394;14701.264936753172;14774.852251477481;14783.630409239775;14810.259487025633;14829;693.5076095971841;817.190980928882;826.2027143806225;835.4778263585259;843.6667516331046;828.4615000249855;832.908380078194;834.9874751878726;835.8495470815251;836.297510091523;886.0165216609558;886.5434301334818;887.6227603268585;888.0897080492482;889.4307643108416;734.6216698400301;868.192580899763;880.6829541365136;893.1202765276198;893.5755516129851;631.6178711879293;633.0188208277152;633.0757094641829;633.1199632510813;633.262249479356;14816.185109818956;14827.258637068211;14831.184284864328;14835.03572267799;14856;13454.260015699132;13737.725245495034;13840.93079534606;13843.861561384358;13881.722365552734;11386.680392382224;11662.241954272946;11883.465244064038;12018.759624807542;12073.758524829542;3930.5283365996265;4012.4583181270445;4033.69747268956;4107.979460102681;4114.403411505345;56565.71717141429;57358.7132064337;58781.41218587802;58793.70603147;59058.40941590615;11283.379414132138;11297.774044519063;11815.350155741346;11837.171398002229;12147.149699520949;21406.357809265748;21539.784602153777;21597.24409645655;21598;21719.348419547438;8639.524826134622;8950.507722075217;8990;9037.68368107113;9080.8637870432;4784;4944.431390390079;4966.553010229097;5009.724465154379;5230.92153617696;3498.6151523332414;3641.5812181598926;3650.2152037311575;3650.543682039745;3657.231981283907;674.3694645506467;675.9155105611799;684.9417799487;690.5373399822131;691.374306252842;713.3651050565002;781.3475747750579;787.3189690917316;809.1786836361098;828.2711214131646;9789.363691360039;10143.797124057477;10294.742631434274;10367.63713270032;10511.579536818595;737.3621817128145;737.6826283651868;762.6110683551318;778.2217334839273;778.7585848387018;4605.930911036337;4610.654200934876;4948.208286674115;5020.748962551857;5026.79892804291;69181.65409172921;69371.65314173498;69698.65150674316;70995;71681.6415917917;61736.69131654313;63766;63881;64329.678351607945;64640.03039954404;55623;56466.43533564694;56479.43520564824;57034.14448783272;59474;9449.454839143818;9552.799102221394;9562.856557151787;9572.664956726481;9595.520223988633;37140.257194856495;37384.252314954094;37681.81159094187;37913.24173516459;38571.421428679154;47450.76274618605;50496;50665.24002139897;50751.74624127003;51220.231696523835;11532.711682208012;11566.016888564567;12335.717892189974;12365.690857728629;12690.873091269339;12389.62831115068;13041.217526948414;13221.735565288833;13335.266560339274;13357.599272021855;12487.562935297221;12872.742545149233;12914.67713307175;13019.544315948897;13425.993050521445;11443.599474018369;11758.470868811095;11787.174897757075;12076.21504602198;12174.756504869676;12008.879911200776;12204.572839950559;12439.689007774878;12940.870591294342;13281.468741250628;9675.9032409675;9726.96415595787;10596.735081623021;10637.14902807789;10760.246782725448;10435.37387756737;10500.133778601321;10553.155747540024;10671.679849604525;10765.838512422164 20:29:15 INFO - PROCESS | 17475 | __end_tp_report 20:29:15 INFO - PROCESS | 17475 | __start_cc_report 20:29:15 INFO - PROCESS | 17475 | _x_x_mozilla_cycle_collect,852 20:29:15 INFO - PROCESS | 17475 | __end_cc_report 20:29:15 INFO - PROCESS | 17475 | __startTimestamp1463282955615__endTimestamp 20:29:15 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | ------- Summary: start ------- 20:29:15 INFO - PROCESS | 17475 | Number of tests: 6 20:29:15 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | [#0] dojo.html Cycles:165 Average:5438.23 Median:5689.63 stddev:639.48 (11.2%) stddev-sans-first:616.22 20:29:15 INFO - PROCESS | 17475 | Values: 3171.1 3231.0 3250.0 3271.5 3284.5 5320.9 5372.4 5399.4 5497.5 5511.6 5362.8 5372.6 5461.3 5528.2 5544.2 4819.2 4888.2 4897.8 4902.4 5084.2 4947.7 5001.1 5037.1 5052.2 5058.8 4733.6 4792.0 4803.5 4890.4 4907.7 4862.6 4881.5 4889.7 4890.4 4992.4 4895.9 4933.7 4943.1 4945.5 4949.2 5197.0 5279.6 5334.3 5347.4 5439.9 5847.4 5854.9 5906.8 5911.4 5921.2 5891.7 5926.9 5972.1 5984.5 5991.9 5294.6 5446.0 5519.6 5520.4 5611.4 6061.5 6062.7 6064.9 6106.8 6119.9 5635.7 5838.5 5847.2 5890.1 5892.4 5708.5 5775.7 5779.9 5822.8 5827.0 5790.7 5794.7 5801.8 5805.2 5806.3 5694.7 5734.2 5751.0 5799.0 5811.7 4398.6 4425.6 4427.3 4441.7 4475.2 3889.4 3908.1 3935.7 3948.9 3959.1 5301.0 5331.7 5455.0 5506.3 5525.4 5906.9 5918.9 5920.0 5934.8 5986.6 5528.2 5551.4 5553.4 5558.9 5572.1 4916.3 5009.7 5075.4 5075.4 5079.0 6175.0 6180.1 6193.5 6193.6 6197.6 5908.3 5912.1 5914.8 5915.7 5989.0 5856.9 6084.7 6087.4 6111.3 6122.8 5758.9 5776.9 5791.9 5795.7 5827.0 5899.2 5903.9 5907.4 5914.4 5914.6 5467.6 5686.3 5689.6 5723.1 5792.4 5760.1 5760.5 5761.7 5763.1 5765.3 5472.2 5681.3 5682.4 5685.2 5691.7 5809.9 5841.3 5870.8 5903.7 5905.3 5707.5 5742.3 5744.6 5749.5 5800.8 20:29:15 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | [#1] ext.html Cycles:180 Average:59372.38 Median:43028.07 stddev:111515.39 (259.2%) stddev-sans-first:111741.02 20:29:15 INFO - PROCESS | 17475 | Values: 636.0 642.9 651.9 659.7 664.8 42811.0 44686.1 44724.8 47585.0 47591.5 46450.8 47018.0 47019.3 47047.5 47081.5 8942.5 9088.5 9091.8 9092.1 9094.8 8843.7 8927.9 8935.7 8942.8 8945.4 8967.9 9018.3 9020.2 9025.6 9027.3 8926.4 8946.6 8954.0 8964.1 8972.0 8868.8 8937.9 8946.1 8948.9 8955.2 43001.1 43381.3 43422.3 43475.3 43521.6 3762.4 3896.7 3949.7 4291.2 4466.0 31118.4 34307.7 34321.1 34408.7 35541.1 4077.7 4104.7 4128.3 4141.1 4168.6 356378.0 359456.0 359980.0 360012.0 362179.0 55882.4 66252.3 67283.3 67380.0 67421.3 54213.0 72074.6 74926.0 75108.2 75323.2 16737.0 16776.2 16850.6 16851.5 16890.8 12091.2 13146.7 13165.2 13179.2 13183.3 20738.9 21377.9 21523.2 21550.1 21656.7 7516.0 7637.4 7859.4 7873.7 7878.3 993.2 1082.6 1091.9 1102.4 1146.8 33797.8 34086.5 34284.5 34340.8 34914.0 1167.4 1184.6 1227.0 1236.3 1268.3 18016.0 18042.5 18100.0 18126.4 18146.6 542962.0 549378.0 549778.0 550576.0 552314.0 60868.7 60882.4 61461.0 61611.4 61772.1 1303.1 1393.1 1405.8 1405.8 1413.8 30543.8 35099.5 35105.3 35416.6 35666.8 51490.5 51518.2 51907.7 52014.5 52053.0 317116.0 320047.0 320192.0 320406.0 320596.0 37324.0 42113.4 43666.8 44238.3 44870.6 49261.8 49487.3 49554.3 50038.2 50520.7 42035.6 42343.0 42747.1 43663.3 43867.3 42155.6 42437.8 42480.6 42862.4 43262.6 42105.0 42210.2 43055.0 43302.0 43336.8 35892.6 36802.3 37622.8 39075.0 39677.2 29139.3 29435.4 29819.0 29860.0 30218.2 20:29:15 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | [#2] jquery.html Cycles:180 Average:28536.21 Median:23733.81 stddev:78005.68 (328.7%) stddev-sans-first:78195.91 20:29:15 INFO - PROCESS | 17475 | Values: 402.8 403.4 403.8 412.8 420.7 13778.7 18356.5 20022.9 20049.6 20074.0 14420.6 18765.1 18866.8 19407.5 19568.8 5588.9 5768.0 5787.4 5795.6 5806.4 5689.7 5785.0 5796.3 5796.4 5800.5 4175.5 5951.1 6245.5 6254.9 6350.1 6256.4 6258.4 6281.0 6337.4 6379.9 5515.2 5612.4 5629.8 5651.8 5652.4 22576.0 23366.5 23679.8 23787.9 23942.0 2529.1 2538.3 2627.4 2736.8 2959.6 7306.5 12937.8 14351.5 14421.2 14432.4 1921.9 1983.2 2047.1 2048.2 2076.2 73781.0 75792.0 76265.6 85003.0 87917.1 10074.3 10470.6 10796.3 10964.3 10986.1 14709.9 14801.3 14925.3 14971.2 15161.7 8143.4 8262.6 8412.2 8433.3 8496.1 8052.0 8147.9 8184.5 8231.0 8281.0 4013.7 4477.6 4493.7 4668.8 4841.7 1824.3 1842.6 1857.3 1886.2 1888.6 1008.1 1032.3 1039.7 1083.1 1084.9 19065.0 19892.6 19917.1 19989.5 20064.6 1043.5 1045.0 1052.4 1067.1 1081.2 10155.2 11091.0 11190.8 11195.5 11203.3 458487.0 460668.0 461267.0 486200.0 496443.0 90193.5 91293.5 92254.5 92524.5 93099.5 1025.1 1028.4 1052.4 1056.9 1060.4 17278.8 17325.7 17539.6 17545.6 17841.2 39765.3 40134.8 40737.4 41162.2 41281.6 54754.0 56291.4 57983.7 58054.1 58322.1 8504.9 8552.7 8601.8 8611.0 8653.9 10396.8 13287.1 13339.7 13779.7 13796.7 11850.6 12039.8 12138.3 12151.5 12283.7 11719.9 11763.5 12068.0 12135.7 12147.6 12143.9 12316.6 12450.6 12774.9 12848.7 8563.1 8569.0 8693.5 8713.2 8834.7 9170.5 11232.9 12350.6 12387.4 12823.4 20:29:15 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | [#3] mootools.html Cycles:180 Average:5990.41 Median:4430.11 stddev:3461.39 (78.1%) stddev-sans-first:3453.66 20:29:15 INFO - PROCESS | 17475 | Values: 1366.4 1532.1 1568.6 1733.5 1867.7 8553.5 8805.0 8819.7 9207.8 9238.5 8865.0 9085.7 9094.7 9098.2 9100.2 2078.4 2132.2 2132.5 2205.1 2229.5 2271.3 2330.5 2332.9 2349.0 2352.0 2310.3 2359.3 2365.7 2372.0 2372.7 2009.8 2268.0 2299.1 2302.8 2304.1 2344.7 2385.1 2386.9 2387.8 2388.3 8909.2 8967.2 8969.5 8970.8 8972.7 5282.8 5432.9 5439.6 5446.2 5446.3 3228.5 3268.6 3423.1 3437.9 3441.2 1104.9 1124.3 1140.8 1142.4 1165.5 12910.1 12942.5 12948.9 12961.4 12973.4 4795.4 4893.8 4908.7 4910.9 4976.7 10750.9 10811.9 10816.0 10820.9 10832.8 5456.0 5468.8 5472.4 5475.2 5476.1 4342.9 4400.2 4941.9 4950.2 5053.3 4001.2 4054.4 4118.1 4142.5 4304.5 1265.9 1287.1 1300.1 1311.7 1312.0 1030.6 1042.9 1046.4 1055.1 1067.2 5957.8 6004.0 6115.7 6182.8 6315.5 450.0 454.3 460.6 462.1 462.3 4185.5 4372.1 4392.2 4430.0 4430.2 10981.6 11419.6 11424.4 11437.9 11475.6 10723.1 11089.7 11097.2 11325.4 11429.4 8418.7 8495.3 8541.1 8553.0 8555.3 6166.6 6384.5 6444.7 6452.4 6459.8 10090.2 10347.5 10352.7 10383.4 10487.2 10619.4 10705.8 10708.9 10710.4 10737.5 7271.2 7360.0 8040.5 8075.4 8217.3 8957.7 9087.9 9111.8 9115.9 9121.0 7801.1 7892.3 7993.4 8024.7 8170.4 7737.8 7867.7 7906.7 7915.2 7996.1 7263.3 7275.0 7321.1 7362.7 7430.8 5268.3 5407.3 5442.7 5564.6 5664.1 5453.7 5464.5 5491.9 5653.4 5700.5 20:29:15 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | [#4] prototype.html Cycles:180 Average:4660.59 Median:5285.60 stddev:1369.30 (25.9%) stddev-sans-first:1334.03 20:29:15 INFO - PROCESS | 17475 | Values: 331.6 332.4 338.1 341.6 346.5 5256.2 5279.5 5288.0 5308.4 5316.1 5240.8 5254.9 5258.8 5261.3 5272.3 3910.8 4014.3 4015.6 4076.2 4111.9 4050.7 4085.8 4095.3 4130.7 4130.9 4048.2 4056.5 4068.7 4106.3 4113.4 3901.9 3965.2 4060.0 4070.4 4087.8 3613.4 3639.5 3644.1 3645.5 3650.5 4943.8 5062.1 5082.4 5085.4 5138.8 4506.2 4706.4 4729.3 4739.7 4750.7 5491.0 5515.8 5517.8 5549.9 5558.5 4054.5 4067.6 4073.6 4077.3 4077.8 5490.5 5635.6 5654.7 5666.8 5803.1 4077.1 4087.6 4089.8 4090.1 4106.9 4135.9 4152.3 4155.7 4161.5 4168.2 4549.7 4681.3 4721.3 4728.0 4764.6 5480.6 5499.3 5511.2 5525.5 5536.0 1436.9 1452.8 1454.0 1460.7 1460.8 917.3 946.5 956.8 972.8 982.7 5209.0 5250.9 5263.4 5268.2 5291.8 5537.8 5548.1 5567.6 5585.5 5620.7 4992.7 5219.8 5267.4 5281.9 5283.2 2932.7 2956.3 2957.5 2959.2 2964.4 5789.8 5800.2 5803.9 5804.2 5813.7 5516.0 5788.2 5798.4 5807.4 5809.2 5863.9 5921.7 5934.8 5949.0 5982.5 5481.6 5698.2 5716.8 5758.7 5784.0 5649.6 5666.2 5680.6 5685.9 5737.8 5895.3 5906.2 5936.3 5963.1 5983.3 3852.6 4069.3 4114.5 4121.9 4125.7 5660.1 5762.5 5782.3 5786.4 5786.9 5372.9 5521.3 5534.8 5558.7 5628.5 5515.8 5547.3 5550.0 5551.4 5564.6 5303.5 5336.5 5444.2 5444.4 5461.5 5595.6 5608.5 5626.4 5628.0 5637.2 5500.8 5511.0 5511.6 5512.8 5528.3 20:29:15 INFO - PROCESS | 17475 | 20:29:15 INFO - PROCESS | 17475 | [#5] yui.html Cycles:180 Average:16022.06 Median:4864.22 stddev:19245.48 (395.7%) stddev-sans-first:19265.34 20:29:15 INFO - PROCESS | 17475 | Values: 760.2 769.4 868.7 880.0 885.0 14404.7 14834.8 14850.6 15074.7 15192.6 14701.3 14774.9 14783.6 14810.3 14829.0 693.5 817.2 826.2 835.5 843.7 828.5 832.9 835.0 835.8 836.3 886.0 886.5 887.6 888.1 889.4 734.6 868.2 880.7 893.1 893.6 631.6 633.0 633.1 633.1 633.3 14816.2 14827.3 14831.2 14835.0 14856.0 13454.3 13737.7 13840.9 13843.9 13881.7 11386.7 11662.2 11883.5 12018.8 12073.8 3930.5 4012.5 4033.7 4108.0 4114.4 56565.7 57358.7 58781.4 58793.7 59058.4 11283.4 11297.8 11815.4 11837.2 12147.1 21406.4 21539.8 21597.2 21598.0 21719.3 8639.5 8950.5 8990.0 9037.7 9080.9 4784.0 4944.4 4966.6 5009.7 5230.9 3498.6 3641.6 3650.2 3650.5 3657.2 674.4 675.9 684.9 690.5 691.4 713.4 781.3 787.3 809.2 828.3 9789.4 10143.8 10294.7 10367.6 10511.6 737.4 737.7 762.6 778.2 778.8 4605.9 4610.7 4948.2 5020.7 5026.8 69181.7 69371.7 69698.7 70995.0 71681.6 61736.7 63766.0 63881.0 64329.7 64640.0 55623.0 56466.4 56479.4 57034.1 59474.0 9449.5 9552.8 9562.9 9572.7 9595.5 37140.3 37384.3 37681.8 37913.2 38571.4 47450.8 50496.0 50665.2 50751.7 51220.2 11532.7 11566.0 12335.7 12365.7 12690.9 12389.6 13041.2 13221.7 13335.3 13357.6 12487.6 12872.7 12914.7 13019.5 13426.0 11443.6 11758.5 11787.2 12076.2 12174.8 12008.9 12204.6 12439.7 12940.9 13281.5 9675.9 9727.0 10596.7 10637.1 10760.2 10435.4 10500.1 10553.2 10671.7 10765.8 20:29:15 INFO - PROCESS | 17475 | -------- Summary: end -------- 20:29:15 INFO - PROCESS | 17475 | 20:29:16 INFO - TEST-INFO | 17475: exit 0 20:29:16 INFO - TEST-OK | dromaeo_css | took 1112407ms 20:29:16 INFO - TEST-START | kraken 20:29:16 INFO - operating with platform_type : linux_ 20:29:16 INFO - Initialising browser for kraken test... 20:29:16 INFO - TEST-INFO | started process 17657 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp60AVwt/profile http://localhost:37860/getInfo.html) 20:29:20 INFO - PROCESS | 17657 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 20:29:20 INFO - PROCESS | 17657 | __metrics Screen width/height:1600/1200 20:29:20 INFO - PROCESS | 17657 | colorDepth:24 20:29:20 INFO - PROCESS | 17657 | Browser inner width/height: 1024/697 20:29:20 INFO - PROCESS | 17657 | __metrics 20:29:21 ERROR - PROCESS | 17657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:29:21 INFO - TEST-INFO | 17657: exit 0 20:29:21 INFO - Browser initialized. 20:29:21 INFO - Running cycle 1/1 for kraken test... 20:29:21 INFO - TEST-INFO | started process 17742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp60AVwt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:29:33 INFO - PROCESS | 17742 | RSS: Main: 153141248 20:29:33 INFO - PROCESS | 17742 | 20:29:36 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37860/tests/kraken/driver.html?testName=audio-beat-detection) 20:29:37 INFO - PROCESS | 17742 | RSS: Main: 209158144 20:29:37 INFO - PROCESS | 17742 | 20:29:39 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37860/tests/kraken/driver.html?testName=audio-dft) 20:29:40 INFO - PROCESS | 17742 | RSS: Main: 198483968 20:29:40 INFO - PROCESS | 17742 | 20:29:43 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37860/tests/kraken/driver.html?testName=audio-fft) 20:29:44 INFO - PROCESS | 17742 | RSS: Main: 265715712 20:29:44 INFO - PROCESS | 17742 | 20:29:46 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37860/tests/kraken/driver.html?testName=audio-oscillator) 20:29:47 INFO - PROCESS | 17742 | RSS: Main: 174845952 20:29:47 INFO - PROCESS | 17742 | 20:29:49 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37860/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:29:49 INFO - PROCESS | 17742 | RSS: Main: 178057216 20:29:49 INFO - PROCESS | 17742 | 20:29:53 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37860/tests/kraken/driver.html?testName=imaging-darkroom) 20:29:53 INFO - PROCESS | 17742 | RSS: Main: 214564864 20:29:53 INFO - PROCESS | 17742 | 20:29:57 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37860/tests/kraken/driver.html?testName=imaging-desaturate) 20:29:57 INFO - PROCESS | 17742 | RSS: Main: 212799488 20:29:57 INFO - PROCESS | 17742 | 20:30:00 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37860/tests/kraken/driver.html?testName=json-parse-financial) 20:30:01 INFO - PROCESS | 17742 | RSS: Main: 213897216 20:30:01 INFO - PROCESS | 17742 | 20:30:03 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37860/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:30:03 INFO - PROCESS | 17742 | RSS: Main: 204976128 20:30:03 INFO - PROCESS | 17742 | 20:30:05 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37860/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:30:06 INFO - PROCESS | 17742 | RSS: Main: 208302080 20:30:06 INFO - PROCESS | 17742 | 20:30:08 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37860/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:30:08 INFO - PROCESS | 17742 | RSS: Main: 215740416 20:30:08 INFO - PROCESS | 17742 | 20:30:11 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37860/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:30:12 INFO - PROCESS | 17742 | RSS: Main: 224239616 20:30:12 INFO - PROCESS | 17742 | 20:30:14 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37860/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:30:15 INFO - PROCESS | 17742 | RSS: Main: 220790784 20:30:15 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | Cycle 1(1): loaded http://localhost:37860/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37860/tests/kraken/driver.html?testName=ai-astar) 20:30:17 INFO - PROCESS | 17742 | RSS: Main: 265863168 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | __start_tp_report 20:30:17 INFO - PROCESS | 17742 | _x_x_mozilla_page_load 20:30:17 INFO - PROCESS | 17742 | _x_x_mozilla_page_load_details 20:30:17 INFO - PROCESS | 17742 | |i|pagename|runs| 20:30:17 INFO - PROCESS | 17742 | |0;ai-astar;109;105;110;110;108;109;109;108;110;109 20:30:17 INFO - PROCESS | 17742 | |1;audio-beat-detection;152;125;150;214;126;158;157;125;178;125 20:30:17 INFO - PROCESS | 17742 | |2;audio-dft;161;159;161;160;161;162;160;161;160;162 20:30:17 INFO - PROCESS | 17742 | |3;audio-fft;74;74;76;139;77;75;76;75;136;76 20:30:17 INFO - PROCESS | 17742 | |4;audio-oscillator;92;93;152;93;91;91;132;92;91;126 20:30:17 INFO - PROCESS | 17742 | |5;imaging-gaussian-blur;117;118;116;116;117;116;117;117;117;118 20:30:17 INFO - PROCESS | 17742 | |6;imaging-darkroom;146;145;146;145;145;145;145;145;145;145 20:30:17 INFO - PROCESS | 17742 | |7;imaging-desaturate;95;95;94;95;94;95;94;95;94;94 20:30:17 INFO - PROCESS | 17742 | |8;json-parse-financial;56;55;55;55;56;54;55;112;53;52 20:30:17 INFO - PROCESS | 17742 | |9;json-stringify-tinderbox;59;60;60;59;60;67;59;59;60;59 20:30:17 INFO - PROCESS | 17742 | |10;stanford-crypto-aes;77;82;79;77;77;78;85;81;78;80 20:30:17 INFO - PROCESS | 17742 | |11;stanford-crypto-ccm;121;120;117;118;124;120;120;119;189;131 20:30:17 INFO - PROCESS | 17742 | |12;stanford-crypto-pbkdf2;161;160;162;167;164;160;159;159;163;161 20:30:17 INFO - PROCESS | 17742 | |13;stanford-crypto-sha256-iterative;62;65;62;63;62;61;63;60;64;61 20:30:17 INFO - PROCESS | 17742 | __end_tp_report 20:30:17 INFO - PROCESS | 17742 | __start_cc_report 20:30:17 INFO - PROCESS | 17742 | _x_x_mozilla_cycle_collect,1871 20:30:17 INFO - PROCESS | 17742 | __end_cc_report 20:30:17 INFO - PROCESS | 17742 | __startTimestamp1463283017413__endTimestamp 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | ------- Summary: start ------- 20:30:17 INFO - PROCESS | 17742 | Number of tests: 14 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#0] ai-astar Cycles:10 Average:108.70 Median:109.00 stddev:1.49 (1.4%) stddev-sans-first:1.58 20:30:17 INFO - PROCESS | 17742 | Values: 109.0 105.0 110.0 110.0 108.0 109.0 109.0 108.0 110.0 109.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#1] audio-beat-detection Cycles:10 Average:151.00 Median:154.50 stddev:28.75 (18.6%) stddev-sans-first:30.49 20:30:17 INFO - PROCESS | 17742 | Values: 152.0 125.0 150.0 214.0 126.0 158.0 157.0 125.0 178.0 125.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 20:30:17 INFO - PROCESS | 17742 | Values: 161.0 159.0 161.0 160.0 161.0 162.0 160.0 161.0 160.0 162.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#3] audio-fft Cycles:10 Average:87.80 Median:75.50 stddev:26.22 (34.7%) stddev-sans-first:27.33 20:30:17 INFO - PROCESS | 17742 | Values: 74.0 74.0 76.0 139.0 77.0 75.0 76.0 75.0 136.0 76.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#4] audio-oscillator Cycles:10 Average:105.30 Median:91.50 stddev:22.59 (24.7%) stddev-sans-first:23.44 20:30:17 INFO - PROCESS | 17742 | Values: 92.0 93.0 152.0 93.0 91.0 91.0 132.0 92.0 91.0 126.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 20:30:17 INFO - PROCESS | 17742 | Values: 117.0 118.0 116.0 116.0 117.0 116.0 117.0 117.0 117.0 118.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 20:30:17 INFO - PROCESS | 17742 | Values: 146.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 20:30:17 INFO - PROCESS | 17742 | Values: 95.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:55.00 stddev:18.21 (33.1%) stddev-sans-first:19.25 20:30:17 INFO - PROCESS | 17742 | Values: 56.0 55.0 55.0 55.0 56.0 54.0 55.0 112.0 53.0 52.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:2.44 (4.1%) stddev-sans-first:2.55 20:30:17 INFO - PROCESS | 17742 | Values: 59.0 60.0 60.0 59.0 60.0 67.0 59.0 59.0 60.0 59.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.50 stddev:2.63 (3.3%) stddev-sans-first:2.65 20:30:17 INFO - PROCESS | 17742 | Values: 77.0 82.0 79.0 77.0 77.0 78.0 85.0 81.0 78.0 80.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:120.50 stddev:21.83 (18.1%) stddev-sans-first:23.01 20:30:17 INFO - PROCESS | 17742 | Values: 121.0 120.0 117.0 118.0 124.0 120.0 120.0 119.0 189.0 131.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.60 Median:161.50 stddev:2.50 (1.6%) stddev-sans-first:2.65 20:30:17 INFO - PROCESS | 17742 | Values: 161.0 160.0 162.0 167.0 164.0 160.0 159.0 159.0 163.0 161.0 20:30:17 INFO - PROCESS | 17742 | 20:30:17 INFO - PROCESS | 17742 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:1.49 (2.4%) stddev-sans-first:1.58 20:30:17 INFO - PROCESS | 17742 | Values: 62.0 65.0 62.0 63.0 62.0 61.0 63.0 60.0 64.0 61.0 20:30:17 INFO - PROCESS | 17742 | -------- Summary: end -------- 20:30:17 INFO - PROCESS | 17742 | 20:30:18 INFO - TEST-INFO | 17742: exit 0 20:30:18 INFO - TEST-OK | kraken | took 62131ms 20:30:18 INFO - SUITE-END | took 1174s 20:30:18 INFO - Completed test suite (00:19:34) 20:30:18 INFO - javascript benchmark 20:30:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3171.0962375722916, 3230.951535726963, 3249.951250731239, 3271.541984122222, 3284.523744057112, 5320.866978325535, 5372.435894231108, 5399.406065332815, 5497.477739614736, 5511.559075273978, 5362.785488580452, 5372.57019438445, 5461.317335333083, 5528.226048353224, 5544.16837474379, 4819.180739274314, 4888.242322440027, 4897.828575999841, 4902.436219834711, 5084.186530155176, 4947.6784009039475, 5001.074801161779, 5037.093323201822, 5052.242163675442, 5058.848234552969, 4733.5976442002475, 4791.952080479203, 4803.543663351993, 4890.413150421937, 4907.656464047518, 4862.5623693867365, 4881.536254055859, 4889.657723959324, 4890.413150421972, 4992.40091189056, 4895.8776030599165, 4933.654644174909, 4943.1102401567705, 4945.505449455026, 4949.158643030693, 5197.0, 5279.5512381447625, 5334.333208348957, 5347.411784703719, 5439.891202175974, 5847.356790753014, 5854.941450585525, 5906.7637294508595, 5911.379305172981, 5921.200637913869, 5891.705414729289, 5926.940730592725, 5972.074328479092, 5984.491318237955, 5991.940080599182, 5294.602904782157, 5445.9727701361635, 5519.586031047647, 5520.447955204488, 5611.354694210137, 6061.480733150516, 6062.690674164, 6064.898402976644, 6106.847328816726, 6119.938800611937, 5635.6618602883955, 5838.5329173666005, 5847.181394604759, 5890.145928840294, 5892.381299963527, 5708.543316534668, 5775.653460792366, 5779.942200578024, 5822.82531524055, 5827.0, 5790.710464476843, 5794.739236734357, 5801.767929282866, 5805.216295800097, 5806.274215723035, 5694.686792226385, 5734.197212390269, 5751.0, 5798.971005144947, 5811.709414529257, 4398.582134697199, 4425.579569940851, 4427.26950053244, 4441.711288766284, 4475.194464996267, 3889.397143442771, 3908.101136738509, 3935.665468435215, 3948.9012774680864, 3959.148783011666, 5300.973495132577, 5331.653442526223, 5455.0, 5506.284183056177, 5525.4474552544425, 5906.852328691817, 5918.940810591925, 5919.970400148058, 5934.821955341346, 5986.551008674367, 5528.170774383794, 5551.361593416728, 5553.444655534414, 5558.916616250759, 5572.080606699849, 4916.311716359713, 5009.699418034929, 5075.365579302587, 5075.441701412841, 5078.97460512695, 6175.011998080286, 6180.103884936749, 6193.473554747807, 6193.566450348432, 6197.597156184834, 5908.291005079418, 5912.113183022581, 5914.811953243879, 5915.674637894957, 5989.011813050798, 5856.882862342814, 6084.69576521181, 6087.391260873877, 6111.348653136637, 6122.755089796358, 5758.942410575841, 5776.8555786104, 5791.9131213031, 5795.652260864193, 5826.970865145647, 5899.203607513103, 5903.911441328298, 5907.350191478848, 5914.408559144051, 5914.58597898143, 5467.644603100785, 5686.2607860977805, 5689.630174038674, 5723.112917497794, 5792.420757924174, 5760.0783874579865, 5760.539156867524, 5761.711914404347, 5763.135529670583, 5765.308163020464, 5472.151816468454, 5681.346645135863, 5682.403347648603, 5685.232493613475, 5691.658500489983, 5809.883802323845, 5841.299044114563, 5870.7945221917025, 5903.7048147593305, 5905.261842269716, 5707.514861236676, 5742.310922689304, 5744.5978781486565, 5749.453801888814, 5800.767969281182], "value": 5394.500836947345, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.0173531893224, 642.9485641148708, 651.8598501322215, 659.6767583883899, 664.8404382948091, 42811.0, 44686.10627787447, 44724.77637611811, 47585.0, 47591.52408475914, 46450.767746161306, 47018.0, 47019.294710579285, 47047.52952470474, 47081.529184708146, 8942.508162051068, 9088.454692718426, 9091.818163636723, 9092.090790920887, 9094.818103637923, 8843.734687959372, 8927.910720892773, 8935.687250946217, 8942.776430589221, 8945.418547794405, 8967.865482017774, 9018.278537716968, 9020.233280171193, 9025.59384827681, 9027.277817774562, 8926.37515373924, 8946.597403116875, 8953.955230223808, 8964.14840590143, 8972.013078561386, 8868.822623547492, 8937.865932011026, 8946.105389461067, 8948.910510894873, 8955.238804701608, 43001.1399772006, 43381.34927976083, 43422.348664770056, 43475.34786978198, 43521.564784352384, 3762.360398732195, 3896.7467114637457, 3949.723519353646, 4291.184674911757, 4466.017476155241, 31118.37763244745, 34307.656923430695, 34321.14197145091, 34408.655913440794, 35541.111472213146, 4077.6533994610495, 4104.671626269921, 4128.256913755523, 4141.130362623852, 4168.645665118469, 356378.0, 359456.0, 359980.0, 360011.9999999974, 362179.0, 55882.44117558854, 66252.33747662559, 67283.32716672869, 67380.0, 67421.32578674151, 54213.0, 72074.63962680259, 74926.0, 75108.24891751121, 75323.24676753272, 16737.0, 16776.161191940355, 16850.578735531464, 16851.494455166365, 16890.831091688924, 12091.214071085351, 13146.737065258641, 13165.210087394787, 13179.209247445184, 13183.274919879499, 20738.896305518676, 21377.893110534347, 21523.246686365903, 21550.137994480046, 21656.675149872764, 7516.0, 7637.365109037373, 7859.449838511364, 7873.724419645285, 7878.290953814184, 993.1707024634367, 1082.6156714366443, 1091.939943303126, 1102.4322473925934, 1146.7534480086654, 33797.83101084528, 34086.48870266948, 34284.48573271403, 34340.82829585886, 34914.0, 1167.3637867362138, 1184.6386852010219, 1227.030645789834, 1236.2925193825492, 1268.2580690296095, 18016.0, 18042.458726238234, 18100.0, 18126.365577204735, 18146.63706725858, 542962.0, 549378.0, 549778.0, 550576.0, 552314.0, 60868.695656521435, 60882.39117608767, 61461.0, 61611.383886161464, 61772.073418898755, 1303.0552849184267, 1393.0736060519641, 1405.7539930512119, 1405.810215620919, 1413.8020677105214, 30543.847280763453, 35099.47350789689, 35105.29789404147, 35416.64583354133, 35666.821665891504, 51490.48509514857, 51518.19686310961, 51907.74046129745, 52014.47985520097, 52053.0, 317116.0, 320047.0, 320192.0, 320406.0, 320596.0, 37324.0, 42113.36829947492, 43666.78166609147, 44238.336424953006, 44870.551294486635, 49261.753691231315, 49487.25769113538, 49554.25668614901, 50038.249426257906, 50520.747396262785, 42035.579644203164, 42343.0, 42747.14505709806, 43663.34504982364, 43867.341989870816, 42155.57844421639, 42437.78781106075, 42480.5751942489, 42862.35706464468, 43262.56737432585, 42105.0, 42210.15579688451, 43055.0, 43302.0, 43336.78331608448, 35892.64107358893, 36802.26395472129, 37622.8118859404, 39075.0, 39677.206455871295, 29139.271518212216, 29435.350818425588, 29819.0, 29860.0, 30218.24454388658], "value": 21108.34342416789, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.82678448267245, 403.38081045595015, 403.8223181800007, 412.78328877339374, 420.7496539558961, 13778.655533611665, 18356.541086472844, 20022.89988550057, 20049.59900801983, 20074.0, 14420.639484012878, 18765.061746912666, 18866.811331886678, 19407.51481212967, 19568.804311956876, 5588.916166257499, 5768.019436695772, 5787.392323806003, 5795.594308398414, 5806.419358064181, 5689.715514224293, 5784.971075144639, 5796.275465566804, 5796.362400135981, 5800.47795698387, 4175.457190565222, 5951.137085122655, 6245.5003599712145, 6254.937450625481, 6350.079238510413, 6256.436920677115, 6258.374162583728, 6280.999999999977, 6337.366263373672, 6379.872402551923, 5515.172724091378, 5612.410696876851, 5629.774809007635, 5651.8304450866535, 5652.434756524356, 22575.984080716244, 23366.53266934669, 23679.7632023681, 23787.85909268174, 23942.0, 2529.064246228907, 2538.2892790018823, 2627.356297707055, 2736.835789852615, 2959.556066590007, 7306.488545801796, 12937.80593291092, 14351.497697580638, 14421.206833624146, 14432.422703091865, 1921.9231230750754, 1983.1571582077559, 2047.1299697628597, 2048.201201531389, 2076.2214169686367, 73781.0, 75792.0, 76265.61867190684, 85003.0, 87917.12082879154, 10074.337734273762, 10470.633527826642, 10796.298240614335, 10964.28732132409, 10986.121110311156, 14709.92645036768, 14801.333939972727, 14925.328360223815, 14971.176585287696, 15161.696766064617, 8143.389245806469, 8262.628181731836, 8412.242898139197, 8433.325333973391, 8496.065432802387, 8052.0, 8147.877781833158, 8184.508929464251, 8230.958845205856, 8281.0, 4013.6989725770686, 4477.597016268486, 4493.662975276868, 4668.8132474701315, 4841.733704646278, 1824.2611742244412, 1842.5854182808762, 1857.2570971611462, 1886.1983656946018, 1888.6033932873975, 1008.1178968402647, 1032.318669678009, 1039.7088815131776, 1083.1172594335592, 1084.9063646181105, 19065.0, 19892.60214795696, 19917.10373033188, 19989.500262493555, 20064.59870802576, 1043.4626167523732, 1045.0020230679684, 1052.3528030261334, 1067.0769784136667, 1081.1729027294127, 10155.238357123246, 11091.0, 11190.832137517944, 11195.4962026709, 11203.327800331843, 458487.0, 460668.0, 461267.0, 486200.0, 496443.0, 90193.54903225573, 91293.54353228191, 92254.53872730593, 92524.53737731268, 93099.53450232705, 1025.0876719719456, 1028.4240825137947, 1052.379096333152, 1056.8837427882927, 1060.3584831177027, 17278.827211728225, 17325.74011389805, 17539.649207016042, 17545.561360965567, 17841.197146128197, 39765.32590861655, 40134.79932600318, 40737.38893916534, 41162.176756465306, 41281.58718412778, 54754.0, 56291.43708562862, 57983.710081449324, 58054.12918806137, 58322.12516812166, 8504.872426913478, 8552.700655477029, 8601.838751768431, 8611.0, 8653.870191947, 10396.792064158522, 13287.13633613812, 13339.733205335644, 13779.724405512034, 13796.724065518834, 11850.58522951727, 12039.75920481603, 12138.271703697807, 12151.513939442677, 12283.69290767738, 11719.88280117222, 11763.5303580615, 12067.974222191213, 12135.696607584881, 12147.635570932885, 12143.878561214628, 12316.568920087753, 12450.626481205578, 12774.936125319315, 12848.678783030498, 8563.143685631387, 8569.0, 8693.478391296543, 8713.215810577078, 8834.690785822722, 9170.495622740813, 11232.943835281098, 12350.567730129402, 12387.442565084413, 12823.358832058546], "value": 9375.11241854076, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.3509832829402, 1532.073095777055, 1568.6078480379904, 1733.505950804021, 1867.6638205123072, 8553.529555874424, 8805.031446540876, 8819.735407937756, 9207.81584368314, 9238.538073096335, 8865.0, 9085.727428177166, 9094.681686140986, 9098.226650734696, 9100.226480749145, 2078.366098340006, 2132.2217390652454, 2132.5201829588295, 2205.095910676619, 2229.476073122815, 2271.2618399020303, 2330.533893221354, 2332.8950197241165, 2349.0, 2351.9764802352015, 2310.318456055459, 2359.315798418457, 2365.73976862545, 2372.0, 2372.7034120734907, 2009.8291645210174, 2268.0247493577754, 2299.1033496936207, 2302.7582103879017, 2304.1129165271323, 2344.6717459555675, 2385.105585405473, 2386.940326491834, 2387.7850993410675, 2388.259639511757, 8909.198172164472, 8967.237784788302, 8969.506677132755, 8970.775730606787, 8972.730818075468, 5282.841514754564, 5432.864178395532, 5439.619226654136, 5446.183072539111, 5446.291982042309, 3228.4673028950197, 3268.6404495505476, 3423.07576954221, 3437.9484307735406, 3441.2429265561536, 1104.8563686720756, 1124.2636073371955, 1140.7604403075363, 1142.4287856071965, 1165.5163107310457, 12910.09629325957, 12942.482300707961, 12948.935255323757, 12961.351932403437, 12973.351332433434, 4795.376601041842, 4893.828715994923, 4908.730019848943, 4910.8772280693265, 4976.676516026447, 10750.94624526888, 10811.891881081247, 10816.0, 10820.89179108199, 10832.837507437394, 5456.045192091368, 5468.808591699271, 5472.370677372153, 5475.206095116186, 5476.096444086761, 4342.913141737147, 4400.185965596375, 4941.876453088702, 4950.18321976877, 5053.292539044537, 4001.1797581495725, 4054.412110243998, 4118.073433477503, 4142.523609784853, 4304.526502084768, 1265.9113862029753, 1287.0797379873436, 1300.1419063418098, 1311.7114234868313, 1311.9737605247842, 1030.5568605499557, 1042.9165666746642, 1046.398320965448, 1055.1084333737979, 1067.2475904487317, 5957.791477298274, 6003.969980150158, 6115.694215289218, 6182.783602573978, 6315.526335524854, 450.04141179287905, 454.32986345140654, 460.580871407018, 462.115049679866, 462.26269100784197, 4185.455890734185, 4372.081862808782, 4392.165488557164, 4430.0, 4430.224710675619, 10981.560737570568, 11419.60031398897, 11424.371659558805, 11437.885621143849, 11475.598354057567, 10723.142148628247, 11089.722756931142, 11097.1677124216, 11325.377104259345, 11429.371384573762, 8418.747437576883, 8495.32037437016, 8541.103184165697, 8553.0, 8555.27280181179, 6166.630002199882, 6384.489240860813, 6444.677766111582, 6452.387023232785, 6459.838504037437, 10090.192784577363, 10347.482625868526, 10352.741181470223, 10383.376997380181, 10487.161027117965, 10619.36283822973, 10705.785884282426, 10708.946455267675, 10710.357378557, 10737.46312684347, 7271.200167981412, 7360.04319438491, 8040.517568945882, 8075.394345423999, 8217.342612591097, 8957.686480973134, 9087.863682044906, 9111.772205694911, 9115.863262050942, 9120.954395227982, 7801.102873169658, 7892.250236227549, 7993.440459167802, 8024.719134830253, 8170.387220958333, 7737.767866964, 7867.724629637935, 7906.683732650629, 7915.208479152038, 7996.120426753168, 7263.309985551364, 7274.96362518184, 7321.084864391951, 7362.7423040193335, 7430.814229644302, 5268.262443258023, 5407.324084489439, 5442.673439593637, 5564.638298510584, 5664.065429204135, 5453.727313634223, 5464.480874316933, 5491.945080549143, 5653.4170432279025, 5700.543956483554], "value": 4684.032843858357, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.568960351543, 332.4000179675686, 338.11414095070916, 341.6463959801607, 346.4941185868632, 5256.1590145576665, 5279.472052794718, 5288.0, 5308.416074231832, 5316.059638232046, 5240.764165612547, 5254.92117618236, 5258.815941442051, 5261.316028916245, 5272.288241087452, 3910.843566257346, 4014.293925081843, 4015.5984401559904, 4076.1847630473876, 4111.917761644765, 4050.7037747920676, 4085.754854708712, 4095.3242714952, 4130.678182981447, 4130.896727581804, 4048.1903619276263, 4056.533498647649, 4068.677679754077, 4106.28140075487, 4113.444684967536, 3901.882943511699, 3965.2267807777353, 4060.0, 4070.4097905803787, 4087.7956102195067, 3613.421852503613, 3639.490471334015, 3644.1254099016046, 3645.54430696163, 3650.507181530486, 4943.802247910079, 5062.139436295838, 5082.415522214956, 5085.389753229636, 5138.820141295074, 4506.188886000518, 4706.44687253209, 4729.266963620645, 4739.739314337745, 4750.738709370983, 5490.968341713552, 5515.751791169367, 5517.78608706084, 5549.889002219973, 5558.527525160366, 4054.472918520603, 4067.572904845008, 4073.572274911151, 4077.2660921034203, 4077.796110194508, 5490.450954904518, 5635.5773317001385, 5654.688992105433, 5666.773329066832, 5803.129530570406, 4077.1437998020156, 4087.58832288945, 4089.7546147230664, 4090.1206240658403, 4106.938395924063, 4135.855245066408, 4152.273352163359, 4155.667546596325, 4161.481059413292, 4168.166366726667, 4549.7497637629585, 4681.344611754357, 4721.315409265637, 4727.95272047282, 4764.642077008036, 5480.616356854982, 5499.257600223918, 5511.200875873056, 5525.530329921921, 5535.972320138373, 1436.8778653814543, 1452.7820826876052, 1453.9854601454063, 1460.7443697353133, 1460.7516722157259, 917.3074328881652, 946.5409276500892, 956.7751578379215, 972.7713987212999, 982.7444864335324, 5208.9739551302755, 5250.868728281824, 5263.421023687391, 5268.2088090049565, 5291.814786482455, 5537.8061767837935, 5548.14003829399, 5567.610267281332, 5585.497305242467, 5620.718964051863, 4992.675476094042, 5219.765110570033, 5267.367915850046, 5281.867953301121, 5283.207518872123, 2932.736053755172, 2956.2757124504633, 2957.46765582193, 2959.1801042358793, 2964.4219377221234, 5789.768409263666, 5800.245968024215, 5803.883922321615, 5804.187413762077, 5813.680247586423, 5516.0, 5788.189653448437, 5798.449147330997, 5807.448292412214, 5809.157672137601, 5863.941360586425, 5921.697226610139, 5934.7922822701, 5948.9773477018025, 5982.461578457785, 5481.561475081904, 5698.1737648041635, 5716.79991200306, 5758.683272420057, 5783.971080144573, 5649.6327738696855, 5666.206731057573, 5680.573956953328, 5685.886282274414, 5737.815512390664, 5895.29256489224, 5906.165040780364, 5936.28764548257, 5963.075723262901, 5983.282006159115, 3852.5745474174737, 4069.348904175318, 4114.547399786081, 4121.91756164881, 4125.711200215956, 5660.132156388464, 5762.481376676119, 5782.306123265235, 5786.42135786418, 5786.94213057864, 5372.892542149057, 5521.309836270466, 5534.7509362078, 5558.749856256397, 5628.521575666115, 5515.7865269640615, 5547.306586676666, 5550.0, 5551.361593416808, 5564.610477266552, 5303.496167864047, 5336.466353364633, 5444.230625046955, 5444.428335024765, 5461.504637032223, 5595.63628364155, 5608.46719561641, 5626.43735626434, 5627.971860140673, 5637.210790489253, 5500.80747173847, 5510.972445137749, 5511.58663100261, 5512.779488820563, 5528.308961379827], "value": 4228.380757494019, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.1945379723157, 769.3960241210643, 868.6829307302835, 880.0231742765529, 885.017630430222, 14404.711905761878, 14834.777478337806, 14850.628734281634, 15074.698506029887, 15192.620184495394, 14701.264936753172, 14774.852251477481, 14783.630409239775, 14810.259487025633, 14829.0, 693.5076095971841, 817.190980928882, 826.2027143806225, 835.4778263585259, 843.6667516331046, 828.4615000249855, 832.908380078194, 834.9874751878726, 835.8495470815251, 836.297510091523, 886.0165216609558, 886.5434301334818, 887.6227603268585, 888.0897080492482, 889.4307643108416, 734.6216698400301, 868.192580899763, 880.6829541365136, 893.1202765276198, 893.5755516129851, 631.6178711879293, 633.0188208277152, 633.0757094641829, 633.1199632510813, 633.262249479356, 14816.185109818956, 14827.258637068211, 14831.184284864328, 14835.03572267799, 14856.0, 13454.260015699132, 13737.725245495034, 13840.93079534606, 13843.861561384358, 13881.722365552734, 11386.680392382224, 11662.241954272946, 11883.465244064038, 12018.759624807542, 12073.758524829542, 3930.5283365996265, 4012.4583181270445, 4033.69747268956, 4107.979460102681, 4114.403411505345, 56565.71717141429, 57358.7132064337, 58781.41218587802, 58793.70603147, 59058.40941590615, 11283.379414132138, 11297.774044519063, 11815.350155741346, 11837.171398002229, 12147.149699520949, 21406.357809265748, 21539.784602153777, 21597.24409645655, 21598.0, 21719.348419547438, 8639.524826134622, 8950.507722075217, 8990.0, 9037.68368107113, 9080.8637870432, 4784.0, 4944.431390390079, 4966.553010229097, 5009.724465154379, 5230.92153617696, 3498.6151523332414, 3641.5812181598926, 3650.2152037311575, 3650.543682039745, 3657.231981283907, 674.3694645506467, 675.9155105611799, 684.9417799487, 690.5373399822131, 691.374306252842, 713.3651050565002, 781.3475747750579, 787.3189690917316, 809.1786836361098, 828.2711214131646, 9789.363691360039, 10143.797124057477, 10294.742631434274, 10367.63713270032, 10511.579536818595, 737.3621817128145, 737.6826283651868, 762.6110683551318, 778.2217334839273, 778.7585848387018, 4605.930911036337, 4610.654200934876, 4948.208286674115, 5020.748962551857, 5026.79892804291, 69181.65409172921, 69371.65314173498, 69698.65150674316, 70995.0, 71681.6415917917, 61736.69131654313, 63766.0, 63881.0, 64329.678351607945, 64640.03039954404, 55623.0, 56466.43533564694, 56479.43520564824, 57034.14448783272, 59474.0, 9449.454839143818, 9552.799102221394, 9562.856557151787, 9572.664956726481, 9595.520223988633, 37140.257194856495, 37384.252314954094, 37681.81159094187, 37913.24173516459, 38571.421428679154, 47450.76274618605, 50496.0, 50665.24002139897, 50751.74624127003, 51220.231696523835, 11532.711682208012, 11566.016888564567, 12335.717892189974, 12365.690857728629, 12690.873091269339, 12389.62831115068, 13041.217526948414, 13221.735565288833, 13335.266560339274, 13357.599272021855, 12487.562935297221, 12872.742545149233, 12914.67713307175, 13019.544315948897, 13425.993050521445, 11443.599474018369, 11758.470868811095, 11787.174897757075, 12076.21504602198, 12174.756504869676, 12008.879911200776, 12204.572839950559, 12439.689007774878, 12940.870591294342, 13281.468741250628, 9675.9032409675, 9726.96415595787, 10596.735081623021, 10637.14902807789, 10760.246782725448, 10435.37387756737, 10500.133778601321, 10553.155747540024, 10671.679849604525, 10765.838512422164], "value": 7089.36233515563, "unit": "score"}], "name": "dromaeo_css", "value": 7288.501980174905, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 110.0, 110.0, 108.0, 109.0, 109.0, 108.0, 110.0, 109.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 125.0, 150.0, 214.0, 126.0, 158.0, 157.0, 125.0, 178.0, 125.0], "value": 151.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 161.0, 160.0, 161.0, 162.0, 160.0, 161.0, 160.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 139.0, 77.0, 75.0, 76.0, 75.0, 136.0, 76.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 152.0, 93.0, 91.0, 91.0, 132.0, 92.0, 91.0, 126.0], "value": 105.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 56.0, 54.0, 55.0, 112.0, 53.0, 52.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 60.0, 67.0, 59.0, 59.0, 60.0, 59.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 79.0, 77.0, 77.0, 78.0, 85.0, 81.0, 78.0, 80.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 117.0, 118.0, 124.0, 120.0, 120.0, 119.0, 189.0, 131.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 167.0, 164.0, 160.0, 159.0, 159.0, 163.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 62.0, 63.0, 62.0, 61.0, 63.0, 60.0, 64.0, 61.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1521.8, "alertThreshold": 2.0}]} 20:30:18 INFO - Return code: 0 20:30:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:30:18 ERROR - # TBPL SUCCESS # 20:30:18 INFO - Running post-action listener: _resource_record_post_action 20:30:18 INFO - ##### 20:30:18 INFO - ##### Finished run-tests step (success) 20:30:18 INFO - ##### 20:30:18 INFO - Running post-run listener: _resource_record_post_run 20:30:18 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 537137152; Read time: 0; Write time: 379320 20:30:18 INFO - TinderboxPrint: CPU usage
13.0% 20:30:18 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:30:18 INFO - TinderboxPrint: I/O write bytes / time
537,137,152 / 379,320 20:30:18 INFO - TinderboxPrint: CPU idle
8,271.6 (86.9%) 20:30:18 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 20:30:18 INFO - TinderboxPrint: Swap in / out
0 / 0 20:30:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 185360384; Read time: 0; Write time: 165896 20:30:18 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 290770944; Read time: 0; Write time: 183076 20:30:19 INFO - Running post-run listener: _upload_blobber_files 20:30:19 INFO - Blob upload gear active. 20:30:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:30:19 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 20:30:19 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'] 20:30:19 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 20:30:19 INFO - (blobuploader) - INFO - Open directory for files ... 20:30:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:30:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:30:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:20 INFO - (blobuploader) - INFO - Done attempting. 20:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:24 INFO - (blobuploader) - INFO - Done attempting. 20:30:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:30:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:24 INFO - (blobuploader) - INFO - Done attempting. 20:30:24 INFO - (blobuploader) - INFO - Iteration through files over. 20:30:24 INFO - Return code: 0 20:30:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:30:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:30:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a2b285bfb1190542000872b75fe36fbbe30c6b64bbdf843aa9b5fa2d3a6ab07568678d706e29aac7f5236b4e802e88f435a09ff57b0ad90ed910e4c2b213ad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b1bfe622790569836ecf2c03928561c20fab140924f5f81e96d409657f94ce1f7cc8d2b6f519cf1c4fc2759f5fe5c2dde19c38696194d1e97558f251991e403c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b717b8fe8068ccf7a17c3f86bab185595e88dfc493929f14bb76e50ce709b36bfa271c32507f83e9e7a9bebda77674f76ea284968cff39957672208251d476e7"} 20:30:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:30:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:30:24 INFO - Contents: 20:30:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a2b285bfb1190542000872b75fe36fbbe30c6b64bbdf843aa9b5fa2d3a6ab07568678d706e29aac7f5236b4e802e88f435a09ff57b0ad90ed910e4c2b213ad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b1bfe622790569836ecf2c03928561c20fab140924f5f81e96d409657f94ce1f7cc8d2b6f519cf1c4fc2759f5fe5c2dde19c38696194d1e97558f251991e403c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b717b8fe8068ccf7a17c3f86bab185595e88dfc493929f14bb76e50ce709b36bfa271c32507f83e9e7a9bebda77674f76ea284968cff39957672208251d476e7"} 20:30:24 INFO - Running post-run listener: copy_logs_to_upload_dir 20:30:24 INFO - Copying logs to upload dir... 20:30:24 INFO - mkdir: /builds/slave/test/build/upload/logs 20:30:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.688107 ========= master_lag: 35.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-14 20:30:59.032047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 20:30:59.040183) ========= 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=6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611 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/0a2b285bfb1190542000872b75fe36fbbe30c6b64bbdf843aa9b5fa2d3a6ab07568678d706e29aac7f5236b4e802e88f435a09ff57b0ad90ed910e4c2b213ad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b1bfe622790569836ecf2c03928561c20fab140924f5f81e96d409657f94ce1f7cc8d2b6f519cf1c4fc2759f5fe5c2dde19c38696194d1e97558f251991e403c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b717b8fe8068ccf7a17c3f86bab185595e88dfc493929f14bb76e50ce709b36bfa271c32507f83e9e7a9bebda77674f76ea284968cff39957672208251d476e7"} build_url:https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/LMM4aMymSoiMH08uXZZhhQ/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/0a2b285bfb1190542000872b75fe36fbbe30c6b64bbdf843aa9b5fa2d3a6ab07568678d706e29aac7f5236b4e802e88f435a09ff57b0ad90ed910e4c2b213ad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b1bfe622790569836ecf2c03928561c20fab140924f5f81e96d409657f94ce1f7cc8d2b6f519cf1c4fc2759f5fe5c2dde19c38696194d1e97558f251991e403c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b717b8fe8068ccf7a17c3f86bab185595e88dfc493929f14bb76e50ce709b36bfa271c32507f83e9e7a9bebda77674f76ea284968cff39957672208251d476e7"}' ========= master_lag: 0.39 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 20:30:59.439482) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:30:59.439846) ========= 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=6dbc10da6d83fee962c624be000001a8-1463281571.661724-1909798611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004482 ========= master_lag: 0.29 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:30:59.737847) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 20:30:59.738221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 20:30:59.763431) ========= ========= Total master_lag: 35.92 =========