builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-034 starttime: 1463201312.99 results: success (0) buildid: 20160513203440 builduid: 63929ea087254c649f6055527b933433 revision: 5a396ec2aeefb4db7298f2406f5132390eada6a7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:32.991855) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:32.992246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:32.992541) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.071587) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.071856) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-13 21:48:33-- 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% 222M=0s 2016-05-13 21:48:33 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114328 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.203814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.204087) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022248 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.253028) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.253346) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5a396ec2aeefb4db7298f2406f5132390eada6a7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 5a396ec2aeefb4db7298f2406f5132390eada6a7 --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=d6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-13 21:48:33,542 truncating revision to first 12 chars 2016-05-13 21:48:33,542 Setting DEBUG logging. 2016-05-13 21:48:33,542 attempt 1/10 2016-05-13 21:48:33,542 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5a396ec2aeef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 21:48:33,792 unpacking tar archive at: fx-team-5a396ec2aeef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.501440 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.768225) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.768563) ========= script_repo_revision: 5a396ec2aeefb4db7298f2406f5132390eada6a7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.769004) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.769269) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 21:48:33.782163) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-05-13 21:48:33.782554) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:48:34 INFO - MultiFileLogger online at 20160513 21:48:34 in /builds/slave/test 21:48:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 21:48:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:48:34 INFO - {'append_to_log': False, 21:48:34 INFO - 'base_work_dir': '/builds/slave/test', 21:48:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:48:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:48:34 INFO - 'branch': 'Fx-Team-Non-PGO', 21:48:34 INFO - 'buildbot_json_path': 'buildprops.json', 21:48:34 INFO - 'config_files': ('talos/linux_config.py',), 21:48:34 INFO - 'default_actions': ('clobber', 21:48:34 INFO - 'read-buildbot-config', 21:48:34 INFO - 'download-and-extract', 21:48:34 INFO - 'populate-webroot', 21:48:34 INFO - 'create-virtualenv', 21:48:34 INFO - 'install', 21:48:34 INFO - 'run-tests'), 21:48:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:48:34 INFO - 'download_minidump_stackwalk': True, 21:48:34 INFO - 'download_symbols': 'ondemand', 21:48:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:48:34 INFO - 'tooltool.py': '/tools/tooltool.py', 21:48:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:48:34 INFO - '/tools/misc-python/virtualenv.py')}, 21:48:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:48:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:48:34 INFO - 'installer_path': 'installer.exe', 21:48:34 INFO - 'log_level': 'info', 21:48:34 INFO - 'log_name': 'talos', 21:48:34 INFO - 'log_to_console': True, 21:48:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:48:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:48:34 INFO - 'opt_config_files': (), 21:48:34 INFO - 'pip_index': False, 21:48:34 INFO - 'sps_profile': False, 21:48:34 INFO - 'sps_profile_interval': 0, 21:48:34 INFO - 'suite': 'dromaeojs', 21:48:34 INFO - 'system_bits': '32', 21:48:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:48:34 INFO - 'title': 'talos-linux64-ix-034', 21:48:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:48:34 INFO - 'use_talos_json': True, 21:48:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:48:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:48:34 INFO - 'work_dir': 'build'} 21:48:34 INFO - ##### 21:48:34 INFO - ##### Running clobber step. 21:48:34 INFO - ##### 21:48:34 INFO - Running pre-action listener: _resource_record_pre_action 21:48:34 INFO - Running main action method: clobber 21:48:34 INFO - rmtree: /builds/slave/test/build 21:48:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:48:35 INFO - Running post-action listener: _resource_record_post_action 21:48:35 INFO - ##### 21:48:35 INFO - ##### Finished clobber step (success) 21:48:35 INFO - ##### 21:48:35 INFO - ##### 21:48:35 INFO - ##### Running read-buildbot-config step. 21:48:35 INFO - ##### 21:48:35 INFO - Running pre-action listener: _resource_record_pre_action 21:48:35 INFO - Running main action method: read_buildbot_config 21:48:35 INFO - Using buildbot properties: 21:48:35 INFO - { 21:48:35 INFO - "project": "", 21:48:35 INFO - "product": "firefox", 21:48:35 INFO - "script_repo_revision": "production", 21:48:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:48:35 INFO - "repository": "", 21:48:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:48:35 INFO - "buildid": "20160513203440", 21:48:35 INFO - "pgo_build": "False", 21:48:35 INFO - "basedir": "/builds/slave/test", 21:48:35 INFO - "buildnumber": 1685, 21:48:35 INFO - "slavename": "talos-linux64-ix-034", 21:48:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:48:35 INFO - "platform": "ubuntu64_hw", 21:48:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:48:35 INFO - "branch": "fx-team", 21:48:35 INFO - "repo_path": "integration/fx-team", 21:48:35 INFO - "revision": "5a396ec2aeefb4db7298f2406f5132390eada6a7", 21:48:35 INFO - "stage_platform": "linux64", 21:48:35 INFO - "builduid": "63929ea087254c649f6055527b933433", 21:48:35 INFO - "slavebuilddir": "test" 21:48:35 INFO - } 21:48:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:48:35 INFO - Running post-action listener: _resource_record_post_action 21:48:35 INFO - ##### 21:48:35 INFO - ##### Finished read-buildbot-config step (success) 21:48:35 INFO - ##### 21:48:35 INFO - ##### 21:48:35 INFO - ##### Running download-and-extract step. 21:48:35 INFO - ##### 21:48:35 INFO - Running pre-action listener: _resource_record_pre_action 21:48:35 INFO - Running main action method: download_and_extract 21:48:35 INFO - mkdir: /builds/slave/test/build/tests 21:48: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')]} 21:48:35 INFO - https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:48:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:48:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:48:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:35 INFO - Downloaded 1361 bytes. 21:48:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:48:35 INFO - Using the following test package requirements: 21:48:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:48:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:48:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:48:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:48:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:48:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:48:35 INFO - u'jsshell-linux-x86_64.zip'], 21:48:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:48:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:48:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:48:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:48:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:48:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:48:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:48:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:48:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:48:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:48:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:48:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:48: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')]} 21:48:35 INFO - https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:48:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:48:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:48:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:36 INFO - Downloaded 22447326 bytes. 21:48:36 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'] 21:48:36 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 21:48:38 INFO - Return code: 0 21:48:38 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 21:48:38 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')]} 21:48:38 INFO - https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:48:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:48:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:48:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:39 INFO - Downloaded 22447326 bytes. 21:48:39 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'] 21:48:39 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 21:48:41 INFO - Return code: 0 21:48:41 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')]} 21:48:41 INFO - https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:48:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:48:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:48:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:42 INFO - Downloaded 10840194 bytes. 21:48:42 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'] 21:48:42 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 21:48:43 INFO - Return code: 0 21:48: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')]} 21:48:43 INFO - https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:48:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:48:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:48:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O2hlyNdERte4lRHSrRl7rg/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 21:48:45 INFO - Downloaded 54727472 bytes. 21:48:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:48:45 INFO - mkdir: /builds/slave/test/properties 21:48:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:48:45 INFO - Writing to file /builds/slave/test/properties/build_url 21:48:45 INFO - Contents: 21:48:45 INFO - build_url:https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:48:46 INFO - Running post-action listener: _resource_record_post_action 21:48:46 INFO - Running post-action listener: set_extra_try_arguments 21:48:46 INFO - ##### 21:48:46 INFO - ##### Finished download-and-extract step (success) 21:48:46 INFO - ##### 21:48:46 INFO - ##### 21:48:46 INFO - ##### Running populate-webroot step. 21:48:46 INFO - ##### 21:48:46 INFO - Running pre-action listener: _resource_record_pre_action 21:48:46 INFO - Running main action method: populate_webroot 21:48:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:48:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:48:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:48:46 INFO - '--tppagecycles', 21:48:46 INFO - '7'], 21:48:46 INFO - 'tests': ['tsvgm']}}, 21:48:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:48:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:48:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:48:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:48:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:46 INFO - 'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:46 INFO - 'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['damp', 'tps']}, 21:48:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:46 INFO - 'tests': ['damp', 'tps']}, 21:48:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['dromaeo_dom']}, 21:48:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:48:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['a11yr', 21:48:46 INFO - 'ts_paint', 21:48:46 INFO - 'tpaint', 21:48:46 INFO - 'sessionrestore', 21:48:46 INFO - 'sessionrestore_no_auto_restore', 21:48:46 INFO - 'tabpaint']}, 21:48:46 INFO - 'other-e10s': {'tests': ['a11yr', 21:48:46 INFO - 'ts_paint', 21:48:46 INFO - 'tpaint', 21:48:46 INFO - 'sessionrestore', 21:48:46 INFO - 'sessionrestore_no_auto_restore', 21:48:46 INFO - 'tabpaint']}, 21:48:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:48:46 INFO - 'ts_paint', 21:48:46 INFO - 'tpaint', 21:48:46 INFO - 'sessionrestore', 21:48:46 INFO - 'sessionrestore_no_auto_restore', 21:48:46 INFO - 'tabpaint']}, 21:48:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:48:46 INFO - 'ts_paint', 21:48:46 INFO - 'tpaint', 21:48:46 INFO - 'sessionrestore', 21:48:46 INFO - 'sessionrestore_no_auto_restore', 21:48:46 INFO - 'tabpaint']}, 21:48:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['a11yr', 21:48:46 INFO - 'ts_paint', 21:48:46 INFO - 'tpaint', 21:48:46 INFO - 'sessionrestore', 21:48:46 INFO - 'sessionrestore_no_auto_restore', 21:48:46 INFO - 'tabpaint']}, 21:48:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['a11yr', 21:48:46 INFO - 'ts_paint', 21:48:46 INFO - 'tpaint', 21:48:46 INFO - 'sessionrestore', 21:48:46 INFO - 'sessionrestore_no_auto_restore', 21:48:46 INFO - 'tabpaint']}, 21:48:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['tsvgx', 21:48:46 INFO - 'tsvgr_opacity', 21:48:46 INFO - 'tart', 21:48:46 INFO - 'tscrollx', 21:48:46 INFO - 'cart']}, 21:48:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:48:46 INFO - 'tsvgr_opacity', 21:48:46 INFO - 'tart', 21:48:46 INFO - 'tscrollx', 21:48:46 INFO - 'cart']}, 21:48:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:46 INFO - 'talos_options': ['--disable-e10s'], 21:48:46 INFO - 'tests': ['tp5o']}, 21:48:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:46 INFO - 'tests': ['tp5o']}, 21:48:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:46 INFO - 'talos_options': ['--disable-e10s', 21:48:46 INFO - '--xperf_path', 21:48:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:46 INFO - 'tests': ['tp5n']}, 21:48:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:46 INFO - 'talos_options': ['--xperf_path', 21:48:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:46 INFO - 'tests': ['tp5n']}}, 21:48:46 INFO - 'talos.zip': {'path': '', 21:48:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:48:46 INFO - Running post-action listener: _resource_record_post_action 21:48:46 INFO - ##### 21:48:46 INFO - ##### Finished populate-webroot step (success) 21:48:46 INFO - ##### 21:48:46 INFO - ##### 21:48:46 INFO - ##### Running create-virtualenv step. 21:48:46 INFO - ##### 21:48:46 INFO - Running pre-action listener: _resource_record_pre_action 21:48:46 INFO - Running main action method: create_virtualenv 21:48:46 INFO - Creating virtualenv /builds/slave/test/build/venv 21:48:46 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 21:48:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:48:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:48:46 INFO - Using real prefix '/usr' 21:48:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:48:47 INFO - Installing distribute.............................................................................................................................................................................................done. 21:48:49 INFO - Installing pip.................done. 21:48:49 INFO - Return code: 0 21:48:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:48:49 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')]} 21:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:49 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')]} 21:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:49 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 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:48:49 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 21:48:49 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 21:48:49 INFO - Using env: {'DISPLAY': ':0', 21:48:49 INFO - 'HOME': '/home/cltbld', 21:48:49 INFO - 'LANG': 'en_US.UTF-8', 21:48:49 INFO - 'LANGUAGE': 'en_US:en', 21:48:49 INFO - 'LOGNAME': 'cltbld', 21:48:49 INFO - 'MAIL': '/var/mail/cltbld', 21:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:49 INFO - 'MOZ_NO_REMOTE': '1', 21:48:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:49 INFO - 'NO_EM_RESTART': '1', 21:48:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:49 INFO - 'PWD': '/builds/slave/test', 21:48:49 INFO - 'SHELL': '/bin/bash', 21:48:49 INFO - 'SHLVL': '1', 21:48:49 INFO - 'TERM': 'linux', 21:48:49 INFO - 'TMOUT': '86400', 21:48:49 INFO - 'USER': 'cltbld', 21:48:49 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:49 INFO - '_': '/tools/buildbot/bin/python'} 21:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:49 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 21:48:49 INFO - Cleaning up... 21:48:49 INFO - Return code: 0 21:48:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:48:49 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')]} 21:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:49 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')]} 21:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:49 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 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:48:49 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 21:48:49 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 21:48:49 INFO - Using env: {'DISPLAY': ':0', 21:48:49 INFO - 'HOME': '/home/cltbld', 21:48:49 INFO - 'LANG': 'en_US.UTF-8', 21:48:49 INFO - 'LANGUAGE': 'en_US:en', 21:48:49 INFO - 'LOGNAME': 'cltbld', 21:48:49 INFO - 'MAIL': '/var/mail/cltbld', 21:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:49 INFO - 'MOZ_NO_REMOTE': '1', 21:48:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:49 INFO - 'NO_EM_RESTART': '1', 21:48:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:49 INFO - 'PWD': '/builds/slave/test', 21:48:49 INFO - 'SHELL': '/bin/bash', 21:48:49 INFO - 'SHLVL': '1', 21:48:49 INFO - 'TERM': 'linux', 21:48:49 INFO - 'TMOUT': '86400', 21:48:49 INFO - 'USER': 'cltbld', 21:48:49 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:49 INFO - '_': '/tools/buildbot/bin/python'} 21:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:49 INFO - Downloading/unpacking psutil>=3.1.1 21:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:48:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:51 INFO - Installing collected packages: psutil 21:48:51 INFO - Running setup.py install for psutil 21:48:51 INFO - building 'psutil._psutil_linux' extension 21:48:51 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 21:48:51 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 21:48:51 INFO - building 'psutil._psutil_posix' extension 21:48:51 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 21:48:52 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 21:48:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:52 INFO - Successfully installed psutil 21:48:52 INFO - Cleaning up... 21:48:52 INFO - Return code: 0 21:48:52 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 21:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 21:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 21:48:52 INFO - Using env: {'DISPLAY': ':0', 21:48:52 INFO - 'HOME': '/home/cltbld', 21:48:52 INFO - 'LANG': 'en_US.UTF-8', 21:48:52 INFO - 'LANGUAGE': 'en_US:en', 21:48:52 INFO - 'LOGNAME': 'cltbld', 21:48:52 INFO - 'MAIL': '/var/mail/cltbld', 21:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:52 INFO - 'MOZ_NO_REMOTE': '1', 21:48:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:52 INFO - 'NO_EM_RESTART': '1', 21:48:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:52 INFO - 'PWD': '/builds/slave/test', 21:48:52 INFO - 'SHELL': '/bin/bash', 21:48:52 INFO - 'SHLVL': '1', 21:48:52 INFO - 'TERM': 'linux', 21:48:52 INFO - 'TMOUT': '86400', 21:48:52 INFO - 'USER': 'cltbld', 21:48:52 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:52 INFO - '_': '/tools/buildbot/bin/python'} 21:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:52 INFO - Downloading/unpacking mozsystemmonitor==0.1 21:48:52 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 21:48:52 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 21:48:52 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 21:48:52 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 21:48:52 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 21:48:52 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 21:48:54 INFO - Downloading mozsystemmonitor-0.1.tar.gz 21:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:48:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 21:48:54 INFO - Installing collected packages: mozsystemmonitor 21:48:54 INFO - Running setup.py install for mozsystemmonitor 21:48:54 INFO - Successfully installed mozsystemmonitor 21:48:54 INFO - Cleaning up... 21:48:54 INFO - Return code: 0 21:48:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:54 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 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:48:54 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 21:48:54 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 21:48:54 INFO - Using env: {'DISPLAY': ':0', 21:48:54 INFO - 'HOME': '/home/cltbld', 21:48:54 INFO - 'LANG': 'en_US.UTF-8', 21:48:54 INFO - 'LANGUAGE': 'en_US:en', 21:48:54 INFO - 'LOGNAME': 'cltbld', 21:48:54 INFO - 'MAIL': '/var/mail/cltbld', 21:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:54 INFO - 'MOZ_NO_REMOTE': '1', 21:48:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:54 INFO - 'NO_EM_RESTART': '1', 21:48:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:54 INFO - 'PWD': '/builds/slave/test', 21:48:54 INFO - 'SHELL': '/bin/bash', 21:48:54 INFO - 'SHLVL': '1', 21:48:54 INFO - 'TERM': 'linux', 21:48:54 INFO - 'TMOUT': '86400', 21:48:54 INFO - 'USER': 'cltbld', 21:48:54 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:54 INFO - '_': '/tools/buildbot/bin/python'} 21:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:54 INFO - Downloading/unpacking blobuploader==1.2.4 21:48:54 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 21:48:54 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 21:48:54 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 21:48:54 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 21:48:54 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 21:48:54 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 21:48:56 INFO - Downloading blobuploader-1.2.4.tar.gz 21:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:48:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:48:56 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 21:48:56 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 21:48:56 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 21:48:56 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 21:48:56 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 21:48:56 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 21:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:48:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:48:57 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 21:48:57 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 21:48:57 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 21:48:57 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 21:48:57 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 21:48:57 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 21:48:57 INFO - Downloading docopt-0.6.1.tar.gz 21:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:48:57 INFO - Installing collected packages: blobuploader, requests, docopt 21:48:57 INFO - Running setup.py install for blobuploader 21:48:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:48:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:48:57 INFO - Running setup.py install for requests 21:48:57 INFO - Running setup.py install for docopt 21:48:57 INFO - Successfully installed blobuploader requests docopt 21:48:57 INFO - Cleaning up... 21:48:58 INFO - Return code: 0 21:48:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:58 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 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:48:58 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 21:48:58 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 21:48:58 INFO - Using env: {'DISPLAY': ':0', 21:48:58 INFO - 'HOME': '/home/cltbld', 21:48:58 INFO - 'LANG': 'en_US.UTF-8', 21:48:58 INFO - 'LANGUAGE': 'en_US:en', 21:48:58 INFO - 'LOGNAME': 'cltbld', 21:48:58 INFO - 'MAIL': '/var/mail/cltbld', 21:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:58 INFO - 'MOZ_NO_REMOTE': '1', 21:48:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:58 INFO - 'NO_EM_RESTART': '1', 21:48:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:58 INFO - 'PWD': '/builds/slave/test', 21:48:58 INFO - 'SHELL': '/bin/bash', 21:48:58 INFO - 'SHLVL': '1', 21:48:58 INFO - 'TERM': 'linux', 21:48:58 INFO - 'TMOUT': '86400', 21:48:58 INFO - 'USER': 'cltbld', 21:48:58 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:58 INFO - '_': '/tools/buildbot/bin/python'} 21:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:58 INFO - Running setup.py (path:/tmp/pip-W7MtPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:58 INFO - Running setup.py (path:/tmp/pip-uevEho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:58 INFO - Running setup.py (path:/tmp/pip-DGJg16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:58 INFO - Running setup.py (path:/tmp/pip-Wav0lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:58 INFO - Running setup.py (path:/tmp/pip-N8V8mi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:58 INFO - Running setup.py (path:/tmp/pip-AQanHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:58 INFO - Running setup.py (path:/tmp/pip-J38Nax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:58 INFO - Running setup.py (path:/tmp/pip-cZDXZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:58 INFO - Running setup.py (path:/tmp/pip-pM_Iye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:58 INFO - Running setup.py (path:/tmp/pip-Url760-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:58 INFO - Running setup.py (path:/tmp/pip-0LU2o5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:59 INFO - Running setup.py (path:/tmp/pip-TDM6lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:59 INFO - Running setup.py (path:/tmp/pip-YK1364-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:59 INFO - Running setup.py (path:/tmp/pip-mBXUEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:59 INFO - Running setup.py (path:/tmp/pip-TZSldc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:59 INFO - Running setup.py (path:/tmp/pip-u6rPGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:59 INFO - Running setup.py (path:/tmp/pip-URccn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:48:59 INFO - Running setup.py install for manifestparser 21:48:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Running setup.py install for mozcrash 21:48:59 INFO - Running setup.py install for mozdebug 21:48:59 INFO - Running setup.py install for mozdevice 21:48:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Running setup.py install for mozfile 21:48:59 INFO - Running setup.py install for mozhttpd 21:49:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Running setup.py install for mozinfo 21:49:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Running setup.py install for mozInstall 21:49:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Running setup.py install for mozleak 21:49:00 INFO - Running setup.py install for mozlog 21:49:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Running setup.py install for moznetwork 21:49:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Running setup.py install for mozprocess 21:49:00 INFO - Running setup.py install for mozprofile 21:49:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Running setup.py install for mozrunner 21:49:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Running setup.py install for mozscreenshot 21:49:01 INFO - Running setup.py install for moztest 21:49:01 INFO - Running setup.py install for mozversion 21:49:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:49:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:49:01 INFO - Cleaning up... 21:49:01 INFO - Return code: 0 21:49:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:01 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')]} 21:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:01 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')]} 21:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:01 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 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:49:01 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 21:49:01 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 21:49:01 INFO - Using env: {'DISPLAY': ':0', 21:49:01 INFO - 'HOME': '/home/cltbld', 21:49:01 INFO - 'LANG': 'en_US.UTF-8', 21:49:01 INFO - 'LANGUAGE': 'en_US:en', 21:49:01 INFO - 'LOGNAME': 'cltbld', 21:49:01 INFO - 'MAIL': '/var/mail/cltbld', 21:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:01 INFO - 'MOZ_NO_REMOTE': '1', 21:49:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:01 INFO - 'NO_EM_RESTART': '1', 21:49:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:01 INFO - 'PWD': '/builds/slave/test', 21:49:01 INFO - 'SHELL': '/bin/bash', 21:49:01 INFO - 'SHLVL': '1', 21:49:01 INFO - 'TERM': 'linux', 21:49:01 INFO - 'TMOUT': '86400', 21:49:01 INFO - 'USER': 'cltbld', 21:49:01 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:01 INFO - '_': '/tools/buildbot/bin/python'} 21:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:49:01 INFO - Running setup.py (path:/tmp/pip-apyXdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:49:01 INFO - Running setup.py (path:/tmp/pip-pPq6ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:49:01 INFO - Running setup.py (path:/tmp/pip-O8BV02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:49:01 INFO - Running setup.py (path:/tmp/pip-EbE23L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:49:01 INFO - Running setup.py (path:/tmp/pip-MrTj5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:49:01 INFO - Running setup.py (path:/tmp/pip-G6Owtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:49:01 INFO - Running setup.py (path:/tmp/pip-FXLJzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:49:01 INFO - Running setup.py (path:/tmp/pip-dvyJJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:49:02 INFO - Running setup.py (path:/tmp/pip-4iIb_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:49:02 INFO - Running setup.py (path:/tmp/pip-SILMwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:49:02 INFO - Running setup.py (path:/tmp/pip-MGOSwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:49:02 INFO - Running setup.py (path:/tmp/pip-KeXqD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:49:02 INFO - Running setup.py (path:/tmp/pip-nXkKnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:49:02 INFO - Running setup.py (path:/tmp/pip-562RlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:02 INFO - Running setup.py (path:/tmp/pip-t5akYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:49:02 INFO - Running setup.py (path:/tmp/pip-GwZeZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:49:02 INFO - Running setup.py (path:/tmp/pip-MPSiNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:49:02 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)) 21:49:02 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)) 21:49:02 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)) 21:49:02 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)) 21:49:02 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)) 21:49:02 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)) 21:49:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:49:02 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 21:49:02 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 21:49:02 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 21:49:02 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 21:49:02 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 21:49:02 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 21:49:04 INFO - Downloading blessings-1.6.tar.gz 21:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:49:04 INFO - Installing collected packages: blessings 21:49:04 INFO - Running setup.py install for blessings 21:49:04 INFO - Successfully installed blessings 21:49:04 INFO - Cleaning up... 21:49:04 INFO - Return code: 0 21:49:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:49:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:49:05 INFO - Reading from file tmpfile_stdout 21:49:05 INFO - Current package versions: 21:49:05 INFO - argparse == 1.2.1 21:49:05 INFO - blessings == 1.6 21:49:05 INFO - blobuploader == 1.2.4 21:49:05 INFO - docopt == 0.6.1 21:49:05 INFO - manifestparser == 1.1 21:49:05 INFO - mozInstall == 1.12 21:49:05 INFO - mozcrash == 0.17 21:49:05 INFO - mozdebug == 0.1 21:49:05 INFO - mozdevice == 0.48 21:49:05 INFO - mozfile == 1.2 21:49:05 INFO - mozhttpd == 0.7 21:49:05 INFO - mozinfo == 0.9 21:49:05 INFO - mozleak == 0.1 21:49:05 INFO - mozlog == 3.2 21:49:05 INFO - moznetwork == 0.27 21:49:05 INFO - mozprocess == 0.22 21:49:05 INFO - mozprofile == 0.28 21:49:05 INFO - mozrunner == 6.11 21:49:05 INFO - mozscreenshot == 0.1 21:49:05 INFO - mozsystemmonitor == 0.1 21:49:05 INFO - moztest == 0.7 21:49:05 INFO - mozversion == 1.4 21:49:05 INFO - psutil == 3.1.1 21:49:05 INFO - requests == 1.2.3 21:49:05 INFO - wsgiref == 0.1.2 21:49:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:05 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')]} 21:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:05 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')]} 21:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:05 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 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:49:05 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 21:49:05 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 21:49:05 INFO - Using env: {'DISPLAY': ':0', 21:49:05 INFO - 'HOME': '/home/cltbld', 21:49:05 INFO - 'LANG': 'en_US.UTF-8', 21:49:05 INFO - 'LANGUAGE': 'en_US:en', 21:49:05 INFO - 'LOGNAME': 'cltbld', 21:49:05 INFO - 'MAIL': '/var/mail/cltbld', 21:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:05 INFO - 'MOZ_NO_REMOTE': '1', 21:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:05 INFO - 'NO_EM_RESTART': '1', 21:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:05 INFO - 'PWD': '/builds/slave/test', 21:49:05 INFO - 'SHELL': '/bin/bash', 21:49:05 INFO - 'SHLVL': '1', 21:49:05 INFO - 'TERM': 'linux', 21:49:05 INFO - 'TMOUT': '86400', 21:49:05 INFO - 'USER': 'cltbld', 21:49:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:05 INFO - '_': '/tools/buildbot/bin/python'} 21:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 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)) 21:49:05 INFO - Cleaning up... 21:49:05 INFO - Return code: 0 21:49:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:49:05 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')]} 21:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:05 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')]} 21:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:05 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 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:49:05 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 21:49:05 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 21:49:05 INFO - Using env: {'DISPLAY': ':0', 21:49:05 INFO - 'HOME': '/home/cltbld', 21:49:05 INFO - 'LANG': 'en_US.UTF-8', 21:49:05 INFO - 'LANGUAGE': 'en_US:en', 21:49:05 INFO - 'LOGNAME': 'cltbld', 21:49:05 INFO - 'MAIL': '/var/mail/cltbld', 21:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:05 INFO - 'MOZ_NO_REMOTE': '1', 21:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:05 INFO - 'NO_EM_RESTART': '1', 21:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:05 INFO - 'PWD': '/builds/slave/test', 21:49:05 INFO - 'SHELL': '/bin/bash', 21:49:05 INFO - 'SHLVL': '1', 21:49:05 INFO - 'TERM': 'linux', 21:49:05 INFO - 'TMOUT': '86400', 21:49:05 INFO - 'USER': 'cltbld', 21:49:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:05 INFO - '_': '/tools/buildbot/bin/python'} 21:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:05 INFO - Downloading/unpacking jsonschema 21:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:49:07 INFO - Downloading/unpacking functools32 (from jsonschema) 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:07 INFO - Downloading functools32-3.2.3-2.tar.gz 21:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:49:07 INFO - warning: no files found matching '*.txt' 21:49:07 INFO - no previously-included directories found matching 'build' 21:49:07 INFO - no previously-included directories found matching 'dist' 21:49:07 INFO - no previously-included directories found matching '.git*' 21:49:07 INFO - Installing collected packages: jsonschema, functools32 21:49:07 INFO - Running setup.py install for functools32 21:49:07 INFO - warning: no files found matching '*.txt' 21:49:07 INFO - no previously-included directories found matching 'build' 21:49:07 INFO - no previously-included directories found matching 'dist' 21:49:07 INFO - no previously-included directories found matching '.git*' 21:49:07 INFO - Successfully installed jsonschema functools32 21:49:07 INFO - Cleaning up... 21:49:07 INFO - Return code: 0 21:49:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc54ce6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', '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 21:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 21:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:49:07 INFO - Using env: {'DISPLAY': ':0', 21:49:07 INFO - 'HOME': '/home/cltbld', 21:49:07 INFO - 'LANG': 'en_US.UTF-8', 21:49:07 INFO - 'LANGUAGE': 'en_US:en', 21:49:07 INFO - 'LOGNAME': 'cltbld', 21:49:07 INFO - 'MAIL': '/var/mail/cltbld', 21:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:07 INFO - 'MOZ_NO_REMOTE': '1', 21:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:07 INFO - 'NO_EM_RESTART': '1', 21:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:07 INFO - 'PWD': '/builds/slave/test', 21:49:07 INFO - 'SHELL': '/bin/bash', 21:49:07 INFO - 'SHLVL': '1', 21:49:07 INFO - 'TERM': 'linux', 21:49:07 INFO - 'TMOUT': '86400', 21:49:07 INFO - 'USER': 'cltbld', 21:49:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:07 INFO - '_': '/tools/buildbot/bin/python'} 21:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:08 INFO - Downloading/unpacking flake8 21:49:08 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 21:49:08 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 21:49:08 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 21:49:08 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 21:49:08 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 21:49:08 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 21:49:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:49:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:49:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:49:10 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 21:49:10 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 21:49:10 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 21:49:10 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 21:49:10 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 21:49:10 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 21:49:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:49:10 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 21:49:10 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 21:49:10 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 21:49:10 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 21:49:10 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 21:49:10 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 21:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:49:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:49:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:49:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:49:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:49:10 INFO - no previously-included directories found matching 'docs/_build' 21:49:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:49:10 INFO - Running setup.py install for pep8 21:49:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:49:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:49:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:49:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:49:10 INFO - no previously-included directories found matching 'docs/_build' 21:49:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:49:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:49:10 INFO - Cleaning up... 21:49:10 INFO - Return code: 0 21:49:10 INFO - Running post-action listener: _resource_record_post_action 21:49:10 INFO - Running post-action listener: _start_resource_monitoring 21:49:10 INFO - Starting resource monitoring. 21:49:10 INFO - ##### 21:49:10 INFO - ##### Finished create-virtualenv step (success) 21:49:10 INFO - ##### 21:49:10 INFO - ##### 21:49:10 INFO - ##### Running install step. 21:49:10 INFO - ##### 21:49:10 INFO - Running pre-action listener: _resource_record_pre_action 21:49:10 INFO - Running main action method: install 21:49:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:49:11 INFO - Reading from file tmpfile_stdout 21:49:11 INFO - Detecting whether we're running mozinstall >=1.0... 21:49:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:49:11 INFO - Reading from file tmpfile_stdout 21:49:11 INFO - Output received: 21:49:11 INFO - Usage: mozinstall [options] installer 21:49:11 INFO - Options: 21:49:11 INFO - -h, --help show this help message and exit 21:49:11 INFO - -d DEST, --destination=DEST 21:49:11 INFO - Directory to install application into. [default: 21:49:11 INFO - "/builds/slave/test"] 21:49:11 INFO - --app=APP Application being installed. [default: firefox] 21:49:11 INFO - mkdir: /builds/slave/test/build/application 21:49:11 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'] 21:49:11 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 21:49:26 INFO - Reading from file tmpfile_stdout 21:49:26 INFO - Output received: 21:49:26 INFO - /builds/slave/test/build/application/firefox/firefox 21:49:26 INFO - Running post-action listener: _resource_record_post_action 21:49:26 INFO - ##### 21:49:26 INFO - ##### Finished install step (success) 21:49:26 INFO - ##### 21:49:26 INFO - ##### 21:49:26 INFO - ##### Running run-tests step. 21:49:26 INFO - ##### 21:49:26 INFO - Running pre-action listener: _resource_record_pre_action 21:49:26 INFO - Running main action method: run_tests 21:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:49:26 INFO - Python 2.7.3 21:49:26 INFO - Return code: 0 21:49:26 INFO - grabbing minidump binary from tooltool 21:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:49:26 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 0x14da9c0>, '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 0x14db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14dbd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:49:26 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 21:49:26 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 21:49:26 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 21:49:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:49:27 INFO - Return code: 0 21:49:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:49:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:49:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:49:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:49:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:49:27 INFO - Return code: 0 21:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 21:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 21:49:27 INFO - Using env: {'DISPLAY': ':0', 21:49:27 INFO - 'HOME': '/home/cltbld', 21:49:27 INFO - 'LANG': 'en_US.UTF-8', 21:49:27 INFO - 'LANGUAGE': 'en_US:en', 21:49:27 INFO - 'LOGNAME': 'cltbld', 21:49:27 INFO - 'MAIL': '/var/mail/cltbld', 21:49:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:49:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:27 INFO - 'MOZ_NO_REMOTE': '1', 21:49:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:49:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:27 INFO - 'NO_EM_RESTART': '1', 21:49:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:27 INFO - 'PWD': '/builds/slave/test', 21:49:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:49:27 INFO - 'SHELL': '/bin/bash', 21:49:27 INFO - 'SHLVL': '1', 21:49:27 INFO - 'TERM': 'linux', 21:49:27 INFO - 'TMOUT': '86400', 21:49:27 INFO - 'USER': 'cltbld', 21:49:27 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439', 21:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:27 INFO - '_': '/tools/buildbot/bin/python'} 21:49:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 21:49:28 INFO - mozversion application_buildid: 20160513203440 21:49:28 INFO - mozversion application_changeset: 5a396ec2aeefb4db7298f2406f5132390eada6a7 21:49:28 INFO - mozversion application_display_name: Nightly 21:49:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:49:28 INFO - mozversion application_name: Firefox 21:49:28 INFO - mozversion application_remotingname: firefox 21:49:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:49:28 INFO - mozversion application_vendor: Mozilla 21:49:28 INFO - mozversion application_version: 49.0a1 21:49:28 INFO - mozversion platform_buildid: 20160513203440 21:49:28 INFO - mozversion platform_changeset: 5a396ec2aeefb4db7298f2406f5132390eada6a7 21:49:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:49:28 INFO - mozversion platform_version: 49.0a1 21:49:28 INFO - using testdate: 1463201368 21:49:28 INFO - actual date: 1463201368 21:49:28 INFO - starting webserver on 'localhost:33148' 21:49:28 INFO - SUITE-START | Running 2 tests 21:49:28 INFO - TEST-START | dromaeo_css 21:49:28 INFO - operating with platform_type : linux_ 21:49:28 INFO - Initialising browser for dromaeo_css test... 21:49:28 INFO - TEST-INFO | started process 6246 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwcybrk/profile http://localhost:33148/getInfo.html) 21:49:32 INFO - PROCESS | 6246 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 21:49:32 INFO - PROCESS | 6246 | __metrics Screen width/height:1600/1200 21:49:32 INFO - PROCESS | 6246 | colorDepth:24 21:49:32 INFO - PROCESS | 6246 | Browser inner width/height: 1024/697 21:49:32 INFO - PROCESS | 6246 | __metrics 21:49:33 ERROR - PROCESS | 6246 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:49:34 INFO - TEST-INFO | 6246: exit 0 21:49:34 INFO - Browser initialized. 21:49:34 INFO - Running cycle 1/1 for dromaeo_css test... 21:49:34 INFO - TEST-INFO | started process 6326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwcybrk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:46 INFO - PROCESS | 6326 | RSS: Main: 153907200 21:49:46 INFO - PROCESS | 6326 | 21:50:37 INFO - PROCESS | 6326 | 1463201437977 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] 21:52:49 INFO - PROCESS | 6326 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33148/tests/dromaeo/cssquery-ext.html) 21:52:50 INFO - PROCESS | 6326 | RSS: Main: 240533504 21:52:50 INFO - PROCESS | 6326 | 21:55:53 INFO - PROCESS | 6326 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-ext.html (next: http://localhost:33148/tests/dromaeo/cssquery-jquery.html) 21:55:54 INFO - PROCESS | 6326 | RSS: Main: 228368384 21:55:54 INFO - PROCESS | 6326 | 21:58:56 INFO - PROCESS | 6326 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33148/tests/dromaeo/cssquery-mootools.html) 21:58:56 INFO - PROCESS | 6326 | RSS: Main: 232083456 21:58:56 INFO - PROCESS | 6326 | 22:01:59 INFO - PROCESS | 6326 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33148/tests/dromaeo/cssquery-prototype.html) 22:01:59 INFO - PROCESS | 6326 | RSS: Main: 233508864 22:01:59 INFO - PROCESS | 6326 | 22:05:02 INFO - PROCESS | 6326 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33148/tests/dromaeo/cssquery-yui.html) 22:05:03 INFO - PROCESS | 6326 | RSS: Main: 223395840 22:05:03 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-yui.html (next: http://localhost:33148/tests/dromaeo/cssquery-dojo.html) 22:08:06 INFO - PROCESS | 6326 | RSS: Main: 296398848 22:08:06 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | __start_tp_report 22:08:06 INFO - PROCESS | 6326 | _x_x_mozilla_page_load 22:08:06 INFO - PROCESS | 6326 | _x_x_mozilla_page_load_details 22:08:06 INFO - PROCESS | 6326 | |i|pagename|runs| 22:08:06 INFO - PROCESS | 6326 | |0;dojo.html;2693.4747724193785;2706.3910620110464;2725.8500782456963;2743.0810678422727;2765.598988146719;4668.229742092559;4707.199776038073;4741.146593613157;4797.208460603996;4826.372571565696;4690.390249267599;4735.242361222206;4752.429708434993;4763.0473905219005;4781.617470602352;4321.719088259269;4355.0418907840385;4420.690551661385;4452.487963884146;4536.77316134192;4439.911201775963;4471.061077173781;4485.058137791068;4491.39366185564;4518.774061296938;4241.915161696764;4282.957170428287;4304.74171549708;4330.588594083557;4351.0862718829085;4345.304751239818;4355.869323920288;4407;4407.1846708358735;4416.271315233015;4388.648908087345;4408.316710909814;4409.272470042439;4433.024734558392;4439.866803995886;4467.865964021084;4618.6997845140295;4655.580997710188;4682.484926658065;4733.24268117103;5108.616853735947;5117.104506711311;5154.304168937183;5165.535101840815;5169.198774190007;5001.099802035632;5122.667026643257;5154.561862241714;5158.484151584849;5211.03595834768;4743.1699452596;4784.736839473792;4795.448523419782;4845.685030473008;4863.392075990501;5281.287026251483;5348.625596208228;5350.143976963667;5352.705601191972;5357.276767636397;4882.145624515695;4966;4994.750262486861;5038.874028149252;5041.470645582161;5086.4659210783075;5104.2088476286235;5129.589632829365;5135.666181698178;5170.1986192140275;5067.113255180328;5084.661601867039;5085.084684756705;5087.3386459759995;5138.845834624967;4889.462159162489;4995.150824359867;5035.269885866602;5047.520485553867;5057.848264552069;4305.9569404306185;4323.329883868006;4328.09110086879;4357.346398040319;4372.40972468719;3741.2704522618374;3788.0340513169517;3799.2020484726263;3826.8086595670106;3829.1575853312784;4302.419173411612;4341.413909122291;4389.385486031958;4413;4420.712653677565;5146.922796158061;5166.328377311001;5173.32746742921;5173.534381905646;5220.321358223407;4421.602055814992;4429.069895322018;4437.0460351024685;4467.173572889026;4481.775911204427;4425.822967081345;4554.612857907115;4586.518415566317;4595.149897268949;4603.654725895572;4819.132556139788;4824.614030877591;4828.565429111256;4840.322354870251;4871.488493708252;5121.206213036985;5127.692338459704;5136.409312928911;5159.071367153798;5204.765785539736;4666.440027196758;4756.120117778222;4780.808767649394;4805.37530121082;4809.326694262876;5022.974885125551;5053.0399224147295;5055.646104772778;5061.190209566452;5076.949230507647;4983.551480366639;5102.132637451717;5121.564667003197;5130.7178105204575;5132.6150538709;4917.754112294299;4921.532454416824;4956.281339205867;4967.478414766398;4986.177280748636;4956.900861982812;4973.452920178705;4979.9004019918675;4989.25161225819;5028.346314979176;4695.319178718998;4855.320255164351;4913.877153071202;4934.851954441372;4981.203007518924;5022.397312322545;5027.195648696191;5039.042581909425;5048.873778155576;5097.898042039064;4851.36932198826;4884.853454396374;4884.877878053077;4889;4940.258961155855 22:08:06 INFO - PROCESS | 6326 | |1;ext.html;588.6968211371145;610.1671218786355;613.8680183760492;632.7342516143219;633.8700566383892;28598.142055738335;43697.12605747883;44309;44431.33352999708;44445.555544444505;43842.56157438425;44104;44198.55801441985;44312.3353149703;44360.77819610905;7564.735234266802;7732.6520306586335;7738.806529836771;7898.407619428552;8035.437519373646;7696.345810606105;9996.550155242996;10243.078122968931;10262.692119236435;10562.735931601734;10287.0741633253;10334.896651033507;10337;10356.119729822974;10356.430396328198;7670.4247181461615;7865.764027079197;10105.595776168944;10112.393256404639;10196.796064078713;7656.770296891103;9811.950940245253;10201.081902628799;10208.08127268542;10505.737356566071;40430.39354409686;40711;40725.389119163236;40747.38878916819;40937.38593921094;3221.6617255188103;3341.766076374655;3428.63999280077;3455.395305821471;3853.5183102112237;29809.701902981127;30357.241068973453;30773.230669233224;30875.38249235003;31975.360492790245;3432.262063656326;3531.205478767257;3546.8935931922083;3618.4391419330045;3651.7626354286886;254729;254767;255055;256196;256229;57588.13617795736;57826.42173578295;58040.4195958035;58046.41953580495;58110.709446453344;59693.403065968785;59839.70080149572;60107.69946150329;60152.39847601468;60153.0977035336;15694.450694225647;15867.761983570255;15901.840981590267;15922.203889805463;15938.601534961486;11274.323540587427;11545.595904143314;11553.422328883522;12028.45871935765;12311.138220324492;17817.821821781617;18229.45311640653;18308;18617.906910465634;18810.435686929413;6103.816885493442;6228.56400051992;6311.2328548006635;6338.271098823694;6443.355664433519;771.5332224004509;906.6146887572755;908.4458480326995;966.7389804752678;993.4287784523927;28724.42551148965;29456.410871782446;29467.557986630218;29999.550006749916;30083.398332033674;935.7005758157325;993.5975929748463;1010.5755582655303;1017.5115944346607;1036.2487196782272;14781.33483993223;15111.54665360041;15121.39514419411;15376.38494460209;15435;354157;355195;358239;358647;360530;52547.737261314214;52559.47440525546;52651.73674131605;52713.736431318364;52818.207726884124;1148.7300484386162;1222.0834374219335;1249.9625011249677;1281.0904257976754;1284.319310765296;27834.304142396602;29484.852575736983;29686.40627187401;29818.70181298246;30256.5461518073;44756.104877901606;45306.77346613246;45461;45500.31749523826;45511;294291;300449;300929;301879;302155;32938.83530582332;38028.2394352117;39279.6072039276;40617;42319;43750;44147.77926110349;44353.33469997888;44408.55591444044;45211;36694;36924;37119.772813631265;37406.6259337414;38352.42471362876;36612.533368992496;36858;37019.44470832994;37241.62758372381;37589.43615845819;36689.26621467502;37359;37565.81217093897;37835.43246851354;37893.05267368338;32659.34681306313;33889.66110338865;33926.49110263299;34305.485417719254;34806.47790283198;25522.617160742233;25813.3546661335;26043.739562604133;26067;26467.205983820517 22:08:06 INFO - PROCESS | 6326 | |2;jquery.html;370.2502432574038;371.25748502994014;379.79111488681235;383.6987964447909;386.47825435661844;16461.341546338146;17118.48644540666;17414.39049633263;17559.561010974732;17626.294948202056;16169;16338.18309084547;17101.144942752875;17143.399981000635;17878.731819022694;4605.240135377659;4797.2564252540915;4824.807007719687;4826.710397376169;4831.37192165018;3501.9649803501893;4695.624350051988;5115.795368185268;5390.838274851761;5399.730013499329;5407.3781515125675;5429.4570542945785;5449.536789372908;5463.754131064092;5464.590155738336;5342.946570534284;5360.142377219646;5416.1063424534905;5416.835380393234;5486.972565137189;3397.575303087114;5023.171176755831;5255.605829562798;5280.471952804689;5303.7348132593215;12653.051021173449;12671.36643167847;12732.23606583608;14481.493147739779;20979.370618881458;2034.8677335973114;2058.7941205879442;2085.113826623694;2119.6078725435846;2362.5274945010897;9054.954725226398;9179.219766319871;9286.535673216313;9422.199113075398;9481.09929556691;1774.095211442161;1796.5418818201406;1817.972730409045;1818.8995657627077;1880.990595047016;68776.6561167191;71221.64389178072;71658.64170679165;72218;72345.27654723437;10014.849777253346;10060.597576097021;10194.184465242763;10264.64073757415;10316.587336506605;14162.733962566146;14255.572332829825;14289.356978935974;14297.785533216802;14315.498957536436;7593.772186834404;7599.734009309647;7652.7704168875025;7667;7700.922990770133;7414.703411863465;7431.702731890664;7545.094588649397;7585;7631.38948884099;4230.555791641895;4337.306031035019;4418.911621767546;4426.048399594103;4428.977855110704;1670.2901266961735;1701.174930158872;1780.866435017379;1785;1803.6573051120245;864.2826454043129;869.7955980344614;871.9171678690515;912.0195789526285;913.9725808225763;16310.102944338176;17143.228554715068;17585.12074396275;17860.464186074438;17941.7308740369;849.609179777297;852.6418904060309;853.8719192121107;874.4272501511521;893.4773157702687;9730;9983.101520863158;10044.899551004542;10064.245181611408;10231;420183;428225;436250;436923;437701;48836.267455988185;60389.39610603838;78483;78491.03772405736;82522.58738706268;861.7199410191713;889.9555022248991;890.4657205676672;894.1639567004871;897.304588943574;14824.332905019515;15387.923060384626;15399.61500962485;15502.9224853875;15776.211189440712;34459.483107753906;34567.308653827284;35611.82194089013;36163;36245.81877090598;50558.2416263749;52024.47975520348;52230.21654675107;52338;52575.47424525706;7617.162112167768;7893.763187104396;7916.525008499509;7967.442279040411;8088.191180881865;7649.808754781175;8754.299656027375;11996.76006479848;11999.82000270014;12415.379231038592;6856.8285792853585;8039;9862.767154105737;10497.160227181654;10573.995470430296;10697.876722943978;10940.507677154388;11010.669679909615;11061.225714199929;11241.494132764208;10960.506777194885;11024.50389732448;11138.33170009802;11155.107591392507;11280.774384512428;6802.897956530557;7955.5624440654;8070.47541909774;8083.555404452812;8181.222783835526;8980.595873185592;10526.736831579272;10720.195985300978;10823.67528974132;10935.17986151026 22:08:06 INFO - PROCESS | 6326 | |3;mootools.html;1271.1991445389403;1463.8243410790703;1507.3066389460848;1641.0973964319628;1807.8824876383026;6769.932300676998;7743.109542402625;7969.322607578349;7979.521228726266;8196.877046844302;7886.250806173419;8048.396370272224;8060.5566693831815;8061.677532898691;8070.435069545134;1878.4552479780848;2175.0647221694744;2206.4263291544235;2229.398062523118;2267.138487374795;2292.5644127615783;2315.340128063502;2319.1651005637955;2319.524497478021;2321.3268152235833;2246.516998845248;2261.3555136786017;2313.2135074074813;2313.5257272259146;2315.617923042704;1802.648483545709;1859.9256029758794;1868.6636405447014;2146.302451703187;2220.100859152038;2200.3069033254474;2233.229535810143;2238.932832015042;2239.1715065425733;2241.529278851443;7985.081715602722;8049.3962952778775;8063.354931605516;8064.758057258292;8071.757847264592;4678.532146785328;4791.616670666338;4792.856214313576;4802.663813533054;4818.253170758538;2972.8513574321196;3098.055093196574;3123.8281894495794;3124.047165614487;3130.1705048162257;948.7296120605595;1001.864748258983;1006.9395836249848;1008.263967303865;1026.7946410717886;10974;11019.173561982883;11025;11025.44872756359;11064.17018723599;4403.691741578059;4458.041521072985;4498.245684183171;4520.254158063948;4532.750698711537;9303.767405814773;9312.48781317034;9323.160915517636;9362.859557106649;9427.292953028551;4899.951000489949;4901.215805471107;4903.583195428358;4907.214845624683;4912.336834527293;3795.981020094882;4217.7891105444605;4366.083122544238;4367.388565600819;4425.09285596452;3623.202895363016;3727.683146932487;3765.6046115157515;3765.6799172070682;3831.942520862189;1177.3877583656451;1188.287027783323;1190.1609365397546;1210.5823490895627;1224.3148124146383;846.5978660136386;853.38129855854;858.9828203435897;873.11815066783;900.8963969143501;5186.858891043964;5249.107651699221;5268.2624432578705;5348.090824559834;5417.702026388508;412.40819424125874;425.041531346811;426.1710972159183;427.7519038957397;428.82715772362565;3552.502649629054;3654.8720794772057;3710.8886733397503;3718.888433347004;3719.8698045568817;9213.81572368549;9438.48088355147;9493.620255189715;9495.192908602847;9496.050394960448;9094.590743416562;9255.490947997925;9258.074192580689;9284.535773211448;9286.582103805345;7238.638068096574;7284.016657751135;7300.926990730131;7308.817279568053;7315.817104572428;5077.974610126926;5094.439611642791;5095.668781529189;5157.922631160461;5174.068667639861;8686.956565217133;8712.607932642919;8713.607887645197;8720.127987201482;8755.124487551195;8685.783990241245;8933.687320943736;9043.547822608713;9057.63769449241;9060.09399060115;5939.524838013035;6103.084537319438;6145.108959200801;6825.385715285648;6864.48516361265;6800.557963732342;6844.657767111724;7385.113786345673;7632.236776322546;7650.655720492702;7048.154221493453;7096.716131354894;7142.142942846892;7222.566646001257;7243.7464688735645;6731.427828634621;6775.220849602359;6900.516963812485;7004.334588214111;7182.138143422822;5649.830505084854;5818.592698511064;5819.76720931158;6584.440322572635;6648.335166483313;4849.515048495122;4860.6111511079735;4890;4906.55840974314;5014.047331007097;4862.878428039327;4945.406551213878;4965.652404331807;4966.180580204225;5004.974975125101 22:08:06 INFO - PROCESS | 6326 | |4;prototype.html;301.8505839609394;307.9846007699615;314.5721818327076;315.4117570730588;318.5079052863325;4949.950500495003;4961.950380496199;4968.42863070747;4972.900541989158;4973.831149679827;4648.558386953243;4723.787429565669;4768.7138771673635;4848.781804818783;4915.852524424264;3822.0827001519574;3861.1119442528234;3861.9227615447676;3875.61243875613;3892.766434013954;3829.0427393151713;3852.9807350963206;3853.8265778040054;3854.9614503855023;3868.729188956774;3654.853805847763;3682.171511409938;3831.6934645228316;3838.2323535292912;3849.3840985442334;3794.31702293587;3815;3817.522809648794;3819.3698039823535;3828.349180639284;3389.932201355959;3406.403879321134;3413.7269018478464;3415.3681568909833;3427.280271143063;4416.646668266531;4690.577847993697;4710.976445117787;4715.221988371914;4749.905001899942;4330.17726631939;4360.193364227628;4362.8909277268;4367.694261401703;4377.518472967971;5160.483951604847;5162.716050617215;5167.767450464738;5176.430592634807;5185.637005409623;3544.7873127612424;3612.692921101683;3618.185908170667;3627.111357717376;3633.16437219441;5335.759890804923;5373.453520676335;5382.008656796971;5391.571233623093;5392.272043274186;3699.112213068844;3714.8669655755252;3715.696268313663;3724.366857634209;3760.341940160461;3768.9246215075545;3772.3398405278967;3777.2634336304704;3779.225258821935;3787.450819631138;4052.371882358239;4282.314829627245;4286.635635970915;4298.946758044237;4311.805968731414;5056.367954005749;5089.389273287229;5114.769835357343;5169.741512924414;5174.016936781999;1238.3065483329365;1270.3775150176532;1275.457930379585;1307.9346032698327;1326.1446367093172;785.9350579964181;804.8691588318422;873.2839071960931;885.831691978522;905.7463910105181;4517.299818528133;4538.863834084983;4548.204064288736;4574.313852922089;4579.977100114478;5300.363956325188;5308.177232528964;5320.547753441001;5328.706921119298;5329;4543.61379282758;4547.8180872764715;4556.157110934488;4561.885952851205;4569.246074397754;2799.650043744532;2811.5782632605274;2832.079574138413;2835.17779843843;2838.318803487148;4719.221328480831;4969.304297398367;4971.726555039435;4972.801087956514;4992.276119962585;5296.417394086648;5368;5375;5377.359905228943;5385.3268341457315;4914;4916.827911023097;4920.532549407801;4920.975395123001;4967.726775027409;5058.772355244097;5220.738963051908;5221.399539053057;5226.581873450191;5238.449962753856;5091.261767043832;5124.9743751281;5136.768845401891;5151.7166555839785;5152.330197074356;4946.950530494649;4951.059298733229;4957.801687932442;4967.279744437109;4967.503249675148;3583.7636015574585;3586.6234045424853;3686.741928065117;3705.0363307447096;3705.2959937611768;5209.7395130244095;5212.087884620252;5221.530062294411;5226.869328266824;5238;5025.623078269217;5033;5043.722595257149;5055.34280543527;5076.953827703348;4870.586000189882;5005.024020316149;5043.722595257296;5057.216131499624;5097.643164978416;4854.490278520704;4942.629302802232;5040.36995375578;5040.521150490697;5044.646874718881;5153.711572106973;5182.356997931007;5206.297149884717;5207.3230480037355;5222.216667499905;4880.950595621958;5030.496950304941;5073.7716802743225;5073.898522029465;5094.490550944876 22:08:06 INFO - PROCESS | 6326 | |5;yui.html;672.1396612336208;675.9290274521178;748.238998097022;764.0716529416759;783.2480818414322;12496.500139994401;13470.528531501399;13831.654208644788;13833.58499245023;13914.095583787046;13678.863211367883;13690.726185476284;13748.725025499483;13783.65540861479;13860.514881979132;688.2223087910679;842.4860834890742;866.9479831210148;874.8731433942074;877.4735158904638;863.5336918064238;866.1987661413168;868.274990383031;869.2133619074748;869.6086760957563;910.5993362920294;912.6303846942;914.5244472874101;915.1351972386097;915.1671978499567;714.4855703893163;735.8307589254501;736.5985537881867;737.2075019354162;904.882365292511;642.9774957876499;664.900264960255;665.986680266392;666.4967949198373;667.0094909060042;13350.799738003938;13363.599092027158;13368.197908125543;13370.732585348238;13382.531611393651;11600.6519804406;11604.94197529007;11805.763884722342;11962.342071186082;12198.268103913704;11473.426328683532;11541.422928853608;11685.649430517098;11693.239939403911;12159.756804863942;4180.540140584533;4205.15896820634;4222.0289333453175;4322.805473753657;4367.58507941745;46860.297095543596;46908.76545617284;47024.294635580496;47221.52778472206;47337.289940650575;9155;9276.582553785096;9389.671361502315;9547.761305967406;9617.422954622745;17108.82891171097;17169.399071032454;17174.74237886433;17276.13619319029;17314.91342543279;6826.52214345001;6831.351021652935;6841.247462779091;6906.309369063154;6996.550717322057;3672.540932383452;3698.4637227601847;3731.9067023324633;3798.620137986179;3924.3524818405212;2862.656481222267;2943.499605067144;2944.764418846487;2949.4248621519027;2951.055662188079;493.46212628235395;502.36200025966815;513.7919142747194;555.8832645144485;591.3820058039273;560.5627610463845;564.4073722591345;566.6741623566465;576.8183022347947;579.1399771339558;8181.263686268265;8270.172982701682;8346.707865224687;8363.247307742333;8427.789305267417;565.6888711208819;578.652808315016;579.1341943793971;591.6124938165508;592.3070008090544;3926.4294282287315;3955.129871428281;3961.8811435656976;4018.015586181404;4033.3143365627916;61716;62118;62193;63130.684346577975;63665.681671591345;46086;47713.5228647716;48986.51013489819;51060.48939510557;54239.45760542423;45671;46416.535834641225;46803;47101;47512;8286.627101780314;8528.403011789364;8553.272971797467;8619.482831030158;8733.126687331216;29194.7080529192;29992.700072999865;30861.69138308678;31114.533282000335;32427.35145297128;42796;42831;42996.140077197655;43020.13959720726;43243.48647797312;10944;11311.434428278717;11350.375729334966;11387.715307117389;11567.942160289145;11887.346195959306;12165.452554634885;12315.93842030784;12451.75096498083;12933.870661293644;11336.319820810448;11463.140264480031;11564;11796.115291353355;12098.395080246128;11242.887571124184;11504.884951150716;11703.122265829927;11915.165938384576;12199.14605977573;11013.779724405627;11337.603183888525;11725.196316817353;11848.052155827341;11888.405579721153;9146.607880521322;9157.49633770149;9253.398529095588;9509.429434233969;9521.809563808823;8910.331725120512;9005.279577633904;9249.398789078958;9758.268129890144;9821.214302855897 22:08:06 INFO - PROCESS | 6326 | __end_tp_report 22:08:06 INFO - PROCESS | 6326 | __start_cc_report 22:08:06 INFO - PROCESS | 6326 | _x_x_mozilla_cycle_collect,831 22:08:06 INFO - PROCESS | 6326 | __end_cc_report 22:08:06 INFO - PROCESS | 6326 | __startTimestamp1463202486309__endTimestamp 22:08:06 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | ------- Summary: start ------- 22:08:06 INFO - PROCESS | 6326 | Number of tests: 6 22:08:06 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | [#0] dojo.html Cycles:165 Average:4721.51 Median:4840.32 stddev:491.74 (10.2%) stddev-sans-first:466.81 22:08:06 INFO - PROCESS | 6326 | Values: 2693.5 2706.4 2725.9 2743.1 2765.6 4668.2 4707.2 4741.1 4797.2 4826.4 4690.4 4735.2 4752.4 4763.0 4781.6 4321.7 4355.0 4420.7 4452.5 4536.8 4439.9 4471.1 4485.1 4491.4 4518.8 4241.9 4283.0 4304.7 4330.6 4351.1 4345.3 4355.9 4407.0 4407.2 4416.3 4388.6 4408.3 4409.3 4433.0 4439.9 4467.9 4618.7 4655.6 4682.5 4733.2 5108.6 5117.1 5154.3 5165.5 5169.2 5001.1 5122.7 5154.6 5158.5 5211.0 4743.2 4784.7 4795.4 4845.7 4863.4 5281.3 5348.6 5350.1 5352.7 5357.3 4882.1 4966.0 4994.8 5038.9 5041.5 5086.5 5104.2 5129.6 5135.7 5170.2 5067.1 5084.7 5085.1 5087.3 5138.8 4889.5 4995.2 5035.3 5047.5 5057.8 4306.0 4323.3 4328.1 4357.3 4372.4 3741.3 3788.0 3799.2 3826.8 3829.2 4302.4 4341.4 4389.4 4413.0 4420.7 5146.9 5166.3 5173.3 5173.5 5220.3 4421.6 4429.1 4437.0 4467.2 4481.8 4425.8 4554.6 4586.5 4595.1 4603.7 4819.1 4824.6 4828.6 4840.3 4871.5 5121.2 5127.7 5136.4 5159.1 5204.8 4666.4 4756.1 4780.8 4805.4 4809.3 5023.0 5053.0 5055.6 5061.2 5076.9 4983.6 5102.1 5121.6 5130.7 5132.6 4917.8 4921.5 4956.3 4967.5 4986.2 4956.9 4973.5 4979.9 4989.3 5028.3 4695.3 4855.3 4913.9 4934.9 4981.2 5022.4 5027.2 5039.0 5048.9 5097.9 4851.4 4884.9 4884.9 4889.0 4940.3 22:08:06 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | [#1] ext.html Cycles:180 Average:47887.21 Median:182074.22 stddev:80343.25 (44.1%) stddev-sans-first:80490.15 22:08:06 INFO - PROCESS | 6326 | Values: 588.7 610.2 613.9 632.7 633.9 28598.1 43697.1 44309.0 44431.3 44445.6 43842.6 44104.0 44198.6 44312.3 44360.8 7564.7 7732.7 7738.8 7898.4 8035.4 7696.3 9996.6 10243.1 10262.7 10562.7 10287.1 10334.9 10337.0 10356.1 10356.4 7670.4 7865.8 10105.6 10112.4 10196.8 7656.8 9812.0 10201.1 10208.1 10505.7 40430.4 40711.0 40725.4 40747.4 40937.4 3221.7 3341.8 3428.6 3455.4 3853.5 29809.7 30357.2 30773.2 30875.4 31975.4 3432.3 3531.2 3546.9 3618.4 3651.8 254729.0 254767.0 255055.0 256196.0 256229.0 57588.1 57826.4 58040.4 58046.4 58110.7 59693.4 59839.7 60107.7 60152.4 60153.1 15694.5 15867.8 15901.8 15922.2 15938.6 11274.3 11545.6 11553.4 12028.5 12311.1 17817.8 18229.5 18308.0 18617.9 18810.4 6103.8 6228.6 6311.2 6338.3 6443.4 771.5 906.6 908.4 966.7 993.4 28724.4 29456.4 29467.6 29999.6 30083.4 935.7 993.6 1010.6 1017.5 1036.2 14781.3 15111.5 15121.4 15376.4 15435.0 354157.0 355195.0 358239.0 358647.0 360530.0 52547.7 52559.5 52651.7 52713.7 52818.2 1148.7 1222.1 1250.0 1281.1 1284.3 27834.3 29484.9 29686.4 29818.7 30256.5 44756.1 45306.8 45461.0 45500.3 45511.0 294291.0 300449.0 300929.0 301879.0 302155.0 32938.8 38028.2 39279.6 40617.0 42319.0 43750.0 44147.8 44353.3 44408.6 45211.0 36694.0 36924.0 37119.8 37406.6 38352.4 36612.5 36858.0 37019.4 37241.6 37589.4 36689.3 37359.0 37565.8 37835.4 37893.1 32659.3 33889.7 33926.5 34305.5 34806.5 25522.6 25813.4 26043.7 26067.0 26467.2 22:08:06 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | [#2] jquery.html Cycles:180 Average:25195.28 Median:221059.96 stddev:71016.92 (32.1%) stddev-sans-first:71191.68 22:08:06 INFO - PROCESS | 6326 | Values: 370.3 371.3 379.8 383.7 386.5 16461.3 17118.5 17414.4 17559.6 17626.3 16169.0 16338.2 17101.1 17143.4 17878.7 4605.2 4797.3 4824.8 4826.7 4831.4 3502.0 4695.6 5115.8 5390.8 5399.7 5407.4 5429.5 5449.5 5463.8 5464.6 5342.9 5360.1 5416.1 5416.8 5487.0 3397.6 5023.2 5255.6 5280.5 5303.7 12653.1 12671.4 12732.2 14481.5 20979.4 2034.9 2058.8 2085.1 2119.6 2362.5 9055.0 9179.2 9286.5 9422.2 9481.1 1774.1 1796.5 1818.0 1818.9 1881.0 68776.7 71221.6 71658.6 72218.0 72345.3 10014.8 10060.6 10194.2 10264.6 10316.6 14162.7 14255.6 14289.4 14297.8 14315.5 7593.8 7599.7 7652.8 7667.0 7700.9 7414.7 7431.7 7545.1 7585.0 7631.4 4230.6 4337.3 4418.9 4426.0 4429.0 1670.3 1701.2 1780.9 1785.0 1803.7 864.3 869.8 871.9 912.0 914.0 16310.1 17143.2 17585.1 17860.5 17941.7 849.6 852.6 853.9 874.4 893.5 9730.0 9983.1 10044.9 10064.2 10231.0 420183.0 428225.0 436250.0 436923.0 437701.0 48836.3 60389.4 78483.0 78491.0 82522.6 861.7 890.0 890.5 894.2 897.3 14824.3 15387.9 15399.6 15502.9 15776.2 34459.5 34567.3 35611.8 36163.0 36245.8 50558.2 52024.5 52230.2 52338.0 52575.5 7617.2 7893.8 7916.5 7967.4 8088.2 7649.8 8754.3 11996.8 11999.8 12415.4 6856.8 8039.0 9862.8 10497.2 10574.0 10697.9 10940.5 11010.7 11061.2 11241.5 10960.5 11024.5 11138.3 11155.1 11280.8 6802.9 7955.6 8070.5 8083.6 8181.2 8980.6 10526.7 10720.2 10823.7 10935.2 22:08:06 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | [#3] mootools.html Cycles:180 Average:5188.07 Median:4965.92 stddev:2882.71 (58.0%) stddev-sans-first:2875.77 22:08:06 INFO - PROCESS | 6326 | Values: 1271.2 1463.8 1507.3 1641.1 1807.9 6769.9 7743.1 7969.3 7979.5 8196.9 7886.3 8048.4 8060.6 8061.7 8070.4 1878.5 2175.1 2206.4 2229.4 2267.1 2292.6 2315.3 2319.2 2319.5 2321.3 2246.5 2261.4 2313.2 2313.5 2315.6 1802.6 1859.9 1868.7 2146.3 2220.1 2200.3 2233.2 2238.9 2239.2 2241.5 7985.1 8049.4 8063.4 8064.8 8071.8 4678.5 4791.6 4792.9 4802.7 4818.3 2972.9 3098.1 3123.8 3124.0 3130.2 948.7 1001.9 1006.9 1008.3 1026.8 10974.0 11019.2 11025.0 11025.4 11064.2 4403.7 4458.0 4498.2 4520.3 4532.8 9303.8 9312.5 9323.2 9362.9 9427.3 4900.0 4901.2 4903.6 4907.2 4912.3 3796.0 4217.8 4366.1 4367.4 4425.1 3623.2 3727.7 3765.6 3765.7 3831.9 1177.4 1188.3 1190.2 1210.6 1224.3 846.6 853.4 859.0 873.1 900.9 5186.9 5249.1 5268.3 5348.1 5417.7 412.4 425.0 426.2 427.8 428.8 3552.5 3654.9 3710.9 3718.9 3719.9 9213.8 9438.5 9493.6 9495.2 9496.1 9094.6 9255.5 9258.1 9284.5 9286.6 7238.6 7284.0 7300.9 7308.8 7315.8 5078.0 5094.4 5095.7 5157.9 5174.1 8687.0 8712.6 8713.6 8720.1 8755.1 8685.8 8933.7 9043.5 9057.6 9060.1 5939.5 6103.1 6145.1 6825.4 6864.5 6800.6 6844.7 7385.1 7632.2 7650.7 7048.2 7096.7 7142.1 7222.6 7243.7 6731.4 6775.2 6900.5 7004.3 7182.1 5649.8 5818.6 5819.8 6584.4 6648.3 4849.5 4860.6 4890.0 4906.6 5014.0 4862.9 4945.4 4965.7 4966.2 5005.0 22:08:06 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | [#4] prototype.html Cycles:180 Average:4245.32 Median:4862.54 stddev:1232.43 (25.3%) stddev-sans-first:1199.82 22:08:06 INFO - PROCESS | 6326 | Values: 301.9 308.0 314.6 315.4 318.5 4950.0 4962.0 4968.4 4972.9 4973.8 4648.6 4723.8 4768.7 4848.8 4915.9 3822.1 3861.1 3861.9 3875.6 3892.8 3829.0 3853.0 3853.8 3855.0 3868.7 3654.9 3682.2 3831.7 3838.2 3849.4 3794.3 3815.0 3817.5 3819.4 3828.3 3389.9 3406.4 3413.7 3415.4 3427.3 4416.6 4690.6 4711.0 4715.2 4749.9 4330.2 4360.2 4362.9 4367.7 4377.5 5160.5 5162.7 5167.8 5176.4 5185.6 3544.8 3612.7 3618.2 3627.1 3633.2 5335.8 5373.5 5382.0 5391.6 5392.3 3699.1 3714.9 3715.7 3724.4 3760.3 3768.9 3772.3 3777.3 3779.2 3787.5 4052.4 4282.3 4286.6 4298.9 4311.8 5056.4 5089.4 5114.8 5169.7 5174.0 1238.3 1270.4 1275.5 1307.9 1326.1 785.9 804.9 873.3 885.8 905.7 4517.3 4538.9 4548.2 4574.3 4580.0 5300.4 5308.2 5320.5 5328.7 5329.0 4543.6 4547.8 4556.2 4561.9 4569.2 2799.7 2811.6 2832.1 2835.2 2838.3 4719.2 4969.3 4971.7 4972.8 4992.3 5296.4 5368.0 5375.0 5377.4 5385.3 4914.0 4916.8 4920.5 4921.0 4967.7 5058.8 5220.7 5221.4 5226.6 5238.4 5091.3 5125.0 5136.8 5151.7 5152.3 4947.0 4951.1 4957.8 4967.3 4967.5 3583.8 3586.6 3686.7 3705.0 3705.3 5209.7 5212.1 5221.5 5226.9 5238.0 5025.6 5033.0 5043.7 5055.3 5077.0 4870.6 5005.0 5043.7 5057.2 5097.6 4854.5 4942.6 5040.4 5040.5 5044.6 5153.7 5182.4 5206.3 5207.3 5222.2 4881.0 5030.5 5073.8 5073.9 5094.5 22:08:06 INFO - PROCESS | 6326 | 22:08:06 INFO - PROCESS | 6326 | [#5] yui.html Cycles:180 Average:13689.21 Median:46966.53 stddev:15989.61 (34.0%) stddev-sans-first:16004.58 22:08:06 INFO - PROCESS | 6326 | Values: 672.1 675.9 748.2 764.1 783.2 12496.5 13470.5 13831.7 13833.6 13914.1 13678.9 13690.7 13748.7 13783.7 13860.5 688.2 842.5 866.9 874.9 877.5 863.5 866.2 868.3 869.2 869.6 910.6 912.6 914.5 915.1 915.2 714.5 735.8 736.6 737.2 904.9 643.0 664.9 666.0 666.5 667.0 13350.8 13363.6 13368.2 13370.7 13382.5 11600.7 11604.9 11805.8 11962.3 12198.3 11473.4 11541.4 11685.6 11693.2 12159.8 4180.5 4205.2 4222.0 4322.8 4367.6 46860.3 46908.8 47024.3 47221.5 47337.3 9155.0 9276.6 9389.7 9547.8 9617.4 17108.8 17169.4 17174.7 17276.1 17314.9 6826.5 6831.4 6841.2 6906.3 6996.6 3672.5 3698.5 3731.9 3798.6 3924.4 2862.7 2943.5 2944.8 2949.4 2951.1 493.5 502.4 513.8 555.9 591.4 560.6 564.4 566.7 576.8 579.1 8181.3 8270.2 8346.7 8363.2 8427.8 565.7 578.7 579.1 591.6 592.3 3926.4 3955.1 3961.9 4018.0 4033.3 61716.0 62118.0 62193.0 63130.7 63665.7 46086.0 47713.5 48986.5 51060.5 54239.5 45671.0 46416.5 46803.0 47101.0 47512.0 8286.6 8528.4 8553.3 8619.5 8733.1 29194.7 29992.7 30861.7 31114.5 32427.4 42796.0 42831.0 42996.1 43020.1 43243.5 10944.0 11311.4 11350.4 11387.7 11567.9 11887.3 12165.5 12315.9 12451.8 12933.9 11336.3 11463.1 11564.0 11796.1 12098.4 11242.9 11504.9 11703.1 11915.2 12199.1 11013.8 11337.6 11725.2 11848.1 11888.4 9146.6 9157.5 9253.4 9509.4 9521.8 8910.3 9005.3 9249.4 9758.3 9821.2 22:08:06 INFO - PROCESS | 6326 | -------- Summary: end -------- 22:08:06 INFO - PROCESS | 6326 | 22:08:07 INFO - TEST-INFO | 6326: exit 0 22:08:07 INFO - TEST-OK | dromaeo_css | took 1118917ms 22:08:07 INFO - TEST-START | kraken 22:08:07 INFO - operating with platform_type : linux_ 22:08:07 INFO - Initialising browser for kraken test... 22:08:07 INFO - TEST-INFO | started process 6502 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPKXa5g/profile http://localhost:33148/getInfo.html) 22:08:10 INFO - PROCESS | 6502 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 22:08:11 INFO - PROCESS | 6502 | __metrics Screen width/height:1600/1200 22:08:11 INFO - PROCESS | 6502 | colorDepth:24 22:08:11 INFO - PROCESS | 6502 | Browser inner width/height: 1024/697 22:08:11 INFO - PROCESS | 6502 | __metrics 22:08:12 ERROR - PROCESS | 6502 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:08:13 INFO - TEST-INFO | 6502: exit 0 22:08:13 INFO - Browser initialized. 22:08:13 INFO - Running cycle 1/1 for kraken test... 22:08:13 INFO - TEST-INFO | started process 6586 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPKXa5g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:08:24 INFO - PROCESS | 6586 | RSS: Main: 154296320 22:08:24 INFO - PROCESS | 6586 | 22:08:27 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33148/tests/kraken/driver.html?testName=audio-beat-detection) 22:08:28 INFO - PROCESS | 6586 | RSS: Main: 209981440 22:08:28 INFO - PROCESS | 6586 | 22:08:31 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33148/tests/kraken/driver.html?testName=audio-dft) 22:08:32 INFO - PROCESS | 6586 | RSS: Main: 198025216 22:08:32 INFO - PROCESS | 6586 | 22:08:35 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33148/tests/kraken/driver.html?testName=audio-fft) 22:08:36 INFO - PROCESS | 6586 | RSS: Main: 267104256 22:08:36 INFO - PROCESS | 6586 | 22:08:38 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33148/tests/kraken/driver.html?testName=audio-oscillator) 22:08:38 INFO - PROCESS | 6586 | RSS: Main: 176730112 22:08:38 INFO - PROCESS | 6586 | 22:08:41 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33148/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:08:41 INFO - PROCESS | 6586 | RSS: Main: 180576256 22:08:41 INFO - PROCESS | 6586 | 22:08:45 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33148/tests/kraken/driver.html?testName=imaging-darkroom) 22:08:45 INFO - PROCESS | 6586 | RSS: Main: 216334336 22:08:45 INFO - PROCESS | 6586 | 22:08:49 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33148/tests/kraken/driver.html?testName=imaging-desaturate) 22:08:50 INFO - PROCESS | 6586 | RSS: Main: 214638592 22:08:50 INFO - PROCESS | 6586 | 22:08:53 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33148/tests/kraken/driver.html?testName=json-parse-financial) 22:08:53 INFO - PROCESS | 6586 | RSS: Main: 214507520 22:08:53 INFO - PROCESS | 6586 | 22:08:55 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33148/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:08:56 INFO - PROCESS | 6586 | RSS: Main: 207192064 22:08:56 INFO - PROCESS | 6586 | 22:08:58 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:08:58 INFO - PROCESS | 6586 | RSS: Main: 209801216 22:08:58 INFO - PROCESS | 6586 | 22:09:01 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:09:01 INFO - PROCESS | 6586 | RSS: Main: 214249472 22:09:01 INFO - PROCESS | 6586 | 22:09:04 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:09:05 INFO - PROCESS | 6586 | RSS: Main: 219533312 22:09:05 INFO - PROCESS | 6586 | 22:09:08 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:09:08 INFO - PROCESS | 6586 | RSS: Main: 218275840 22:09:08 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33148/tests/kraken/driver.html?testName=ai-astar) 22:09:10 INFO - PROCESS | 6586 | RSS: Main: 265170944 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | __start_tp_report 22:09:10 INFO - PROCESS | 6586 | _x_x_mozilla_page_load 22:09:10 INFO - PROCESS | 6586 | _x_x_mozilla_page_load_details 22:09:10 INFO - PROCESS | 6586 | |i|pagename|runs| 22:09:10 INFO - PROCESS | 6586 | |0;ai-astar;114;109;115;115;114;115;116;115;114;112 22:09:10 INFO - PROCESS | 6586 | |1;audio-beat-detection;157;129;155;213;131;154;157;132;209;129 22:09:10 INFO - PROCESS | 6586 | |2;audio-dft;161;162;161;161;162;162;162;165;161;163 22:09:10 INFO - PROCESS | 6586 | |3;audio-fft;76;74;76;143;74;76;76;75;132;75 22:09:10 INFO - PROCESS | 6586 | |4;audio-oscillator;93;92;156;92;94;93;135;93;92;127 22:09:10 INFO - PROCESS | 6586 | |5;imaging-gaussian-blur;117;117;117;116;118;116;117;117;117;117 22:09:10 INFO - PROCESS | 6586 | |6;imaging-darkroom;147;146;146;146;146;146;146;146;146;150 22:09:10 INFO - PROCESS | 6586 | |7;imaging-desaturate;94;95;95;94;95;95;94;95;95;94 22:09:10 INFO - PROCESS | 6586 | |8;json-parse-financial;65;75;66;64;65;63;64;63;71;62 22:09:10 INFO - PROCESS | 6586 | |9;json-stringify-tinderbox;63;63;64;64;64;64;63;64;64;64 22:09:10 INFO - PROCESS | 6586 | |10;stanford-crypto-aes;82;82;82;87;88;86;83;86;152;83 22:09:10 INFO - PROCESS | 6586 | |11;stanford-crypto-ccm;142;130;140;128;137;137;135;136;150;138 22:09:10 INFO - PROCESS | 6586 | |12;stanford-crypto-pbkdf2;171;170;166;170;174;172;162;163;165;161 22:09:10 INFO - PROCESS | 6586 | |13;stanford-crypto-sha256-iterative;65;66;66;65;65;64;66;63;65;67 22:09:10 INFO - PROCESS | 6586 | __end_tp_report 22:09:10 INFO - PROCESS | 6586 | __start_cc_report 22:09:10 INFO - PROCESS | 6586 | _x_x_mozilla_cycle_collect,2072 22:09:10 INFO - PROCESS | 6586 | __end_cc_report 22:09:10 INFO - PROCESS | 6586 | __startTimestamp1463202550907__endTimestamp 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | ------- Summary: start ------- 22:09:10 INFO - PROCESS | 6586 | Number of tests: 14 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:2.02 (1.8%) stddev-sans-first:2.15 22:09:10 INFO - PROCESS | 6586 | Values: 114.0 109.0 115.0 115.0 114.0 115.0 116.0 115.0 114.0 112.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#1] audio-beat-detection Cycles:10 Average:156.60 Median:156.00 stddev:31.13 (20.0%) stddev-sans-first:33.02 22:09:10 INFO - PROCESS | 6586 | Values: 157.0 129.0 155.0 213.0 131.0 154.0 157.0 132.0 209.0 129.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.27 22:09:10 INFO - PROCESS | 6586 | Values: 161.0 162.0 161.0 161.0 162.0 162.0 162.0 165.0 161.0 163.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#3] audio-fft Cycles:10 Average:87.70 Median:75.50 stddev:26.39 (34.9%) stddev-sans-first:27.65 22:09:10 INFO - PROCESS | 6586 | Values: 76.0 74.0 76.0 143.0 74.0 76.0 76.0 75.0 132.0 75.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#4] audio-oscillator Cycles:10 Average:106.70 Median:92.50 stddev:23.61 (25.5%) stddev-sans-first:24.51 22:09:10 INFO - PROCESS | 6586 | Values: 93.0 92.0 156.0 92.0 94.0 93.0 135.0 93.0 92.0 127.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 22:09:10 INFO - PROCESS | 6586 | Values: 117.0 117.0 117.0 116.0 118.0 116.0 117.0 117.0 117.0 117.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.00 stddev:1.27 (0.9%) stddev-sans-first:1.33 22:09:10 INFO - PROCESS | 6586 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 150.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 22:09:10 INFO - PROCESS | 6586 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 94.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:65.00 stddev:4.08 (6.3%) stddev-sans-first:4.31 22:09:10 INFO - PROCESS | 6586 | Values: 65.0 75.0 66.0 64.0 65.0 63.0 64.0 63.0 71.0 62.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:64.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 22:09:10 INFO - PROCESS | 6586 | Values: 63.0 63.0 64.0 64.0 64.0 64.0 63.0 64.0 64.0 64.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:84.50 stddev:21.52 (25.5%) stddev-sans-first:22.57 22:09:10 INFO - PROCESS | 6586 | Values: 82.0 82.0 82.0 87.0 88.0 86.0 83.0 86.0 152.0 83.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#11] stanford-crypto-ccm Cycles:10 Average:137.30 Median:137.50 stddev:6.13 (4.5%) stddev-sans-first:6.26 22:09:10 INFO - PROCESS | 6586 | Values: 142.0 130.0 140.0 128.0 137.0 137.0 135.0 136.0 150.0 138.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:170.00 stddev:4.58 (2.7%) stddev-sans-first:4.66 22:09:10 INFO - PROCESS | 6586 | Values: 171.0 170.0 166.0 170.0 174.0 172.0 162.0 163.0 165.0 161.0 22:09:10 INFO - PROCESS | 6586 | 22:09:10 INFO - PROCESS | 6586 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.14 (1.7%) stddev-sans-first:1.20 22:09:10 INFO - PROCESS | 6586 | Values: 65.0 66.0 66.0 65.0 65.0 64.0 66.0 63.0 65.0 67.0 22:09:10 INFO - PROCESS | 6586 | -------- Summary: end -------- 22:09:10 INFO - PROCESS | 6586 | 22:09:11 INFO - TEST-INFO | 6586: exit 0 22:09:12 INFO - TEST-OK | kraken | took 64929ms 22:09:12 INFO - SUITE-END | took 1183s 22:09:12 INFO - Completed test suite (00:19:43) 22:09:12 INFO - javascript benchmark 22:09:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2693.4747724193785, 2706.3910620110464, 2725.8500782456963, 2743.0810678422727, 2765.598988146719, 4668.229742092559, 4707.199776038073, 4741.146593613157, 4797.208460603996, 4826.372571565696, 4690.390249267599, 4735.242361222206, 4752.429708434993, 4763.0473905219005, 4781.617470602352, 4321.719088259269, 4355.0418907840385, 4420.690551661385, 4452.487963884146, 4536.77316134192, 4439.911201775963, 4471.061077173781, 4485.058137791068, 4491.39366185564, 4518.774061296938, 4241.915161696764, 4282.957170428287, 4304.74171549708, 4330.588594083557, 4351.0862718829085, 4345.304751239818, 4355.869323920288, 4407.0, 4407.1846708358735, 4416.271315233015, 4388.648908087345, 4408.316710909814, 4409.272470042439, 4433.024734558392, 4439.866803995886, 4467.865964021084, 4618.6997845140295, 4655.580997710188, 4682.484926658065, 4733.24268117103, 5108.616853735947, 5117.104506711311, 5154.304168937183, 5165.535101840815, 5169.198774190007, 5001.099802035632, 5122.667026643257, 5154.561862241714, 5158.484151584849, 5211.03595834768, 4743.1699452596, 4784.736839473792, 4795.448523419782, 4845.685030473008, 4863.392075990501, 5281.287026251483, 5348.625596208228, 5350.143976963667, 5352.705601191972, 5357.276767636397, 4882.145624515695, 4966.0, 4994.750262486861, 5038.874028149252, 5041.470645582161, 5086.4659210783075, 5104.2088476286235, 5129.589632829365, 5135.666181698178, 5170.1986192140275, 5067.113255180328, 5084.661601867039, 5085.084684756705, 5087.3386459759995, 5138.845834624967, 4889.462159162489, 4995.150824359867, 5035.269885866602, 5047.520485553867, 5057.848264552069, 4305.9569404306185, 4323.329883868006, 4328.09110086879, 4357.346398040319, 4372.40972468719, 3741.2704522618374, 3788.0340513169517, 3799.2020484726263, 3826.8086595670106, 3829.1575853312784, 4302.419173411612, 4341.413909122291, 4389.385486031958, 4413.0, 4420.712653677565, 5146.922796158061, 5166.328377311001, 5173.32746742921, 5173.534381905646, 5220.321358223407, 4421.602055814992, 4429.069895322018, 4437.0460351024685, 4467.173572889026, 4481.775911204427, 4425.822967081345, 4554.612857907115, 4586.518415566317, 4595.149897268949, 4603.654725895572, 4819.132556139788, 4824.614030877591, 4828.565429111256, 4840.322354870251, 4871.488493708252, 5121.206213036985, 5127.692338459704, 5136.409312928911, 5159.071367153798, 5204.765785539736, 4666.440027196758, 4756.120117778222, 4780.808767649394, 4805.37530121082, 4809.326694262876, 5022.974885125551, 5053.0399224147295, 5055.646104772778, 5061.190209566452, 5076.949230507647, 4983.551480366639, 5102.132637451717, 5121.564667003197, 5130.7178105204575, 5132.6150538709, 4917.754112294299, 4921.532454416824, 4956.281339205867, 4967.478414766398, 4986.177280748636, 4956.900861982812, 4973.452920178705, 4979.9004019918675, 4989.25161225819, 5028.346314979176, 4695.319178718998, 4855.320255164351, 4913.877153071202, 4934.851954441372, 4981.203007518924, 5022.397312322545, 5027.195648696191, 5039.042581909425, 5048.873778155576, 5097.898042039064, 4851.36932198826, 4884.853454396374, 4884.877878053077, 4889.0, 4940.258961155855], "value": 4690.853317076916, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.6968211371145, 610.1671218786355, 613.8680183760492, 632.7342516143219, 633.8700566383892, 28598.142055738335, 43697.12605747883, 44309.0, 44431.33352999708, 44445.555544444505, 43842.56157438425, 44104.0, 44198.55801441985, 44312.3353149703, 44360.77819610905, 7564.735234266802, 7732.6520306586335, 7738.806529836771, 7898.407619428552, 8035.437519373646, 7696.345810606105, 9996.550155242996, 10243.078122968931, 10262.692119236435, 10562.735931601734, 10287.0741633253, 10334.896651033507, 10337.0, 10356.119729822974, 10356.430396328198, 7670.4247181461615, 7865.764027079197, 10105.595776168944, 10112.393256404639, 10196.796064078713, 7656.770296891103, 9811.950940245253, 10201.081902628799, 10208.08127268542, 10505.737356566071, 40430.39354409686, 40711.0, 40725.389119163236, 40747.38878916819, 40937.38593921094, 3221.6617255188103, 3341.766076374655, 3428.63999280077, 3455.395305821471, 3853.5183102112237, 29809.701902981127, 30357.241068973453, 30773.230669233224, 30875.38249235003, 31975.360492790245, 3432.262063656326, 3531.205478767257, 3546.8935931922083, 3618.4391419330045, 3651.7626354286886, 254729.0, 254767.0, 255055.0, 256196.0, 256229.0, 57588.13617795736, 57826.42173578295, 58040.4195958035, 58046.41953580495, 58110.709446453344, 59693.403065968785, 59839.70080149572, 60107.69946150329, 60152.39847601468, 60153.0977035336, 15694.450694225647, 15867.761983570255, 15901.840981590267, 15922.203889805463, 15938.601534961486, 11274.323540587427, 11545.595904143314, 11553.422328883522, 12028.45871935765, 12311.138220324492, 17817.821821781617, 18229.45311640653, 18308.0, 18617.906910465634, 18810.435686929413, 6103.816885493442, 6228.56400051992, 6311.2328548006635, 6338.271098823694, 6443.355664433519, 771.5332224004509, 906.6146887572755, 908.4458480326995, 966.7389804752678, 993.4287784523927, 28724.42551148965, 29456.410871782446, 29467.557986630218, 29999.550006749916, 30083.398332033674, 935.7005758157325, 993.5975929748463, 1010.5755582655303, 1017.5115944346607, 1036.2487196782272, 14781.33483993223, 15111.54665360041, 15121.39514419411, 15376.38494460209, 15435.0, 354157.0, 355195.0, 358239.0, 358647.0, 360530.0, 52547.737261314214, 52559.47440525546, 52651.73674131605, 52713.736431318364, 52818.207726884124, 1148.7300484386162, 1222.0834374219335, 1249.9625011249677, 1281.0904257976754, 1284.319310765296, 27834.304142396602, 29484.852575736983, 29686.40627187401, 29818.70181298246, 30256.5461518073, 44756.104877901606, 45306.77346613246, 45461.0, 45500.31749523826, 45511.0, 294291.0, 300449.0, 300929.0, 301879.0, 302155.0, 32938.83530582332, 38028.2394352117, 39279.6072039276, 40617.0, 42319.0, 43750.0, 44147.77926110349, 44353.33469997888, 44408.55591444044, 45211.0, 36694.0, 36924.0, 37119.772813631265, 37406.6259337414, 38352.42471362876, 36612.533368992496, 36858.0, 37019.44470832994, 37241.62758372381, 37589.43615845819, 36689.26621467502, 37359.0, 37565.81217093897, 37835.43246851354, 37893.05267368338, 32659.34681306313, 33889.66110338865, 33926.49110263299, 34305.485417719254, 34806.47790283198, 25522.617160742233, 25813.3546661335, 26043.739562604133, 26067.0, 26467.205983820517], "value": 18757.894350066614, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.2502432574038, 371.25748502994014, 379.79111488681235, 383.6987964447909, 386.47825435661844, 16461.341546338146, 17118.48644540666, 17414.39049633263, 17559.561010974732, 17626.294948202056, 16169.0, 16338.18309084547, 17101.144942752875, 17143.399981000635, 17878.731819022694, 4605.240135377659, 4797.2564252540915, 4824.807007719687, 4826.710397376169, 4831.37192165018, 3501.9649803501893, 4695.624350051988, 5115.795368185268, 5390.838274851761, 5399.730013499329, 5407.3781515125675, 5429.4570542945785, 5449.536789372908, 5463.754131064092, 5464.590155738336, 5342.946570534284, 5360.142377219646, 5416.1063424534905, 5416.835380393234, 5486.972565137189, 3397.575303087114, 5023.171176755831, 5255.605829562798, 5280.471952804689, 5303.7348132593215, 12653.051021173449, 12671.36643167847, 12732.23606583608, 14481.493147739779, 20979.370618881458, 2034.8677335973114, 2058.7941205879442, 2085.113826623694, 2119.6078725435846, 2362.5274945010897, 9054.954725226398, 9179.219766319871, 9286.535673216313, 9422.199113075398, 9481.09929556691, 1774.095211442161, 1796.5418818201406, 1817.972730409045, 1818.8995657627077, 1880.990595047016, 68776.6561167191, 71221.64389178072, 71658.64170679165, 72218.0, 72345.27654723437, 10014.849777253346, 10060.597576097021, 10194.184465242763, 10264.64073757415, 10316.587336506605, 14162.733962566146, 14255.572332829825, 14289.356978935974, 14297.785533216802, 14315.498957536436, 7593.772186834404, 7599.734009309647, 7652.7704168875025, 7667.0, 7700.922990770133, 7414.703411863465, 7431.702731890664, 7545.094588649397, 7585.0, 7631.38948884099, 4230.555791641895, 4337.306031035019, 4418.911621767546, 4426.048399594103, 4428.977855110704, 1670.2901266961735, 1701.174930158872, 1780.866435017379, 1785.0, 1803.6573051120245, 864.2826454043129, 869.7955980344614, 871.9171678690515, 912.0195789526285, 913.9725808225763, 16310.102944338176, 17143.228554715068, 17585.12074396275, 17860.464186074438, 17941.7308740369, 849.609179777297, 852.6418904060309, 853.8719192121107, 874.4272501511521, 893.4773157702687, 9730.0, 9983.101520863158, 10044.899551004542, 10064.245181611408, 10231.0, 420183.0, 428225.0, 436250.0, 436923.0, 437701.0, 48836.267455988185, 60389.39610603838, 78483.0, 78491.03772405736, 82522.58738706268, 861.7199410191713, 889.9555022248991, 890.4657205676672, 894.1639567004871, 897.304588943574, 14824.332905019515, 15387.923060384626, 15399.61500962485, 15502.9224853875, 15776.211189440712, 34459.483107753906, 34567.308653827284, 35611.82194089013, 36163.0, 36245.81877090598, 50558.2416263749, 52024.47975520348, 52230.21654675107, 52338.0, 52575.47424525706, 7617.162112167768, 7893.763187104396, 7916.525008499509, 7967.442279040411, 8088.191180881865, 7649.808754781175, 8754.299656027375, 11996.76006479848, 11999.82000270014, 12415.379231038592, 6856.8285792853585, 8039.0, 9862.767154105737, 10497.160227181654, 10573.995470430296, 10697.876722943978, 10940.507677154388, 11010.669679909615, 11061.225714199929, 11241.494132764208, 10960.506777194885, 11024.50389732448, 11138.33170009802, 11155.107591392507, 11280.774384512428, 6802.897956530557, 7955.5624440654, 8070.47541909774, 8083.555404452812, 8181.222783835526, 8980.595873185592, 10526.736831579272, 10720.195985300978, 10823.67528974132, 10935.17986151026], "value": 8162.641940700759, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.1991445389403, 1463.8243410790703, 1507.3066389460848, 1641.0973964319628, 1807.8824876383026, 6769.932300676998, 7743.109542402625, 7969.322607578349, 7979.521228726266, 8196.877046844302, 7886.250806173419, 8048.396370272224, 8060.5566693831815, 8061.677532898691, 8070.435069545134, 1878.4552479780848, 2175.0647221694744, 2206.4263291544235, 2229.398062523118, 2267.138487374795, 2292.5644127615783, 2315.340128063502, 2319.1651005637955, 2319.524497478021, 2321.3268152235833, 2246.516998845248, 2261.3555136786017, 2313.2135074074813, 2313.5257272259146, 2315.617923042704, 1802.648483545709, 1859.9256029758794, 1868.6636405447014, 2146.302451703187, 2220.100859152038, 2200.3069033254474, 2233.229535810143, 2238.932832015042, 2239.1715065425733, 2241.529278851443, 7985.081715602722, 8049.3962952778775, 8063.354931605516, 8064.758057258292, 8071.757847264592, 4678.532146785328, 4791.616670666338, 4792.856214313576, 4802.663813533054, 4818.253170758538, 2972.8513574321196, 3098.055093196574, 3123.8281894495794, 3124.047165614487, 3130.1705048162257, 948.7296120605595, 1001.864748258983, 1006.9395836249848, 1008.263967303865, 1026.7946410717886, 10974.0, 11019.173561982883, 11025.0, 11025.44872756359, 11064.17018723599, 4403.691741578059, 4458.041521072985, 4498.245684183171, 4520.254158063948, 4532.750698711537, 9303.767405814773, 9312.48781317034, 9323.160915517636, 9362.859557106649, 9427.292953028551, 4899.951000489949, 4901.215805471107, 4903.583195428358, 4907.214845624683, 4912.336834527293, 3795.981020094882, 4217.7891105444605, 4366.083122544238, 4367.388565600819, 4425.09285596452, 3623.202895363016, 3727.683146932487, 3765.6046115157515, 3765.6799172070682, 3831.942520862189, 1177.3877583656451, 1188.287027783323, 1190.1609365397546, 1210.5823490895627, 1224.3148124146383, 846.5978660136386, 853.38129855854, 858.9828203435897, 873.11815066783, 900.8963969143501, 5186.858891043964, 5249.107651699221, 5268.2624432578705, 5348.090824559834, 5417.702026388508, 412.40819424125874, 425.041531346811, 426.1710972159183, 427.7519038957397, 428.82715772362565, 3552.502649629054, 3654.8720794772057, 3710.8886733397503, 3718.888433347004, 3719.8698045568817, 9213.81572368549, 9438.48088355147, 9493.620255189715, 9495.192908602847, 9496.050394960448, 9094.590743416562, 9255.490947997925, 9258.074192580689, 9284.535773211448, 9286.582103805345, 7238.638068096574, 7284.016657751135, 7300.926990730131, 7308.817279568053, 7315.817104572428, 5077.974610126926, 5094.439611642791, 5095.668781529189, 5157.922631160461, 5174.068667639861, 8686.956565217133, 8712.607932642919, 8713.607887645197, 8720.127987201482, 8755.124487551195, 8685.783990241245, 8933.687320943736, 9043.547822608713, 9057.63769449241, 9060.09399060115, 5939.524838013035, 6103.084537319438, 6145.108959200801, 6825.385715285648, 6864.48516361265, 6800.557963732342, 6844.657767111724, 7385.113786345673, 7632.236776322546, 7650.655720492702, 7048.154221493453, 7096.716131354894, 7142.142942846892, 7222.566646001257, 7243.7464688735645, 6731.427828634621, 6775.220849602359, 6900.516963812485, 7004.334588214111, 7182.138143422822, 5649.830505084854, 5818.592698511064, 5819.76720931158, 6584.440322572635, 6648.335166483313, 4849.515048495122, 4860.6111511079735, 4890.0, 4906.55840974314, 5014.047331007097, 4862.878428039327, 4945.406551213878, 4965.652404331807, 4966.180580204225, 5004.974975125101], "value": 4131.482155272978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.8505839609394, 307.9846007699615, 314.5721818327076, 315.4117570730588, 318.5079052863325, 4949.950500495003, 4961.950380496199, 4968.42863070747, 4972.900541989158, 4973.831149679827, 4648.558386953243, 4723.787429565669, 4768.7138771673635, 4848.781804818783, 4915.852524424264, 3822.0827001519574, 3861.1119442528234, 3861.9227615447676, 3875.61243875613, 3892.766434013954, 3829.0427393151713, 3852.9807350963206, 3853.8265778040054, 3854.9614503855023, 3868.729188956774, 3654.853805847763, 3682.171511409938, 3831.6934645228316, 3838.2323535292912, 3849.3840985442334, 3794.31702293587, 3815.0, 3817.522809648794, 3819.3698039823535, 3828.349180639284, 3389.932201355959, 3406.403879321134, 3413.7269018478464, 3415.3681568909833, 3427.280271143063, 4416.646668266531, 4690.577847993697, 4710.976445117787, 4715.221988371914, 4749.905001899942, 4330.17726631939, 4360.193364227628, 4362.8909277268, 4367.694261401703, 4377.518472967971, 5160.483951604847, 5162.716050617215, 5167.767450464738, 5176.430592634807, 5185.637005409623, 3544.7873127612424, 3612.692921101683, 3618.185908170667, 3627.111357717376, 3633.16437219441, 5335.759890804923, 5373.453520676335, 5382.008656796971, 5391.571233623093, 5392.272043274186, 3699.112213068844, 3714.8669655755252, 3715.696268313663, 3724.366857634209, 3760.341940160461, 3768.9246215075545, 3772.3398405278967, 3777.2634336304704, 3779.225258821935, 3787.450819631138, 4052.371882358239, 4282.314829627245, 4286.635635970915, 4298.946758044237, 4311.805968731414, 5056.367954005749, 5089.389273287229, 5114.769835357343, 5169.741512924414, 5174.016936781999, 1238.3065483329365, 1270.3775150176532, 1275.457930379585, 1307.9346032698327, 1326.1446367093172, 785.9350579964181, 804.8691588318422, 873.2839071960931, 885.831691978522, 905.7463910105181, 4517.299818528133, 4538.863834084983, 4548.204064288736, 4574.313852922089, 4579.977100114478, 5300.363956325188, 5308.177232528964, 5320.547753441001, 5328.706921119298, 5329.0, 4543.61379282758, 4547.8180872764715, 4556.157110934488, 4561.885952851205, 4569.246074397754, 2799.650043744532, 2811.5782632605274, 2832.079574138413, 2835.17779843843, 2838.318803487148, 4719.221328480831, 4969.304297398367, 4971.726555039435, 4972.801087956514, 4992.276119962585, 5296.417394086648, 5368.0, 5375.0, 5377.359905228943, 5385.3268341457315, 4914.0, 4916.827911023097, 4920.532549407801, 4920.975395123001, 4967.726775027409, 5058.772355244097, 5220.738963051908, 5221.399539053057, 5226.581873450191, 5238.449962753856, 5091.261767043832, 5124.9743751281, 5136.768845401891, 5151.7166555839785, 5152.330197074356, 4946.950530494649, 4951.059298733229, 4957.801687932442, 4967.279744437109, 4967.503249675148, 3583.7636015574585, 3586.6234045424853, 3686.741928065117, 3705.0363307447096, 3705.2959937611768, 5209.7395130244095, 5212.087884620252, 5221.530062294411, 5226.869328266824, 5238.0, 5025.623078269217, 5033.0, 5043.722595257149, 5055.34280543527, 5076.953827703348, 4870.586000189882, 5005.024020316149, 5043.722595257296, 5057.216131499624, 5097.643164978416, 4854.490278520704, 4942.629302802232, 5040.36995375578, 5040.521150490697, 5044.646874718881, 5153.711572106973, 5182.356997931007, 5206.297149884717, 5207.3230480037355, 5222.216667499905, 4880.950595621958, 5030.496950304941, 5073.7716802743225, 5073.898522029465, 5094.490550944876], "value": 3854.15830351166, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.1396612336208, 675.9290274521178, 748.238998097022, 764.0716529416759, 783.2480818414322, 12496.500139994401, 13470.528531501399, 13831.654208644788, 13833.58499245023, 13914.095583787046, 13678.863211367883, 13690.726185476284, 13748.725025499483, 13783.65540861479, 13860.514881979132, 688.2223087910679, 842.4860834890742, 866.9479831210148, 874.8731433942074, 877.4735158904638, 863.5336918064238, 866.1987661413168, 868.274990383031, 869.2133619074748, 869.6086760957563, 910.5993362920294, 912.6303846942, 914.5244472874101, 915.1351972386097, 915.1671978499567, 714.4855703893163, 735.8307589254501, 736.5985537881867, 737.2075019354162, 904.882365292511, 642.9774957876499, 664.900264960255, 665.986680266392, 666.4967949198373, 667.0094909060042, 13350.799738003938, 13363.599092027158, 13368.197908125543, 13370.732585348238, 13382.531611393651, 11600.6519804406, 11604.94197529007, 11805.763884722342, 11962.342071186082, 12198.268103913704, 11473.426328683532, 11541.422928853608, 11685.649430517098, 11693.239939403911, 12159.756804863942, 4180.540140584533, 4205.15896820634, 4222.0289333453175, 4322.805473753657, 4367.58507941745, 46860.297095543596, 46908.76545617284, 47024.294635580496, 47221.52778472206, 47337.289940650575, 9155.0, 9276.582553785096, 9389.671361502315, 9547.761305967406, 9617.422954622745, 17108.82891171097, 17169.399071032454, 17174.74237886433, 17276.13619319029, 17314.91342543279, 6826.52214345001, 6831.351021652935, 6841.247462779091, 6906.309369063154, 6996.550717322057, 3672.540932383452, 3698.4637227601847, 3731.9067023324633, 3798.620137986179, 3924.3524818405212, 2862.656481222267, 2943.499605067144, 2944.764418846487, 2949.4248621519027, 2951.055662188079, 493.46212628235395, 502.36200025966815, 513.7919142747194, 555.8832645144485, 591.3820058039273, 560.5627610463845, 564.4073722591345, 566.6741623566465, 576.8183022347947, 579.1399771339558, 8181.263686268265, 8270.172982701682, 8346.707865224687, 8363.247307742333, 8427.789305267417, 565.6888711208819, 578.652808315016, 579.1341943793971, 591.6124938165508, 592.3070008090544, 3926.4294282287315, 3955.129871428281, 3961.8811435656976, 4018.015586181404, 4033.3143365627916, 61716.0, 62118.0, 62193.0, 63130.684346577975, 63665.681671591345, 46086.0, 47713.5228647716, 48986.51013489819, 51060.48939510557, 54239.45760542423, 45671.0, 46416.535834641225, 46803.0, 47101.0, 47512.0, 8286.627101780314, 8528.403011789364, 8553.272971797467, 8619.482831030158, 8733.126687331216, 29194.7080529192, 29992.700072999865, 30861.69138308678, 31114.533282000335, 32427.35145297128, 42796.0, 42831.0, 42996.140077197655, 43020.13959720726, 43243.48647797312, 10944.0, 11311.434428278717, 11350.375729334966, 11387.715307117389, 11567.942160289145, 11887.346195959306, 12165.452554634885, 12315.93842030784, 12451.75096498083, 12933.870661293644, 11336.319820810448, 11463.140264480031, 11564.0, 11796.115291353355, 12098.395080246128, 11242.887571124184, 11504.884951150716, 11703.122265829927, 11915.165938384576, 12199.14605977573, 11013.779724405627, 11337.603183888525, 11725.196316817353, 11848.052155827341, 11888.405579721153, 9146.607880521322, 9157.49633770149, 9253.398529095588, 9509.429434233969, 9521.809563808823, 8910.331725120512, 9005.279577633904, 9249.398789078958, 9758.268129890144, 9821.214302855897], "value": 6221.327942480748, "unit": "score"}], "name": "dromaeo_css", "value": 6437.335717031192, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 115.0, 115.0, 114.0, 115.0, 116.0, 115.0, 114.0, 112.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 129.0, 155.0, 213.0, 131.0, 154.0, 157.0, 132.0, 209.0, 129.0], "value": 156.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 162.0, 162.0, 162.0, 165.0, 161.0, 163.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 143.0, 74.0, 76.0, 76.0, 75.0, 132.0, 75.0], "value": 87.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 156.0, 92.0, 94.0, 93.0, 135.0, 93.0, 92.0, 127.0], "value": 106.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 150.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 66.0, 64.0, 65.0, 63.0, 64.0, 63.0, 71.0, 62.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 64.0, 64.0, 64.0, 63.0, 64.0, 64.0, 64.0], "value": 63.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 87.0, 88.0, 86.0, 83.0, 86.0, 152.0, 83.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 130.0, 140.0, 128.0, 137.0, 137.0, 135.0, 136.0, 150.0, 138.0], "value": 137.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 166.0, 170.0, 174.0, 172.0, 162.0, 163.0, 165.0, 161.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 65.0, 64.0, 66.0, 63.0, 65.0, 67.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1575.4, "alertThreshold": 2.0}]} 22:09:12 INFO - Return code: 0 22:09:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:09:12 ERROR - # TBPL SUCCESS # 22:09:12 INFO - Running post-action listener: _resource_record_post_action 22:09:12 INFO - ##### 22:09:12 INFO - ##### Finished run-tests step (success) 22:09:12 INFO - ##### 22:09:12 INFO - Running post-run listener: _resource_record_post_run 22:09:12 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 545325056; Read time: 8; Write time: 661244 22:09:12 INFO - TinderboxPrint: CPU usage
13.1% 22:09:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 22:09:12 INFO - TinderboxPrint: I/O write bytes / time
545,325,056 / 661,244 22:09:12 INFO - TinderboxPrint: CPU idle
8,326.7 (86.9%) 22:09:12 INFO - TinderboxPrint: CPU user
1,212.7 (12.7%) 22:09:12 INFO - TinderboxPrint: Swap in / out
0 / 0 22:09:12 INFO - install - Wall time: 16s; CPU: 16.0%; Read bytes: 0; Write bytes: 250667008; Read time: 0; Write time: 457496 22:09:12 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294658048; Read time: 8; Write time: 203748 22:09:12 INFO - Running post-run listener: _upload_blobber_files 22:09:12 INFO - Blob upload gear active. 22:09:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:09:12 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 22:09:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:09:13 INFO - (blobuploader) - INFO - Open directory for files ... 22:09:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:09:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:09:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:15 INFO - (blobuploader) - INFO - Done attempting. 22:09:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:09:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:09:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:16 INFO - (blobuploader) - INFO - Done attempting. 22:09:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:09:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:09:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:20 INFO - (blobuploader) - INFO - Done attempting. 22:09:20 INFO - (blobuploader) - INFO - Iteration through files over. 22:09:20 INFO - Return code: 0 22:09:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:09:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:09:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fbaa9fbbe02ca2f8a8c76466d390523e8c6bdeb39401734379d3b49169c5685b69a19821e16e758c281bd6dc369cde08ab2310c6e00816a90d08cda2fbb1b278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5bd2619d68c15fef69ce927f446541bdb3f38168505be276b9c340ffe62a09cdd2ea12c786618c38d0c95c8fd5ee0cc55ef340a17bb28651b3f669a927ec3a32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fe2227ed8f3b1ddc873c8e3bf139609e9e8b7a83310905a550d26a4387c960c2078cf4a2d784386f9dc8e8711f237a6f3d588abf2f0a59df7ce9eb34fef1b6d9"} 22:09:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:09:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:09:20 INFO - Contents: 22:09:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fbaa9fbbe02ca2f8a8c76466d390523e8c6bdeb39401734379d3b49169c5685b69a19821e16e758c281bd6dc369cde08ab2310c6e00816a90d08cda2fbb1b278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5bd2619d68c15fef69ce927f446541bdb3f38168505be276b9c340ffe62a09cdd2ea12c786618c38d0c95c8fd5ee0cc55ef340a17bb28651b3f669a927ec3a32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fe2227ed8f3b1ddc873c8e3bf139609e9e8b7a83310905a550d26a4387c960c2078cf4a2d784386f9dc8e8711f237a6f3d588abf2f0a59df7ce9eb34fef1b6d9"} 22:09:20 INFO - Running post-run listener: copy_logs_to_upload_dir 22:09:20 INFO - Copying logs to upload dir... 22:09:20 INFO - mkdir: /builds/slave/test/build/upload/logs 22:09:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.029571 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-05-13 22:09:19.821133) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 22:09:19.824864) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fbaa9fbbe02ca2f8a8c76466d390523e8c6bdeb39401734379d3b49169c5685b69a19821e16e758c281bd6dc369cde08ab2310c6e00816a90d08cda2fbb1b278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5bd2619d68c15fef69ce927f446541bdb3f38168505be276b9c340ffe62a09cdd2ea12c786618c38d0c95c8fd5ee0cc55ef340a17bb28651b3f669a927ec3a32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fe2227ed8f3b1ddc873c8e3bf139609e9e8b7a83310905a550d26a4387c960c2078cf4a2d784386f9dc8e8711f237a6f3d588abf2f0a59df7ce9eb34fef1b6d9"} build_url:https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/O2hlyNdERte4lRHSrRl7rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fbaa9fbbe02ca2f8a8c76466d390523e8c6bdeb39401734379d3b49169c5685b69a19821e16e758c281bd6dc369cde08ab2310c6e00816a90d08cda2fbb1b278", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5bd2619d68c15fef69ce927f446541bdb3f38168505be276b9c340ffe62a09cdd2ea12c786618c38d0c95c8fd5ee0cc55ef340a17bb28651b3f669a927ec3a32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fe2227ed8f3b1ddc873c8e3bf139609e9e8b7a83310905a550d26a4387c960c2078cf4a2d784386f9dc8e8711f237a6f3d588abf2f0a59df7ce9eb34fef1b6d9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 22:09:19.849247) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 22:09:19.849558) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463197525.961289-1672753439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004631 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 22:09:19.931289) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 22:09:19.931621) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 22:09:19.931905) ========= ========= Total master_lag: 0.16 =========