builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-067 starttime: 1464279789.35 results: success (0) buildid: 20160526080550 builduid: 9a8897d3cefa42198723b081555703be revision: 56e5195bef5bf352d37181195dc6043ac3d198ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.350520) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.350990) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.351269) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.372710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.373011) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-26 09:23:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.5M=0s 2016-05-26 09:23:10 (42.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.488765) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.489075) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020520 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.632530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:09.632892) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 56e5195bef5bf352d37181195dc6043ac3d198ad --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 56e5195bef5bf352d37181195dc6043ac3d198ad --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=e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-26 09:23:11,048 truncating revision to first 12 chars 2016-05-26 09:23:11,048 Setting DEBUG logging. 2016-05-26 09:23:11,048 attempt 1/10 2016-05-26 09:23:11,048 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/56e5195bef5b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 09:23:11,413 unpacking tar archive at: mozilla-central-56e5195bef5b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686166 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:10.338217) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:10.338511) ========= script_repo_revision: 56e5195bef5bf352d37181195dc6043ac3d198ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:10.338910) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:10.339484) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 09:23:10.374527) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-26 09:23:10.374831) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:23:11 INFO - MultiFileLogger online at 20160526 09:23:11 in /builds/slave/test 09:23:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:23:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:23:11 INFO - {'append_to_log': False, 09:23:11 INFO - 'base_work_dir': '/builds/slave/test', 09:23:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:23:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:23:11 INFO - 'branch': 'Firefox-Non-PGO', 09:23:11 INFO - 'buildbot_json_path': 'buildprops.json', 09:23:11 INFO - 'config_files': ('talos/linux_config.py',), 09:23:11 INFO - 'default_actions': ('clobber', 09:23:11 INFO - 'read-buildbot-config', 09:23:11 INFO - 'download-and-extract', 09:23:11 INFO - 'populate-webroot', 09:23:11 INFO - 'create-virtualenv', 09:23:11 INFO - 'install', 09:23:11 INFO - 'run-tests'), 09:23:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:23:11 INFO - 'download_minidump_stackwalk': True, 09:23:11 INFO - 'download_symbols': 'ondemand', 09:23:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:23:11 INFO - 'tooltool.py': '/tools/tooltool.py', 09:23:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:23:11 INFO - '/tools/misc-python/virtualenv.py')}, 09:23:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:23:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:23:11 INFO - 'installer_path': 'installer.exe', 09:23:11 INFO - 'log_level': 'info', 09:23:11 INFO - 'log_name': 'talos', 09:23:11 INFO - 'log_to_console': True, 09:23:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:23:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:23:11 INFO - 'opt_config_files': (), 09:23:11 INFO - 'pip_index': False, 09:23:11 INFO - 'sps_profile': False, 09:23:11 INFO - 'sps_profile_interval': 0, 09:23:11 INFO - 'suite': 'dromaeojs', 09:23:11 INFO - 'system_bits': '32', 09:23:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:23:11 INFO - 'title': 'talos-linux64-ix-067', 09:23:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:23:11 INFO - 'use_talos_json': True, 09:23:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:23:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:23:11 INFO - 'work_dir': 'build'} 09:23:11 INFO - ##### 09:23:11 INFO - ##### Running clobber step. 09:23:11 INFO - ##### 09:23:11 INFO - Running pre-action listener: _resource_record_pre_action 09:23:11 INFO - Running main action method: clobber 09:23:11 INFO - rmtree: /builds/slave/test/build 09:23:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:23:12 INFO - Running post-action listener: _resource_record_post_action 09:23:12 INFO - ##### 09:23:12 INFO - ##### Finished clobber step (success) 09:23:12 INFO - ##### 09:23:12 INFO - ##### 09:23:12 INFO - ##### Running read-buildbot-config step. 09:23:12 INFO - ##### 09:23:12 INFO - Running pre-action listener: _resource_record_pre_action 09:23:12 INFO - Running main action method: read_buildbot_config 09:23:12 INFO - Using buildbot properties: 09:23:12 INFO - { 09:23:12 INFO - "project": "", 09:23:12 INFO - "product": "firefox", 09:23:12 INFO - "script_repo_revision": "production", 09:23:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:23:12 INFO - "repository": "", 09:23:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 09:23:12 INFO - "buildid": "20160526080550", 09:23:12 INFO - "pgo_build": "False", 09:23:12 INFO - "basedir": "/builds/slave/test", 09:23:12 INFO - "buildnumber": 595, 09:23:12 INFO - "slavename": "talos-linux64-ix-067", 09:23:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:23:12 INFO - "platform": "ubuntu64_hw", 09:23:12 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:23:12 INFO - "branch": "mozilla-central", 09:23:12 INFO - "repo_path": "mozilla-central", 09:23:12 INFO - "revision": "56e5195bef5bf352d37181195dc6043ac3d198ad", 09:23:12 INFO - "stage_platform": "linux64", 09:23:12 INFO - "builduid": "9a8897d3cefa42198723b081555703be", 09:23:12 INFO - "slavebuilddir": "test" 09:23:12 INFO - } 09:23:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 09:23:12 INFO - Running post-action listener: _resource_record_post_action 09:23:12 INFO - ##### 09:23:12 INFO - ##### Finished read-buildbot-config step (success) 09:23:12 INFO - ##### 09:23:12 INFO - ##### 09:23:12 INFO - ##### Running download-and-extract step. 09:23:12 INFO - ##### 09:23:12 INFO - Running pre-action listener: _resource_record_pre_action 09:23:12 INFO - Running main action method: download_and_extract 09:23:12 INFO - mkdir: /builds/slave/test/build/tests 09:23: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')]} 09:23:12 INFO - https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:23:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:23:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:23:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:13 INFO - Downloaded 1361 bytes. 09:23:13 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:23:13 INFO - Using the following test package requirements: 09:23:13 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:23:13 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:23:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:23:13 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:23:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:23:13 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:23:13 INFO - u'jsshell-linux-x86_64.zip'], 09:23:13 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:23:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:23:13 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:23:13 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:23:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:23:13 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:23:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:23:13 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:23:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:23:13 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:23:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:23:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:13 INFO - https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:23:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:23:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:23:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:15 INFO - Downloaded 22835565 bytes. 09:23:15 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'] 09:23:15 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 09:23:16 INFO - Return code: 0 09:23:16 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 09:23: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')]} 09:23:16 INFO - https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:23:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:23:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:23:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:18 INFO - Downloaded 22835565 bytes. 09:23:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:23:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:23:20 INFO - Return code: 0 09:23: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')]} 09:23:20 INFO - https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:23:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:23:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:23:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:21 INFO - Downloaded 10834034 bytes. 09:23:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:23:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:23:21 INFO - Return code: 0 09:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:21 INFO - https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:23:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:23:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:23:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/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 09:23:23 INFO - Downloaded 54832334 bytes. 09:23:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:23:23 INFO - mkdir: /builds/slave/test/properties 09:23:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:23:23 INFO - Writing to file /builds/slave/test/properties/build_url 09:23:23 INFO - Contents: 09:23:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:23:24 INFO - Running post-action listener: _resource_record_post_action 09:23:24 INFO - Running post-action listener: set_extra_try_arguments 09:23:24 INFO - ##### 09:23:24 INFO - ##### Finished download-and-extract step (success) 09:23:24 INFO - ##### 09:23:24 INFO - ##### 09:23:24 INFO - ##### Running populate-webroot step. 09:23:24 INFO - ##### 09:23:24 INFO - Running pre-action listener: _resource_record_pre_action 09:23:24 INFO - Running main action method: populate_webroot 09:23:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:23:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:23:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:23:24 INFO - '--tppagecycles', 09:23:24 INFO - '7'], 09:23:24 INFO - 'tests': ['tsvgm']}}, 09:23:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:23:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:23:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:23:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:23:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:24 INFO - 'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:24 INFO - 'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['damp', 'tps']}, 09:23:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:24 INFO - 'tests': ['damp', 'tps']}, 09:23:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['dromaeo_dom']}, 09:23:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:23:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['a11yr', 09:23:24 INFO - 'ts_paint', 09:23:24 INFO - 'tpaint', 09:23:24 INFO - 'sessionrestore', 09:23:24 INFO - 'sessionrestore_no_auto_restore', 09:23:24 INFO - 'tabpaint']}, 09:23:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:23:24 INFO - 'ts_paint', 09:23:24 INFO - 'tpaint', 09:23:24 INFO - 'sessionrestore', 09:23:24 INFO - 'sessionrestore_no_auto_restore', 09:23:24 INFO - 'tabpaint']}, 09:23:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:23:24 INFO - 'ts_paint', 09:23:24 INFO - 'tpaint', 09:23:24 INFO - 'sessionrestore', 09:23:24 INFO - 'sessionrestore_no_auto_restore', 09:23:24 INFO - 'tabpaint']}, 09:23:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:23:24 INFO - 'ts_paint', 09:23:24 INFO - 'tpaint', 09:23:24 INFO - 'sessionrestore', 09:23:24 INFO - 'sessionrestore_no_auto_restore', 09:23:24 INFO - 'tabpaint']}, 09:23:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['a11yr', 09:23:24 INFO - 'ts_paint', 09:23:24 INFO - 'tpaint', 09:23:24 INFO - 'sessionrestore', 09:23:24 INFO - 'sessionrestore_no_auto_restore', 09:23:24 INFO - 'tabpaint']}, 09:23:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['a11yr', 09:23:24 INFO - 'ts_paint', 09:23:24 INFO - 'tpaint', 09:23:24 INFO - 'sessionrestore', 09:23:24 INFO - 'sessionrestore_no_auto_restore', 09:23:24 INFO - 'tabpaint']}, 09:23:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['tsvgx', 09:23:24 INFO - 'tsvgr_opacity', 09:23:24 INFO - 'tart', 09:23:24 INFO - 'tscrollx', 09:23:24 INFO - 'cart']}, 09:23:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:23:24 INFO - 'tsvgr_opacity', 09:23:24 INFO - 'tart', 09:23:24 INFO - 'tscrollx', 09:23:24 INFO - 'cart']}, 09:23:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:24 INFO - 'talos_options': ['--disable-e10s'], 09:23:24 INFO - 'tests': ['tp5o']}, 09:23:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:24 INFO - 'tests': ['tp5o']}, 09:23:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:24 INFO - 'talos_options': ['--disable-e10s', 09:23:24 INFO - '--xperf_path', 09:23:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:24 INFO - 'tests': ['tp5n']}, 09:23:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:24 INFO - 'talos_options': ['--xperf_path', 09:23:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:24 INFO - 'tests': ['tp5n']}}, 09:23:24 INFO - 'talos.zip': {'path': '', 09:23:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:23:24 INFO - Running post-action listener: _resource_record_post_action 09:23:24 INFO - ##### 09:23:24 INFO - ##### Finished populate-webroot step (success) 09:23:24 INFO - ##### 09:23:24 INFO - ##### 09:23:24 INFO - ##### Running create-virtualenv step. 09:23:24 INFO - ##### 09:23:24 INFO - Running pre-action listener: _resource_record_pre_action 09:23:24 INFO - Running main action method: create_virtualenv 09:23:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:23:24 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 09:23:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:23:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:23:24 INFO - Using real prefix '/usr' 09:23:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:23:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:23:27 INFO - Installing pip.................done. 09:23:27 INFO - Return code: 0 09:23:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:23:27 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')]} 09:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:27 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')]} 09:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:27 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 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:27 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 09:23:27 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 09:23:27 INFO - Using env: {'DISPLAY': ':0', 09:23:27 INFO - 'HOME': '/home/cltbld', 09:23:27 INFO - 'LANG': 'en_US.UTF-8', 09:23:27 INFO - 'LANGUAGE': 'en_US:en', 09:23:27 INFO - 'LOGNAME': 'cltbld', 09:23:27 INFO - 'MAIL': '/var/mail/cltbld', 09:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:27 INFO - 'MOZ_NO_REMOTE': '1', 09:23:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:27 INFO - 'NO_EM_RESTART': '1', 09:23:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:27 INFO - 'PWD': '/builds/slave/test', 09:23:27 INFO - 'SHELL': '/bin/bash', 09:23:27 INFO - 'SHLVL': '1', 09:23:27 INFO - 'TERM': 'linux', 09:23:27 INFO - 'TMOUT': '86400', 09:23:27 INFO - 'USER': 'cltbld', 09:23:27 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:27 INFO - '_': '/tools/buildbot/bin/python'} 09:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:27 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 09:23:27 INFO - Cleaning up... 09:23:27 INFO - Return code: 0 09:23:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:23:27 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')]} 09:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:27 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')]} 09:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:27 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 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:27 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 09:23:27 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 09:23:27 INFO - Using env: {'DISPLAY': ':0', 09:23:27 INFO - 'HOME': '/home/cltbld', 09:23:27 INFO - 'LANG': 'en_US.UTF-8', 09:23:27 INFO - 'LANGUAGE': 'en_US:en', 09:23:27 INFO - 'LOGNAME': 'cltbld', 09:23:27 INFO - 'MAIL': '/var/mail/cltbld', 09:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:27 INFO - 'MOZ_NO_REMOTE': '1', 09:23:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:27 INFO - 'NO_EM_RESTART': '1', 09:23:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:27 INFO - 'PWD': '/builds/slave/test', 09:23:27 INFO - 'SHELL': '/bin/bash', 09:23:27 INFO - 'SHLVL': '1', 09:23:27 INFO - 'TERM': 'linux', 09:23:27 INFO - 'TMOUT': '86400', 09:23:27 INFO - 'USER': 'cltbld', 09:23:27 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:27 INFO - '_': '/tools/buildbot/bin/python'} 09:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:27 INFO - Downloading/unpacking psutil>=3.1.1 09:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:29 INFO - Installing collected packages: psutil 09:23:29 INFO - Running setup.py install for psutil 09:23:29 INFO - building 'psutil._psutil_linux' extension 09:23:29 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 09:23:29 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 09:23:29 INFO - building 'psutil._psutil_posix' extension 09:23:29 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 09:23:29 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 09:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:29 INFO - Successfully installed psutil 09:23:29 INFO - Cleaning up... 09:23:30 INFO - Return code: 0 09:23:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:23:30 INFO - Using env: {'DISPLAY': ':0', 09:23:30 INFO - 'HOME': '/home/cltbld', 09:23:30 INFO - 'LANG': 'en_US.UTF-8', 09:23:30 INFO - 'LANGUAGE': 'en_US:en', 09:23:30 INFO - 'LOGNAME': 'cltbld', 09:23:30 INFO - 'MAIL': '/var/mail/cltbld', 09:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:30 INFO - 'MOZ_NO_REMOTE': '1', 09:23:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:30 INFO - 'NO_EM_RESTART': '1', 09:23:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:30 INFO - 'PWD': '/builds/slave/test', 09:23:30 INFO - 'SHELL': '/bin/bash', 09:23:30 INFO - 'SHLVL': '1', 09:23:30 INFO - 'TERM': 'linux', 09:23:30 INFO - 'TMOUT': '86400', 09:23:30 INFO - 'USER': 'cltbld', 09:23:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:30 INFO - '_': '/tools/buildbot/bin/python'} 09:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:23:32 INFO - Installing collected packages: mozsystemmonitor 09:23:32 INFO - Running setup.py install for mozsystemmonitor 09:23:32 INFO - Successfully installed mozsystemmonitor 09:23:32 INFO - Cleaning up... 09:23:32 INFO - Return code: 0 09:23:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:23:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:23:32 INFO - Using env: {'DISPLAY': ':0', 09:23:32 INFO - 'HOME': '/home/cltbld', 09:23:32 INFO - 'LANG': 'en_US.UTF-8', 09:23:32 INFO - 'LANGUAGE': 'en_US:en', 09:23:32 INFO - 'LOGNAME': 'cltbld', 09:23:32 INFO - 'MAIL': '/var/mail/cltbld', 09:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:32 INFO - 'MOZ_NO_REMOTE': '1', 09:23:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:32 INFO - 'NO_EM_RESTART': '1', 09:23:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:32 INFO - 'PWD': '/builds/slave/test', 09:23:32 INFO - 'SHELL': '/bin/bash', 09:23:32 INFO - 'SHLVL': '1', 09:23:32 INFO - 'TERM': 'linux', 09:23:32 INFO - 'TMOUT': '86400', 09:23:32 INFO - 'USER': 'cltbld', 09:23:32 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:32 INFO - '_': '/tools/buildbot/bin/python'} 09:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:23:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:23:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:23:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:35 INFO - Downloading docopt-0.6.1.tar.gz 09:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:23:35 INFO - Installing collected packages: blobuploader, requests, docopt 09:23:35 INFO - Running setup.py install for blobuploader 09:23:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:23:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:23:35 INFO - Running setup.py install for requests 09:23:35 INFO - Running setup.py install for docopt 09:23:35 INFO - Successfully installed blobuploader requests docopt 09:23:35 INFO - Cleaning up... 09:23:35 INFO - Return code: 0 09:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:35 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')]} 09:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:35 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')]} 09:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:35 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 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:35 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 09:23:35 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 09:23:35 INFO - Using env: {'DISPLAY': ':0', 09:23:35 INFO - 'HOME': '/home/cltbld', 09:23:35 INFO - 'LANG': 'en_US.UTF-8', 09:23:35 INFO - 'LANGUAGE': 'en_US:en', 09:23:35 INFO - 'LOGNAME': 'cltbld', 09:23:35 INFO - 'MAIL': '/var/mail/cltbld', 09:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:35 INFO - 'MOZ_NO_REMOTE': '1', 09:23:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:35 INFO - 'NO_EM_RESTART': '1', 09:23:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:35 INFO - 'PWD': '/builds/slave/test', 09:23:35 INFO - 'SHELL': '/bin/bash', 09:23:35 INFO - 'SHLVL': '1', 09:23:35 INFO - 'TERM': 'linux', 09:23:35 INFO - 'TMOUT': '86400', 09:23:35 INFO - 'USER': 'cltbld', 09:23:35 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:35 INFO - '_': '/tools/buildbot/bin/python'} 09:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:23:36 INFO - Running setup.py (path:/tmp/pip-mC0Ml2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:23:36 INFO - Running setup.py (path:/tmp/pip-FoAavA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:23:36 INFO - Running setup.py (path:/tmp/pip-et1rWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:23:36 INFO - Running setup.py (path:/tmp/pip-ogJpjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:23:36 INFO - Running setup.py (path:/tmp/pip-9uwlF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:23:36 INFO - Running setup.py (path:/tmp/pip-k9kt3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:23:36 INFO - Running setup.py (path:/tmp/pip-Wzp8We-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:23:36 INFO - Running setup.py (path:/tmp/pip-9tMOTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:23:36 INFO - Running setup.py (path:/tmp/pip-LqCdpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:23:36 INFO - Running setup.py (path:/tmp/pip-ytJyA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:23:36 INFO - Running setup.py (path:/tmp/pip-NbsYEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:23:36 INFO - Running setup.py (path:/tmp/pip-GuWTPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:23:36 INFO - Running setup.py (path:/tmp/pip-nsUWC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:23:37 INFO - Running setup.py (path:/tmp/pip-vMsKXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:37 INFO - Running setup.py (path:/tmp/pip-0m13AX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:23:37 INFO - Running setup.py (path:/tmp/pip-kAPCoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:23:37 INFO - Running setup.py (path:/tmp/pip-0HoN3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:23:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:23:37 INFO - Running setup.py install for manifestparser 09:23:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Running setup.py install for mozcrash 09:23:37 INFO - Running setup.py install for mozdebug 09:23:37 INFO - Running setup.py install for mozdevice 09:23:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Running setup.py install for mozfile 09:23:37 INFO - Running setup.py install for mozhttpd 09:23:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Running setup.py install for mozinfo 09:23:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Running setup.py install for mozInstall 09:23:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Running setup.py install for mozleak 09:23:38 INFO - Running setup.py install for mozlog 09:23:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Running setup.py install for moznetwork 09:23:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Running setup.py install for mozprocess 09:23:38 INFO - Running setup.py install for mozprofile 09:23:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Running setup.py install for mozrunner 09:23:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Running setup.py install for mozscreenshot 09:23:38 INFO - Running setup.py install for moztest 09:23:38 INFO - Running setup.py install for mozversion 09:23:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:23:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:23:39 INFO - Cleaning up... 09:23:39 INFO - Return code: 0 09:23:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:39 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 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:39 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 09:23:39 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 09:23:39 INFO - Using env: {'DISPLAY': ':0', 09:23:39 INFO - 'HOME': '/home/cltbld', 09:23:39 INFO - 'LANG': 'en_US.UTF-8', 09:23:39 INFO - 'LANGUAGE': 'en_US:en', 09:23:39 INFO - 'LOGNAME': 'cltbld', 09:23:39 INFO - 'MAIL': '/var/mail/cltbld', 09:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:39 INFO - 'MOZ_NO_REMOTE': '1', 09:23:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:39 INFO - 'NO_EM_RESTART': '1', 09:23:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:39 INFO - 'PWD': '/builds/slave/test', 09:23:39 INFO - 'SHELL': '/bin/bash', 09:23:39 INFO - 'SHLVL': '1', 09:23:39 INFO - 'TERM': 'linux', 09:23:39 INFO - 'TMOUT': '86400', 09:23:39 INFO - 'USER': 'cltbld', 09:23:39 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:39 INFO - '_': '/tools/buildbot/bin/python'} 09:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:23:39 INFO - Running setup.py (path:/tmp/pip-nThU21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:23:39 INFO - Running setup.py (path:/tmp/pip-3iBl3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:23:39 INFO - Running setup.py (path:/tmp/pip-7D9Wid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:23:39 INFO - Running setup.py (path:/tmp/pip-3RZWl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:23:39 INFO - Running setup.py (path:/tmp/pip-vxuASL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:23:39 INFO - Running setup.py (path:/tmp/pip-nC7rdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:23:39 INFO - Running setup.py (path:/tmp/pip-YLzJvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:23:39 INFO - Running setup.py (path:/tmp/pip-X1PAZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:23:39 INFO - Running setup.py (path:/tmp/pip-d8FGlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:23:39 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)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:23:39 INFO - Running setup.py (path:/tmp/pip-t5DD5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:23:40 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)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:23:40 INFO - Running setup.py (path:/tmp/pip-Er_Vd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:23:40 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)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:23:40 INFO - Running setup.py (path:/tmp/pip-TfsJPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:23:40 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)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:23:40 INFO - Running setup.py (path:/tmp/pip-8Nzfn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:23:40 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)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:23:40 INFO - Running setup.py (path:/tmp/pip-gC6uWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:23:40 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)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:40 INFO - Running setup.py (path:/tmp/pip-2SmSop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:40 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)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:23:40 INFO - Running setup.py (path:/tmp/pip-icy05P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:23:40 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)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:23:40 INFO - Running setup.py (path:/tmp/pip-pAazu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:23:40 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)) 09:23:40 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)) 09:23:40 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)) 09:23:40 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)) 09:23:40 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)) 09:23:40 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)) 09:23:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:23:40 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 09:23:40 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 09:23:40 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 09:23:40 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 09:23:40 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 09:23:40 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 09:23:42 INFO - Downloading blessings-1.6.tar.gz 09:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:23:42 INFO - Installing collected packages: blessings 09:23:42 INFO - Running setup.py install for blessings 09:23:42 INFO - Successfully installed blessings 09:23:42 INFO - Cleaning up... 09:23:42 INFO - Return code: 0 09:23:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:23:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:23:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:23:42 INFO - Reading from file tmpfile_stdout 09:23:42 INFO - Current package versions: 09:23:42 INFO - argparse == 1.2.1 09:23:42 INFO - blessings == 1.6 09:23:42 INFO - blobuploader == 1.2.4 09:23:42 INFO - docopt == 0.6.1 09:23:42 INFO - manifestparser == 1.1 09:23:42 INFO - mozInstall == 1.12 09:23:42 INFO - mozcrash == 0.17 09:23:42 INFO - mozdebug == 0.1 09:23:42 INFO - mozdevice == 0.48 09:23:42 INFO - mozfile == 1.2 09:23:42 INFO - mozhttpd == 0.7 09:23:42 INFO - mozinfo == 0.9 09:23:42 INFO - mozleak == 0.1 09:23:42 INFO - mozlog == 3.2 09:23:42 INFO - moznetwork == 0.27 09:23:42 INFO - mozprocess == 0.22 09:23:42 INFO - mozprofile == 0.28 09:23:42 INFO - mozrunner == 6.11 09:23:42 INFO - mozscreenshot == 0.1 09:23:42 INFO - mozsystemmonitor == 0.3 09:23:42 INFO - moztest == 0.7 09:23:42 INFO - mozversion == 1.4 09:23:42 INFO - psutil == 3.1.1 09:23:42 INFO - requests == 1.2.3 09:23:42 INFO - wsgiref == 0.1.2 09:23:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23: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')]} 09:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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')]} 09:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:42 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 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:42 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 09:23:42 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 09:23:42 INFO - Using env: {'DISPLAY': ':0', 09:23:42 INFO - 'HOME': '/home/cltbld', 09:23:42 INFO - 'LANG': 'en_US.UTF-8', 09:23:42 INFO - 'LANGUAGE': 'en_US:en', 09:23:42 INFO - 'LOGNAME': 'cltbld', 09:23:42 INFO - 'MAIL': '/var/mail/cltbld', 09:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:42 INFO - 'MOZ_NO_REMOTE': '1', 09:23:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:42 INFO - 'NO_EM_RESTART': '1', 09:23:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:42 INFO - 'PWD': '/builds/slave/test', 09:23:42 INFO - 'SHELL': '/bin/bash', 09:23:42 INFO - 'SHLVL': '1', 09:23:42 INFO - 'TERM': 'linux', 09:23:42 INFO - 'TMOUT': '86400', 09:23:42 INFO - 'USER': 'cltbld', 09:23:42 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:42 INFO - '_': '/tools/buildbot/bin/python'} 09:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 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)) 09:23:43 INFO - Cleaning up... 09:23:43 INFO - Return code: 0 09:23:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:23: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')]} 09:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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')]} 09:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:43 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 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:43 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 09:23:43 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 09:23:43 INFO - Using env: {'DISPLAY': ':0', 09:23:43 INFO - 'HOME': '/home/cltbld', 09:23:43 INFO - 'LANG': 'en_US.UTF-8', 09:23:43 INFO - 'LANGUAGE': 'en_US:en', 09:23:43 INFO - 'LOGNAME': 'cltbld', 09:23:43 INFO - 'MAIL': '/var/mail/cltbld', 09:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:43 INFO - 'MOZ_NO_REMOTE': '1', 09:23:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:43 INFO - 'NO_EM_RESTART': '1', 09:23:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:43 INFO - 'PWD': '/builds/slave/test', 09:23:43 INFO - 'SHELL': '/bin/bash', 09:23:43 INFO - 'SHLVL': '1', 09:23:43 INFO - 'TERM': 'linux', 09:23:43 INFO - 'TMOUT': '86400', 09:23:43 INFO - 'USER': 'cltbld', 09:23:43 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:43 INFO - '_': '/tools/buildbot/bin/python'} 09:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:43 INFO - Downloading/unpacking jsonschema 09:23:43 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 09:23:43 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 09:23:43 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 09:23:43 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 09:23:43 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 09:23:43 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 09:23:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:23:45 INFO - Downloading/unpacking functools32 (from jsonschema) 09:23:45 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 09:23:45 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 09:23:45 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 09:23:45 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 09:23:45 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 09:23:45 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 09:23:45 INFO - Downloading functools32-3.2.3-2.tar.gz 09:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:23:45 INFO - warning: no files found matching '*.txt' 09:23:45 INFO - no previously-included directories found matching 'build' 09:23:45 INFO - no previously-included directories found matching 'dist' 09:23:45 INFO - no previously-included directories found matching '.git*' 09:23:45 INFO - Installing collected packages: jsonschema, functools32 09:23:45 INFO - Running setup.py install for functools32 09:23:45 INFO - warning: no files found matching '*.txt' 09:23:45 INFO - no previously-included directories found matching 'build' 09:23:45 INFO - no previously-included directories found matching 'dist' 09:23:45 INFO - no previously-included directories found matching '.git*' 09:23:45 INFO - Successfully installed jsonschema functools32 09:23:45 INFO - Cleaning up... 09:23:45 INFO - Return code: 0 09:23:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:23: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')]} 09:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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')]} 09:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:45 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 0x7f2589942f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe35770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', '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 09:23:45 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 09:23:45 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 09:23:45 INFO - Using env: {'DISPLAY': ':0', 09:23:45 INFO - 'HOME': '/home/cltbld', 09:23:45 INFO - 'LANG': 'en_US.UTF-8', 09:23:45 INFO - 'LANGUAGE': 'en_US:en', 09:23:45 INFO - 'LOGNAME': 'cltbld', 09:23:45 INFO - 'MAIL': '/var/mail/cltbld', 09:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:45 INFO - 'MOZ_NO_REMOTE': '1', 09:23:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:45 INFO - 'NO_EM_RESTART': '1', 09:23:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:45 INFO - 'PWD': '/builds/slave/test', 09:23:45 INFO - 'SHELL': '/bin/bash', 09:23:45 INFO - 'SHLVL': '1', 09:23:45 INFO - 'TERM': 'linux', 09:23:45 INFO - 'TMOUT': '86400', 09:23:45 INFO - 'USER': 'cltbld', 09:23:45 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:45 INFO - '_': '/tools/buildbot/bin/python'} 09:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:45 INFO - Downloading/unpacking flake8 09:23:45 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 09:23:45 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 09:23:45 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 09:23:45 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 09:23:45 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 09:23:45 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 09:23:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:23:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:23:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:23:48 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 09:23:48 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 09:23:48 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 09:23:48 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 09:23:48 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 09:23:48 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 09:23:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:23:48 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 09:23:48 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 09:23:48 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 09:23:48 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 09:23:48 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 09:23:48 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 09:23:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:23:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:23:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:23:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:23:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:23:48 INFO - no previously-included directories found matching 'docs/_build' 09:23:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:23:48 INFO - Running setup.py install for pep8 09:23:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:23:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:23:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:23:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:23:48 INFO - no previously-included directories found matching 'docs/_build' 09:23:48 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:23:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:23:48 INFO - Cleaning up... 09:23:48 INFO - Return code: 0 09:23:48 INFO - Running post-action listener: _resource_record_post_action 09:23:48 INFO - Running post-action listener: _start_resource_monitoring 09:23:48 INFO - Starting resource monitoring. 09:23:48 INFO - ##### 09:23:48 INFO - ##### Finished create-virtualenv step (success) 09:23:48 INFO - ##### 09:23:48 INFO - ##### 09:23:48 INFO - ##### Running install step. 09:23:48 INFO - ##### 09:23:48 INFO - Running pre-action listener: _resource_record_pre_action 09:23:48 INFO - Running main action method: install 09:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:23:49 INFO - Reading from file tmpfile_stdout 09:23:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:23:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:23:49 INFO - Reading from file tmpfile_stdout 09:23:49 INFO - Output received: 09:23:49 INFO - Usage: mozinstall [options] installer 09:23:49 INFO - Options: 09:23:49 INFO - -h, --help show this help message and exit 09:23:49 INFO - -d DEST, --destination=DEST 09:23:49 INFO - Directory to install application into. [default: 09:23:49 INFO - "/builds/slave/test"] 09:23:49 INFO - --app=APP Application being installed. [default: firefox] 09:23:49 INFO - mkdir: /builds/slave/test/build/application 09:23:49 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'] 09:23:49 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 09:24:04 INFO - Reading from file tmpfile_stdout 09:24:04 INFO - Output received: 09:24:04 INFO - /builds/slave/test/build/application/firefox/firefox 09:24:04 INFO - Running post-action listener: _resource_record_post_action 09:24:04 INFO - ##### 09:24:04 INFO - ##### Finished install step (success) 09:24:04 INFO - ##### 09:24:04 INFO - ##### 09:24:04 INFO - ##### Running run-tests step. 09:24:04 INFO - ##### 09:24:04 INFO - Running pre-action listener: _resource_record_pre_action 09:24:04 INFO - Running main action method: run_tests 09:24:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:24:04 INFO - Python 2.7.3 09:24:04 INFO - Return code: 0 09:24:04 INFO - grabbing minidump binary from tooltool 09:24: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')]} 09:24:04 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 0xec49c0>, '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 0xec58c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xec5d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:24:04 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 09:24:04 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 09:24:04 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 09:24:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:24:05 INFO - Return code: 0 09:24:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:24:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:24:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:24:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:24:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:24:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:24:05 INFO - Return code: 0 09:24:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 09:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 09:24:05 INFO - Using env: {'DISPLAY': ':0', 09:24:05 INFO - 'HOME': '/home/cltbld', 09:24:05 INFO - 'LANG': 'en_US.UTF-8', 09:24:05 INFO - 'LANGUAGE': 'en_US:en', 09:24:05 INFO - 'LOGNAME': 'cltbld', 09:24:05 INFO - 'MAIL': '/var/mail/cltbld', 09:24:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:24:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:05 INFO - 'MOZ_NO_REMOTE': '1', 09:24:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:24:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:05 INFO - 'NO_EM_RESTART': '1', 09:24:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:05 INFO - 'PWD': '/builds/slave/test', 09:24:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:24:05 INFO - 'SHELL': '/bin/bash', 09:24:05 INFO - 'SHLVL': '1', 09:24:05 INFO - 'TERM': 'linux', 09:24:05 INFO - 'TMOUT': '86400', 09:24:05 INFO - 'USER': 'cltbld', 09:24:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436', 09:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:05 INFO - '_': '/tools/buildbot/bin/python'} 09:24:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 09:24:06 INFO - mozversion application_buildid: 20160526080550 09:24:06 INFO - mozversion application_changeset: 56e5195bef5bf352d37181195dc6043ac3d198ad 09:24:06 INFO - mozversion application_display_name: Nightly 09:24:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:24:06 INFO - mozversion application_name: Firefox 09:24:06 INFO - mozversion application_remotingname: firefox 09:24:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:24:06 INFO - mozversion application_vendor: Mozilla 09:24:06 INFO - mozversion application_version: 49.0a1 09:24:06 INFO - mozversion platform_buildid: 20160526080550 09:24:06 INFO - mozversion platform_changeset: 56e5195bef5bf352d37181195dc6043ac3d198ad 09:24:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:24:06 INFO - mozversion platform_version: 49.0a1 09:24:06 INFO - using testdate: 1464279846 09:24:06 INFO - actual date: 1464279846 09:24:06 INFO - starting webserver on 'localhost:35087' 09:24:06 INFO - SUITE-START | Running 2 tests 09:24:06 INFO - TEST-START | dromaeo_css 09:24:06 INFO - operating with platform_type : linux_ 09:24:06 INFO - Initialising browser for dromaeo_css test... 09:24:06 INFO - TEST-INFO | started process 24915 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3C6XeB/profile http://localhost:35087/getInfo.html) 09:24:10 INFO - PROCESS | 24915 | __metrics Screen width/height:1600/1200 09:24:10 INFO - PROCESS | 24915 | colorDepth:24 09:24:10 INFO - PROCESS | 24915 | Browser inner width/height: 1024/697 09:24:10 INFO - PROCESS | 24915 | __metrics 09:24:10 ERROR - PROCESS | 24915 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:24:11 INFO - TEST-INFO | 24915: exit 0 09:24:11 INFO - Browser initialized. 09:24:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:24:11 INFO - TEST-INFO | started process 24997 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3C6XeB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:24:23 INFO - PROCESS | 24997 | RSS: Main: 156344320 09:24:23 INFO - PROCESS | 24997 | 09:25:14 INFO - PROCESS | 24997 | 1464279914916 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] 09:27:26 INFO - PROCESS | 24997 | Cycle 1(1): loaded http://localhost:35087/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35087/tests/dromaeo/cssquery-ext.html) 09:27:27 INFO - PROCESS | 24997 | RSS: Main: 261074944 09:27:27 INFO - PROCESS | 24997 | 09:30:30 INFO - PROCESS | 24997 | Cycle 1(1): loaded http://localhost:35087/tests/dromaeo/cssquery-ext.html (next: http://localhost:35087/tests/dromaeo/cssquery-jquery.html) 09:30:31 INFO - PROCESS | 24997 | RSS: Main: 238366720 09:30:31 INFO - PROCESS | 24997 | 09:33:33 INFO - PROCESS | 24997 | Cycle 1(1): loaded http://localhost:35087/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35087/tests/dromaeo/cssquery-mootools.html) 09:33:33 INFO - PROCESS | 24997 | RSS: Main: 234708992 09:33:33 INFO - PROCESS | 24997 | 09:36:36 INFO - PROCESS | 24997 | Cycle 1(1): loaded http://localhost:35087/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35087/tests/dromaeo/cssquery-prototype.html) 09:36:36 INFO - PROCESS | 24997 | RSS: Main: 229052416 09:36:36 INFO - PROCESS | 24997 | 09:39:39 INFO - PROCESS | 24997 | Cycle 1(1): loaded http://localhost:35087/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35087/tests/dromaeo/cssquery-yui.html) 09:39:40 INFO - PROCESS | 24997 | RSS: Main: 218353664 09:39:40 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | Cycle 1(1): loaded http://localhost:35087/tests/dromaeo/cssquery-yui.html (next: http://localhost:35087/tests/dromaeo/cssquery-dojo.html) 09:42:43 INFO - PROCESS | 24997 | RSS: Main: 308883456 09:42:43 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | __start_tp_report 09:42:43 INFO - PROCESS | 24997 | _x_x_mozilla_page_load 09:42:43 INFO - PROCESS | 24997 | _x_x_mozilla_page_load_details 09:42:43 INFO - PROCESS | 24997 | |i|pagename|runs| 09:42:43 INFO - PROCESS | 24997 | |0;dojo.html;2690.1122629532256;2713.893739087054;2715.6741191057076;2752.710965348638;2767.0177087134066;4673.228917228662;4747.145513807513;4785.401824771903;4850.393700787402;4925.655204135712;4752.453467851198;4792.6165906727465;4829.517048295177;4831.565159135677;4906.607471402288;4343.131373725259;4360.23695853226;4417.271150260203;4472.843450479234;4548.545145485458;4477.485089214733;4519.932201016987;4546.477155127153;4563.018950925551;4567.2920697291975;4270.530241673413;4319.028218650778;4336.262835318003;4429.870383052301;4455.46534415869;4356.542563030899;4368.191884501346;4370;4416.492103408117;4425.336199570058;4343.457067866517;4347.869563913087;4349.826006959718;4351.673624478145;4393.868183954486;4693.55411235932;4772.976135119302;4797.424309082898;4800.975995120037;4806.0628177505405;4995.500449954975;5153.922691159635;5212.50481204285;5222.843314700565;5234.8691282717855;5270.9736451317885;5274.762635681365;5309.867253318698;5315.893682126336;5341.305630268041;4919.581835543947;4928.507149285078;4968.7018778873025;4971.353724015855;4971.4282857471135;5220.947790522122;5265.236540701577;5348.03735327637;5353.625246232804;5379.65032272901;5120.590352771769;5155.32980712505;5167.302414174113;5168.948310516922;5184.818531351385;5006.64953453262;5186.377634683787;5210.374755029345;5210.452902445264;5213.45258747826;5086.3896332440345;5165.509276618715;5172.19830926207;5205.29728486657;5230.293910322058;5151.510606492452;5162.380514338229;5162.845114646567;5166.3025491558155;5217.113090774577;4280.357946308079;4489.236829738952;4495.865124046284;4503.347014682919;4540.79566419512;3776.33914065043;3855.51806024247;3879.379299312153;3894.941575876364;3942.4283478895973;4500.369948207253;4502.392177056121;4510.819567217274;4547.818087276538;4561.384213131251;5009.373828271466;5215.895682086413;5224.085784987612;5231.503007214308;5279.340082489689;4359.847405340798;4455.242608756519;4491.505934347219;4500.3249512573375;4516.9774151129695;4720.834770783006;4739.170645137087;4753.83361582343;4759.928601070987;4801.7118972861745;4702.600278976325;4907.754612269372;4917.409910810726;4920.507949205051;4970.676906001098;5118.206677964921;5157.819476318461;5179.19722443022;5183.403908550565;5252.501012403815;4853.247746599282;4856;4857.101436234308;4859.271109333628;4909.631777616764;4894.559489645949;5117.130087884994;5119.411267704261;5123.6157288202785;5176.197689358156;5092.719900405513;5136;5146.228065790161;5148.871278218075;5168.405633352213;4963.305137280711;4996.725180115129;5002.599792016703;5003.624728145476;5045.924311135409;4860.1251774680895;4887.120318342732;4995.5004499551205;5005.599552035901;5007.198848184273;4866.926996095132;4937.086638971802;4964.106460837084;4974.129527332775;4985.700857948535;5034.748262586929;5037.823676171316;5038.899222015612;5079.441261461162;5092.541671249605;4810.2063159579675;4910.779014944264;4926.531979461945;4967.155583550877;4990.50094990498 09:42:43 INFO - PROCESS | 24997 | |1;ext.html;552.1221258199462;598.1297212555731;598.8053882488192;600.8437806170396;611.9505048841238;39390.2121957561;41034.38448423269;41037.99999999996;41052;45129.09741805162;41801;42484.36273455901;42547.36178957318;42667.146657066914;42670.7866460668;7162.928370716279;7715;7931.524108553505;9871.457069861155;10272.28094033418;9711.757206069835;9801.41191528507;9812.215022798158;9812.90187098127;9821.214302855755;9863.358881672704;9883.950580247089;9885.406875587487;9895.950520247388;9896.901030989706;7478.14001389839;9981.700548983543;10235.948820255926;10277.588896444133;10542.578296868116;9959.90040099597;10024.849627255597;10036.24728145392;10037.498125093716;10041.949790251076;37791;38038.619613803785;38189.80905095484;38204.80897595522;38216;3109.6890310968947;3257.4136655402017;3333.2666813301034;3403.642617525149;3733;29645.555316670267;29677.70322296771;29726;30117.247068823235;30150.547741783892;3434.1414646338417;3485.4771784232316;3494.930101397958;3538.0270425633157;3595.514605528246;246780;246945;248245;248334.75832620903;248953;51969;52105;52222.47777522176;52656;52700;33064.50403243953;33818;33895.49156762651;55042;55166.72416637971;10969.177311701495;13980.091294065853;14100.871930245556;14149.858501414854;14154.36305366261;9816.65641702537;10066.496675166212;10101.444420556792;10314.1748660107;10355.896441035493;18306.542336441427;18335.266589336276;18371.173297201658;18379.356722514647;18382.448526544224;6960.234374218832;7049.68276427562;7121.857562848714;7201.531900426456;7252.746153884589;750.4709180028067;832.8917240758663;887.9644814207488;962.9085236902623;997.4314640654902;27569.310767230578;27659;27778.722212777615;28321.884592694787;28537.71462285392;945.6170251048336;951.8810148731409;1005.4771518810323;1012.5443550402348;1099.7140743406771;14585.489507867385;14854;14910.627234319012;15156.545303640909;15219;374658;378484;379291;379992;381856;48799;49182.75408623005;49213.261801073015;49243.50756492389;49365;1103.3545375954996;1207.9818802718141;1219.0552321950559;1223.4066477758101;1236.9628911132681;27736.167914962585;28925.56611650785;28942.85528572428;29057;29442.558361624164;41406.37890431706;41863.37204941867;41886.37170442385;41891.581084188765;41950.58049419467;289573;291209;291581;291641;291677;20652.483687907923;24969.87515062413;25007.12475063364;26172.083977060713;26327.21018369452;36864.81567592145;39935.801925942265;41603;41868.1626367477;42774.78612606917;35160.47259291061;35695.46456803202;35912.281754365285;35983.82008089943;36528.26943461063;35425.645743542234;35704.107397315274;35718.107047324025;35825;35944.46083308805;34445;35049.299014020085;35518.46722299219;35743.28513429769;35812.82093589516;30810;32272;32272.515912261806;32341.838290808395;32435.837820810746;24368.26895193147;24384;24629.13798017061;24999.625005625294;25024.374390639652 09:42:43 INFO - PROCESS | 24997 | |2;jquery.html;361.28826212361645;364.61715199041015;372.56968201727;377.377327409774;393.616224181423;15866.524004279876;16821.41125060623;17213.397531086415;17285.654286914254;17676.29294828207;15179.848201517982;17138;17324.740128898044;17332.740008899844;17340.47978560645;4967.925481117777;5190.688558686491;5214.530692237698;5221.746780772606;5229.450907654679;3750.8874733757893;3927.0967677434205;5075.238714192882;5555;5859.970700146493;5867.55993300502;5888.263967004124;5927.525797936176;5929.229200203968;5932.6143800653035;5767.279090113736;5811.128330750379;5940.46535811779;5949.524038076944;5959.433853783884;3353.6478669739813;4928.137575924199;5178.922316165261;5241.318628578299;5246.396664383607;19863.7020444692;20431.38705838827;20459.48851278715;20888.060037298357;20903.477413064644;1924.4130540185238;2004.5489764802803;2202.361315218585;2256.244158207003;2566.7304932982142;6325.778597749057;11791.174617776758;13016.479340826356;13018.307620009433;13760.31198440074;1728.2741248675586;1789.3008931626619;1809.8280663336961;1816.4459839748872;1851.3612803582741;65644.67177664129;66258.66870665664;66479.66760166216;66849.3315066848;67008.66495667539;8885.777855553533;8920.24177944882;8976.237019853255;8981.910180898238;9271.397359171633;11945.522179112739;12037.578684746168;12086.758264834654;12095.032397408186;12109.939450302869;6957.478189135835;6984.301569842975;7133.517289382836;7204.56772593646;7205.702973464722;6874.862502749917;6986.895196572055;6995.545289556163;7092.659422390599;7187.353138217585;5853.09277062056;5887.411258874078;5984.371640977722;6000;6030.216071910711;1519.5022732711077;1559.7816305717208;1656.6024154203146;1695.9236834342485;1751.8861274017147;836.0552575589667;847.8982522097388;905.0949050949051;914.1955079529971;1012.4633944009687;16358.289303160345;16926.322947082226;17324.480265592054;17476.737848932276;17499;852.8550146475114;874.2568816505919;896.7847716547982;951.353079905657;976.0483528559739;9656.37233579815;10032.398056116657;10060.748481288027;10081.69754907354;10154.492275386201;364109;372806;379945;380751;381650;64106.35893641004;64713.67643161754;66640;66959.33040669629;67086.66456667686;906.7959709065408;916.4180745226829;934.4346670264392;936.4147407870081;940.581441258647;15100.320485577955;15134.470293539673;15400.537983860502;15413.691726165189;15779.605509862346;30795.846020769754;32713.34573308568;33098.66901330956;33912.491312629834;34137.31725365429;41189.58810411858;42567.787161064;43626.12747745091;44905.77547112243;44926.55073449224;6819.2498825128205;7211.783646490614;7318.3047610476915;7384.409247260099;7500.36246919018;9859.457729824928;10715.249932504961;10720.624778132727;10776.245662803529;10892.891071089505;8759.649614015369;8944.37389382737;8981.832361792925;9166;9166.945801232678;9199;9361.344705870524;9467.384619999679;9566.330356874952;9589.472579008221;8895.510746908982;9097;9105.043970383014;9262.72211833646;9603.855942160733;7099.183593886769;7222;7373.668184931584;7440.4419668524;7473.588952607659;8629.6979605716;9132.315076369432;9218.907810921804;9310.57924939507;9343.439393636403 09:42:43 INFO - PROCESS | 24997 | |3;mootools.html;1255.0963306419376;1374.7387996280709;1424.679447124397;1462.5466105507287;1736.53113659312;6555.311692272308;7507.812304692386;7764.767056988299;7765.301122898938;7937.563434011127;7771.145174030852;7774.650140743666;7834.138244793083;7850.097238817538;7854.293113619773;2166.458385403645;2187.245400336881;2197.263916587945;2200.8239340852692;2202.4053505553493;2120.6076875777953;2146.957060858782;2179.2154824263253;2187.223535644847;2192.079326682789;2142.3037512808323;2168.4253672776726;2175.3147758456034;2178.6187417202004;2210.4805370737863;2100.19142630087;2104.252990188492;2105.799949004842;2106.09437941685;2107.3045894854663;2060.4333808202714;2108.146200788683;2109.6202683516963;2117.544727883512;2119.0782009825775;7613.80965475862;7805.9609701951695;7854;7860.371170306362;7865.410094242898;4504.662150338738;4635.119327327797;4660.974585591199;4709.270063140219;4736.313234581001;3020.320427903726;3035.3929214157256;3073.2163298359;3098.51972944194;3107;932.4265576670367;935.349931797401;944.7165850244968;960.3757557587554;962.0956301076965;10471.10995565378;10631.787364252748;10668.679939601825;10673.519691613817;10691.572337106507;4177.874663760092;4251.617354438115;4313.525512193656;4334.219840428753;4336;9160.8625870612;9173.082691730773;9182.127697868691;9185.357025008185;9199.402038867453;4619.330197121399;4632.189366860786;4633.907321853544;4635.466921304026;4636.884077898079;3438.673326034023;4088.8568900088353;4090.1001779609032;4157.605027522381;4245.1085272093205;3731.477593136963;3762.680172185395;3859.591249194024;3880.534335879656;3893.2213557288655;1203.4103289388136;1220.2434490615874;1221.9389030548436;1227.0858210633135;1243.7699025680279;649.2630863969429;709.8580283943232;750.5196674128562;774.1523032279757;804.2359758229705;4768.642351823627;4794.832180873653;4798.184308667465;4844.975775121102;4923.655344125948;385.9903502412406;387.81384935231245;392.06296950288834;409.25107054090944;411.80645096804454;3216.3567286542316;3225.193701574606;3262.6737326267184;3284.1953721338423;3310.7682462227895;9372.906270937203;9731.41611503312;9735.756606084904;9751.853722194173;9791.510424478747;9403.623855045722;9513.809723805485;9530.571124299284;9865.852012219822;9879.80240395188;7356.301151390609;7405.9629701851145;7411.62941852916;7424.628768561551;7467;4695.900705534331;4700.764961751967;4706.388169537938;4777.259524773665;4777.689450185727;8965.372423930263;9039.548022598712;9057.18485336323;9064.90935090667;9080.40977336471;9028.413153145024;9216.907830921606;9234.492102934402;9378.155965963097;9390.295727820578;5692.117721753135;6512.5766825156215;6688.197416310074;6868.210155831944;6905.205901321211;7590.316871481593;7591.924080759342;7681.8463630725955;7699.114601820638;7700.961495192488;6401.55189136756;6453.806385808433;6558.311377305315;6656.5340426169705;6665.40011398957;6411.71147298382;6430.807075787734;6497.741972425629;6558.442532384801;6594.175728033996;6138.907916381348;6151.907721384094;6174.135621012972;6187.969060154851;6196.659183744938;4222.239996800606;4303.50509691377;4325.97837010813;4338.652907767434;4406.625436837905;4048.797560122041;4216.515100763451;4230.280852255062;4326.891827704332;4358.193734159191 09:42:43 INFO - PROCESS | 24997 | |4;prototype.html;296.50927714632263;305.2475647528841;308.4155525279594;312.4500878453922;313.32322184082386;4868.707877527351;4899.358714830531;4910.361652985107;4925.802967881281;4949.851504454868;4596.655250856183;4662.370579971703;4777.832775852846;4785.497522760113;4890.559849613534;3769.227308401771;3783.205526839367;3797.905052373699;3811.2758575870635;3812.504374431334;3810.466534685146;3811.7712937223855;3820.808959552025;3823.1971286029966;3824.082220267144;3572.821358932043;3652.4886515887797;3750.1187221003042;3753.155540003491;3766;3736.159364143073;3741.5510138783247;3748.725433352673;3761.567419746737;3768.2086761779924;3313;3316.60200775906;3324.6675332466803;3336.282699219655;3339.1985923378215;4470.530594287618;4642.280446530793;4692.694974826625;4694.647901407408;4713.703731473838;4247.256730072256;4292.656587473026;4295.871123866289;4296.871093867189;4302.182585308781;5006.599472042228;5012.373453318335;5021.121303771862;5029.673071250394;5041.143005689041;3580.7314451416246;3618.620044895301;3621.8732344368086;3624.4925710400566;3629.745917785756;5108.616853735947;5289.841304760864;5296.496832800878;5301;5301.973490132525;3667.578228503703;3675.9081022974638;3677.8344974476213;3711.406175011985;3716.331060409152;3716.479692843004;3742.9519734474393;3769.1896242308035;3772.75477093988;3778.9433158502643;4150.418941348214;4283.957160428418;4293.656507479394;4309.547497512779;4342.544032876565;5078.4413714491375;5080.695158290514;5085.160948443539;5111.795528178905;5121.795128194831;1269.8603153653091;1298.2405292903757;1299.603620895617;1318.8615195404536;1326.7611829870523;870.1602953150241;873.7378786364065;890.4835195586544;894.917150248207;898.6764764684708;4095.2014357200655;4104.322786740155;4107.383892416102;4109.404136400205;4109.609587089281;4700.130475861976;4720.504347043579;4724.598409135262;4729.574338309569;4734.4081989751285;4069.2471892699946;4072.7759973201164;4082.4692789937117;4129.587041295847;4150.937735933963;2357.3281614739717;2520.575874630828;2540.8312176399063;2549.6048112542585;2553.629723690092;4406.1187762447635;4442.444694413199;4448.64410847138;4452.109578084396;4482.25192175052;4698.40254313535;4716.693414928019;4770.805429502468;4772.832950846704;4789.377380940525;4351.456067991501;4399.846005389796;4552.588697503718;4928.778204980857;4975.7512124392915;5261.763220655003;5293.655912365684;5301.443348448357;5359.356877174764;5362.544183744425;5242.318498595158;5247.737613119252;5259.658122222043;5262.184361423985;5263.394709608444;4692.366530518473;4989.276554899592;4991.954907506346;5013.573846223112;5025.723585202849;3733.383991641457;3747.437884317374;3748.4002559590335;3773.056735816046;3781.0169355968196;5129.153689641167;5393.541548968382;5407.270018547603;5409.350877894678;5430.294061771944;5009.423916249678;5086.160783470686;5106.948930510796;5116.9232461512365;5132.30713853625;5069.467705890828;5101.2910674924315;5104.1578139606545;5109.616778741683;5150.948490515047;4874.53691899269;4988.55103040728;5032.144535428913;5040.672356296829;5055.5955523557295;5032.194848824171;5055.595552355876;5099.832571157318;5107;5114.667546609458;4992.500749924979;5007.449180590059;5029.371328583927;5029.421616514148;5035.345405097314 09:42:43 INFO - PROCESS | 24997 | |5;yui.html;692.9896051559227;699.2168770976507;725.9582499113773;738.5827007740626;742.5062333548191;12100.031997440195;12673;12720.173188742738;12736.490540378374;12754.808677869827;11700.707482312946;11827;11853;11881.584144554943;11912.34482103484;617.5399327501018;766.2490759056128;780.5862892666896;795.2405452792569;797.4059325802285;792.1444839573247;795.08168065884;796.7131832540281;798.1180795221284;798.1778767869104;847.6439895243982;849.2908421468081;849.3290300662469;849.4563479373206;849.800296930221;661.044790278046;680.2143524229523;681.6932380428827;682.160942041292;846.4836449765638;590.100097351538;612.5099920063931;612.6538505744285;612.9417705317987;613.4693490131049;12315.815262771066;12321.69195770104;12321.815172772416;12322.137450378477;12335.136540442172;11649.941750291278;12057.457414416373;12071.094667899855;12134.393280335948;12325;11018.283811552303;11043.889561104366;11207.103431725442;11327.546898124065;11782.469788859436;3958.267720471722;4006.599340065999;4018.276710192164;4117.958820411787;4125.030617804813;46029.30956035695;46481.535184648055;46573.767131164124;46642;46712;9580.281478889112;9677.70966870995;9697.127258546765;9736.41581505112;10174.745631359126;16795.41216057432;16852.831471685127;16913.40803071911;17050.573735656708;17112;6718.899216511756;6769.593824370553;6821.249662537117;6836.077129587541;6872.62200578963;3539.43369060949;3570.125319296789;3570.696490798259;3610.097475631092;3711.9420965024838;2717.3070866928806;2802.9159125226274;2805.0603047979157;2808.508511010565;2809.817361871472;492.2493197873272;501.2731539268076;509.3912774234754;513.6019584821793;515.9406668233202;385.9343911535046;398.7487882633922;407.03938704656986;441.3798612948812;442.11356230757127;6147.72335244915;6445.903311450332;6451.903221451682;6474.6762661866715;6599.505037122235;407.9877603671895;416.2486711485797;425.99787001064794;444.92436285831485;448.08814063381516;2986.22358186873;3041.574179614856;3050.2069461939614;3064.0960916529675;3123.8906638268004;53462.465375345746;53515;54083.45916540784;55008.449915501136;55989.72005139948;48562.75718621455;49662.75168624134;50481.24278135831;50686.493135068915;50784.238236426485;45985.78099533067;46031.5396846034;46277.537224627325;46749.76625116784;47195.764021179675;5810.5061069809535;5887.3523912370465;6013.9699301503215;6055.757769689163;6126.877462450815;28267.71732282651;28270.434591307647;29253.41493170082;32289.838550807097;32290.192745181557;40781.388279176426;40892.59107408888;40991.180176396905;41052.626318655806;41373.79313103415;10034.347767395097;10226.13077888358;10470.842937356098;10488.737781555523;10490.947545262225;11474.598389056342;11670.591529296435;11769.411529423665;11779.705507362041;12069.517219311128;10074;10391.116755075904;10694.73263168427;10732.409717465614;10749.247552671552;10568.154547636017;10619.30974486656;10775.94612026935;10830.95069685913;11136.610218642309;10180.287379883634;10291.228157888338;10528.894711052792;10550.525226364985;10855.620053298097;8837.248833849195;8912.821743565222;8931.821363572562;8986.82026359456;9092.227160691415;8784.253338466302;8995.730128096167;9114.589843457194;9180.495072771062;9231 09:42:43 INFO - PROCESS | 24997 | __end_tp_report 09:42:43 INFO - PROCESS | 24997 | __start_cc_report 09:42:43 INFO - PROCESS | 24997 | _x_x_mozilla_cycle_collect,954 09:42:43 INFO - PROCESS | 24997 | __end_cc_report 09:42:43 INFO - PROCESS | 24997 | __startTimestamp1464280963359__endTimestamp 09:42:43 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | ------- Summary: start ------- 09:42:43 INFO - PROCESS | 24997 | Number of tests: 6 09:42:43 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | [#0] dojo.html Cycles:165 Average:4784.56 Median:4917.41 stddev:500.30 (10.2%) stddev-sans-first:474.09 09:42:43 INFO - PROCESS | 24997 | Values: 2690.1 2713.9 2715.7 2752.7 2767.0 4673.2 4747.1 4785.4 4850.4 4925.7 4752.5 4792.6 4829.5 4831.6 4906.6 4343.1 4360.2 4417.3 4472.8 4548.5 4477.5 4519.9 4546.5 4563.0 4567.3 4270.5 4319.0 4336.3 4429.9 4455.5 4356.5 4368.2 4370.0 4416.5 4425.3 4343.5 4347.9 4349.8 4351.7 4393.9 4693.6 4773.0 4797.4 4801.0 4806.1 4995.5 5153.9 5212.5 5222.8 5234.9 5271.0 5274.8 5309.9 5315.9 5341.3 4919.6 4928.5 4968.7 4971.4 4971.4 5220.9 5265.2 5348.0 5353.6 5379.7 5120.6 5155.3 5167.3 5168.9 5184.8 5006.6 5186.4 5210.4 5210.5 5213.5 5086.4 5165.5 5172.2 5205.3 5230.3 5151.5 5162.4 5162.8 5166.3 5217.1 4280.4 4489.2 4495.9 4503.3 4540.8 3776.3 3855.5 3879.4 3894.9 3942.4 4500.4 4502.4 4510.8 4547.8 4561.4 5009.4 5215.9 5224.1 5231.5 5279.3 4359.8 4455.2 4491.5 4500.3 4517.0 4720.8 4739.2 4753.8 4759.9 4801.7 4702.6 4907.8 4917.4 4920.5 4970.7 5118.2 5157.8 5179.2 5183.4 5252.5 4853.2 4856.0 4857.1 4859.3 4909.6 4894.6 5117.1 5119.4 5123.6 5176.2 5092.7 5136.0 5146.2 5148.9 5168.4 4963.3 4996.7 5002.6 5003.6 5045.9 4860.1 4887.1 4995.5 5005.6 5007.2 4866.9 4937.1 4964.1 4974.1 4985.7 5034.7 5037.8 5038.9 5079.4 5092.5 4810.2 4910.8 4926.5 4967.2 4990.5 09:42:43 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | [#1] ext.html Cycles:180 Average:46206.76 Median:35606.97 stddev:81577.43 (229.1%) stddev-sans-first:81734.26 09:42:43 INFO - PROCESS | 24997 | Values: 552.1 598.1 598.8 600.8 612.0 39390.2 41034.4 41038.0 41052.0 45129.1 41801.0 42484.4 42547.4 42667.1 42670.8 7162.9 7715.0 7931.5 9871.5 10272.3 9711.8 9801.4 9812.2 9812.9 9821.2 9863.4 9884.0 9885.4 9896.0 9896.9 7478.1 9981.7 10235.9 10277.6 10542.6 9959.9 10024.8 10036.2 10037.5 10041.9 37791.0 38038.6 38189.8 38204.8 38216.0 3109.7 3257.4 3333.3 3403.6 3733.0 29645.6 29677.7 29726.0 30117.2 30150.5 3434.1 3485.5 3494.9 3538.0 3595.5 246780.0 246945.0 248245.0 248334.8 248953.0 51969.0 52105.0 52222.5 52656.0 52700.0 33064.5 33818.0 33895.5 55042.0 55166.7 10969.2 13980.1 14100.9 14149.9 14154.4 9816.7 10066.5 10101.4 10314.2 10355.9 18306.5 18335.3 18371.2 18379.4 18382.4 6960.2 7049.7 7121.9 7201.5 7252.7 750.5 832.9 888.0 962.9 997.4 27569.3 27659.0 27778.7 28321.9 28537.7 945.6 951.9 1005.5 1012.5 1099.7 14585.5 14854.0 14910.6 15156.5 15219.0 374658.0 378484.0 379291.0 379992.0 381856.0 48799.0 49182.8 49213.3 49243.5 49365.0 1103.4 1208.0 1219.1 1223.4 1237.0 27736.2 28925.6 28942.9 29057.0 29442.6 41406.4 41863.4 41886.4 41891.6 41950.6 289573.0 291209.0 291581.0 291641.0 291677.0 20652.5 24969.9 25007.1 26172.1 26327.2 36864.8 39935.8 41603.0 41868.2 42774.8 35160.5 35695.5 35912.3 35983.8 36528.3 35425.6 35704.1 35718.1 35825.0 35944.5 34445.0 35049.3 35518.5 35743.3 35812.8 30810.0 32272.0 32272.5 32341.8 32435.8 24368.3 24384.0 24629.1 24999.6 25024.4 09:42:43 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | [#2] jquery.html Cycles:180 Average:23093.52 Median:5400.70 stddev:61790.32 (1144.1%) stddev-sans-first:61940.09 09:42:43 INFO - PROCESS | 24997 | Values: 361.3 364.6 372.6 377.4 393.6 15866.5 16821.4 17213.4 17285.7 17676.3 15179.8 17138.0 17324.7 17332.7 17340.5 4967.9 5190.7 5214.5 5221.7 5229.5 3750.9 3927.1 5075.2 5555.0 5860.0 5867.6 5888.3 5927.5 5929.2 5932.6 5767.3 5811.1 5940.5 5949.5 5959.4 3353.6 4928.1 5178.9 5241.3 5246.4 19863.7 20431.4 20459.5 20888.1 20903.5 1924.4 2004.5 2202.4 2256.2 2566.7 6325.8 11791.2 13016.5 13018.3 13760.3 1728.3 1789.3 1809.8 1816.4 1851.4 65644.7 66258.7 66479.7 66849.3 67008.7 8885.8 8920.2 8976.2 8981.9 9271.4 11945.5 12037.6 12086.8 12095.0 12109.9 6957.5 6984.3 7133.5 7204.6 7205.7 6874.9 6986.9 6995.5 7092.7 7187.4 5853.1 5887.4 5984.4 6000.0 6030.2 1519.5 1559.8 1656.6 1695.9 1751.9 836.1 847.9 905.1 914.2 1012.5 16358.3 16926.3 17324.5 17476.7 17499.0 852.9 874.3 896.8 951.4 976.0 9656.4 10032.4 10060.7 10081.7 10154.5 364109.0 372806.0 379945.0 380751.0 381650.0 64106.4 64713.7 66640.0 66959.3 67086.7 906.8 916.4 934.4 936.4 940.6 15100.3 15134.5 15400.5 15413.7 15779.6 30795.8 32713.3 33098.7 33912.5 34137.3 41189.6 42567.8 43626.1 44905.8 44926.6 6819.2 7211.8 7318.3 7384.4 7500.4 9859.5 10715.2 10720.6 10776.2 10892.9 8759.6 8944.4 8981.8 9166.0 9166.9 9199.0 9361.3 9467.4 9566.3 9589.5 8895.5 9097.0 9105.0 9262.7 9603.9 7099.2 7222.0 7373.7 7440.4 7473.6 8629.7 9132.3 9218.9 9310.6 9343.4 09:42:43 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | [#3] mootools.html Cycles:180 Average:5074.43 Median:4707.83 stddev:2919.26 (62.0%) stddev-sans-first:2913.34 09:42:43 INFO - PROCESS | 24997 | Values: 1255.1 1374.7 1424.7 1462.5 1736.5 6555.3 7507.8 7764.8 7765.3 7937.6 7771.1 7774.7 7834.1 7850.1 7854.3 2166.5 2187.2 2197.3 2200.8 2202.4 2120.6 2147.0 2179.2 2187.2 2192.1 2142.3 2168.4 2175.3 2178.6 2210.5 2100.2 2104.3 2105.8 2106.1 2107.3 2060.4 2108.1 2109.6 2117.5 2119.1 7613.8 7806.0 7854.0 7860.4 7865.4 4504.7 4635.1 4661.0 4709.3 4736.3 3020.3 3035.4 3073.2 3098.5 3107.0 932.4 935.3 944.7 960.4 962.1 10471.1 10631.8 10668.7 10673.5 10691.6 4177.9 4251.6 4313.5 4334.2 4336.0 9160.9 9173.1 9182.1 9185.4 9199.4 4619.3 4632.2 4633.9 4635.5 4636.9 3438.7 4088.9 4090.1 4157.6 4245.1 3731.5 3762.7 3859.6 3880.5 3893.2 1203.4 1220.2 1221.9 1227.1 1243.8 649.3 709.9 750.5 774.2 804.2 4768.6 4794.8 4798.2 4845.0 4923.7 386.0 387.8 392.1 409.3 411.8 3216.4 3225.2 3262.7 3284.2 3310.8 9372.9 9731.4 9735.8 9751.9 9791.5 9403.6 9513.8 9530.6 9865.9 9879.8 7356.3 7406.0 7411.6 7424.6 7467.0 4695.9 4700.8 4706.4 4777.3 4777.7 8965.4 9039.5 9057.2 9064.9 9080.4 9028.4 9216.9 9234.5 9378.2 9390.3 5692.1 6512.6 6688.2 6868.2 6905.2 7590.3 7591.9 7681.8 7699.1 7701.0 6401.6 6453.8 6558.3 6656.5 6665.4 6411.7 6430.8 6497.7 6558.4 6594.2 6138.9 6151.9 6174.1 6188.0 6196.7 4222.2 4303.5 4326.0 4338.7 4406.6 4048.8 4216.5 4230.3 4326.9 4358.2 09:42:43 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | [#4] prototype.html Cycles:180 Average:4149.23 Median:4652.33 stddev:1209.76 (26.0%) stddev-sans-first:1178.08 09:42:43 INFO - PROCESS | 24997 | Values: 296.5 305.2 308.4 312.5 313.3 4868.7 4899.4 4910.4 4925.8 4949.9 4596.7 4662.4 4777.8 4785.5 4890.6 3769.2 3783.2 3797.9 3811.3 3812.5 3810.5 3811.8 3820.8 3823.2 3824.1 3572.8 3652.5 3750.1 3753.2 3766.0 3736.2 3741.6 3748.7 3761.6 3768.2 3313.0 3316.6 3324.7 3336.3 3339.2 4470.5 4642.3 4692.7 4694.6 4713.7 4247.3 4292.7 4295.9 4296.9 4302.2 5006.6 5012.4 5021.1 5029.7 5041.1 3580.7 3618.6 3621.9 3624.5 3629.7 5108.6 5289.8 5296.5 5301.0 5302.0 3667.6 3675.9 3677.8 3711.4 3716.3 3716.5 3743.0 3769.2 3772.8 3778.9 4150.4 4284.0 4293.7 4309.5 4342.5 5078.4 5080.7 5085.2 5111.8 5121.8 1269.9 1298.2 1299.6 1318.9 1326.8 870.2 873.7 890.5 894.9 898.7 4095.2 4104.3 4107.4 4109.4 4109.6 4700.1 4720.5 4724.6 4729.6 4734.4 4069.2 4072.8 4082.5 4129.6 4150.9 2357.3 2520.6 2540.8 2549.6 2553.6 4406.1 4442.4 4448.6 4452.1 4482.3 4698.4 4716.7 4770.8 4772.8 4789.4 4351.5 4399.8 4552.6 4928.8 4975.8 5261.8 5293.7 5301.4 5359.4 5362.5 5242.3 5247.7 5259.7 5262.2 5263.4 4692.4 4989.3 4992.0 5013.6 5025.7 3733.4 3747.4 3748.4 3773.1 3781.0 5129.2 5393.5 5407.3 5409.4 5430.3 5009.4 5086.2 5106.9 5116.9 5132.3 5069.5 5101.3 5104.2 5109.6 5150.9 4874.5 4988.6 5032.1 5040.7 5055.6 5032.2 5055.6 5099.8 5107.0 5114.7 4992.5 5007.4 5029.4 5029.4 5035.3 09:42:43 INFO - PROCESS | 24997 | 09:42:43 INFO - PROCESS | 24997 | [#5] yui.html Cycles:180 Average:12934.89 Median:421.12 stddev:15295.95 (3632.2%) stddev-sans-first:15311.24 09:42:43 INFO - PROCESS | 24997 | Values: 693.0 699.2 726.0 738.6 742.5 12100.0 12673.0 12720.2 12736.5 12754.8 11700.7 11827.0 11853.0 11881.6 11912.3 617.5 766.2 780.6 795.2 797.4 792.1 795.1 796.7 798.1 798.2 847.6 849.3 849.3 849.5 849.8 661.0 680.2 681.7 682.2 846.5 590.1 612.5 612.7 612.9 613.5 12315.8 12321.7 12321.8 12322.1 12335.1 11649.9 12057.5 12071.1 12134.4 12325.0 11018.3 11043.9 11207.1 11327.5 11782.5 3958.3 4006.6 4018.3 4118.0 4125.0 46029.3 46481.5 46573.8 46642.0 46712.0 9580.3 9677.7 9697.1 9736.4 10174.7 16795.4 16852.8 16913.4 17050.6 17112.0 6718.9 6769.6 6821.2 6836.1 6872.6 3539.4 3570.1 3570.7 3610.1 3711.9 2717.3 2802.9 2805.1 2808.5 2809.8 492.2 501.3 509.4 513.6 515.9 385.9 398.7 407.0 441.4 442.1 6147.7 6445.9 6451.9 6474.7 6599.5 408.0 416.2 426.0 444.9 448.1 2986.2 3041.6 3050.2 3064.1 3123.9 53462.5 53515.0 54083.5 55008.4 55989.7 48562.8 49662.8 50481.2 50686.5 50784.2 45985.8 46031.5 46277.5 46749.8 47195.8 5810.5 5887.4 6014.0 6055.8 6126.9 28267.7 28270.4 29253.4 32289.8 32290.2 40781.4 40892.6 40991.2 41052.6 41373.8 10034.3 10226.1 10470.8 10488.7 10490.9 11474.6 11670.6 11769.4 11779.7 12069.5 10074.0 10391.1 10694.7 10732.4 10749.2 10568.2 10619.3 10775.9 10831.0 11136.6 10180.3 10291.2 10528.9 10550.5 10855.6 8837.2 8912.8 8931.8 8986.8 9092.2 8784.3 8995.7 9114.6 9180.5 9231.0 09:42:43 INFO - PROCESS | 24997 | -------- Summary: end -------- 09:42:43 INFO - PROCESS | 24997 | 09:42:44 INFO - TEST-INFO | 24997: exit 0 09:42:44 INFO - TEST-OK | dromaeo_css | took 1117980ms 09:42:44 INFO - TEST-START | kraken 09:42:44 INFO - operating with platform_type : linux_ 09:42:44 INFO - Initialising browser for kraken test... 09:42:44 INFO - TEST-INFO | started process 25172 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYLShAH/profile http://localhost:35087/getInfo.html) 09:42:47 INFO - PROCESS | 25172 | __metrics Screen width/height:1600/1200 09:42:47 INFO - PROCESS | 25172 | colorDepth:24 09:42:47 INFO - PROCESS | 25172 | Browser inner width/height: 1024/697 09:42:47 INFO - PROCESS | 25172 | __metrics 09:42:48 ERROR - PROCESS | 25172 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:42:49 INFO - TEST-INFO | 25172: exit 0 09:42:49 INFO - Browser initialized. 09:42:49 INFO - Running cycle 1/1 for kraken test... 09:42:49 INFO - TEST-INFO | started process 25255 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYLShAH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:01 INFO - PROCESS | 25255 | RSS: Main: 156925952 09:43:01 INFO - PROCESS | 25255 | 09:43:04 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35087/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:05 INFO - PROCESS | 25255 | RSS: Main: 211288064 09:43:05 INFO - PROCESS | 25255 | 09:43:07 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35087/tests/kraken/driver.html?testName=audio-dft) 09:43:08 INFO - PROCESS | 25255 | RSS: Main: 198877184 09:43:08 INFO - PROCESS | 25255 | 09:43:11 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35087/tests/kraken/driver.html?testName=audio-fft) 09:43:12 INFO - PROCESS | 25255 | RSS: Main: 269623296 09:43:12 INFO - PROCESS | 25255 | 09:43:14 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35087/tests/kraken/driver.html?testName=audio-oscillator) 09:43:15 INFO - PROCESS | 25255 | RSS: Main: 178253824 09:43:15 INFO - PROCESS | 25255 | 09:43:17 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35087/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:43:18 INFO - PROCESS | 25255 | RSS: Main: 181809152 09:43:18 INFO - PROCESS | 25255 | 09:43:21 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35087/tests/kraken/driver.html?testName=imaging-darkroom) 09:43:21 INFO - PROCESS | 25255 | RSS: Main: 218419200 09:43:21 INFO - PROCESS | 25255 | 09:43:25 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35087/tests/kraken/driver.html?testName=imaging-desaturate) 09:43:25 INFO - PROCESS | 25255 | RSS: Main: 216670208 09:43:25 INFO - PROCESS | 25255 | 09:43:28 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35087/tests/kraken/driver.html?testName=json-parse-financial) 09:43:29 INFO - PROCESS | 25255 | RSS: Main: 216907776 09:43:29 INFO - PROCESS | 25255 | 09:43:31 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35087/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:43:31 INFO - PROCESS | 25255 | RSS: Main: 209039360 09:43:31 INFO - PROCESS | 25255 | 09:43:33 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35087/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:43:34 INFO - PROCESS | 25255 | RSS: Main: 212529152 09:43:34 INFO - PROCESS | 25255 | 09:43:36 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35087/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:43:37 INFO - PROCESS | 25255 | RSS: Main: 222064640 09:43:37 INFO - PROCESS | 25255 | 09:43:39 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35087/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:43:40 INFO - PROCESS | 25255 | RSS: Main: 226217984 09:43:40 INFO - PROCESS | 25255 | 09:43:43 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35087/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:43:44 INFO - PROCESS | 25255 | RSS: Main: 223961088 09:43:44 INFO - PROCESS | 25255 | 09:43:45 INFO - PROCESS | 25255 | Cycle 1(1): loaded http://localhost:35087/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35087/tests/kraken/driver.html?testName=ai-astar) 09:43:46 INFO - PROCESS | 25255 | RSS: Main: 269742080 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | __start_tp_report 09:43:46 INFO - PROCESS | 25255 | _x_x_mozilla_page_load 09:43:46 INFO - PROCESS | 25255 | _x_x_mozilla_page_load_details 09:43:46 INFO - PROCESS | 25255 | |i|pagename|runs| 09:43:46 INFO - PROCESS | 25255 | |0;ai-astar;112;108;111;114;111;112;111;113;114;112 09:43:46 INFO - PROCESS | 25255 | |1;audio-beat-detection;152;130;130;188;130;131;166;129;171;138 09:43:46 INFO - PROCESS | 25255 | |2;audio-dft;161;161;163;162;161;162;161;163;161;162 09:43:46 INFO - PROCESS | 25255 | |3;audio-fft;77;76;74;102;75;76;76;76;99;77 09:43:46 INFO - PROCESS | 25255 | |4;audio-oscillator;84;85;130;84;85;85;119;85;85;133 09:43:46 INFO - PROCESS | 25255 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 09:43:46 INFO - PROCESS | 25255 | |6;imaging-darkroom;147;146;146;146;146;146;146;146;146;145 09:43:46 INFO - PROCESS | 25255 | |7;imaging-desaturate;95;94;96;95;95;95;95;95;94;95 09:43:46 INFO - PROCESS | 25255 | |8;json-parse-financial;65;66;65;65;65;67;65;86;63;63 09:43:46 INFO - PROCESS | 25255 | |9;json-stringify-tinderbox;64;65;64;64;64;64;66;65;65;65 09:43:46 INFO - PROCESS | 25255 | |10;stanford-crypto-aes;83;88;82;85;83;84;85;84;83;83 09:43:46 INFO - PROCESS | 25255 | |11;stanford-crypto-ccm;143;149;129;130;130;131;128;127;179;135 09:43:46 INFO - PROCESS | 25255 | |12;stanford-crypto-pbkdf2;172;174;169;171;170;169;166;175;171;169 09:43:46 INFO - PROCESS | 25255 | |13;stanford-crypto-sha256-iterative;66;68;69;71;66;66;66;66;66;65 09:43:46 INFO - PROCESS | 25255 | __end_tp_report 09:43:46 INFO - PROCESS | 25255 | __start_cc_report 09:43:46 INFO - PROCESS | 25255 | _x_x_mozilla_cycle_collect,1902 09:43:46 INFO - PROCESS | 25255 | __end_cc_report 09:43:46 INFO - PROCESS | 25255 | __startTimestamp1464281026068__endTimestamp 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | ------- Summary: start ------- 09:43:46 INFO - PROCESS | 25255 | Number of tests: 14 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:1.75 (1.6%) stddev-sans-first:1.86 09:43:46 INFO - PROCESS | 25255 | Values: 112.0 108.0 111.0 114.0 111.0 112.0 111.0 113.0 114.0 112.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#1] audio-beat-detection Cycles:10 Average:146.50 Median:145.00 stddev:21.52 (14.8%) stddev-sans-first:22.73 09:43:46 INFO - PROCESS | 25255 | Values: 152.0 130.0 130.0 188.0 130.0 131.0 166.0 129.0 171.0 138.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 09:43:46 INFO - PROCESS | 25255 | Values: 161.0 161.0 163.0 162.0 161.0 162.0 161.0 163.0 161.0 162.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:10.44 (13.7%) stddev-sans-first:10.99 09:43:46 INFO - PROCESS | 25255 | Values: 77.0 76.0 74.0 102.0 75.0 76.0 76.0 76.0 99.0 77.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:85.00 stddev:20.88 (24.6%) stddev-sans-first:21.57 09:43:46 INFO - PROCESS | 25255 | Values: 84.0 85.0 130.0 84.0 85.0 85.0 119.0 85.0 85.0 133.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:43:46 INFO - PROCESS | 25255 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 09:43:46 INFO - PROCESS | 25255 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 09:43:46 INFO - PROCESS | 25255 | Values: 95.0 94.0 96.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#8] json-parse-financial Cycles:10 Average:67.00 Median:65.00 stddev:6.78 (10.4%) stddev-sans-first:7.16 09:43:46 INFO - PROCESS | 25255 | Values: 65.0 66.0 65.0 65.0 65.0 67.0 65.0 86.0 63.0 63.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 09:43:46 INFO - PROCESS | 25255 | Values: 64.0 65.0 64.0 64.0 64.0 64.0 66.0 65.0 65.0 65.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:1.70 (2.0%) stddev-sans-first:1.76 09:43:46 INFO - PROCESS | 25255 | Values: 83.0 88.0 82.0 85.0 83.0 84.0 85.0 84.0 83.0 83.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#11] stanford-crypto-ccm Cycles:10 Average:138.10 Median:133.00 stddev:16.04 (12.1%) stddev-sans-first:16.91 09:43:46 INFO - PROCESS | 25255 | Values: 143.0 149.0 129.0 130.0 130.0 131.0 128.0 127.0 179.0 135.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:171.00 stddev:2.63 (1.5%) stddev-sans-first:2.74 09:43:46 INFO - PROCESS | 25255 | Values: 172.0 174.0 169.0 171.0 170.0 169.0 166.0 175.0 171.0 169.0 09:43:46 INFO - PROCESS | 25255 | 09:43:46 INFO - PROCESS | 25255 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.00 stddev:1.85 (2.8%) stddev-sans-first:1.94 09:43:46 INFO - PROCESS | 25255 | Values: 66.0 68.0 69.0 71.0 66.0 66.0 66.0 66.0 66.0 65.0 09:43:46 INFO - PROCESS | 25255 | -------- Summary: end -------- 09:43:46 INFO - PROCESS | 25255 | 09:43:47 INFO - TEST-INFO | 25255: exit 0 09:43:47 INFO - TEST-OK | kraken | took 62951ms 09:43:47 INFO - SUITE-END | took 1180s 09:43:47 INFO - Completed test suite (00:19:41) 09:43:47 INFO - javascript benchmark 09:43:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2690.1122629532256, 2713.893739087054, 2715.6741191057076, 2752.710965348638, 2767.0177087134066, 4673.228917228662, 4747.145513807513, 4785.401824771903, 4850.393700787402, 4925.655204135712, 4752.453467851198, 4792.6165906727465, 4829.517048295177, 4831.565159135677, 4906.607471402288, 4343.131373725259, 4360.23695853226, 4417.271150260203, 4472.843450479234, 4548.545145485458, 4477.485089214733, 4519.932201016987, 4546.477155127153, 4563.018950925551, 4567.2920697291975, 4270.530241673413, 4319.028218650778, 4336.262835318003, 4429.870383052301, 4455.46534415869, 4356.542563030899, 4368.191884501346, 4370.0, 4416.492103408117, 4425.336199570058, 4343.457067866517, 4347.869563913087, 4349.826006959718, 4351.673624478145, 4393.868183954486, 4693.55411235932, 4772.976135119302, 4797.424309082898, 4800.975995120037, 4806.0628177505405, 4995.500449954975, 5153.922691159635, 5212.50481204285, 5222.843314700565, 5234.8691282717855, 5270.9736451317885, 5274.762635681365, 5309.867253318698, 5315.893682126336, 5341.305630268041, 4919.581835543947, 4928.507149285078, 4968.7018778873025, 4971.353724015855, 4971.4282857471135, 5220.947790522122, 5265.236540701577, 5348.03735327637, 5353.625246232804, 5379.65032272901, 5120.590352771769, 5155.32980712505, 5167.302414174113, 5168.948310516922, 5184.818531351385, 5006.64953453262, 5186.377634683787, 5210.374755029345, 5210.452902445264, 5213.45258747826, 5086.3896332440345, 5165.509276618715, 5172.19830926207, 5205.29728486657, 5230.293910322058, 5151.510606492452, 5162.380514338229, 5162.845114646567, 5166.3025491558155, 5217.113090774577, 4280.357946308079, 4489.236829738952, 4495.865124046284, 4503.347014682919, 4540.79566419512, 3776.33914065043, 3855.51806024247, 3879.379299312153, 3894.941575876364, 3942.4283478895973, 4500.369948207253, 4502.392177056121, 4510.819567217274, 4547.818087276538, 4561.384213131251, 5009.373828271466, 5215.895682086413, 5224.085784987612, 5231.503007214308, 5279.340082489689, 4359.847405340798, 4455.242608756519, 4491.505934347219, 4500.3249512573375, 4516.9774151129695, 4720.834770783006, 4739.170645137087, 4753.83361582343, 4759.928601070987, 4801.7118972861745, 4702.600278976325, 4907.754612269372, 4917.409910810726, 4920.507949205051, 4970.676906001098, 5118.206677964921, 5157.819476318461, 5179.19722443022, 5183.403908550565, 5252.501012403815, 4853.247746599282, 4856.0, 4857.101436234308, 4859.271109333628, 4909.631777616764, 4894.559489645949, 5117.130087884994, 5119.411267704261, 5123.6157288202785, 5176.197689358156, 5092.719900405513, 5136.0, 5146.228065790161, 5148.871278218075, 5168.405633352213, 4963.305137280711, 4996.725180115129, 5002.599792016703, 5003.624728145476, 5045.924311135409, 4860.1251774680895, 4887.120318342732, 4995.5004499551205, 5005.599552035901, 5007.198848184273, 4866.926996095132, 4937.086638971802, 4964.106460837084, 4974.129527332775, 4985.700857948535, 5034.748262586929, 5037.823676171316, 5038.899222015612, 5079.441261461162, 5092.541671249605, 4810.2063159579675, 4910.779014944264, 4926.531979461945, 4967.155583550877, 4990.50094990498], "value": 4753.036539546699, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.1221258199462, 598.1297212555731, 598.8053882488192, 600.8437806170396, 611.9505048841238, 39390.2121957561, 41034.38448423269, 41037.99999999996, 41052.0, 45129.09741805162, 41801.0, 42484.36273455901, 42547.36178957318, 42667.146657066914, 42670.7866460668, 7162.928370716279, 7715.0, 7931.524108553505, 9871.457069861155, 10272.28094033418, 9711.757206069835, 9801.41191528507, 9812.215022798158, 9812.90187098127, 9821.214302855755, 9863.358881672704, 9883.950580247089, 9885.406875587487, 9895.950520247388, 9896.901030989706, 7478.14001389839, 9981.700548983543, 10235.948820255926, 10277.588896444133, 10542.578296868116, 9959.90040099597, 10024.849627255597, 10036.24728145392, 10037.498125093716, 10041.949790251076, 37791.0, 38038.619613803785, 38189.80905095484, 38204.80897595522, 38216.0, 3109.6890310968947, 3257.4136655402017, 3333.2666813301034, 3403.642617525149, 3733.0, 29645.555316670267, 29677.70322296771, 29726.0, 30117.247068823235, 30150.547741783892, 3434.1414646338417, 3485.4771784232316, 3494.930101397958, 3538.0270425633157, 3595.514605528246, 246780.0, 246945.0, 248245.0, 248334.75832620903, 248953.0, 51969.0, 52105.0, 52222.47777522176, 52656.0, 52700.0, 33064.50403243953, 33818.0, 33895.49156762651, 55042.0, 55166.72416637971, 10969.177311701495, 13980.091294065853, 14100.871930245556, 14149.858501414854, 14154.36305366261, 9816.65641702537, 10066.496675166212, 10101.444420556792, 10314.1748660107, 10355.896441035493, 18306.542336441427, 18335.266589336276, 18371.173297201658, 18379.356722514647, 18382.448526544224, 6960.234374218832, 7049.68276427562, 7121.857562848714, 7201.531900426456, 7252.746153884589, 750.4709180028067, 832.8917240758663, 887.9644814207488, 962.9085236902623, 997.4314640654902, 27569.310767230578, 27659.0, 27778.722212777615, 28321.884592694787, 28537.71462285392, 945.6170251048336, 951.8810148731409, 1005.4771518810323, 1012.5443550402348, 1099.7140743406771, 14585.489507867385, 14854.0, 14910.627234319012, 15156.545303640909, 15219.0, 374658.0, 378484.0, 379291.0, 379992.0, 381856.0, 48799.0, 49182.75408623005, 49213.261801073015, 49243.50756492389, 49365.0, 1103.3545375954996, 1207.9818802718141, 1219.0552321950559, 1223.4066477758101, 1236.9628911132681, 27736.167914962585, 28925.56611650785, 28942.85528572428, 29057.0, 29442.558361624164, 41406.37890431706, 41863.37204941867, 41886.37170442385, 41891.581084188765, 41950.58049419467, 289573.0, 291209.0, 291581.0, 291641.0, 291677.0, 20652.483687907923, 24969.87515062413, 25007.12475063364, 26172.083977060713, 26327.21018369452, 36864.81567592145, 39935.801925942265, 41603.0, 41868.1626367477, 42774.78612606917, 35160.47259291061, 35695.46456803202, 35912.281754365285, 35983.82008089943, 36528.26943461063, 35425.645743542234, 35704.107397315274, 35718.107047324025, 35825.0, 35944.46083308805, 34445.0, 35049.299014020085, 35518.46722299219, 35743.28513429769, 35812.82093589516, 30810.0, 32272.0, 32272.515912261806, 32341.838290808395, 32435.837820810746, 24368.26895193147, 24384.0, 24629.13798017061, 24999.625005625294, 25024.374390639652], "value": 17854.887899705227, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.28826212361645, 364.61715199041015, 372.56968201727, 377.377327409774, 393.616224181423, 15866.524004279876, 16821.41125060623, 17213.397531086415, 17285.654286914254, 17676.29294828207, 15179.848201517982, 17138.0, 17324.740128898044, 17332.740008899844, 17340.47978560645, 4967.925481117777, 5190.688558686491, 5214.530692237698, 5221.746780772606, 5229.450907654679, 3750.8874733757893, 3927.0967677434205, 5075.238714192882, 5555.0, 5859.970700146493, 5867.55993300502, 5888.263967004124, 5927.525797936176, 5929.229200203968, 5932.6143800653035, 5767.279090113736, 5811.128330750379, 5940.46535811779, 5949.524038076944, 5959.433853783884, 3353.6478669739813, 4928.137575924199, 5178.922316165261, 5241.318628578299, 5246.396664383607, 19863.7020444692, 20431.38705838827, 20459.48851278715, 20888.060037298357, 20903.477413064644, 1924.4130540185238, 2004.5489764802803, 2202.361315218585, 2256.244158207003, 2566.7304932982142, 6325.778597749057, 11791.174617776758, 13016.479340826356, 13018.307620009433, 13760.31198440074, 1728.2741248675586, 1789.3008931626619, 1809.8280663336961, 1816.4459839748872, 1851.3612803582741, 65644.67177664129, 66258.66870665664, 66479.66760166216, 66849.3315066848, 67008.66495667539, 8885.777855553533, 8920.24177944882, 8976.237019853255, 8981.910180898238, 9271.397359171633, 11945.522179112739, 12037.578684746168, 12086.758264834654, 12095.032397408186, 12109.939450302869, 6957.478189135835, 6984.301569842975, 7133.517289382836, 7204.56772593646, 7205.702973464722, 6874.862502749917, 6986.895196572055, 6995.545289556163, 7092.659422390599, 7187.353138217585, 5853.09277062056, 5887.411258874078, 5984.371640977722, 6000.0, 6030.216071910711, 1519.5022732711077, 1559.7816305717208, 1656.6024154203146, 1695.9236834342485, 1751.8861274017147, 836.0552575589667, 847.8982522097388, 905.0949050949051, 914.1955079529971, 1012.4633944009687, 16358.289303160345, 16926.322947082226, 17324.480265592054, 17476.737848932276, 17499.0, 852.8550146475114, 874.2568816505919, 896.7847716547982, 951.353079905657, 976.0483528559739, 9656.37233579815, 10032.398056116657, 10060.748481288027, 10081.69754907354, 10154.492275386201, 364109.0, 372806.0, 379945.0, 380751.0, 381650.0, 64106.35893641004, 64713.67643161754, 66640.0, 66959.33040669629, 67086.66456667686, 906.7959709065408, 916.4180745226829, 934.4346670264392, 936.4147407870081, 940.581441258647, 15100.320485577955, 15134.470293539673, 15400.537983860502, 15413.691726165189, 15779.605509862346, 30795.846020769754, 32713.34573308568, 33098.66901330956, 33912.491312629834, 34137.31725365429, 41189.58810411858, 42567.787161064, 43626.12747745091, 44905.77547112243, 44926.55073449224, 6819.2498825128205, 7211.783646490614, 7318.3047610476915, 7384.409247260099, 7500.36246919018, 9859.457729824928, 10715.249932504961, 10720.624778132727, 10776.245662803529, 10892.891071089505, 8759.649614015369, 8944.37389382737, 8981.832361792925, 9166.0, 9166.945801232678, 9199.0, 9361.344705870524, 9467.384619999679, 9566.330356874952, 9589.472579008221, 8895.510746908982, 9097.0, 9105.043970383014, 9262.72211833646, 9603.855942160733, 7099.183593886769, 7222.0, 7373.668184931584, 7440.4419668524, 7473.588952607659, 8629.6979605716, 9132.315076369432, 9218.907810921804, 9310.57924939507, 9343.439393636403], "value": 8056.4506301417805, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1255.0963306419376, 1374.7387996280709, 1424.679447124397, 1462.5466105507287, 1736.53113659312, 6555.311692272308, 7507.812304692386, 7764.767056988299, 7765.301122898938, 7937.563434011127, 7771.145174030852, 7774.650140743666, 7834.138244793083, 7850.097238817538, 7854.293113619773, 2166.458385403645, 2187.245400336881, 2197.263916587945, 2200.8239340852692, 2202.4053505553493, 2120.6076875777953, 2146.957060858782, 2179.2154824263253, 2187.223535644847, 2192.079326682789, 2142.3037512808323, 2168.4253672776726, 2175.3147758456034, 2178.6187417202004, 2210.4805370737863, 2100.19142630087, 2104.252990188492, 2105.799949004842, 2106.09437941685, 2107.3045894854663, 2060.4333808202714, 2108.146200788683, 2109.6202683516963, 2117.544727883512, 2119.0782009825775, 7613.80965475862, 7805.9609701951695, 7854.0, 7860.371170306362, 7865.410094242898, 4504.662150338738, 4635.119327327797, 4660.974585591199, 4709.270063140219, 4736.313234581001, 3020.320427903726, 3035.3929214157256, 3073.2163298359, 3098.51972944194, 3107.0, 932.4265576670367, 935.349931797401, 944.7165850244968, 960.3757557587554, 962.0956301076965, 10471.10995565378, 10631.787364252748, 10668.679939601825, 10673.519691613817, 10691.572337106507, 4177.874663760092, 4251.617354438115, 4313.525512193656, 4334.219840428753, 4336.0, 9160.8625870612, 9173.082691730773, 9182.127697868691, 9185.357025008185, 9199.402038867453, 4619.330197121399, 4632.189366860786, 4633.907321853544, 4635.466921304026, 4636.884077898079, 3438.673326034023, 4088.8568900088353, 4090.1001779609032, 4157.605027522381, 4245.1085272093205, 3731.477593136963, 3762.680172185395, 3859.591249194024, 3880.534335879656, 3893.2213557288655, 1203.4103289388136, 1220.2434490615874, 1221.9389030548436, 1227.0858210633135, 1243.7699025680279, 649.2630863969429, 709.8580283943232, 750.5196674128562, 774.1523032279757, 804.2359758229705, 4768.642351823627, 4794.832180873653, 4798.184308667465, 4844.975775121102, 4923.655344125948, 385.9903502412406, 387.81384935231245, 392.06296950288834, 409.25107054090944, 411.80645096804454, 3216.3567286542316, 3225.193701574606, 3262.6737326267184, 3284.1953721338423, 3310.7682462227895, 9372.906270937203, 9731.41611503312, 9735.756606084904, 9751.853722194173, 9791.510424478747, 9403.623855045722, 9513.809723805485, 9530.571124299284, 9865.852012219822, 9879.80240395188, 7356.301151390609, 7405.9629701851145, 7411.62941852916, 7424.628768561551, 7467.0, 4695.900705534331, 4700.764961751967, 4706.388169537938, 4777.259524773665, 4777.689450185727, 8965.372423930263, 9039.548022598712, 9057.18485336323, 9064.90935090667, 9080.40977336471, 9028.413153145024, 9216.907830921606, 9234.492102934402, 9378.155965963097, 9390.295727820578, 5692.117721753135, 6512.5766825156215, 6688.197416310074, 6868.210155831944, 6905.205901321211, 7590.316871481593, 7591.924080759342, 7681.8463630725955, 7699.114601820638, 7700.961495192488, 6401.55189136756, 6453.806385808433, 6558.311377305315, 6656.5340426169705, 6665.40011398957, 6411.71147298382, 6430.807075787734, 6497.741972425629, 6558.442532384801, 6594.175728033996, 6138.907916381348, 6151.907721384094, 6174.135621012972, 6187.969060154851, 6196.659183744938, 4222.239996800606, 4303.50509691377, 4325.97837010813, 4338.652907767434, 4406.625436837905, 4048.797560122041, 4216.515100763451, 4230.280852255062, 4326.891827704332, 4358.193734159191], "value": 3995.0796987281583, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.50927714632263, 305.2475647528841, 308.4155525279594, 312.4500878453922, 313.32322184082386, 4868.707877527351, 4899.358714830531, 4910.361652985107, 4925.802967881281, 4949.851504454868, 4596.655250856183, 4662.370579971703, 4777.832775852846, 4785.497522760113, 4890.559849613534, 3769.227308401771, 3783.205526839367, 3797.905052373699, 3811.2758575870635, 3812.504374431334, 3810.466534685146, 3811.7712937223855, 3820.808959552025, 3823.1971286029966, 3824.082220267144, 3572.821358932043, 3652.4886515887797, 3750.1187221003042, 3753.155540003491, 3766.0, 3736.159364143073, 3741.5510138783247, 3748.725433352673, 3761.567419746737, 3768.2086761779924, 3313.0, 3316.60200775906, 3324.6675332466803, 3336.282699219655, 3339.1985923378215, 4470.530594287618, 4642.280446530793, 4692.694974826625, 4694.647901407408, 4713.703731473838, 4247.256730072256, 4292.656587473026, 4295.871123866289, 4296.871093867189, 4302.182585308781, 5006.599472042228, 5012.373453318335, 5021.121303771862, 5029.673071250394, 5041.143005689041, 3580.7314451416246, 3618.620044895301, 3621.8732344368086, 3624.4925710400566, 3629.745917785756, 5108.616853735947, 5289.841304760864, 5296.496832800878, 5301.0, 5301.973490132525, 3667.578228503703, 3675.9081022974638, 3677.8344974476213, 3711.406175011985, 3716.331060409152, 3716.479692843004, 3742.9519734474393, 3769.1896242308035, 3772.75477093988, 3778.9433158502643, 4150.418941348214, 4283.957160428418, 4293.656507479394, 4309.547497512779, 4342.544032876565, 5078.4413714491375, 5080.695158290514, 5085.160948443539, 5111.795528178905, 5121.795128194831, 1269.8603153653091, 1298.2405292903757, 1299.603620895617, 1318.8615195404536, 1326.7611829870523, 870.1602953150241, 873.7378786364065, 890.4835195586544, 894.917150248207, 898.6764764684708, 4095.2014357200655, 4104.322786740155, 4107.383892416102, 4109.404136400205, 4109.609587089281, 4700.130475861976, 4720.504347043579, 4724.598409135262, 4729.574338309569, 4734.4081989751285, 4069.2471892699946, 4072.7759973201164, 4082.4692789937117, 4129.587041295847, 4150.937735933963, 2357.3281614739717, 2520.575874630828, 2540.8312176399063, 2549.6048112542585, 2553.629723690092, 4406.1187762447635, 4442.444694413199, 4448.64410847138, 4452.109578084396, 4482.25192175052, 4698.40254313535, 4716.693414928019, 4770.805429502468, 4772.832950846704, 4789.377380940525, 4351.456067991501, 4399.846005389796, 4552.588697503718, 4928.778204980857, 4975.7512124392915, 5261.763220655003, 5293.655912365684, 5301.443348448357, 5359.356877174764, 5362.544183744425, 5242.318498595158, 5247.737613119252, 5259.658122222043, 5262.184361423985, 5263.394709608444, 4692.366530518473, 4989.276554899592, 4991.954907506346, 5013.573846223112, 5025.723585202849, 3733.383991641457, 3747.437884317374, 3748.4002559590335, 3773.056735816046, 3781.0169355968196, 5129.153689641167, 5393.541548968382, 5407.270018547603, 5409.350877894678, 5430.294061771944, 5009.423916249678, 5086.160783470686, 5106.948930510796, 5116.9232461512365, 5132.30713853625, 5069.467705890828, 5101.2910674924315, 5104.1578139606545, 5109.616778741683, 5150.948490515047, 4874.53691899269, 4988.55103040728, 5032.144535428913, 5040.672356296829, 5055.5955523557295, 5032.194848824171, 5055.595552355876, 5099.832571157318, 5107.0, 5114.667546609458, 4992.500749924979, 5007.449180590059, 5029.371328583927, 5029.421616514148, 5035.345405097314], "value": 3771.9609087042622, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.9896051559227, 699.2168770976507, 725.9582499113773, 738.5827007740626, 742.5062333548191, 12100.031997440195, 12673.0, 12720.173188742738, 12736.490540378374, 12754.808677869827, 11700.707482312946, 11827.0, 11853.0, 11881.584144554943, 11912.34482103484, 617.5399327501018, 766.2490759056128, 780.5862892666896, 795.2405452792569, 797.4059325802285, 792.1444839573247, 795.08168065884, 796.7131832540281, 798.1180795221284, 798.1778767869104, 847.6439895243982, 849.2908421468081, 849.3290300662469, 849.4563479373206, 849.800296930221, 661.044790278046, 680.2143524229523, 681.6932380428827, 682.160942041292, 846.4836449765638, 590.100097351538, 612.5099920063931, 612.6538505744285, 612.9417705317987, 613.4693490131049, 12315.815262771066, 12321.69195770104, 12321.815172772416, 12322.137450378477, 12335.136540442172, 11649.941750291278, 12057.457414416373, 12071.094667899855, 12134.393280335948, 12325.0, 11018.283811552303, 11043.889561104366, 11207.103431725442, 11327.546898124065, 11782.469788859436, 3958.267720471722, 4006.599340065999, 4018.276710192164, 4117.958820411787, 4125.030617804813, 46029.30956035695, 46481.535184648055, 46573.767131164124, 46642.0, 46712.0, 9580.281478889112, 9677.70966870995, 9697.127258546765, 9736.41581505112, 10174.745631359126, 16795.41216057432, 16852.831471685127, 16913.40803071911, 17050.573735656708, 17112.0, 6718.899216511756, 6769.593824370553, 6821.249662537117, 6836.077129587541, 6872.62200578963, 3539.43369060949, 3570.125319296789, 3570.696490798259, 3610.097475631092, 3711.9420965024838, 2717.3070866928806, 2802.9159125226274, 2805.0603047979157, 2808.508511010565, 2809.817361871472, 492.2493197873272, 501.2731539268076, 509.3912774234754, 513.6019584821793, 515.9406668233202, 385.9343911535046, 398.7487882633922, 407.03938704656986, 441.3798612948812, 442.11356230757127, 6147.72335244915, 6445.903311450332, 6451.903221451682, 6474.6762661866715, 6599.505037122235, 407.9877603671895, 416.2486711485797, 425.99787001064794, 444.92436285831485, 448.08814063381516, 2986.22358186873, 3041.574179614856, 3050.2069461939614, 3064.0960916529675, 3123.8906638268004, 53462.465375345746, 53515.0, 54083.45916540784, 55008.449915501136, 55989.72005139948, 48562.75718621455, 49662.75168624134, 50481.24278135831, 50686.493135068915, 50784.238236426485, 45985.78099533067, 46031.5396846034, 46277.537224627325, 46749.76625116784, 47195.764021179675, 5810.5061069809535, 5887.3523912370465, 6013.9699301503215, 6055.757769689163, 6126.877462450815, 28267.71732282651, 28270.434591307647, 29253.41493170082, 32289.838550807097, 32290.192745181557, 40781.388279176426, 40892.59107408888, 40991.180176396905, 41052.626318655806, 41373.79313103415, 10034.347767395097, 10226.13077888358, 10470.842937356098, 10488.737781555523, 10490.947545262225, 11474.598389056342, 11670.591529296435, 11769.411529423665, 11779.705507362041, 12069.517219311128, 10074.0, 10391.116755075904, 10694.73263168427, 10732.409717465614, 10749.247552671552, 10568.154547636017, 10619.30974486656, 10775.94612026935, 10830.95069685913, 11136.610218642309, 10180.287379883634, 10291.228157888338, 10528.894711052792, 10550.525226364985, 10855.620053298097, 8837.248833849195, 8912.821743565222, 8931.821363572562, 8986.82026359456, 9092.227160691415, 8784.253338466302, 8995.730128096167, 9114.589843457194, 9180.495072771062, 9231.0], "value": 5701.396635806777, "unit": "score"}], "name": "dromaeo_css", "value": 6234.949382273255, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 111.0, 114.0, 111.0, 112.0, 111.0, 113.0, 114.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 130.0, 130.0, 188.0, 130.0, 131.0, 166.0, 129.0, 171.0, 138.0], "value": 146.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 162.0, 161.0, 162.0, 161.0, 163.0, 161.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 74.0, 102.0, 75.0, 76.0, 76.0, 76.0, 99.0, 77.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 130.0, 84.0, 85.0, 85.0, 119.0, 85.0, 85.0, 133.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 65.0, 67.0, 65.0, 86.0, 63.0, 63.0], "value": 67.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 66.0, 65.0, 65.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 82.0, 85.0, 83.0, 84.0, 85.0, 84.0, 83.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 149.0, 129.0, 130.0, 130.0, 131.0, 128.0, 127.0, 179.0, 135.0], "value": 138.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 169.0, 171.0, 170.0, 169.0, 166.0, 175.0, 171.0, 169.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 71.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1547.2999999999997, "alertThreshold": 2.0}]} 09:43:47 INFO - Return code: 0 09:43:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:43:47 ERROR - # TBPL SUCCESS # 09:43:47 INFO - Running post-action listener: _resource_record_post_action 09:43:47 INFO - ##### 09:43:47 INFO - ##### Finished run-tests step (success) 09:43:47 INFO - ##### 09:43:47 INFO - Running post-run listener: _resource_record_post_run 09:43:47 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 533102592; Read time: 20; Write time: 410072 09:43:47 INFO - TinderboxPrint: CPU usage
13.1% 09:43:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 09:43:47 INFO - TinderboxPrint: I/O write bytes / time
533,102,592 / 410,072 09:43:47 INFO - TinderboxPrint: CPU idle
8,306.4 (86.9%) 09:43:47 INFO - TinderboxPrint: CPU user
1,215.2 (12.7%) 09:43:47 INFO - TinderboxPrint: Swap in / out
0 / 0 09:43:47 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 249303040; Read time: 0; Write time: 202260 09:43:47 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283799552; Read time: 20; Write time: 207812 09:43:47 INFO - Running post-run listener: _upload_blobber_files 09:43:47 INFO - Blob upload gear active. 09:43:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:43:47 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 09:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:43:48 INFO - (blobuploader) - INFO - Open directory for files ... 09:43:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:43:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:43:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:43:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:43:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:43:54 INFO - (blobuploader) - INFO - Done attempting. 09:43:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:43:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:43:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:43:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:43:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:43:55 INFO - (blobuploader) - INFO - Done attempting. 09:43:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:43:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:43:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:43:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:43:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:43:56 INFO - (blobuploader) - INFO - Done attempting. 09:43:56 INFO - (blobuploader) - INFO - Iteration through files over. 09:43:56 INFO - Return code: 0 09:43:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:43:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:43:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56e0d8ef13053a6f8e939fd5d11eac117b5d7f72d7cf226091e6af0a97069a47d3df751f72715e9303ed6a998ceeda3c002e4136e75c1ecabc2745b5f3fa88b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08964d0c45c745e7305cce83a2be0d9e4e68f884a9d457ef8aede6c0a61f3454a7fc716555986b555879f4c509c736284bb40767abce7dca2c84b595917ad820", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a66af4d1c832aee9bc4db247be72f96140e255534ae72100bab7d6e23d5316e03b3821b378e535fabac4df67b99d1053612a1bdfa32e3072adadf7962930fb05"} 09:43:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:43:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:43:56 INFO - Contents: 09:43:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56e0d8ef13053a6f8e939fd5d11eac117b5d7f72d7cf226091e6af0a97069a47d3df751f72715e9303ed6a998ceeda3c002e4136e75c1ecabc2745b5f3fa88b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08964d0c45c745e7305cce83a2be0d9e4e68f884a9d457ef8aede6c0a61f3454a7fc716555986b555879f4c509c736284bb40767abce7dca2c84b595917ad820", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a66af4d1c832aee9bc4db247be72f96140e255534ae72100bab7d6e23d5316e03b3821b378e535fabac4df67b99d1053612a1bdfa32e3072adadf7962930fb05"} 09:43:56 INFO - Running post-run listener: copy_logs_to_upload_dir 09:43:56 INFO - Copying logs to upload dir... 09:43:56 INFO - mkdir: /builds/slave/test/build/upload/logs 09:43:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.707054 ========= master_lag: 1.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-26 09:43:56.253600) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 09:43:56.257199) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56e0d8ef13053a6f8e939fd5d11eac117b5d7f72d7cf226091e6af0a97069a47d3df751f72715e9303ed6a998ceeda3c002e4136e75c1ecabc2745b5f3fa88b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08964d0c45c745e7305cce83a2be0d9e4e68f884a9d457ef8aede6c0a61f3454a7fc716555986b555879f4c509c736284bb40767abce7dca2c84b595917ad820", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a66af4d1c832aee9bc4db247be72f96140e255534ae72100bab7d6e23d5316e03b3821b378e535fabac4df67b99d1053612a1bdfa32e3072adadf7962930fb05"} build_url:https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/Bf3OTkV1Rg2Lvwxrhe5Tlw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56e0d8ef13053a6f8e939fd5d11eac117b5d7f72d7cf226091e6af0a97069a47d3df751f72715e9303ed6a998ceeda3c002e4136e75c1ecabc2745b5f3fa88b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08964d0c45c745e7305cce83a2be0d9e4e68f884a9d457ef8aede6c0a61f3454a7fc716555986b555879f4c509c736284bb40767abce7dca2c84b595917ad820", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a66af4d1c832aee9bc4db247be72f96140e255534ae72100bab7d6e23d5316e03b3821b378e535fabac4df67b99d1053612a1bdfa32e3072adadf7962930fb05"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 09:43:56.287000) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:43:56.287378) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1464277775.259652-565081436 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:43:56.339589) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 09:43:56.340293) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 09:43:56.341052) ========= ========= Total master_lag: 1.40 =========