builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1463215772.96 results: success (0) buildid: 20160513233037 builduid: c8f88f4b8fdf48d8b77a46e6c69dce6e revision: 5a396ec2aeefb4db7298f2406f5132390eada6a7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:32.955424) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:32.955866) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:32.956145) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.041841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.042100) ========= 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=62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-14 01:49: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% 34.0M=0s 2016-05-14 01:49:33 (34.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.191158 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.253228) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.253541) ========= 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=62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017677 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.304222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.304623) ========= 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=62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-14 01:49:33,510 truncating revision to first 12 chars 2016-05-14 01:49:33,510 Setting DEBUG logging. 2016-05-14 01:49:33,510 attempt 1/10 2016-05-14 01:49:33,510 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-14 01:49:33,894 unpacking tar archive at: fx-team-5a396ec2aeef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.986136) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.986420) ========= script_repo_revision: 5a396ec2aeefb4db7298f2406f5132390eada6a7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.986820) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.987097) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 01:49:33.998649) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-14 01:49:33.998930) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:49:34 INFO - MultiFileLogger online at 20160514 01:49:34 in /builds/slave/test 01:49:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:49:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:49:34 INFO - {'append_to_log': False, 01:49:34 INFO - 'base_work_dir': '/builds/slave/test', 01:49:34 INFO - 'blob_upload_branch': 'Fx-Team', 01:49:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:49:34 INFO - 'branch': 'Fx-Team', 01:49:34 INFO - 'buildbot_json_path': 'buildprops.json', 01:49:34 INFO - 'config_files': ('talos/linux_config.py',), 01:49:34 INFO - 'default_actions': ('clobber', 01:49:34 INFO - 'read-buildbot-config', 01:49:34 INFO - 'download-and-extract', 01:49:34 INFO - 'populate-webroot', 01:49:34 INFO - 'create-virtualenv', 01:49:34 INFO - 'install', 01:49:34 INFO - 'run-tests'), 01:49:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:49:34 INFO - 'download_minidump_stackwalk': True, 01:49:34 INFO - 'download_symbols': 'ondemand', 01:49:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:49:34 INFO - 'tooltool.py': '/tools/tooltool.py', 01:49:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:49:34 INFO - '/tools/misc-python/virtualenv.py')}, 01:49:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:49:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:49:34 INFO - 'installer_path': 'installer.exe', 01:49:34 INFO - 'log_level': 'info', 01:49:34 INFO - 'log_name': 'talos', 01:49:34 INFO - 'log_to_console': True, 01:49:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:49:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:49:34 INFO - 'opt_config_files': (), 01:49:34 INFO - 'pip_index': False, 01:49:34 INFO - 'sps_profile': False, 01:49:34 INFO - 'sps_profile_interval': 0, 01:49:34 INFO - 'suite': 'dromaeojs', 01:49:34 INFO - 'system_bits': '32', 01:49:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:49:34 INFO - 'title': 'talos-linux64-ix-018', 01:49:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:49:34 INFO - 'use_talos_json': True, 01:49:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:49:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:49:34 INFO - 'work_dir': 'build'} 01:49:34 INFO - ##### 01:49:34 INFO - ##### Running clobber step. 01:49:34 INFO - ##### 01:49:34 INFO - Running pre-action listener: _resource_record_pre_action 01:49:34 INFO - Running main action method: clobber 01:49:34 INFO - rmtree: /builds/slave/test/build 01:49:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:49:34 INFO - Running post-action listener: _resource_record_post_action 01:49:34 INFO - ##### 01:49:34 INFO - ##### Finished clobber step (success) 01:49:34 INFO - ##### 01:49:34 INFO - ##### 01:49:34 INFO - ##### Running read-buildbot-config step. 01:49:34 INFO - ##### 01:49:34 INFO - Running pre-action listener: _resource_record_pre_action 01:49:34 INFO - Running main action method: read_buildbot_config 01:49:34 INFO - Using buildbot properties: 01:49:34 INFO - { 01:49:34 INFO - "project": "", 01:49:34 INFO - "product": "firefox", 01:49:34 INFO - "script_repo_revision": "production", 01:49:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 01:49:34 INFO - "repository": "", 01:49:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 01:49:34 INFO - "buildid": "20160513233037", 01:49:34 INFO - "pgo_build": "True", 01:49:34 INFO - "basedir": "/builds/slave/test", 01:49:34 INFO - "buildnumber": 677, 01:49:34 INFO - "slavename": "talos-linux64-ix-018", 01:49:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:49:34 INFO - "platform": "ubuntu64_hw", 01:49:34 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 01:49:34 INFO - "branch": "fx-team", 01:49:34 INFO - "repo_path": "integration/fx-team", 01:49:34 INFO - "revision": "5a396ec2aeefb4db7298f2406f5132390eada6a7", 01:49:34 INFO - "stage_platform": "linux64-pgo", 01:49:34 INFO - "builduid": "c8f88f4b8fdf48d8b77a46e6c69dce6e", 01:49:34 INFO - "slavebuilddir": "test" 01:49:34 INFO - } 01:49:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 01:49:34 INFO - Running post-action listener: _resource_record_post_action 01:49:34 INFO - ##### 01:49:34 INFO - ##### Finished read-buildbot-config step (success) 01:49:34 INFO - ##### 01:49:34 INFO - ##### 01:49:34 INFO - ##### Running download-and-extract step. 01:49:34 INFO - ##### 01:49:34 INFO - Running pre-action listener: _resource_record_pre_action 01:49:34 INFO - Running main action method: download_and_extract 01:49:34 INFO - mkdir: /builds/slave/test/build/tests 01:49:34 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')]} 01:49:34 INFO - https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:49:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:49:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:49:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:38 INFO - Downloaded 1361 bytes. 01:49:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:49:38 INFO - Using the following test package requirements: 01:49:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:49:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:49:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:49:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:49:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:49:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:49:38 INFO - u'jsshell-linux-x86_64.zip'], 01:49:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:49:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:49:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:49:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:49:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:49:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:49:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:49:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:49:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:49:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:49:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:49:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:49: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')]} 01:49:38 INFO - https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:49:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:49:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:49:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:47 INFO - Downloaded 24023122 bytes. 01:49:47 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'] 01:49:47 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 01:49:48 INFO - Return code: 0 01:49:48 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 01:49:48 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')]} 01:49:48 INFO - https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:49:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:49:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:49:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:52 INFO - Downloaded 24023122 bytes. 01:49:52 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'] 01:49:52 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 01:49:53 INFO - Return code: 0 01:49:53 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')]} 01:49:53 INFO - https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:49:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:49:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:49:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:54 INFO - Downloaded 10840194 bytes. 01:49:54 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'] 01:49:54 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 01:49:54 INFO - Return code: 0 01:49: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')]} 01:49:54 INFO - https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:49:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:49:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:49:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XKbWTokSSSCFK4XM_i13ug/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 01:49:56 INFO - Downloaded 58394829 bytes. 01:49:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:49:56 INFO - mkdir: /builds/slave/test/properties 01:49:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:49:56 INFO - Writing to file /builds/slave/test/properties/build_url 01:49:56 INFO - Contents: 01:49:56 INFO - build_url:https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:49:58 INFO - Running post-action listener: _resource_record_post_action 01:49:58 INFO - Running post-action listener: set_extra_try_arguments 01:49:58 INFO - ##### 01:49:58 INFO - ##### Finished download-and-extract step (success) 01:49:58 INFO - ##### 01:49:58 INFO - ##### 01:49:58 INFO - ##### Running populate-webroot step. 01:49:58 INFO - ##### 01:49:58 INFO - Running pre-action listener: _resource_record_pre_action 01:49:58 INFO - Running main action method: populate_webroot 01:49:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:49:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:49:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:49:58 INFO - '--tppagecycles', 01:49:58 INFO - '7'], 01:49:58 INFO - 'tests': ['tsvgm']}}, 01:49:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:49:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:49:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:49:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:49:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:58 INFO - 'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:49:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:49:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:58 INFO - 'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['damp', 'tps']}, 01:49:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:58 INFO - 'tests': ['damp', 'tps']}, 01:49:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['dromaeo_dom']}, 01:49:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:49:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['a11yr', 01:49:58 INFO - 'ts_paint', 01:49:58 INFO - 'tpaint', 01:49:58 INFO - 'sessionrestore', 01:49:58 INFO - 'sessionrestore_no_auto_restore', 01:49:58 INFO - 'tabpaint']}, 01:49:58 INFO - 'other-e10s': {'tests': ['a11yr', 01:49:58 INFO - 'ts_paint', 01:49:58 INFO - 'tpaint', 01:49:58 INFO - 'sessionrestore', 01:49:58 INFO - 'sessionrestore_no_auto_restore', 01:49:58 INFO - 'tabpaint']}, 01:49:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:49:58 INFO - 'ts_paint', 01:49:58 INFO - 'tpaint', 01:49:58 INFO - 'sessionrestore', 01:49:58 INFO - 'sessionrestore_no_auto_restore', 01:49:58 INFO - 'tabpaint']}, 01:49:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:49:58 INFO - 'ts_paint', 01:49:58 INFO - 'tpaint', 01:49:58 INFO - 'sessionrestore', 01:49:58 INFO - 'sessionrestore_no_auto_restore', 01:49:58 INFO - 'tabpaint']}, 01:49:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['a11yr', 01:49:58 INFO - 'ts_paint', 01:49:58 INFO - 'tpaint', 01:49:58 INFO - 'sessionrestore', 01:49:58 INFO - 'sessionrestore_no_auto_restore', 01:49:58 INFO - 'tabpaint']}, 01:49:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['a11yr', 01:49:58 INFO - 'ts_paint', 01:49:58 INFO - 'tpaint', 01:49:58 INFO - 'sessionrestore', 01:49:58 INFO - 'sessionrestore_no_auto_restore', 01:49:58 INFO - 'tabpaint']}, 01:49:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['tsvgx', 01:49:58 INFO - 'tsvgr_opacity', 01:49:58 INFO - 'tart', 01:49:58 INFO - 'tscrollx', 01:49:58 INFO - 'cart']}, 01:49:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:49:58 INFO - 'tsvgr_opacity', 01:49:58 INFO - 'tart', 01:49:58 INFO - 'tscrollx', 01:49:58 INFO - 'cart']}, 01:49:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:58 INFO - 'talos_options': ['--disable-e10s'], 01:49:58 INFO - 'tests': ['tp5o']}, 01:49:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:58 INFO - 'tests': ['tp5o']}, 01:49:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:58 INFO - 'talos_options': ['--disable-e10s', 01:49:58 INFO - '--xperf_path', 01:49:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:49:58 INFO - 'tests': ['tp5n']}, 01:49:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:58 INFO - 'talos_options': ['--xperf_path', 01:49:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:49:58 INFO - 'tests': ['tp5n']}}, 01:49:58 INFO - 'talos.zip': {'path': '', 01:49:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:49:58 INFO - Running post-action listener: _resource_record_post_action 01:49:58 INFO - ##### 01:49:58 INFO - ##### Finished populate-webroot step (success) 01:49:58 INFO - ##### 01:49:58 INFO - ##### 01:49:58 INFO - ##### Running create-virtualenv step. 01:49:58 INFO - ##### 01:49:58 INFO - Running pre-action listener: _resource_record_pre_action 01:49:58 INFO - Running main action method: create_virtualenv 01:49:58 INFO - Creating virtualenv /builds/slave/test/build/venv 01:49:58 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 01:49:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:49:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:49:58 INFO - Using real prefix '/usr' 01:49:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:49:58 INFO - Installing distribute.............................................................................................................................................................................................done. 01:50:00 INFO - Installing pip.................done. 01:50:00 INFO - Return code: 0 01:50:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:50:00 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')]} 01:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:00 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')]} 01:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:00 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 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:00 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 01:50:00 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 01:50:00 INFO - Using env: {'DISPLAY': ':0', 01:50:00 INFO - 'HOME': '/home/cltbld', 01:50:00 INFO - 'LANG': 'en_US.UTF-8', 01:50:00 INFO - 'LANGUAGE': 'en_US:en', 01:50:00 INFO - 'LOGNAME': 'cltbld', 01:50:00 INFO - 'MAIL': '/var/mail/cltbld', 01:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:00 INFO - 'MOZ_NO_REMOTE': '1', 01:50:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:00 INFO - 'NO_EM_RESTART': '1', 01:50:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:00 INFO - 'PWD': '/builds/slave/test', 01:50:00 INFO - 'SHELL': '/bin/bash', 01:50:00 INFO - 'SHLVL': '1', 01:50:00 INFO - 'TERM': 'linux', 01:50:00 INFO - 'TMOUT': '86400', 01:50:00 INFO - 'USER': 'cltbld', 01:50:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:00 INFO - '_': '/tools/buildbot/bin/python'} 01:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:01 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 01:50:01 INFO - Cleaning up... 01:50:01 INFO - Return code: 0 01:50:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:50: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')]} 01:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50: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')]} 01:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:01 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 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:01 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 01:50:01 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 01:50:01 INFO - Using env: {'DISPLAY': ':0', 01:50:01 INFO - 'HOME': '/home/cltbld', 01:50:01 INFO - 'LANG': 'en_US.UTF-8', 01:50:01 INFO - 'LANGUAGE': 'en_US:en', 01:50:01 INFO - 'LOGNAME': 'cltbld', 01:50:01 INFO - 'MAIL': '/var/mail/cltbld', 01:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:01 INFO - 'MOZ_NO_REMOTE': '1', 01:50:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:01 INFO - 'NO_EM_RESTART': '1', 01:50:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:01 INFO - 'PWD': '/builds/slave/test', 01:50:01 INFO - 'SHELL': '/bin/bash', 01:50:01 INFO - 'SHLVL': '1', 01:50:01 INFO - 'TERM': 'linux', 01:50:01 INFO - 'TMOUT': '86400', 01:50:01 INFO - 'USER': 'cltbld', 01:50:01 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:01 INFO - '_': '/tools/buildbot/bin/python'} 01:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:01 INFO - Downloading/unpacking psutil>=3.1.1 01:50:01 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 01:50:01 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 01:50:01 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 01:50:01 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 01:50:01 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 01:50:01 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 01:50:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:50:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:50:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:50:03 INFO - Installing collected packages: psutil 01:50:03 INFO - Running setup.py install for psutil 01:50:03 INFO - building 'psutil._psutil_linux' extension 01:50:03 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 01:50:03 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 01:50:03 INFO - building 'psutil._psutil_posix' extension 01:50:03 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 01:50:03 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 01:50:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:50:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:50:03 INFO - Successfully installed psutil 01:50:03 INFO - Cleaning up... 01:50:03 INFO - Return code: 0 01:50:03 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 01:50:03 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')]} 01:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:03 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')]} 01:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:03 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 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:03 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 01:50:03 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 01:50:03 INFO - Using env: {'DISPLAY': ':0', 01:50:03 INFO - 'HOME': '/home/cltbld', 01:50:03 INFO - 'LANG': 'en_US.UTF-8', 01:50:03 INFO - 'LANGUAGE': 'en_US:en', 01:50:03 INFO - 'LOGNAME': 'cltbld', 01:50:03 INFO - 'MAIL': '/var/mail/cltbld', 01:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:03 INFO - 'MOZ_NO_REMOTE': '1', 01:50:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:03 INFO - 'NO_EM_RESTART': '1', 01:50:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:03 INFO - 'PWD': '/builds/slave/test', 01:50:03 INFO - 'SHELL': '/bin/bash', 01:50:03 INFO - 'SHLVL': '1', 01:50:03 INFO - 'TERM': 'linux', 01:50:03 INFO - 'TMOUT': '86400', 01:50:03 INFO - 'USER': 'cltbld', 01:50:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:03 INFO - '_': '/tools/buildbot/bin/python'} 01:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:04 INFO - Downloading/unpacking mozsystemmonitor==0.1 01:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:05 INFO - Downloading mozsystemmonitor-0.1.tar.gz 01:50:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 01:50:05 INFO - Installing collected packages: mozsystemmonitor 01:50:05 INFO - Running setup.py install for mozsystemmonitor 01:50:06 INFO - Successfully installed mozsystemmonitor 01:50:06 INFO - Cleaning up... 01:50:06 INFO - Return code: 0 01:50:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:06 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 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:06 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 01:50:06 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 01:50:06 INFO - Using env: {'DISPLAY': ':0', 01:50:06 INFO - 'HOME': '/home/cltbld', 01:50:06 INFO - 'LANG': 'en_US.UTF-8', 01:50:06 INFO - 'LANGUAGE': 'en_US:en', 01:50:06 INFO - 'LOGNAME': 'cltbld', 01:50:06 INFO - 'MAIL': '/var/mail/cltbld', 01:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:06 INFO - 'MOZ_NO_REMOTE': '1', 01:50:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:06 INFO - 'NO_EM_RESTART': '1', 01:50:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:06 INFO - 'PWD': '/builds/slave/test', 01:50:06 INFO - 'SHELL': '/bin/bash', 01:50:06 INFO - 'SHLVL': '1', 01:50:06 INFO - 'TERM': 'linux', 01:50:06 INFO - 'TMOUT': '86400', 01:50:06 INFO - 'USER': 'cltbld', 01:50:06 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:06 INFO - '_': '/tools/buildbot/bin/python'} 01:50:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:06 INFO - Downloading/unpacking blobuploader==1.2.4 01:50:06 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 01:50:06 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 01:50:06 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 01:50:06 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 01:50:06 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 01:50:06 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 01:50:08 INFO - Downloading blobuploader-1.2.4.tar.gz 01:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:50:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:50: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 01:50: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 01:50: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 01:50: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 01:50: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 01:50: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 01:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:50:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:50: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 01:50: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 01:50: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 01:50: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 01:50: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 01:50: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 01:50:08 INFO - Downloading docopt-0.6.1.tar.gz 01:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:50:09 INFO - Installing collected packages: blobuploader, requests, docopt 01:50:09 INFO - Running setup.py install for blobuploader 01:50:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:50:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:50:09 INFO - Running setup.py install for requests 01:50:09 INFO - Running setup.py install for docopt 01:50:09 INFO - Successfully installed blobuploader requests docopt 01:50:09 INFO - Cleaning up... 01:50:09 INFO - Return code: 0 01:50:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:09 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 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:09 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 01:50:09 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 01:50:09 INFO - Using env: {'DISPLAY': ':0', 01:50:09 INFO - 'HOME': '/home/cltbld', 01:50:09 INFO - 'LANG': 'en_US.UTF-8', 01:50:09 INFO - 'LANGUAGE': 'en_US:en', 01:50:09 INFO - 'LOGNAME': 'cltbld', 01:50:09 INFO - 'MAIL': '/var/mail/cltbld', 01:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:09 INFO - 'MOZ_NO_REMOTE': '1', 01:50:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:09 INFO - 'NO_EM_RESTART': '1', 01:50:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:09 INFO - 'PWD': '/builds/slave/test', 01:50:09 INFO - 'SHELL': '/bin/bash', 01:50:09 INFO - 'SHLVL': '1', 01:50:09 INFO - 'TERM': 'linux', 01:50:09 INFO - 'TMOUT': '86400', 01:50:09 INFO - 'USER': 'cltbld', 01:50:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:09 INFO - '_': '/tools/buildbot/bin/python'} 01:50:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:50:09 INFO - Running setup.py (path:/tmp/pip-UDidQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:50:09 INFO - Running setup.py (path:/tmp/pip-ctafXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:50:09 INFO - Running setup.py (path:/tmp/pip-hKlZWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:50:10 INFO - Running setup.py (path:/tmp/pip-i9_fuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:50:10 INFO - Running setup.py (path:/tmp/pip-zxArIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:50:10 INFO - Running setup.py (path:/tmp/pip-c5b0tO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:50:10 INFO - Running setup.py (path:/tmp/pip-Ztvik_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:50:10 INFO - Running setup.py (path:/tmp/pip-DOOwpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:50:10 INFO - Running setup.py (path:/tmp/pip-v6Hert-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:50:10 INFO - Running setup.py (path:/tmp/pip-fXy5kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:50:10 INFO - Running setup.py (path:/tmp/pip-QQtMNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:50:10 INFO - Running setup.py (path:/tmp/pip-vfIpcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:50:10 INFO - Running setup.py (path:/tmp/pip-bHk9T0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:50:10 INFO - Running setup.py (path:/tmp/pip-IoQp1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:50:10 INFO - Running setup.py (path:/tmp/pip-e5lGOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:50:10 INFO - Running setup.py (path:/tmp/pip-AOjFGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:50:10 INFO - Running setup.py (path:/tmp/pip-YNIrp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:50:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:50:11 INFO - Running setup.py install for manifestparser 01:50:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Running setup.py install for mozcrash 01:50:11 INFO - Running setup.py install for mozdebug 01:50:11 INFO - Running setup.py install for mozdevice 01:50:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Running setup.py install for mozfile 01:50:11 INFO - Running setup.py install for mozhttpd 01:50:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Running setup.py install for mozinfo 01:50:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Running setup.py install for mozInstall 01:50:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:50:11 INFO - Running setup.py install for mozleak 01:50:11 INFO - Running setup.py install for mozlog 01:50:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:50:12 INFO - Running setup.py install for moznetwork 01:50:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:50:12 INFO - Running setup.py install for mozprocess 01:50:12 INFO - Running setup.py install for mozprofile 01:50:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:50:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:50:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:50:12 INFO - Running setup.py install for mozrunner 01:50:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:50:12 INFO - Running setup.py install for mozscreenshot 01:50:12 INFO - Running setup.py install for moztest 01:50:12 INFO - Running setup.py install for mozversion 01:50:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:50:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:50:12 INFO - Cleaning up... 01:50:12 INFO - Return code: 0 01:50:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:12 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 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:12 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 01:50:12 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 01:50:12 INFO - Using env: {'DISPLAY': ':0', 01:50:12 INFO - 'HOME': '/home/cltbld', 01:50:12 INFO - 'LANG': 'en_US.UTF-8', 01:50:12 INFO - 'LANGUAGE': 'en_US:en', 01:50:12 INFO - 'LOGNAME': 'cltbld', 01:50:12 INFO - 'MAIL': '/var/mail/cltbld', 01:50:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:12 INFO - 'MOZ_NO_REMOTE': '1', 01:50:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:12 INFO - 'NO_EM_RESTART': '1', 01:50:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:12 INFO - 'PWD': '/builds/slave/test', 01:50:12 INFO - 'SHELL': '/bin/bash', 01:50:12 INFO - 'SHLVL': '1', 01:50:12 INFO - 'TERM': 'linux', 01:50:12 INFO - 'TMOUT': '86400', 01:50:12 INFO - 'USER': 'cltbld', 01:50:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:12 INFO - '_': '/tools/buildbot/bin/python'} 01:50:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:50:13 INFO - Running setup.py (path:/tmp/pip-hABfj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:50:13 INFO - Running setup.py (path:/tmp/pip-2CB935-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:50:13 INFO - Running setup.py (path:/tmp/pip-CS3i7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:50:13 INFO - Running setup.py (path:/tmp/pip-PIHOW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:50:13 INFO - Running setup.py (path:/tmp/pip-UNesLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:50:13 INFO - Running setup.py (path:/tmp/pip-wMLC_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:50:13 INFO - Running setup.py (path:/tmp/pip-gAnNGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:50:13 INFO - Running setup.py (path:/tmp/pip-q6z1oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:50:13 INFO - Running setup.py (path:/tmp/pip-dlZwwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:50:13 INFO - Running setup.py (path:/tmp/pip-d15QC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:50:13 INFO - Running setup.py (path:/tmp/pip-ljcE4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:50:13 INFO - Running setup.py (path:/tmp/pip-wxgmtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:50:13 INFO - Running setup.py (path:/tmp/pip-SLrO0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:50:13 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)) 01:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:50:13 INFO - Running setup.py (path:/tmp/pip-VL925M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:50:14 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)) 01:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:50:14 INFO - Running setup.py (path:/tmp/pip-uROWT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:50:14 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)) 01:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:50:14 INFO - Running setup.py (path:/tmp/pip-3T8v_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:50:14 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)) 01:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:50:14 INFO - Running setup.py (path:/tmp/pip-Zz9ZJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:50:14 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)) 01:50:14 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)) 01:50:14 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)) 01:50:14 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)) 01:50:14 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)) 01:50:14 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)) 01:50:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:50:14 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 01:50:14 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 01:50:14 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 01:50:14 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 01:50:14 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 01:50:14 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 01:50:16 INFO - Downloading blessings-1.6.tar.gz 01:50:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:50:16 INFO - Installing collected packages: blessings 01:50:16 INFO - Running setup.py install for blessings 01:50:16 INFO - Successfully installed blessings 01:50:16 INFO - Cleaning up... 01:50:16 INFO - Return code: 0 01:50:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:50:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:50:16 INFO - Reading from file tmpfile_stdout 01:50:16 INFO - Current package versions: 01:50:16 INFO - argparse == 1.2.1 01:50:16 INFO - blessings == 1.6 01:50:16 INFO - blobuploader == 1.2.4 01:50:16 INFO - docopt == 0.6.1 01:50:16 INFO - manifestparser == 1.1 01:50:16 INFO - mozInstall == 1.12 01:50:16 INFO - mozcrash == 0.17 01:50:16 INFO - mozdebug == 0.1 01:50:16 INFO - mozdevice == 0.48 01:50:16 INFO - mozfile == 1.2 01:50:16 INFO - mozhttpd == 0.7 01:50:16 INFO - mozinfo == 0.9 01:50:16 INFO - mozleak == 0.1 01:50:16 INFO - mozlog == 3.2 01:50:16 INFO - moznetwork == 0.27 01:50:16 INFO - mozprocess == 0.22 01:50:16 INFO - mozprofile == 0.28 01:50:16 INFO - mozrunner == 6.11 01:50:16 INFO - mozscreenshot == 0.1 01:50:16 INFO - mozsystemmonitor == 0.1 01:50:16 INFO - moztest == 0.7 01:50:16 INFO - mozversion == 1.4 01:50:16 INFO - psutil == 3.1.1 01:50:16 INFO - requests == 1.2.3 01:50:16 INFO - wsgiref == 0.1.2 01:50:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:16 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 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:16 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 01:50:16 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 01:50:16 INFO - Using env: {'DISPLAY': ':0', 01:50:16 INFO - 'HOME': '/home/cltbld', 01:50:16 INFO - 'LANG': 'en_US.UTF-8', 01:50:16 INFO - 'LANGUAGE': 'en_US:en', 01:50:16 INFO - 'LOGNAME': 'cltbld', 01:50:16 INFO - 'MAIL': '/var/mail/cltbld', 01:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:16 INFO - 'MOZ_NO_REMOTE': '1', 01:50:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:16 INFO - 'NO_EM_RESTART': '1', 01:50:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:16 INFO - 'PWD': '/builds/slave/test', 01:50:16 INFO - 'SHELL': '/bin/bash', 01:50:16 INFO - 'SHLVL': '1', 01:50:16 INFO - 'TERM': 'linux', 01:50:16 INFO - 'TMOUT': '86400', 01:50:16 INFO - 'USER': 'cltbld', 01:50:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:16 INFO - '_': '/tools/buildbot/bin/python'} 01:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 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)) 01:50:16 INFO - Cleaning up... 01:50:16 INFO - Return code: 0 01:50:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:16 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 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:16 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 01:50:16 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 01:50:16 INFO - Using env: {'DISPLAY': ':0', 01:50:16 INFO - 'HOME': '/home/cltbld', 01:50:16 INFO - 'LANG': 'en_US.UTF-8', 01:50:16 INFO - 'LANGUAGE': 'en_US:en', 01:50:16 INFO - 'LOGNAME': 'cltbld', 01:50:16 INFO - 'MAIL': '/var/mail/cltbld', 01:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:16 INFO - 'MOZ_NO_REMOTE': '1', 01:50:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:16 INFO - 'NO_EM_RESTART': '1', 01:50:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:16 INFO - 'PWD': '/builds/slave/test', 01:50:16 INFO - 'SHELL': '/bin/bash', 01:50:16 INFO - 'SHLVL': '1', 01:50:16 INFO - 'TERM': 'linux', 01:50:16 INFO - 'TMOUT': '86400', 01:50:16 INFO - 'USER': 'cltbld', 01:50:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:16 INFO - '_': '/tools/buildbot/bin/python'} 01:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:17 INFO - Downloading/unpacking jsonschema 01:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:50:18 INFO - Downloading/unpacking functools32 (from jsonschema) 01:50:18 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 01:50:18 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 01:50:18 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 01:50:18 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 01:50:18 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 01:50:18 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 01:50:19 INFO - Downloading functools32-3.2.3-2.tar.gz 01:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:50:19 INFO - warning: no files found matching '*.txt' 01:50:19 INFO - no previously-included directories found matching 'build' 01:50:19 INFO - no previously-included directories found matching 'dist' 01:50:19 INFO - no previously-included directories found matching '.git*' 01:50:19 INFO - Installing collected packages: jsonschema, functools32 01:50:19 INFO - Running setup.py install for functools32 01:50:19 INFO - warning: no files found matching '*.txt' 01:50:19 INFO - no previously-included directories found matching 'build' 01:50:19 INFO - no previously-included directories found matching 'dist' 01:50:19 INFO - no previously-included directories found matching '.git*' 01:50:19 INFO - Successfully installed jsonschema functools32 01:50:19 INFO - Cleaning up... 01:50:19 INFO - Return code: 0 01:50:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2ac2d24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c14770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, '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': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', '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 01:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 01:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:50:19 INFO - Using env: {'DISPLAY': ':0', 01:50:19 INFO - 'HOME': '/home/cltbld', 01:50:19 INFO - 'LANG': 'en_US.UTF-8', 01:50:19 INFO - 'LANGUAGE': 'en_US:en', 01:50:19 INFO - 'LOGNAME': 'cltbld', 01:50:19 INFO - 'MAIL': '/var/mail/cltbld', 01:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:19 INFO - 'MOZ_NO_REMOTE': '1', 01:50:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:19 INFO - 'NO_EM_RESTART': '1', 01:50:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:19 INFO - 'PWD': '/builds/slave/test', 01:50:19 INFO - 'SHELL': '/bin/bash', 01:50:19 INFO - 'SHLVL': '1', 01:50:19 INFO - 'TERM': 'linux', 01:50:19 INFO - 'TMOUT': '86400', 01:50:19 INFO - 'USER': 'cltbld', 01:50:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:19 INFO - '_': '/tools/buildbot/bin/python'} 01:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:19 INFO - Downloading/unpacking flake8 01:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:50:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:50:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:50:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:50:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:50:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:50:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:50:22 INFO - no previously-included directories found matching 'docs/_build' 01:50:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:50:22 INFO - Running setup.py install for pep8 01:50:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:50:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:50:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:50:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:50:22 INFO - no previously-included directories found matching 'docs/_build' 01:50:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:50:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:50:22 INFO - Cleaning up... 01:50:22 INFO - Return code: 0 01:50:22 INFO - Running post-action listener: _resource_record_post_action 01:50:22 INFO - Running post-action listener: _start_resource_monitoring 01:50:22 INFO - Starting resource monitoring. 01:50:22 INFO - ##### 01:50:22 INFO - ##### Finished create-virtualenv step (success) 01:50:22 INFO - ##### 01:50:22 INFO - ##### 01:50:22 INFO - ##### Running install step. 01:50:22 INFO - ##### 01:50:22 INFO - Running pre-action listener: _resource_record_pre_action 01:50:22 INFO - Running main action method: install 01:50:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:50:22 INFO - Reading from file tmpfile_stdout 01:50:22 INFO - Detecting whether we're running mozinstall >=1.0... 01:50:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:50:22 INFO - Reading from file tmpfile_stdout 01:50:22 INFO - Output received: 01:50:22 INFO - Usage: mozinstall [options] installer 01:50:22 INFO - Options: 01:50:22 INFO - -h, --help show this help message and exit 01:50:22 INFO - -d DEST, --destination=DEST 01:50:22 INFO - Directory to install application into. [default: 01:50:22 INFO - "/builds/slave/test"] 01:50:22 INFO - --app=APP Application being installed. [default: firefox] 01:50:22 INFO - mkdir: /builds/slave/test/build/application 01:50:22 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'] 01:50:22 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 01:50:39 INFO - Reading from file tmpfile_stdout 01:50:39 INFO - Output received: 01:50:39 INFO - /builds/slave/test/build/application/firefox/firefox 01:50:39 INFO - Running post-action listener: _resource_record_post_action 01:50:39 INFO - ##### 01:50:39 INFO - ##### Finished install step (success) 01:50:39 INFO - ##### 01:50:39 INFO - ##### 01:50:39 INFO - ##### Running run-tests step. 01:50:39 INFO - ##### 01:50:39 INFO - Running pre-action listener: _resource_record_pre_action 01:50:39 INFO - Running main action method: run_tests 01:50:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:50:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:50:39 INFO - Python 2.7.3 01:50:39 INFO - Return code: 0 01:50:39 INFO - grabbing minidump binary from tooltool 01:50:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:39 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 0x2ca39c0>, '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 0x2ca48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca4d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:50:39 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 01:50:39 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 01:50:39 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 01:50:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:50:39 INFO - Return code: 0 01:50:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:50:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:50:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:50:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:50:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:50:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:50:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:50:40 INFO - Return code: 0 01:50:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 01:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 01:50:40 INFO - Using env: {'DISPLAY': ':0', 01:50:40 INFO - 'HOME': '/home/cltbld', 01:50:40 INFO - 'LANG': 'en_US.UTF-8', 01:50:40 INFO - 'LANGUAGE': 'en_US:en', 01:50:40 INFO - 'LOGNAME': 'cltbld', 01:50:40 INFO - 'MAIL': '/var/mail/cltbld', 01:50:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:50:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:40 INFO - 'MOZ_NO_REMOTE': '1', 01:50:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:50:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:40 INFO - 'NO_EM_RESTART': '1', 01:50:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:40 INFO - 'PWD': '/builds/slave/test', 01:50:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:50:40 INFO - 'SHELL': '/bin/bash', 01:50:40 INFO - 'SHLVL': '1', 01:50:40 INFO - 'TERM': 'linux', 01:50:40 INFO - 'TMOUT': '86400', 01:50:40 INFO - 'USER': 'cltbld', 01:50:40 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283', 01:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:40 INFO - '_': '/tools/buildbot/bin/python'} 01:50:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 01:50:40 INFO - mozversion application_buildid: 20160513233037 01:50:40 INFO - mozversion application_changeset: 5a396ec2aeefb4db7298f2406f5132390eada6a7 01:50:40 INFO - mozversion application_display_name: Nightly 01:50:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:50:40 INFO - mozversion application_name: Firefox 01:50:40 INFO - mozversion application_remotingname: firefox 01:50:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:50:40 INFO - mozversion application_vendor: Mozilla 01:50:40 INFO - mozversion application_version: 49.0a1 01:50:40 INFO - mozversion platform_buildid: 20160513233037 01:50:40 INFO - mozversion platform_changeset: 5a396ec2aeefb4db7298f2406f5132390eada6a7 01:50:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:50:40 INFO - mozversion platform_version: 49.0a1 01:50:40 INFO - using testdate: 1463215840 01:50:40 INFO - actual date: 1463215840 01:50:40 INFO - starting webserver on 'localhost:47782' 01:50:40 INFO - SUITE-START | Running 2 tests 01:50:40 INFO - TEST-START | dromaeo_css 01:50:40 INFO - operating with platform_type : linux_ 01:50:40 INFO - Initialising browser for dromaeo_css test... 01:50:40 INFO - TEST-INFO | started process 25967 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4L59wZ/profile http://localhost:47782/getInfo.html) 01:50:44 INFO - PROCESS | 25967 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 01:50:44 INFO - PROCESS | 25967 | __metrics Screen width/height:1600/1200 01:50:44 INFO - PROCESS | 25967 | colorDepth:24 01:50:44 INFO - PROCESS | 25967 | Browser inner width/height: 1024/697 01:50:44 INFO - PROCESS | 25967 | __metrics 01:50:45 ERROR - PROCESS | 25967 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:50:46 INFO - TEST-INFO | 25967: exit 0 01:50:46 INFO - Browser initialized. 01:50:46 INFO - Running cycle 1/1 for dromaeo_css test... 01:50:46 INFO - TEST-INFO | started process 26050 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4L59wZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:50:58 INFO - PROCESS | 26050 | RSS: Main: 152510464 01:50:58 INFO - PROCESS | 26050 | 01:51:49 INFO - PROCESS | 26050 | 1463215909615 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] 01:53:57 INFO - PROCESS | 26050 | Cycle 1(1): loaded http://localhost:47782/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47782/tests/dromaeo/cssquery-ext.html) 01:53:58 INFO - PROCESS | 26050 | RSS: Main: 276070400 01:53:58 INFO - PROCESS | 26050 | 01:57:01 INFO - PROCESS | 26050 | Cycle 1(1): loaded http://localhost:47782/tests/dromaeo/cssquery-ext.html (next: http://localhost:47782/tests/dromaeo/cssquery-jquery.html) 01:57:01 INFO - PROCESS | 26050 | RSS: Main: 234119168 01:57:01 INFO - PROCESS | 26050 | 02:00:03 INFO - PROCESS | 26050 | Cycle 1(1): loaded http://localhost:47782/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47782/tests/dromaeo/cssquery-mootools.html) 02:00:04 INFO - PROCESS | 26050 | RSS: Main: 239751168 02:00:04 INFO - PROCESS | 26050 | 02:03:06 INFO - PROCESS | 26050 | Cycle 1(1): loaded http://localhost:47782/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47782/tests/dromaeo/cssquery-prototype.html) 02:03:06 INFO - PROCESS | 26050 | RSS: Main: 229212160 02:03:06 INFO - PROCESS | 26050 | 02:06:09 INFO - PROCESS | 26050 | Cycle 1(1): loaded http://localhost:47782/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47782/tests/dromaeo/cssquery-yui.html) 02:06:10 INFO - PROCESS | 26050 | RSS: Main: 199790592 02:06:10 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | Cycle 1(1): loaded http://localhost:47782/tests/dromaeo/cssquery-yui.html (next: http://localhost:47782/tests/dromaeo/cssquery-dojo.html) 02:09:12 INFO - PROCESS | 26050 | RSS: Main: 245878784 02:09:12 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | __start_tp_report 02:09:12 INFO - PROCESS | 26050 | _x_x_mozilla_page_load 02:09:12 INFO - PROCESS | 26050 | _x_x_mozilla_page_load_details 02:09:12 INFO - PROCESS | 26050 | |i|pagename|runs| 02:09:12 INFO - PROCESS | 26050 | |0;dojo.html;3167.9683203167974;3174.9365012699745;3184.092533627916;3219.243477782722;3248.1230067881675;5306.734663266836;5316.9202461963005;5406.02691515527;5421.756020979055;5465.2895123633925;5348.866278343044;5355.410904800468;5366.597505187107;5406.053940560406;5419.160030195316;4822.252550854623;4907.2148456247005;4917.188663870457;4921.753912304388;5147.150720131174;5032.471590483002;5054.064997975386;5082.923756143642;5094.1085310070775;5136.768845401947;4825.276208568725;4879.536444037811;4950.801967921278;4952.108620448336;4990.750462476862;4861.197902346144;4947.183714687072;4947.901041979177;4949.752512374367;4991.525805048551;4880.487548807395;4919.139150648658;4920.3111564381015;4927.704337739747;4975.7760900759195;5218.295530103425;5273.472652734734;5326.946730532683;5336.519713225828;5382.6232163748555;5797.217375654317;5830.4169583041785;5831.329397119301;5859.267591551056;5864.6187997780435;5636.887262254732;5823.650580965155;5835.591508594358;5880.6765627890445;5888.352281249059;5311.760970756325;5411.972940135313;5419.918701219445;5494.203340515683;5500.477454641843;5891.823245302648;5929.822105336847;5947.821565353046;5952.494038006731;5959.880802384015;5700.714964251771;5733.226014487991;5744.597878148489;5745.482906538432;5747.597668163188;5628.971855140698;5774.942250577525;5840.620359676607;5843.3864444233595;5859.589828712034;5713.942860571424;5763.740631671585;5771.076627739625;5775.249217601685;5778.624389414675;5736.799212027559;5786.508146807484;5796.1305804129715;5802.854928626733;5806.593538452352;4322.546132656026;4352.521222665504;4374.846880359172;4411.404460397805;4419.580139886705;3936.7637941723588;3944.6055394460323;3948.447217389568;3994.9201015979515;3996.4005399190355;5536.889262214733;5538.307711536058;5547.55619550435;5548.278723765885;5554.583406244467;5687.090065589568;5785;5787.8553036174435;5819.941800582024;5851.444112809277;5512.310961129859;5525.060739674265;5533.833984980457;5538.723063846792;5545.944540554624;4910.607151427877;5006.674566153189;5015.1975683890405;5025.572826309732;5075.289459475676;5952.404759524013;5991.850203744942;5998.670073145931;6068.241469816273;6070.362611925772;5848.766049357978;5851.94148058531;5852.531797456279;5856.824295271149;5864.824055278348;5985.221921150222;6039.516838652809;6053.122297266784;6053.364396738279;6058.212432383762;5574.16387541872;5669.121286200645;5740.282464691913;5740.483356497935;5782.710864456844;5820.243368362086;5853.619514731528;5868.501177399969;5872.55955803325;5882.323532793784;5562.3047119110115;5707.743151558107;5721.284839395075;5722.2561067061015;5728;5640.32316122068;5683.715814209356;5689.943100568941;5736.483716465538;5743.368229494836;5590.748416321194;5628.662280263197;5639.577031722719;5644.040513112863;5645.943540564542;5590.0496915523645;5668.37647858727;5763.8559036024435;5765.336986246635;5821.126830975219;5605.887882242414;5637.774489020558;5644.85887852807;5647.0964645656495;5647.830565083054 02:09:12 INFO - PROCESS | 26050 | |1;ext.html;598.6527813867955;633.9144215530905;644.5745807766874;655.0828839624527;655.8360409897525;32913.506297405525;43278.78360608197;44409.333859992126;44788.55211447884;44793.55206447939;46245;46709.532904670945;46770.53229467705;46806.29790553136;46839;7936.24605662464;8867.467951922888;9018.68434604789;10496.265261431703;10792.622258220963;10594.894051059468;10683.290673492244;10690.78618427631;10696.73258168548;10700.143988480942;10757.892421075807;10784.083352914973;10818.404987725671;10821.891781082168;10830.458477076154;10716.839247411295;10778.4610769462;10779.299345542593;10780.838287425695;10786.514606842711;10539.420331881744;10575.735606609896;10626.628068017659;10627.946860265727;10628.787424251548;42176;42604.78697606523;42647.57352426467;42651.786741066404;42661.146777064285;3668.63313668631;3686.594474607791;3769.3403654360377;3889.358255887775;4187.895302617429;32372.190695232806;32856.83571582151;33046.83476582626;33706.49440258398;33905.15237119067;4285.035866929916;4335.458067741532;4361.6510679145595;4380.912381752379;4423.623991960655;332442;334736;334879;336404;336436;60761.69619151876;60881.69559152176;61297.693511532154;61318.69340653268;61397;65245.67377163179;65276.67361663161;65341;65455.34544654588;65498.34501655018;15863.762043569355;16242.675146497004;16259.105749183673;16299.918500407422;16308.918455407647;11667.708307292216;11737.295762254293;11739.823902641467;11775.05799536035;11850.762984740257;8349.8330033399;8386.664533418718;8438.240558349655;8460.111688272642;8485;3192.154079169022;3202.1194171602997;3242.789218700777;3275.5905511811025;3292.0782180989363;969.3505351414569;1029.8978552078902;1064.3720205079042;1099.1701265544464;1164.2781475485085;32197.839010805266;32500.73523180966;32576;32698.83650581732;32953.505697414555;1080.5677728908342;1108.2630051016145;1111.3276467737064;1156.8264760285856;1183.5147589488267;16959.745603815954;17859.196336164903;18097.18562664683;18277.177527011318;18414.723779143325;511012;776374;776512;786331;790147;59941.40058599445;60189.39810601925;60338.69830650819;60693.39306606966;60715.39284607097;1191.684203686024;1368.2201145347256;1392.4847806311527;1415.1014106042737;1421.204125689617;30425.23936901595;34038.3192336147;34247.35249621781;34295.31409371849;34637.30725385529;51373.7431312856;51912;51957.740211298704;51983;52088;373186;374605;375086;375380;375880;27578.310542235802;28309.43381132407;28331.291717707223;28373.574396384483;28498.287542811595;49020.529384118534;49034.50965490299;49047;49597.25604115869;49945;40561.90077537903;40973.795131024155;41585;41830.58169418267;42057.57942420537;41024;41466.170676585694;41731.7913410431;42334;42407.575924240366;41142.79428602838;41484.1703165941;41629.58370416257;42404.787976059924;42758;35703.82148089243;36918.301982210636;37057.25885482329;37429.4385584211;37778.811105944296;28304.575431368135;28404.57393139146;28588.714112859438;30568.694313056585;30844.537331940486 02:09:12 INFO - PROCESS | 26050 | |2;jquery.html;407.81240629310514;408.5505943462191;408.8691618682021;409.7807672895002;425.7147711033607;12516.998640108792;17336;19104.331348402808;19125.234990600376;19839.404817855488;15098;15120.546383608482;18529.536761580934;18916.71624925627;19415.32046378377;5489.313835770528;5628.915566266509;5684.744186511617;5685.118806584966;5690.5447564194765;5516.91724624131;5784.16129661201;5788.363280039192;5836.591438599278;5854.09261564456;3896.08442016126;5552.194931734896;5784.363719990798;5817.709114544277;5819.039858423355;5792.536597072245;5818.44724751148;5839.8540036499;5851.590388672774;5854.356020837705;5497.780088796443;5669.2346533218315;5681.971590142023;5682.6306290091015;5695.14572814077;13459.528916487876;13750.931245343809;13811.378487968066;13820.170789752547;13971.441142354293;2399.5560821248123;2516.7357427470033;2851.9732896157366;2914.936048342361;3038.9696103039128;9974.650887218913;10374.740631484197;11007.669769906915;11076.612318568812;14745.631359215999;1917.549375896677;1947.0848701110456;2020.979790202094;2052.8768273903615;2105.2421128393767;79033.60483197604;82173.17826821715;82351.9999999994;82865.58567207125;82996;10989.450527473595;11072.335659860435;11181.552737890393;11238.21332506733;11383.658490245305;15526.689466210613;15627.687446251011;15780.210989450481;15809.446669366747;15827.68344633101;8082.232187942253;8201.83596328082;8293.8341233175;8441.95779021101;8454.61954212062;7888.329491993245;7896.921030789618;7929.841403171904;8062.919370806217;8070.192980701883;4757.881052973703;4778.1399348117675;4779.450363208206;4791.6406269529925;4834.806607735722;1979.8614097013071;1984.2757393551537;2000.5898790747863;2019.5657933544358;2027.9087441065187;478.57885061146663;968.5302628225305;1011.2466212671607;1084.9783004340027;1110.0675432636624;11230.3261804292;12188.634340969786;12191.756164876653;12223.633291001106;12281.69295767613;478.289739736491;487.7146869081556;502.24161516110206;505.30520534265384;521.0048806779109;6310.5267104967315;6481.09264702942;6528.510361722795;6544.345565443513;6573.441257493167;489945;496392;502098;512201;512484;91406;96635.51682241684;97611;100670.4966475163;101181.49409253054;1068.0921216965517;1070.6306324318098;1095.2825899036304;1100.1858624617885;1112.1214240749878;17209.186469386837;17486.82513174903;17676.73484897702;17830.197641105922;17890.821091788916;40077.59922400739;41615.37576936409;42522;42828.57171428246;42849.14301713886;56628;58269;58447.70776146092;58544;59211.40788592059;7564.319211271012;7820.921790782019;8124.431289809893;8545.444546104483;8673.609687563949;12399.814002790146;12597.181183223427;12686.682832929251;12774.36128193568;12792.081187821901;6082.452579267887;9544.761380965532;11662.358570278717;11875.52497900109;12109.394530273275;10880.619178328721;11105.333679979227;11380.658580242605;11795.52817887275;12067.215630983957;12124;12188.024958003516;12211.75576488483;12598.055145863913;12734.044946628854;8588.141185881363;8862;8939.910600893907;8963.910360896307;9063.456192628464;10735.785284294114;11193.944030279796;11656.18406711522;11773.88226117728;12166.878331216574 02:09:12 INFO - PROCESS | 26050 | |3;mootools.html;1363.7817949128141;1494.3200843616153;1536.8616824485796;1671.6405972715863;1857.377778444222;8436.072032076474;8747.781305467366;8771.736847894565;8953.373263871516;9012.053734357896;8737;8914.375993680445;8923.73228803137;8937.910620893788;8941.284697224222;2032.837373010163;2086.2698055680544;2266.909323627053;2293.1515339324433;2328.976710232893;2357.882105894707;2371.5494056129364;2378.1200955646436;2381.7499162587937;2385.105585405473;2017.6166528359638;2026.3211824038965;2057.670772676372;2062.0823733438624;2067.276453241361;1996.3212507747362;2199.2082850173924;2209.6795964585126;2266.5466906618576;2273;2267.036509483463;2295.334353037617;2295.747467778543;2295.896684649178;2297.058206135493;9060.637574497012;9089.454632722058;9115.22520585751;9129.132732390412;9136.223421009223;5282.762275697564;5412.566994640459;5413;5415.918761218585;5423.701696406696;3232.159638493985;3257.8533965971587;3263.951040734391;3375.544301519288;3391.491276308549;1000.6647772996055;1038.0242571982312;1043.639944219243;1044.958201671932;1047.2250534604414;12853.93573032129;12855.550055748097;12857.485700572059;12865.742685146339;12883.355832208446;4606.378138951199;4654.953450465519;4728.338032675429;4739.241721324572;4742.810287588527;10950.726231844108;10954.397508137128;10958.068564171976;10961.616343427942;10968.561257549769;5434.510894019557;5442.265294185234;5448.891022179534;5449.209864569694;5451.400345961941;4442.577955094261;4795.520447955177;4911.901761964741;4914.459409464956;5007.248912663057;4064.6425073457913;4089.407035979766;4151.813168407429;4159.272127377697;4166.250074986531;1255.209218192533;1256.9308688022247;1259.4521383198155;1265.6266401411606;1277.6614197237554;835.8119423129747;891.8483857744199;921.3734660430837;943.2689665509286;952.9714108576753;5909.8227053188475;6025.427584379477;6027.186329845415;6321.336259692758;6414.7811915735865;950.8858936927475;968.7287559483356;1008.0524307151254;1029.7734498410339;1048.2976405808126;3945.151792364655;4049.817758200888;4149.730267532601;4167.2082304362075;4208.200441916026;11429.714257143638;11649.941750291364;11736.413179340998;11792;11840.230385024946;11541.134414918915;11600.245984011011;11613.76772464563;11627.88372116268;11692.240004399688;8141.389395795339;8486.957565212258;8530.10433904431;8540.017897941692;8709.608067636971;6322.557420980487;6411.294757576756;6512.283648798723;6515.12045873801;6598.901016484852;10386;10558.155347572329;10646.73383165396;10753.623623173151;10849.728756781144;10884.238103332691;10905.182111341523;10948.178886583379;10950.835737464104;11108.111351092055;7396.4082873368925;7576.204498527575;7588.696452141852;7592.354649854824;7706.961465192639;8419;8430.15698430152;8584.484930904166;8601.096884827002;8827;7864.5281283123195;7903.56530390834;8046.959765201136;8181.713640022571;8317.168283171635;7784.260495252942;7989.920100798918;8026.8394632105865;8040.517568945882;8069.838603227785;7200.207977122408;7210;7286.271372862671;7287.380572651384;7308.5614863108385;4851.611871050378;4861.173600487855;4869.099216644932;4935.925961110514;4950.03474322518;4992.900141997213;5015.470281564158;5031.572316353005;5071.2139618359215;5112.412072611697 02:09:12 INFO - PROCESS | 26050 | |4;prototype.html;330.8697567422054;337.9121428428608;342.18559827610284;344.9103233159378;346.2279117567825;5395.514403703666;5447.509724124828;5462.940753178213;5466.835994920149;5518.475744804247;5182.974085129578;5196.636235463519;5311.176767601028;5372.516473517383;5406.864828379283;4187.706860519765;4219.13507730916;4230.534641189467;4237.38557909103;4242.278812601865;4222.134462435193;4224.72539284947;4244.108737165199;4247.320428731404;4252.0858015526655;4024.2756303865294;4027.436158937751;4204.831806727742;4208.831646734142;4247.320428731404;4145.233131870584;4173.3114036184;4210.452641156646;4221.852235171785;4223.936640950388;3751.624837516254;3761.1349389640263;3764.3412402829395;3771.924561508782;3784.564775050877;5147.819826306099;5188.844334669966;5193.8961220775755;5194.3247377840635;5314.441983591744;4807.75961201942;4813.66304358695;4826.61387089032;4833.685810422311;4858.878528036792;5539.861503462405;5594.440555944414;5640.351359593658;5650.660960342351;5673.716314184316;3864.420336949452;4033.334499807528;4079.4492743479846;4089.650415362953;4094.28350038742;5733.226014488074;5763.435271304271;5768.682722450264;5812.941870581282;5850.297964244275;4110.136871257009;4131.5248746394545;4140.503139623264;4144.217986265935;4152.709310348247;3999.440078389028;4136.379543068564;4176.979115104405;4188.476440444944;4191.91616167675;4786.234202527578;4787.4494433140635;4795;4800.159972004885;4830.95169048305;5617.38208797032;5630.493255607015;5658.830235092953;5667.489925906688;5677.716114194356;1395.239594421043;1420.6235347632885;1441.1929319581068;1472.146155229964;1476.6308422894276;930.7388488597987;934;947.4883562876107;952.1525842000625;959.3140904253493;4935.506449355107;4935.975320123376;4945.703257804544;4947.480514545993;4955.216122196007;5081.8475445736685;5102.846914592494;5111.795528178831;5114.3095682083185;5178.084108879663;4849.4908034656555;4874.245271702255;4892.633052521075;4895.164313382482;5115.360579927509;3052.1911693401266;3081.1064791210297;3097.6282846058616;3098.5816914716224;3102.37952409519;5977.880442391128;6001.4298641628975;6028.4272994065495;6053.273607167168;6054.54590905675;5697.401772813795;5931.1992880960515;5964.493018093511;5967.283925928917;5984.828041138204;5949;5978.342382337939;5992.58051936369;6001.159837622649;6022.307434645072;5595.272614560081;5810.94189058104;5828.76684932598;5853.151293062568;5869.325027621878;5754.9424505754405;5770.740316685843;5785.710714464176;5787.218725472176;5809.564282678732;5971.970140149271;5975.297040343481;6050.9394906050375;6098.207233059674;6102.718429129754;3987.72085953992;3994.8601798936897;4008.2183974124764;4175.62419382257;4193.580641935782;5759.596828222152;5795.043817770021;5796.565257605781;5799.362070172193;5812.476877081083;5611.38274789781;5618.2415373924;5669.659820410789;5677.261955945701;5682.5453963681975;5495.230667706604;5510.181640058851;5664.912622620599;5675.7445914933105;5679.574031947538;5469.453054694499;5481.945180548143;5526.143447765603;5528.917066243929;5542.475819149766;5431.538319242909;5651;5660.490555849993;5678.744456499385;5695.689991301941;5519.39286678457;5541.085720856014;5561.833145005657;5564.415736347625;5574.136008918624 02:09:12 INFO - PROCESS | 26050 | |5;yui.html;644.8968165093586;679.4123083532744;747.383408687833;757.9355754760845;771.5062360089543;13626.931865340672;14201.502947396843;14283.143011419295;14416.855831441682;14458.927705361471;13583.932080339584;13593.38829752661;13649.727005459885;13664.658383540416;13668.316584170776;680.6936878404738;783.8393129408486;802.5786462107396;820.167529957091;820.4748960665179;804.9959750201241;805.0138580239189;805.9717909873156;806.818465845183;806.9757907262791;841.1336323586685;841.5918279634403;841.9536925469097;842.1872892658583;843.3169133002257;701.3127135407304;816.4570560577209;838.1953324808192;838.5388036579888;845.5856630251194;621.0528943361364;621.3041393639108;621.6021746082511;621.7046902721171;621.7481919822434;13710.177389356571;13758.93120534401;13760.587182384445;13760.793588096187;13786.586402407944;12806.679833004157;13256.13835100725;13490;13496.865031349658;13702.794458083137;10956.178286628456;11057.281276716987;11217.15871309655;12232.877671223263;12334.198277111958;4494.550544945512;4498.280275155992;4511.278195488706;4528.162289976365;4690.530946905316;60902;61265;61433.07850382248;61459;61845;10508.842367364496;10706.411147386969;10796.78406431867;11036;11102.888971110186;17722.46832595024;17922.91038544799;17935;17940.730889036415;17953.371631992817;8424.368172387096;8501.574921254036;8527.488350698977;8698.391112622183;8804.163604457566;4809.78355973982;4959.603231741452;5008.524190201925;5072.315237442972;5200.739963001834;3197.2326641605846;3274.950875736866;3276.5085237214607;3281.015695291451;3286.1784553861535;617.5183356981562;618.2982315072425;628.0641843652925;639.8784230996189;645.0002496131071;648.8929326661142;663.0882536512296;685.5954986557955;750.084896426359;778.9298963093349;8902.821943561223;9240.075992400707;9289.210417114486;9340;9386.436813791195;528.5454509122152;616.8519555306784;672.8418821576926;695.0616667498937;697.8429853282971;4190.1200747843295;4289.206496798102;4325.5674432557125;4374.518802931675;4381.978090109529;66851;67693.32306676969;67982.30044248834;68244.6587767068;68337.65831170912;59931;60082.399176008556;61207.6939615308;61269.387306126366;62302.37697623057;54206;54270;54730.72634636881;55071.173932391044;55475.44524554784;8409.53747543891;8415.969475964203;8615.526146062026;8654.350923680624;8780.560971951505;37760;38076.61923380731;38375;38504.42243366408;39143.41284880672;47340.7632961833;49764;50570.74714626551;50582;51268;10695.679129626124;11039.668809935714;11392.886071139514;11506;11664.6500604982;13151.60545183646;13271.867281327062;13622.31888405556;13654.726905462034;13674;12645.94540745183;12660.620181394574;12816.423260953094;12819.230846149261;12940.41768120418;11290.548378064785;11979.28124312544;12241.571544995883;12546;12583.87416125827;11756.23584467007;11948;12008.699782505159;12200.572979945659;12445.688857778629;10306.587736490756;10409.68770936873;10446.126269667277;10566.00029763369;10768.300060496043;10680.57277708914;10922.617708380169;10924.344539327665;10952.123830093415;10999.61501347449 02:09:12 INFO - PROCESS | 26050 | __end_tp_report 02:09:12 INFO - PROCESS | 26050 | __start_cc_report 02:09:12 INFO - PROCESS | 26050 | _x_x_mozilla_cycle_collect,1064 02:09:12 INFO - PROCESS | 26050 | __end_cc_report 02:09:12 INFO - PROCESS | 26050 | __startTimestamp1463216952830__endTimestamp 02:09:12 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | ------- Summary: start ------- 02:09:12 INFO - PROCESS | 26050 | Number of tests: 6 02:09:12 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | [#0] dojo.html Cycles:165 Average:5400.45 Median:5636.89 stddev:610.85 (10.8%) stddev-sans-first:587.08 02:09:12 INFO - PROCESS | 26050 | Values: 3168.0 3174.9 3184.1 3219.2 3248.1 5306.7 5316.9 5406.0 5421.8 5465.3 5348.9 5355.4 5366.6 5406.1 5419.2 4822.3 4907.2 4917.2 4921.8 5147.2 5032.5 5054.1 5082.9 5094.1 5136.8 4825.3 4879.5 4950.8 4952.1 4990.8 4861.2 4947.2 4947.9 4949.8 4991.5 4880.5 4919.1 4920.3 4927.7 4975.8 5218.3 5273.5 5326.9 5336.5 5382.6 5797.2 5830.4 5831.3 5859.3 5864.6 5636.9 5823.7 5835.6 5880.7 5888.4 5311.8 5412.0 5419.9 5494.2 5500.5 5891.8 5929.8 5947.8 5952.5 5959.9 5700.7 5733.2 5744.6 5745.5 5747.6 5629.0 5774.9 5840.6 5843.4 5859.6 5713.9 5763.7 5771.1 5775.2 5778.6 5736.8 5786.5 5796.1 5802.9 5806.6 4322.5 4352.5 4374.8 4411.4 4419.6 3936.8 3944.6 3948.4 3994.9 3996.4 5536.9 5538.3 5547.6 5548.3 5554.6 5687.1 5785.0 5787.9 5819.9 5851.4 5512.3 5525.1 5533.8 5538.7 5545.9 4910.6 5006.7 5015.2 5025.6 5075.3 5952.4 5991.9 5998.7 6068.2 6070.4 5848.8 5851.9 5852.5 5856.8 5864.8 5985.2 6039.5 6053.1 6053.4 6058.2 5574.2 5669.1 5740.3 5740.5 5782.7 5820.2 5853.6 5868.5 5872.6 5882.3 5562.3 5707.7 5721.3 5722.3 5728.0 5640.3 5683.7 5689.9 5736.5 5743.4 5590.7 5628.7 5639.6 5644.0 5645.9 5590.0 5668.4 5763.9 5765.3 5821.1 5605.9 5637.8 5644.9 5647.1 5647.8 02:09:12 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | [#1] ext.html Cycles:180 Average:63841.65 Median:36987.78 stddev:138160.53 (373.5%) stddev-sans-first:138466.51 02:09:12 INFO - PROCESS | 26050 | Values: 598.7 633.9 644.6 655.1 655.8 32913.5 43278.8 44409.3 44788.6 44793.6 46245.0 46709.5 46770.5 46806.3 46839.0 7936.2 8867.5 9018.7 10496.3 10792.6 10594.9 10683.3 10690.8 10696.7 10700.1 10757.9 10784.1 10818.4 10821.9 10830.5 10716.8 10778.5 10779.3 10780.8 10786.5 10539.4 10575.7 10626.6 10627.9 10628.8 42176.0 42604.8 42647.6 42651.8 42661.1 3668.6 3686.6 3769.3 3889.4 4187.9 32372.2 32856.8 33046.8 33706.5 33905.2 4285.0 4335.5 4361.7 4380.9 4423.6 332442.0 334736.0 334879.0 336404.0 336436.0 60761.7 60881.7 61297.7 61318.7 61397.0 65245.7 65276.7 65341.0 65455.3 65498.3 15863.8 16242.7 16259.1 16299.9 16308.9 11667.7 11737.3 11739.8 11775.1 11850.8 8349.8 8386.7 8438.2 8460.1 8485.0 3192.2 3202.1 3242.8 3275.6 3292.1 969.4 1029.9 1064.4 1099.2 1164.3 32197.8 32500.7 32576.0 32698.8 32953.5 1080.6 1108.3 1111.3 1156.8 1183.5 16959.7 17859.2 18097.2 18277.2 18414.7 511012.0 776374.0 776512.0 786331.0 790147.0 59941.4 60189.4 60338.7 60693.4 60715.4 1191.7 1368.2 1392.5 1415.1 1421.2 30425.2 34038.3 34247.4 34295.3 34637.3 51373.7 51912.0 51957.7 51983.0 52088.0 373186.0 374605.0 375086.0 375380.0 375880.0 27578.3 28309.4 28331.3 28373.6 28498.3 49020.5 49034.5 49047.0 49597.3 49945.0 40561.9 40973.8 41585.0 41830.6 42057.6 41024.0 41466.2 41731.8 42334.0 42407.6 41142.8 41484.2 41629.6 42404.8 42758.0 35703.8 36918.3 37057.3 37429.4 37778.8 28304.6 28404.6 28588.7 30568.7 30844.5 02:09:12 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | [#2] jquery.html Cycles:180 Average:28879.50 Median:408.71 stddev:83070.47 (20325.0%) stddev-sans-first:83275.99 02:09:12 INFO - PROCESS | 26050 | Values: 407.8 408.6 408.9 409.8 425.7 12517.0 17336.0 19104.3 19125.2 19839.4 15098.0 15120.5 18529.5 18916.7 19415.3 5489.3 5628.9 5684.7 5685.1 5690.5 5516.9 5784.2 5788.4 5836.6 5854.1 3896.1 5552.2 5784.4 5817.7 5819.0 5792.5 5818.4 5839.9 5851.6 5854.4 5497.8 5669.2 5682.0 5682.6 5695.1 13459.5 13750.9 13811.4 13820.2 13971.4 2399.6 2516.7 2852.0 2914.9 3039.0 9974.7 10374.7 11007.7 11076.6 14745.6 1917.5 1947.1 2021.0 2052.9 2105.2 79033.6 82173.2 82352.0 82865.6 82996.0 10989.5 11072.3 11181.6 11238.2 11383.7 15526.7 15627.7 15780.2 15809.4 15827.7 8082.2 8201.8 8293.8 8442.0 8454.6 7888.3 7896.9 7929.8 8062.9 8070.2 4757.9 4778.1 4779.5 4791.6 4834.8 1979.9 1984.3 2000.6 2019.6 2027.9 478.6 968.5 1011.2 1085.0 1110.1 11230.3 12188.6 12191.8 12223.6 12281.7 478.3 487.7 502.2 505.3 521.0 6310.5 6481.1 6528.5 6544.3 6573.4 489945.0 496392.0 502098.0 512201.0 512484.0 91406.0 96635.5 97611.0 100670.5 101181.5 1068.1 1070.6 1095.3 1100.2 1112.1 17209.2 17486.8 17676.7 17830.2 17890.8 40077.6 41615.4 42522.0 42828.6 42849.1 56628.0 58269.0 58447.7 58544.0 59211.4 7564.3 7820.9 8124.4 8545.4 8673.6 12399.8 12597.2 12686.7 12774.4 12792.1 6082.5 9544.8 11662.4 11875.5 12109.4 10880.6 11105.3 11380.7 11795.5 12067.2 12124.0 12188.0 12211.8 12598.1 12734.0 8588.1 8862.0 8939.9 8963.9 9063.5 10735.8 11193.9 11656.2 11773.9 12166.9 02:09:12 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | [#3] mootools.html Cycles:180 Average:5965.51 Median:4691.65 stddev:3515.08 (74.9%) stddev-sans-first:3507.93 02:09:12 INFO - PROCESS | 26050 | Values: 1363.8 1494.3 1536.9 1671.6 1857.4 8436.1 8747.8 8771.7 8953.4 9012.1 8737.0 8914.4 8923.7 8937.9 8941.3 2032.8 2086.3 2266.9 2293.2 2329.0 2357.9 2371.5 2378.1 2381.7 2385.1 2017.6 2026.3 2057.7 2062.1 2067.3 1996.3 2199.2 2209.7 2266.5 2273.0 2267.0 2295.3 2295.7 2295.9 2297.1 9060.6 9089.5 9115.2 9129.1 9136.2 5282.8 5412.6 5413.0 5415.9 5423.7 3232.2 3257.9 3264.0 3375.5 3391.5 1000.7 1038.0 1043.6 1045.0 1047.2 12853.9 12855.6 12857.5 12865.7 12883.4 4606.4 4655.0 4728.3 4739.2 4742.8 10950.7 10954.4 10958.1 10961.6 10968.6 5434.5 5442.3 5448.9 5449.2 5451.4 4442.6 4795.5 4911.9 4914.5 5007.2 4064.6 4089.4 4151.8 4159.3 4166.3 1255.2 1256.9 1259.5 1265.6 1277.7 835.8 891.8 921.4 943.3 953.0 5909.8 6025.4 6027.2 6321.3 6414.8 950.9 968.7 1008.1 1029.8 1048.3 3945.2 4049.8 4149.7 4167.2 4208.2 11429.7 11649.9 11736.4 11792.0 11840.2 11541.1 11600.2 11613.8 11627.9 11692.2 8141.4 8487.0 8530.1 8540.0 8709.6 6322.6 6411.3 6512.3 6515.1 6598.9 10386.0 10558.2 10646.7 10753.6 10849.7 10884.2 10905.2 10948.2 10950.8 11108.1 7396.4 7576.2 7588.7 7592.4 7707.0 8419.0 8430.2 8584.5 8601.1 8827.0 7864.5 7903.6 8047.0 8181.7 8317.2 7784.3 7989.9 8026.8 8040.5 8069.8 7200.2 7210.0 7286.3 7287.4 7308.6 4851.6 4861.2 4869.1 4935.9 4950.0 4992.9 5015.5 5031.6 5071.2 5112.4 02:09:12 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | [#4] prototype.html Cycles:180 Average:4712.70 Median:5253.91 stddev:1382.18 (26.3%) stddev-sans-first:1346.36 02:09:12 INFO - PROCESS | 26050 | Values: 330.9 337.9 342.2 344.9 346.2 5395.5 5447.5 5462.9 5466.8 5518.5 5183.0 5196.6 5311.2 5372.5 5406.9 4187.7 4219.1 4230.5 4237.4 4242.3 4222.1 4224.7 4244.1 4247.3 4252.1 4024.3 4027.4 4204.8 4208.8 4247.3 4145.2 4173.3 4210.5 4221.9 4223.9 3751.6 3761.1 3764.3 3771.9 3784.6 5147.8 5188.8 5193.9 5194.3 5314.4 4807.8 4813.7 4826.6 4833.7 4858.9 5539.9 5594.4 5640.4 5650.7 5673.7 3864.4 4033.3 4079.4 4089.7 4094.3 5733.2 5763.4 5768.7 5812.9 5850.3 4110.1 4131.5 4140.5 4144.2 4152.7 3999.4 4136.4 4177.0 4188.5 4191.9 4786.2 4787.4 4795.0 4800.2 4831.0 5617.4 5630.5 5658.8 5667.5 5677.7 1395.2 1420.6 1441.2 1472.1 1476.6 930.7 934.0 947.5 952.2 959.3 4935.5 4936.0 4945.7 4947.5 4955.2 5081.8 5102.8 5111.8 5114.3 5178.1 4849.5 4874.2 4892.6 4895.2 5115.4 3052.2 3081.1 3097.6 3098.6 3102.4 5977.9 6001.4 6028.4 6053.3 6054.5 5697.4 5931.2 5964.5 5967.3 5984.8 5949.0 5978.3 5992.6 6001.2 6022.3 5595.3 5810.9 5828.8 5853.2 5869.3 5754.9 5770.7 5785.7 5787.2 5809.6 5972.0 5975.3 6050.9 6098.2 6102.7 3987.7 3994.9 4008.2 4175.6 4193.6 5759.6 5795.0 5796.6 5799.4 5812.5 5611.4 5618.2 5669.7 5677.3 5682.5 5495.2 5510.2 5664.9 5675.7 5679.6 5469.5 5481.9 5526.1 5528.9 5542.5 5431.5 5651.0 5660.5 5678.7 5695.7 5519.4 5541.1 5561.8 5564.4 5574.1 02:09:12 INFO - PROCESS | 26050 | 02:09:12 INFO - PROCESS | 26050 | [#5] yui.html Cycles:180 Average:15600.01 Median:26075.27 stddev:18996.74 (72.9%) stddev-sans-first:19016.83 02:09:12 INFO - PROCESS | 26050 | Values: 644.9 679.4 747.4 757.9 771.5 13626.9 14201.5 14283.1 14416.9 14458.9 13583.9 13593.4 13649.7 13664.7 13668.3 680.7 783.8 802.6 820.2 820.5 805.0 805.0 806.0 806.8 807.0 841.1 841.6 842.0 842.2 843.3 701.3 816.5 838.2 838.5 845.6 621.1 621.3 621.6 621.7 621.7 13710.2 13758.9 13760.6 13760.8 13786.6 12806.7 13256.1 13490.0 13496.9 13702.8 10956.2 11057.3 11217.2 12232.9 12334.2 4494.6 4498.3 4511.3 4528.2 4690.5 60902.0 61265.0 61433.1 61459.0 61845.0 10508.8 10706.4 10796.8 11036.0 11102.9 17722.5 17922.9 17935.0 17940.7 17953.4 8424.4 8501.6 8527.5 8698.4 8804.2 4809.8 4959.6 5008.5 5072.3 5200.7 3197.2 3275.0 3276.5 3281.0 3286.2 617.5 618.3 628.1 639.9 645.0 648.9 663.1 685.6 750.1 778.9 8902.8 9240.1 9289.2 9340.0 9386.4 528.5 616.9 672.8 695.1 697.8 4190.1 4289.2 4325.6 4374.5 4382.0 66851.0 67693.3 67982.3 68244.7 68337.7 59931.0 60082.4 61207.7 61269.4 62302.4 54206.0 54270.0 54730.7 55071.2 55475.4 8409.5 8416.0 8615.5 8654.4 8780.6 37760.0 38076.6 38375.0 38504.4 39143.4 47340.8 49764.0 50570.7 50582.0 51268.0 10695.7 11039.7 11392.9 11506.0 11664.7 13151.6 13271.9 13622.3 13654.7 13674.0 12645.9 12660.6 12816.4 12819.2 12940.4 11290.5 11979.3 12241.6 12546.0 12583.9 11756.2 11948.0 12008.7 12200.6 12445.7 10306.6 10409.7 10446.1 10566.0 10768.3 10680.6 10922.6 10924.3 10952.1 10999.6 02:09:12 INFO - PROCESS | 26050 | -------- Summary: end -------- 02:09:12 INFO - PROCESS | 26050 | 02:09:13 INFO - TEST-INFO | 26050: exit 0 02:09:13 INFO - TEST-OK | dromaeo_css | took 1113064ms 02:09:13 INFO - TEST-START | kraken 02:09:13 INFO - operating with platform_type : linux_ 02:09:13 INFO - Initialising browser for kraken test... 02:09:13 INFO - TEST-INFO | started process 26229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNKGify/profile http://localhost:47782/getInfo.html) 02:09:17 INFO - PROCESS | 26229 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 02:09:17 INFO - PROCESS | 26229 | __metrics Screen width/height:1600/1200 02:09:17 INFO - PROCESS | 26229 | colorDepth:24 02:09:17 INFO - PROCESS | 26229 | Browser inner width/height: 1024/697 02:09:17 INFO - PROCESS | 26229 | __metrics 02:09:18 ERROR - PROCESS | 26229 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:09:19 INFO - TEST-INFO | 26229: exit 0 02:09:19 INFO - Browser initialized. 02:09:19 INFO - Running cycle 1/1 for kraken test... 02:09:19 INFO - TEST-INFO | started process 26309 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNKGify/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:09:31 INFO - PROCESS | 26309 | RSS: Main: 152567808 02:09:31 INFO - PROCESS | 26309 | 02:09:33 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47782/tests/kraken/driver.html?testName=audio-beat-detection) 02:09:34 INFO - PROCESS | 26309 | RSS: Main: 208662528 02:09:34 INFO - PROCESS | 26309 | 02:09:37 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47782/tests/kraken/driver.html?testName=audio-dft) 02:09:37 INFO - PROCESS | 26309 | RSS: Main: 197689344 02:09:37 INFO - PROCESS | 26309 | 02:09:41 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47782/tests/kraken/driver.html?testName=audio-fft) 02:09:41 INFO - PROCESS | 26309 | RSS: Main: 265662464 02:09:41 INFO - PROCESS | 26309 | 02:09:43 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47782/tests/kraken/driver.html?testName=audio-oscillator) 02:09:44 INFO - PROCESS | 26309 | RSS: Main: 174481408 02:09:44 INFO - PROCESS | 26309 | 02:09:46 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47782/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:09:47 INFO - PROCESS | 26309 | RSS: Main: 178499584 02:09:47 INFO - PROCESS | 26309 | 02:09:50 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47782/tests/kraken/driver.html?testName=imaging-darkroom) 02:09:51 INFO - PROCESS | 26309 | RSS: Main: 214949888 02:09:51 INFO - PROCESS | 26309 | 02:09:54 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47782/tests/kraken/driver.html?testName=imaging-desaturate) 02:09:55 INFO - PROCESS | 26309 | RSS: Main: 212475904 02:09:55 INFO - PROCESS | 26309 | 02:09:58 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47782/tests/kraken/driver.html?testName=json-parse-financial) 02:09:58 INFO - PROCESS | 26309 | RSS: Main: 213585920 02:09:58 INFO - PROCESS | 26309 | 02:10:00 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47782/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:10:00 INFO - PROCESS | 26309 | RSS: Main: 205881344 02:10:00 INFO - PROCESS | 26309 | 02:10:02 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47782/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:10:03 INFO - PROCESS | 26309 | RSS: Main: 210329600 02:10:03 INFO - PROCESS | 26309 | 02:10:05 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47782/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:10:06 INFO - PROCESS | 26309 | RSS: Main: 217911296 02:10:06 INFO - PROCESS | 26309 | 02:10:08 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47782/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:10:09 INFO - PROCESS | 26309 | RSS: Main: 223653888 02:10:09 INFO - PROCESS | 26309 | 02:10:12 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47782/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:10:12 INFO - PROCESS | 26309 | RSS: Main: 219934720 02:10:12 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | Cycle 1(1): loaded http://localhost:47782/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47782/tests/kraken/driver.html?testName=ai-astar) 02:10:14 INFO - PROCESS | 26309 | RSS: Main: 265416704 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | __start_tp_report 02:10:14 INFO - PROCESS | 26309 | _x_x_mozilla_page_load 02:10:14 INFO - PROCESS | 26309 | _x_x_mozilla_page_load_details 02:10:14 INFO - PROCESS | 26309 | |i|pagename|runs| 02:10:14 INFO - PROCESS | 26309 | |0;ai-astar;110;105;109;109;110;111;112;109;111;111 02:10:14 INFO - PROCESS | 26309 | |1;audio-beat-detection;166;132;156;204;124;155;157;137;193;123 02:10:14 INFO - PROCESS | 26309 | |2;audio-dft;160;161;162;160;161;161;161;162;162;160 02:10:14 INFO - PROCESS | 26309 | |3;audio-fft;74;75;75;152;75;75;74;74;144;77 02:10:14 INFO - PROCESS | 26309 | |4;audio-oscillator;92;91;146;91;91;92;132;91;92;125 02:10:14 INFO - PROCESS | 26309 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;116;117 02:10:14 INFO - PROCESS | 26309 | |6;imaging-darkroom;146;147;146;145;145;146;145;145;146;146 02:10:14 INFO - PROCESS | 26309 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;96;96 02:10:14 INFO - PROCESS | 26309 | |8;json-parse-financial;56;53;56;54;56;56;54;106;52;51 02:10:14 INFO - PROCESS | 26309 | |9;json-stringify-tinderbox;58;59;58;58;59;60;59;60;61;60 02:10:14 INFO - PROCESS | 26309 | |10;stanford-crypto-aes;78;78;84;78;76;78;79;83;79;78 02:10:14 INFO - PROCESS | 26309 | |11;stanford-crypto-ccm;131;120;125;122;127;127;126;125;196;123 02:10:14 INFO - PROCESS | 26309 | |12;stanford-crypto-pbkdf2;165;163;162;160;161;161;161;163;159;160 02:10:14 INFO - PROCESS | 26309 | |13;stanford-crypto-sha256-iterative;64;63;63;63;62;61;63;63;61;61 02:10:14 INFO - PROCESS | 26309 | __end_tp_report 02:10:14 INFO - PROCESS | 26309 | __start_cc_report 02:10:14 INFO - PROCESS | 26309 | _x_x_mozilla_cycle_collect,1829 02:10:14 INFO - PROCESS | 26309 | __end_cc_report 02:10:14 INFO - PROCESS | 26309 | __startTimestamp1463217014760__endTimestamp 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | ------- Summary: start ------- 02:10:14 INFO - PROCESS | 26309 | Number of tests: 14 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:1.95 (1.8%) stddev-sans-first:2.06 02:10:14 INFO - PROCESS | 26309 | Values: 110.0 105.0 109.0 109.0 110.0 111.0 112.0 109.0 111.0 111.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#1] audio-beat-detection Cycles:10 Average:154.70 Median:156.50 stddev:27.50 (17.6%) stddev-sans-first:28.87 02:10:14 INFO - PROCESS | 26309 | Values: 166.0 132.0 156.0 204.0 124.0 155.0 157.0 137.0 193.0 123.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.78 02:10:14 INFO - PROCESS | 26309 | Values: 160.0 161.0 162.0 160.0 161.0 161.0 161.0 162.0 162.0 160.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#3] audio-fft Cycles:10 Average:89.50 Median:75.00 stddev:30.90 (41.2%) stddev-sans-first:32.26 02:10:14 INFO - PROCESS | 26309 | Values: 74.0 75.0 75.0 152.0 75.0 75.0 74.0 74.0 144.0 77.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#4] audio-oscillator Cycles:10 Average:104.30 Median:91.00 stddev:21.33 (23.4%) stddev-sans-first:22.16 02:10:14 INFO - PROCESS | 26309 | Values: 92.0 91.0 146.0 91.0 91.0 92.0 132.0 91.0 92.0 125.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 02:10:14 INFO - PROCESS | 26309 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 02:10:14 INFO - PROCESS | 26309 | Values: 146.0 147.0 146.0 145.0 145.0 146.0 145.0 145.0 146.0 146.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 02:10:14 INFO - PROCESS | 26309 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:55.00 stddev:16.47 (30.0%) stddev-sans-first:17.43 02:10:14 INFO - PROCESS | 26309 | Values: 56.0 53.0 56.0 54.0 56.0 56.0 54.0 106.0 52.0 51.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.50 stddev:1.03 (1.7%) stddev-sans-first:1.00 02:10:14 INFO - PROCESS | 26309 | Values: 58.0 59.0 58.0 58.0 59.0 60.0 59.0 60.0 61.0 60.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:78.50 stddev:2.47 (3.1%) stddev-sans-first:2.59 02:10:14 INFO - PROCESS | 26309 | Values: 78.0 78.0 84.0 78.0 76.0 78.0 79.0 83.0 79.0 78.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:126.50 stddev:22.62 (17.9%) stddev-sans-first:23.99 02:10:14 INFO - PROCESS | 26309 | Values: 131.0 120.0 125.0 122.0 127.0 127.0 126.0 125.0 196.0 123.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:161.50 stddev:1.78 (1.1%) stddev-sans-first:1.36 02:10:14 INFO - PROCESS | 26309 | Values: 165.0 163.0 162.0 160.0 161.0 161.0 161.0 163.0 159.0 160.0 02:10:14 INFO - PROCESS | 26309 | 02:10:14 INFO - PROCESS | 26309 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:1.07 (1.7%) stddev-sans-first:0.97 02:10:14 INFO - PROCESS | 26309 | Values: 64.0 63.0 63.0 63.0 62.0 61.0 63.0 63.0 61.0 61.0 02:10:14 INFO - PROCESS | 26309 | -------- Summary: end -------- 02:10:14 INFO - PROCESS | 26309 | 02:10:15 INFO - TEST-INFO | 26309: exit 0 02:10:15 INFO - TEST-OK | kraken | took 62202ms 02:10:15 INFO - SUITE-END | took 1175s 02:10:15 INFO - Completed test suite (00:19:35) 02:10:15 INFO - javascript benchmark 02:10:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3167.9683203167974, 3174.9365012699745, 3184.092533627916, 3219.243477782722, 3248.1230067881675, 5306.734663266836, 5316.9202461963005, 5406.02691515527, 5421.756020979055, 5465.2895123633925, 5348.866278343044, 5355.410904800468, 5366.597505187107, 5406.053940560406, 5419.160030195316, 4822.252550854623, 4907.2148456247005, 4917.188663870457, 4921.753912304388, 5147.150720131174, 5032.471590483002, 5054.064997975386, 5082.923756143642, 5094.1085310070775, 5136.768845401947, 4825.276208568725, 4879.536444037811, 4950.801967921278, 4952.108620448336, 4990.750462476862, 4861.197902346144, 4947.183714687072, 4947.901041979177, 4949.752512374367, 4991.525805048551, 4880.487548807395, 4919.139150648658, 4920.3111564381015, 4927.704337739747, 4975.7760900759195, 5218.295530103425, 5273.472652734734, 5326.946730532683, 5336.519713225828, 5382.6232163748555, 5797.217375654317, 5830.4169583041785, 5831.329397119301, 5859.267591551056, 5864.6187997780435, 5636.887262254732, 5823.650580965155, 5835.591508594358, 5880.6765627890445, 5888.352281249059, 5311.760970756325, 5411.972940135313, 5419.918701219445, 5494.203340515683, 5500.477454641843, 5891.823245302648, 5929.822105336847, 5947.821565353046, 5952.494038006731, 5959.880802384015, 5700.714964251771, 5733.226014487991, 5744.597878148489, 5745.482906538432, 5747.597668163188, 5628.971855140698, 5774.942250577525, 5840.620359676607, 5843.3864444233595, 5859.589828712034, 5713.942860571424, 5763.740631671585, 5771.076627739625, 5775.249217601685, 5778.624389414675, 5736.799212027559, 5786.508146807484, 5796.1305804129715, 5802.854928626733, 5806.593538452352, 4322.546132656026, 4352.521222665504, 4374.846880359172, 4411.404460397805, 4419.580139886705, 3936.7637941723588, 3944.6055394460323, 3948.447217389568, 3994.9201015979515, 3996.4005399190355, 5536.889262214733, 5538.307711536058, 5547.55619550435, 5548.278723765885, 5554.583406244467, 5687.090065589568, 5785.0, 5787.8553036174435, 5819.941800582024, 5851.444112809277, 5512.310961129859, 5525.060739674265, 5533.833984980457, 5538.723063846792, 5545.944540554624, 4910.607151427877, 5006.674566153189, 5015.1975683890405, 5025.572826309732, 5075.289459475676, 5952.404759524013, 5991.850203744942, 5998.670073145931, 6068.241469816273, 6070.362611925772, 5848.766049357978, 5851.94148058531, 5852.531797456279, 5856.824295271149, 5864.824055278348, 5985.221921150222, 6039.516838652809, 6053.122297266784, 6053.364396738279, 6058.212432383762, 5574.16387541872, 5669.121286200645, 5740.282464691913, 5740.483356497935, 5782.710864456844, 5820.243368362086, 5853.619514731528, 5868.501177399969, 5872.55955803325, 5882.323532793784, 5562.3047119110115, 5707.743151558107, 5721.284839395075, 5722.2561067061015, 5728.0, 5640.32316122068, 5683.715814209356, 5689.943100568941, 5736.483716465538, 5743.368229494836, 5590.748416321194, 5628.662280263197, 5639.577031722719, 5644.040513112863, 5645.943540564542, 5590.0496915523645, 5668.37647858727, 5763.8559036024435, 5765.336986246635, 5821.126830975219, 5605.887882242414, 5637.774489020558, 5644.85887852807, 5647.0964645656495, 5647.830565083054], "value": 5359.655130309172, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.6527813867955, 633.9144215530905, 644.5745807766874, 655.0828839624527, 655.8360409897525, 32913.506297405525, 43278.78360608197, 44409.333859992126, 44788.55211447884, 44793.55206447939, 46245.0, 46709.532904670945, 46770.53229467705, 46806.29790553136, 46839.0, 7936.24605662464, 8867.467951922888, 9018.68434604789, 10496.265261431703, 10792.622258220963, 10594.894051059468, 10683.290673492244, 10690.78618427631, 10696.73258168548, 10700.143988480942, 10757.892421075807, 10784.083352914973, 10818.404987725671, 10821.891781082168, 10830.458477076154, 10716.839247411295, 10778.4610769462, 10779.299345542593, 10780.838287425695, 10786.514606842711, 10539.420331881744, 10575.735606609896, 10626.628068017659, 10627.946860265727, 10628.787424251548, 42176.0, 42604.78697606523, 42647.57352426467, 42651.786741066404, 42661.146777064285, 3668.63313668631, 3686.594474607791, 3769.3403654360377, 3889.358255887775, 4187.895302617429, 32372.190695232806, 32856.83571582151, 33046.83476582626, 33706.49440258398, 33905.15237119067, 4285.035866929916, 4335.458067741532, 4361.6510679145595, 4380.912381752379, 4423.623991960655, 332442.0, 334736.0, 334879.0, 336404.0, 336436.0, 60761.69619151876, 60881.69559152176, 61297.693511532154, 61318.69340653268, 61397.0, 65245.67377163179, 65276.67361663161, 65341.0, 65455.34544654588, 65498.34501655018, 15863.762043569355, 16242.675146497004, 16259.105749183673, 16299.918500407422, 16308.918455407647, 11667.708307292216, 11737.295762254293, 11739.823902641467, 11775.05799536035, 11850.762984740257, 8349.8330033399, 8386.664533418718, 8438.240558349655, 8460.111688272642, 8485.0, 3192.154079169022, 3202.1194171602997, 3242.789218700777, 3275.5905511811025, 3292.0782180989363, 969.3505351414569, 1029.8978552078902, 1064.3720205079042, 1099.1701265544464, 1164.2781475485085, 32197.839010805266, 32500.73523180966, 32576.0, 32698.83650581732, 32953.505697414555, 1080.5677728908342, 1108.2630051016145, 1111.3276467737064, 1156.8264760285856, 1183.5147589488267, 16959.745603815954, 17859.196336164903, 18097.18562664683, 18277.177527011318, 18414.723779143325, 511012.0, 776374.0, 776512.0, 786331.0, 790147.0, 59941.40058599445, 60189.39810601925, 60338.69830650819, 60693.39306606966, 60715.39284607097, 1191.684203686024, 1368.2201145347256, 1392.4847806311527, 1415.1014106042737, 1421.204125689617, 30425.23936901595, 34038.3192336147, 34247.35249621781, 34295.31409371849, 34637.30725385529, 51373.7431312856, 51912.0, 51957.740211298704, 51983.0, 52088.0, 373186.0, 374605.0, 375086.0, 375380.0, 375880.0, 27578.310542235802, 28309.43381132407, 28331.291717707223, 28373.574396384483, 28498.287542811595, 49020.529384118534, 49034.50965490299, 49047.0, 49597.25604115869, 49945.0, 40561.90077537903, 40973.795131024155, 41585.0, 41830.58169418267, 42057.57942420537, 41024.0, 41466.170676585694, 41731.7913410431, 42334.0, 42407.575924240366, 41142.79428602838, 41484.1703165941, 41629.58370416257, 42404.787976059924, 42758.0, 35703.82148089243, 36918.301982210636, 37057.25885482329, 37429.4385584211, 37778.811105944296, 28304.575431368135, 28404.57393139146, 28588.714112859438, 30568.694313056585, 30844.537331940486], "value": 20059.74694051955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.81240629310514, 408.5505943462191, 408.8691618682021, 409.7807672895002, 425.7147711033607, 12516.998640108792, 17336.0, 19104.331348402808, 19125.234990600376, 19839.404817855488, 15098.0, 15120.546383608482, 18529.536761580934, 18916.71624925627, 19415.32046378377, 5489.313835770528, 5628.915566266509, 5684.744186511617, 5685.118806584966, 5690.5447564194765, 5516.91724624131, 5784.16129661201, 5788.363280039192, 5836.591438599278, 5854.09261564456, 3896.08442016126, 5552.194931734896, 5784.363719990798, 5817.709114544277, 5819.039858423355, 5792.536597072245, 5818.44724751148, 5839.8540036499, 5851.590388672774, 5854.356020837705, 5497.780088796443, 5669.2346533218315, 5681.971590142023, 5682.6306290091015, 5695.14572814077, 13459.528916487876, 13750.931245343809, 13811.378487968066, 13820.170789752547, 13971.441142354293, 2399.5560821248123, 2516.7357427470033, 2851.9732896157366, 2914.936048342361, 3038.9696103039128, 9974.650887218913, 10374.740631484197, 11007.669769906915, 11076.612318568812, 14745.631359215999, 1917.549375896677, 1947.0848701110456, 2020.979790202094, 2052.8768273903615, 2105.2421128393767, 79033.60483197604, 82173.17826821715, 82351.9999999994, 82865.58567207125, 82996.0, 10989.450527473595, 11072.335659860435, 11181.552737890393, 11238.21332506733, 11383.658490245305, 15526.689466210613, 15627.687446251011, 15780.210989450481, 15809.446669366747, 15827.68344633101, 8082.232187942253, 8201.83596328082, 8293.8341233175, 8441.95779021101, 8454.61954212062, 7888.329491993245, 7896.921030789618, 7929.841403171904, 8062.919370806217, 8070.192980701883, 4757.881052973703, 4778.1399348117675, 4779.450363208206, 4791.6406269529925, 4834.806607735722, 1979.8614097013071, 1984.2757393551537, 2000.5898790747863, 2019.5657933544358, 2027.9087441065187, 478.57885061146663, 968.5302628225305, 1011.2466212671607, 1084.9783004340027, 1110.0675432636624, 11230.3261804292, 12188.634340969786, 12191.756164876653, 12223.633291001106, 12281.69295767613, 478.289739736491, 487.7146869081556, 502.24161516110206, 505.30520534265384, 521.0048806779109, 6310.5267104967315, 6481.09264702942, 6528.510361722795, 6544.345565443513, 6573.441257493167, 489945.0, 496392.0, 502098.0, 512201.0, 512484.0, 91406.0, 96635.51682241684, 97611.0, 100670.4966475163, 101181.49409253054, 1068.0921216965517, 1070.6306324318098, 1095.2825899036304, 1100.1858624617885, 1112.1214240749878, 17209.186469386837, 17486.82513174903, 17676.73484897702, 17830.197641105922, 17890.821091788916, 40077.59922400739, 41615.37576936409, 42522.0, 42828.57171428246, 42849.14301713886, 56628.0, 58269.0, 58447.70776146092, 58544.0, 59211.40788592059, 7564.319211271012, 7820.921790782019, 8124.431289809893, 8545.444546104483, 8673.609687563949, 12399.814002790146, 12597.181183223427, 12686.682832929251, 12774.36128193568, 12792.081187821901, 6082.452579267887, 9544.761380965532, 11662.358570278717, 11875.52497900109, 12109.394530273275, 10880.619178328721, 11105.333679979227, 11380.658580242605, 11795.52817887275, 12067.215630983957, 12124.0, 12188.024958003516, 12211.75576488483, 12598.055145863913, 12734.044946628854, 8588.141185881363, 8862.0, 8939.910600893907, 8963.910360896307, 9063.456192628464, 10735.785284294114, 11193.944030279796, 11656.18406711522, 11773.88226117728, 12166.878331216574], "value": 8723.934299311191, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.7817949128141, 1494.3200843616153, 1536.8616824485796, 1671.6405972715863, 1857.377778444222, 8436.072032076474, 8747.781305467366, 8771.736847894565, 8953.373263871516, 9012.053734357896, 8737.0, 8914.375993680445, 8923.73228803137, 8937.910620893788, 8941.284697224222, 2032.837373010163, 2086.2698055680544, 2266.909323627053, 2293.1515339324433, 2328.976710232893, 2357.882105894707, 2371.5494056129364, 2378.1200955646436, 2381.7499162587937, 2385.105585405473, 2017.6166528359638, 2026.3211824038965, 2057.670772676372, 2062.0823733438624, 2067.276453241361, 1996.3212507747362, 2199.2082850173924, 2209.6795964585126, 2266.5466906618576, 2273.0, 2267.036509483463, 2295.334353037617, 2295.747467778543, 2295.896684649178, 2297.058206135493, 9060.637574497012, 9089.454632722058, 9115.22520585751, 9129.132732390412, 9136.223421009223, 5282.762275697564, 5412.566994640459, 5413.0, 5415.918761218585, 5423.701696406696, 3232.159638493985, 3257.8533965971587, 3263.951040734391, 3375.544301519288, 3391.491276308549, 1000.6647772996055, 1038.0242571982312, 1043.639944219243, 1044.958201671932, 1047.2250534604414, 12853.93573032129, 12855.550055748097, 12857.485700572059, 12865.742685146339, 12883.355832208446, 4606.378138951199, 4654.953450465519, 4728.338032675429, 4739.241721324572, 4742.810287588527, 10950.726231844108, 10954.397508137128, 10958.068564171976, 10961.616343427942, 10968.561257549769, 5434.510894019557, 5442.265294185234, 5448.891022179534, 5449.209864569694, 5451.400345961941, 4442.577955094261, 4795.520447955177, 4911.901761964741, 4914.459409464956, 5007.248912663057, 4064.6425073457913, 4089.407035979766, 4151.813168407429, 4159.272127377697, 4166.250074986531, 1255.209218192533, 1256.9308688022247, 1259.4521383198155, 1265.6266401411606, 1277.6614197237554, 835.8119423129747, 891.8483857744199, 921.3734660430837, 943.2689665509286, 952.9714108576753, 5909.8227053188475, 6025.427584379477, 6027.186329845415, 6321.336259692758, 6414.7811915735865, 950.8858936927475, 968.7287559483356, 1008.0524307151254, 1029.7734498410339, 1048.2976405808126, 3945.151792364655, 4049.817758200888, 4149.730267532601, 4167.2082304362075, 4208.200441916026, 11429.714257143638, 11649.941750291364, 11736.413179340998, 11792.0, 11840.230385024946, 11541.134414918915, 11600.245984011011, 11613.76772464563, 11627.88372116268, 11692.240004399688, 8141.389395795339, 8486.957565212258, 8530.10433904431, 8540.017897941692, 8709.608067636971, 6322.557420980487, 6411.294757576756, 6512.283648798723, 6515.12045873801, 6598.901016484852, 10386.0, 10558.155347572329, 10646.73383165396, 10753.623623173151, 10849.728756781144, 10884.238103332691, 10905.182111341523, 10948.178886583379, 10950.835737464104, 11108.111351092055, 7396.4082873368925, 7576.204498527575, 7588.696452141852, 7592.354649854824, 7706.961465192639, 8419.0, 8430.15698430152, 8584.484930904166, 8601.096884827002, 8827.0, 7864.5281283123195, 7903.56530390834, 8046.959765201136, 8181.713640022571, 8317.168283171635, 7784.260495252942, 7989.920100798918, 8026.8394632105865, 8040.517568945882, 8069.838603227785, 7200.207977122408, 7210.0, 7286.271372862671, 7287.380572651384, 7308.5614863108385, 4851.611871050378, 4861.173600487855, 4869.099216644932, 4935.925961110514, 4950.03474322518, 4992.900141997213, 5015.470281564158, 5031.572316353005, 5071.2139618359215, 5112.412072611697], "value": 4694.769279189918, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.8697567422054, 337.9121428428608, 342.18559827610284, 344.9103233159378, 346.2279117567825, 5395.514403703666, 5447.509724124828, 5462.940753178213, 5466.835994920149, 5518.475744804247, 5182.974085129578, 5196.636235463519, 5311.176767601028, 5372.516473517383, 5406.864828379283, 4187.706860519765, 4219.13507730916, 4230.534641189467, 4237.38557909103, 4242.278812601865, 4222.134462435193, 4224.72539284947, 4244.108737165199, 4247.320428731404, 4252.0858015526655, 4024.2756303865294, 4027.436158937751, 4204.831806727742, 4208.831646734142, 4247.320428731404, 4145.233131870584, 4173.3114036184, 4210.452641156646, 4221.852235171785, 4223.936640950388, 3751.624837516254, 3761.1349389640263, 3764.3412402829395, 3771.924561508782, 3784.564775050877, 5147.819826306099, 5188.844334669966, 5193.8961220775755, 5194.3247377840635, 5314.441983591744, 4807.75961201942, 4813.66304358695, 4826.61387089032, 4833.685810422311, 4858.878528036792, 5539.861503462405, 5594.440555944414, 5640.351359593658, 5650.660960342351, 5673.716314184316, 3864.420336949452, 4033.334499807528, 4079.4492743479846, 4089.650415362953, 4094.28350038742, 5733.226014488074, 5763.435271304271, 5768.682722450264, 5812.941870581282, 5850.297964244275, 4110.136871257009, 4131.5248746394545, 4140.503139623264, 4144.217986265935, 4152.709310348247, 3999.440078389028, 4136.379543068564, 4176.979115104405, 4188.476440444944, 4191.91616167675, 4786.234202527578, 4787.4494433140635, 4795.0, 4800.159972004885, 4830.95169048305, 5617.38208797032, 5630.493255607015, 5658.830235092953, 5667.489925906688, 5677.716114194356, 1395.239594421043, 1420.6235347632885, 1441.1929319581068, 1472.146155229964, 1476.6308422894276, 930.7388488597987, 934.0, 947.4883562876107, 952.1525842000625, 959.3140904253493, 4935.506449355107, 4935.975320123376, 4945.703257804544, 4947.480514545993, 4955.216122196007, 5081.8475445736685, 5102.846914592494, 5111.795528178831, 5114.3095682083185, 5178.084108879663, 4849.4908034656555, 4874.245271702255, 4892.633052521075, 4895.164313382482, 5115.360579927509, 3052.1911693401266, 3081.1064791210297, 3097.6282846058616, 3098.5816914716224, 3102.37952409519, 5977.880442391128, 6001.4298641628975, 6028.4272994065495, 6053.273607167168, 6054.54590905675, 5697.401772813795, 5931.1992880960515, 5964.493018093511, 5967.283925928917, 5984.828041138204, 5949.0, 5978.342382337939, 5992.58051936369, 6001.159837622649, 6022.307434645072, 5595.272614560081, 5810.94189058104, 5828.76684932598, 5853.151293062568, 5869.325027621878, 5754.9424505754405, 5770.740316685843, 5785.710714464176, 5787.218725472176, 5809.564282678732, 5971.970140149271, 5975.297040343481, 6050.9394906050375, 6098.207233059674, 6102.718429129754, 3987.72085953992, 3994.8601798936897, 4008.2183974124764, 4175.62419382257, 4193.580641935782, 5759.596828222152, 5795.043817770021, 5796.565257605781, 5799.362070172193, 5812.476877081083, 5611.38274789781, 5618.2415373924, 5669.659820410789, 5677.261955945701, 5682.5453963681975, 5495.230667706604, 5510.181640058851, 5664.912622620599, 5675.7445914933105, 5679.574031947538, 5469.453054694499, 5481.945180548143, 5526.143447765603, 5528.917066243929, 5542.475819149766, 5431.538319242909, 5651.0, 5660.490555849993, 5678.744456499385, 5695.689991301941, 5519.39286678457, 5541.085720856014, 5561.833145005657, 5564.415736347625, 5574.136008918624], "value": 4274.097187128524, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.8968165093586, 679.4123083532744, 747.383408687833, 757.9355754760845, 771.5062360089543, 13626.931865340672, 14201.502947396843, 14283.143011419295, 14416.855831441682, 14458.927705361471, 13583.932080339584, 13593.38829752661, 13649.727005459885, 13664.658383540416, 13668.316584170776, 680.6936878404738, 783.8393129408486, 802.5786462107396, 820.167529957091, 820.4748960665179, 804.9959750201241, 805.0138580239189, 805.9717909873156, 806.818465845183, 806.9757907262791, 841.1336323586685, 841.5918279634403, 841.9536925469097, 842.1872892658583, 843.3169133002257, 701.3127135407304, 816.4570560577209, 838.1953324808192, 838.5388036579888, 845.5856630251194, 621.0528943361364, 621.3041393639108, 621.6021746082511, 621.7046902721171, 621.7481919822434, 13710.177389356571, 13758.93120534401, 13760.587182384445, 13760.793588096187, 13786.586402407944, 12806.679833004157, 13256.13835100725, 13490.0, 13496.865031349658, 13702.794458083137, 10956.178286628456, 11057.281276716987, 11217.15871309655, 12232.877671223263, 12334.198277111958, 4494.550544945512, 4498.280275155992, 4511.278195488706, 4528.162289976365, 4690.530946905316, 60902.0, 61265.0, 61433.07850382248, 61459.0, 61845.0, 10508.842367364496, 10706.411147386969, 10796.78406431867, 11036.0, 11102.888971110186, 17722.46832595024, 17922.91038544799, 17935.0, 17940.730889036415, 17953.371631992817, 8424.368172387096, 8501.574921254036, 8527.488350698977, 8698.391112622183, 8804.163604457566, 4809.78355973982, 4959.603231741452, 5008.524190201925, 5072.315237442972, 5200.739963001834, 3197.2326641605846, 3274.950875736866, 3276.5085237214607, 3281.015695291451, 3286.1784553861535, 617.5183356981562, 618.2982315072425, 628.0641843652925, 639.8784230996189, 645.0002496131071, 648.8929326661142, 663.0882536512296, 685.5954986557955, 750.084896426359, 778.9298963093349, 8902.821943561223, 9240.075992400707, 9289.210417114486, 9340.0, 9386.436813791195, 528.5454509122152, 616.8519555306784, 672.8418821576926, 695.0616667498937, 697.8429853282971, 4190.1200747843295, 4289.206496798102, 4325.5674432557125, 4374.518802931675, 4381.978090109529, 66851.0, 67693.32306676969, 67982.30044248834, 68244.6587767068, 68337.65831170912, 59931.0, 60082.399176008556, 61207.6939615308, 61269.387306126366, 62302.37697623057, 54206.0, 54270.0, 54730.72634636881, 55071.173932391044, 55475.44524554784, 8409.53747543891, 8415.969475964203, 8615.526146062026, 8654.350923680624, 8780.560971951505, 37760.0, 38076.61923380731, 38375.0, 38504.42243366408, 39143.41284880672, 47340.7632961833, 49764.0, 50570.74714626551, 50582.0, 51268.0, 10695.679129626124, 11039.668809935714, 11392.886071139514, 11506.0, 11664.6500604982, 13151.60545183646, 13271.867281327062, 13622.31888405556, 13654.726905462034, 13674.0, 12645.94540745183, 12660.620181394574, 12816.423260953094, 12819.230846149261, 12940.41768120418, 11290.548378064785, 11979.28124312544, 12241.571544995883, 12546.0, 12583.87416125827, 11756.23584467007, 11948.0, 12008.699782505159, 12200.572979945659, 12445.688857778629, 10306.587736490756, 10409.68770936873, 10446.126269667277, 10566.00029763369, 10768.300060496043, 10680.57277708914, 10922.617708380169, 10924.344539327665, 10952.123830093415, 10999.61501347449], "value": 6777.347312090163, "unit": "score"}], "name": "dromaeo_css", "value": 7095.060052899878, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 109.0, 109.0, 110.0, 111.0, 112.0, 109.0, 111.0, 111.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 132.0, 156.0, 204.0, 124.0, 155.0, 157.0, 137.0, 193.0, 123.0], "value": 154.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 160.0, 161.0, 161.0, 161.0, 162.0, 162.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 152.0, 75.0, 75.0, 74.0, 74.0, 144.0, 77.0], "value": 89.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 146.0, 91.0, 91.0, 92.0, 132.0, 91.0, 92.0, 125.0], "value": 104.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 56.0, 54.0, 56.0, 56.0, 54.0, 106.0, 52.0, 51.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 58.0, 59.0, 60.0, 59.0, 60.0, 61.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 84.0, 78.0, 76.0, 78.0, 79.0, 83.0, 79.0, 78.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 120.0, 125.0, 122.0, 127.0, 127.0, 126.0, 125.0, 196.0, 123.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 162.0, 160.0, 161.0, 161.0, 161.0, 163.0, 159.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 62.0, 61.0, 63.0, 63.0, 61.0, 61.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1530.7, "alertThreshold": 2.0}]} 02:10:16 INFO - Return code: 0 02:10:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:10:16 ERROR - # TBPL SUCCESS # 02:10:16 INFO - Running post-action listener: _resource_record_post_action 02:10:16 INFO - ##### 02:10:16 INFO - ##### Finished run-tests step (success) 02:10:16 INFO - ##### 02:10:16 INFO - Running post-run listener: _resource_record_post_run 02:10:16 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 537497600; Read time: 0; Write time: 570196 02:10:16 INFO - TinderboxPrint: CPU usage
13.1% 02:10:16 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 02:10:16 INFO - TinderboxPrint: I/O write bytes / time
537,497,600 / 570,196 02:10:16 INFO - TinderboxPrint: CPU idle
8,274.5 (86.9%) 02:10:16 INFO - TinderboxPrint: CPU user
1,201.0 (12.6%) 02:10:16 INFO - TinderboxPrint: Swap in / out
0 / 0 02:10:16 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 205582336; Read time: 0; Write time: 291064 02:10:16 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 289341440; Read time: 0; Write time: 260776 02:10:16 INFO - Running post-run listener: _upload_blobber_files 02:10:16 INFO - Blob upload gear active. 02:10:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:10:16 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 02:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:10:17 INFO - (blobuploader) - INFO - Open directory for files ... 02:10:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:10:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:10:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:10:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:10:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:10:17 INFO - (blobuploader) - INFO - Done attempting. 02:10:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:10:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:10:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:10:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:10:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:10:18 INFO - (blobuploader) - INFO - Done attempting. 02:10:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:10:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:10:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:10:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:10:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:10:22 INFO - (blobuploader) - INFO - Done attempting. 02:10:22 INFO - (blobuploader) - INFO - Iteration through files over. 02:10:22 INFO - Return code: 0 02:10:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:10:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:10:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/564e5b67cc9175ef383a00ccf6889226227d790b19503beb363adf6405dc8d01d17f2032624bdd5f477fca630bafb58a3e272aba854c98a8fc2f1b46a092f7ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab3d9eae859ca6f250484512801848a42af4207fc09617de1cc7ce827ff572936dfd0ca17d5202b1091498a50b541c88235d1c4af57b30dd362241b831a7a094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a778d7a2fc369b63e446d5fa666722a2bf729ff1297c4b41a762cf8cdd87cf9c059241965e01b10f4a1529bcf0e22af6e236eb2c0d77505faf45ca1929c13a32"} 02:10:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:10:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:10:22 INFO - Contents: 02:10:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/564e5b67cc9175ef383a00ccf6889226227d790b19503beb363adf6405dc8d01d17f2032624bdd5f477fca630bafb58a3e272aba854c98a8fc2f1b46a092f7ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab3d9eae859ca6f250484512801848a42af4207fc09617de1cc7ce827ff572936dfd0ca17d5202b1091498a50b541c88235d1c4af57b30dd362241b831a7a094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a778d7a2fc369b63e446d5fa666722a2bf729ff1297c4b41a762cf8cdd87cf9c059241965e01b10f4a1529bcf0e22af6e236eb2c0d77505faf45ca1929c13a32"} 02:10:22 INFO - Running post-run listener: copy_logs_to_upload_dir 02:10:22 INFO - Copying logs to upload dir... 02:10:22 INFO - mkdir: /builds/slave/test/build/upload/logs 02:10:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.627320 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-14 02:10:22.653576) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 02:10:22.657187) ========= 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=62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/564e5b67cc9175ef383a00ccf6889226227d790b19503beb363adf6405dc8d01d17f2032624bdd5f477fca630bafb58a3e272aba854c98a8fc2f1b46a092f7ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab3d9eae859ca6f250484512801848a42af4207fc09617de1cc7ce827ff572936dfd0ca17d5202b1091498a50b541c88235d1c4af57b30dd362241b831a7a094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a778d7a2fc369b63e446d5fa666722a2bf729ff1297c4b41a762cf8cdd87cf9c059241965e01b10f4a1529bcf0e22af6e236eb2c0d77505faf45ca1929c13a32"} build_url:https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/XKbWTokSSSCFK4XM_i13ug/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/564e5b67cc9175ef383a00ccf6889226227d790b19503beb363adf6405dc8d01d17f2032624bdd5f477fca630bafb58a3e272aba854c98a8fc2f1b46a092f7ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab3d9eae859ca6f250484512801848a42af4207fc09617de1cc7ce827ff572936dfd0ca17d5202b1091498a50b541c88235d1c4af57b30dd362241b831a7a094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a778d7a2fc369b63e446d5fa666722a2bf729ff1297c4b41a762cf8cdd87cf9c059241965e01b10f4a1529bcf0e22af6e236eb2c0d77505faf45ca1929c13a32"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 02:10:22.682798) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 02:10:22.683178) ========= 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=62c568580acbfdaac3eec7be000001dc-1463211869.17017-1367050283 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004360 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 02:10:22.769733) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 02:10:22.770327) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 02:10:22.770901) ========= ========= Total master_lag: 0.19 =========