builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-045 starttime: 1463195609.48 results: success (0) buildid: 20160513173055 builduid: d42427f787524c80a3a72ae863361f56 revision: 93d60e9db618bd817bdba3fec6151d701bd5627a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.482474) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.482968) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.483241) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.508288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.508561) ========= 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=4300f881736b422476174874000001bc-1463194606.805281-182790938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-13 20:13:29-- 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% 225M=0s 2016-05-13 20:13:29 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107329 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.630400) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.630752) ========= 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=4300f881736b422476174874000001bc-1463194606.805281-182790938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018386 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.681057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:29.681364) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 93d60e9db618bd817bdba3fec6151d701bd5627a --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 93d60e9db618bd817bdba3fec6151d701bd5627a --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=4300f881736b422476174874000001bc-1463194606.805281-182790938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-13 20:13:29,829 truncating revision to first 12 chars 2016-05-13 20:13:29,829 Setting DEBUG logging. 2016-05-13 20:13:29,829 attempt 1/10 2016-05-13 20:13:29,829 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/93d60e9db618?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 20:13:30,094 unpacking tar archive at: fx-team-93d60e9db618/testing/mozharness/ program finished with exit code 0 elapsedTime=0.553468 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:30.247010) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:30.247294) ========= script_repo_revision: 93d60e9db618bd817bdba3fec6151d701bd5627a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:30.247665) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:30.247936) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 20:13:30.257647) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-05-13 20:13:30.257933) ========= /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=4300f881736b422476174874000001bc-1463194606.805281-182790938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:13:30 INFO - MultiFileLogger online at 20160513 20:13:30 in /builds/slave/test 20:13:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:13:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:13:30 INFO - {'append_to_log': False, 20:13:30 INFO - 'base_work_dir': '/builds/slave/test', 20:13:30 INFO - 'blob_upload_branch': 'Fx-Team', 20:13:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:13:30 INFO - 'branch': 'Fx-Team', 20:13:30 INFO - 'buildbot_json_path': 'buildprops.json', 20:13:30 INFO - 'config_files': ('talos/linux_config.py',), 20:13:30 INFO - 'default_actions': ('clobber', 20:13:30 INFO - 'read-buildbot-config', 20:13:30 INFO - 'download-and-extract', 20:13:30 INFO - 'populate-webroot', 20:13:30 INFO - 'create-virtualenv', 20:13:30 INFO - 'install', 20:13:30 INFO - 'run-tests'), 20:13:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:13:30 INFO - 'download_minidump_stackwalk': True, 20:13:30 INFO - 'download_symbols': 'ondemand', 20:13:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:13:30 INFO - 'tooltool.py': '/tools/tooltool.py', 20:13:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:13:30 INFO - '/tools/misc-python/virtualenv.py')}, 20:13:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:13:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:13:30 INFO - 'installer_path': 'installer.exe', 20:13:30 INFO - 'log_level': 'info', 20:13:30 INFO - 'log_name': 'talos', 20:13:30 INFO - 'log_to_console': True, 20:13:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:13:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:13:30 INFO - 'opt_config_files': (), 20:13:30 INFO - 'pip_index': False, 20:13:30 INFO - 'sps_profile': False, 20:13:30 INFO - 'sps_profile_interval': 0, 20:13:30 INFO - 'suite': 'dromaeojs', 20:13:30 INFO - 'system_bits': '32', 20:13:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:13:30 INFO - 'title': 'talos-linux64-ix-045', 20:13:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:13:30 INFO - 'use_talos_json': True, 20:13:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:13:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:13:30 INFO - 'work_dir': 'build'} 20:13:30 INFO - ##### 20:13:30 INFO - ##### Running clobber step. 20:13:30 INFO - ##### 20:13:30 INFO - Running pre-action listener: _resource_record_pre_action 20:13:30 INFO - Running main action method: clobber 20:13:30 INFO - rmtree: /builds/slave/test/build 20:13:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:13:30 INFO - Running post-action listener: _resource_record_post_action 20:13:30 INFO - ##### 20:13:30 INFO - ##### Finished clobber step (success) 20:13:30 INFO - ##### 20:13:30 INFO - ##### 20:13:30 INFO - ##### Running read-buildbot-config step. 20:13:30 INFO - ##### 20:13:30 INFO - Running pre-action listener: _resource_record_pre_action 20:13:30 INFO - Running main action method: read_buildbot_config 20:13:30 INFO - Using buildbot properties: 20:13:30 INFO - { 20:13:30 INFO - "project": "", 20:13:30 INFO - "product": "firefox", 20:13:30 INFO - "script_repo_revision": "production", 20:13:30 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:13:30 INFO - "repository": "", 20:13:30 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:13:30 INFO - "buildid": "20160513173055", 20:13:30 INFO - "pgo_build": "True", 20:13:30 INFO - "basedir": "/builds/slave/test", 20:13:30 INFO - "buildnumber": 1153, 20:13:30 INFO - "slavename": "talos-linux64-ix-045", 20:13:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:13:30 INFO - "platform": "ubuntu64_hw", 20:13:30 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:13:30 INFO - "branch": "fx-team", 20:13:30 INFO - "repo_path": "integration/fx-team", 20:13:30 INFO - "revision": "93d60e9db618bd817bdba3fec6151d701bd5627a", 20:13:30 INFO - "stage_platform": "linux64-pgo", 20:13:30 INFO - "builduid": "d42427f787524c80a3a72ae863361f56", 20:13:30 INFO - "slavebuilddir": "test" 20:13:30 INFO - } 20:13:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 20:13:30 INFO - Running post-action listener: _resource_record_post_action 20:13:30 INFO - ##### 20:13:30 INFO - ##### Finished read-buildbot-config step (success) 20:13:30 INFO - ##### 20:13:30 INFO - ##### 20:13:30 INFO - ##### Running download-and-extract step. 20:13:30 INFO - ##### 20:13:30 INFO - Running pre-action listener: _resource_record_pre_action 20:13:30 INFO - Running main action method: download_and_extract 20:13:30 INFO - mkdir: /builds/slave/test/build/tests 20:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:13:30 INFO - https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:13:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:13:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:13:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:13:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:13:42 INFO - Downloaded 1361 bytes. 20:13:42 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:13:42 INFO - Using the following test package requirements: 20:13:42 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:13:42 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:13:42 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:13:42 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:13:42 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:13:42 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:13:42 INFO - u'jsshell-linux-x86_64.zip'], 20:13:42 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:13:42 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:13:42 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:13:42 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:13:42 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:13:42 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:13:42 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:13:42 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:13:42 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:13:42 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:13:42 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:13:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:13:42 INFO - https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:13:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:13:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:13:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:13:47 INFO - Downloaded 24021874 bytes. 20:13: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'] 20:13: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 20:13:48 INFO - Return code: 0 20:13: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 20:13: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')]} 20:13:48 INFO - https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:13:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:13:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:13:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:13:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:13:51 INFO - Downloaded 24021874 bytes. 20:13:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:13:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:13:52 INFO - Return code: 0 20:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:13:52 INFO - https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:13:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:13:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:13:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:13:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:13:54 INFO - Downloaded 10840194 bytes. 20:13: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'] 20:13: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 20:13:55 INFO - Return code: 0 20:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:13:55 INFO - https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:13:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:13:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:13:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:13:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:13:56 INFO - Downloaded 58394256 bytes. 20:13:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:13:56 INFO - mkdir: /builds/slave/test/properties 20:13:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:13:56 INFO - Writing to file /builds/slave/test/properties/build_url 20:13:56 INFO - Contents: 20:13:56 INFO - build_url:https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:13:57 INFO - Running post-action listener: _resource_record_post_action 20:13:57 INFO - Running post-action listener: set_extra_try_arguments 20:13:57 INFO - ##### 20:13:57 INFO - ##### Finished download-and-extract step (success) 20:13:57 INFO - ##### 20:13:57 INFO - ##### 20:13:57 INFO - ##### Running populate-webroot step. 20:13:57 INFO - ##### 20:13:57 INFO - Running pre-action listener: _resource_record_pre_action 20:13:57 INFO - Running main action method: populate_webroot 20:13:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:13:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:13:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:13:57 INFO - '--tppagecycles', 20:13:57 INFO - '7'], 20:13:57 INFO - 'tests': ['tsvgm']}}, 20:13:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:13:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:13:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:13:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:13:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:13:57 INFO - 'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:13:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:13:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:13:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:13:57 INFO - 'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['damp', 'tps']}, 20:13:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:13:57 INFO - 'tests': ['damp', 'tps']}, 20:13:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['dromaeo_dom']}, 20:13:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:13:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['a11yr', 20:13:57 INFO - 'ts_paint', 20:13:57 INFO - 'tpaint', 20:13:57 INFO - 'sessionrestore', 20:13:57 INFO - 'sessionrestore_no_auto_restore', 20:13:57 INFO - 'tabpaint']}, 20:13:57 INFO - 'other-e10s': {'tests': ['a11yr', 20:13:57 INFO - 'ts_paint', 20:13:57 INFO - 'tpaint', 20:13:57 INFO - 'sessionrestore', 20:13:57 INFO - 'sessionrestore_no_auto_restore', 20:13:57 INFO - 'tabpaint']}, 20:13:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:13:57 INFO - 'ts_paint', 20:13:57 INFO - 'tpaint', 20:13:57 INFO - 'sessionrestore', 20:13:57 INFO - 'sessionrestore_no_auto_restore', 20:13:57 INFO - 'tabpaint']}, 20:13:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:13:57 INFO - 'ts_paint', 20:13:57 INFO - 'tpaint', 20:13:57 INFO - 'sessionrestore', 20:13:57 INFO - 'sessionrestore_no_auto_restore', 20:13:57 INFO - 'tabpaint']}, 20:13:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['a11yr', 20:13:57 INFO - 'ts_paint', 20:13:57 INFO - 'tpaint', 20:13:57 INFO - 'sessionrestore', 20:13:57 INFO - 'sessionrestore_no_auto_restore', 20:13:57 INFO - 'tabpaint']}, 20:13:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['a11yr', 20:13:57 INFO - 'ts_paint', 20:13:57 INFO - 'tpaint', 20:13:57 INFO - 'sessionrestore', 20:13:57 INFO - 'sessionrestore_no_auto_restore', 20:13:57 INFO - 'tabpaint']}, 20:13:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['tsvgx', 20:13:57 INFO - 'tsvgr_opacity', 20:13:57 INFO - 'tart', 20:13:57 INFO - 'tscrollx', 20:13:57 INFO - 'cart']}, 20:13:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:13:57 INFO - 'tsvgr_opacity', 20:13:57 INFO - 'tart', 20:13:57 INFO - 'tscrollx', 20:13:57 INFO - 'cart']}, 20:13:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:13:57 INFO - 'talos_options': ['--disable-e10s'], 20:13:57 INFO - 'tests': ['tp5o']}, 20:13:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:13:57 INFO - 'tests': ['tp5o']}, 20:13:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:13:57 INFO - 'talos_options': ['--disable-e10s', 20:13:57 INFO - '--xperf_path', 20:13:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:13:57 INFO - 'tests': ['tp5n']}, 20:13:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:13:57 INFO - 'talos_options': ['--xperf_path', 20:13:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:13:57 INFO - 'tests': ['tp5n']}}, 20:13:57 INFO - 'talos.zip': {'path': '', 20:13:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:13:57 INFO - Running post-action listener: _resource_record_post_action 20:13:57 INFO - ##### 20:13:57 INFO - ##### Finished populate-webroot step (success) 20:13:57 INFO - ##### 20:13:57 INFO - ##### 20:13:57 INFO - ##### Running create-virtualenv step. 20:13:57 INFO - ##### 20:13:57 INFO - Running pre-action listener: _resource_record_pre_action 20:13:57 INFO - Running main action method: create_virtualenv 20:13:57 INFO - Creating virtualenv /builds/slave/test/build/venv 20:13:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:13:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:13:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:13:57 INFO - Using real prefix '/usr' 20:13:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:13:57 INFO - Installing distribute.............................................................................................................................................................................................done. 20:13:59 INFO - Installing pip.................done. 20:13:59 INFO - Return code: 0 20:13:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:13:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:13:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:13:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:13:59 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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:13:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:13:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:13:59 INFO - Using env: {'DISPLAY': ':0', 20:13:59 INFO - 'HOME': '/home/cltbld', 20:13:59 INFO - 'LANG': 'en_US.UTF-8', 20:13:59 INFO - 'LANGUAGE': 'en_US:en', 20:13:59 INFO - 'LOGNAME': 'cltbld', 20:13:59 INFO - 'MAIL': '/var/mail/cltbld', 20:13:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:13:59 INFO - 'MOZ_NO_REMOTE': '1', 20:13:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:13:59 INFO - 'NO_EM_RESTART': '1', 20:13:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:13:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:13:59 INFO - 'PWD': '/builds/slave/test', 20:13:59 INFO - 'SHELL': '/bin/bash', 20:13:59 INFO - 'SHLVL': '1', 20:13:59 INFO - 'TERM': 'linux', 20:13:59 INFO - 'TMOUT': '86400', 20:13:59 INFO - 'USER': 'cltbld', 20:13:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:13:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:13:59 INFO - '_': '/tools/buildbot/bin/python'} 20:13:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:13:59 INFO - Cleaning up... 20:14:00 INFO - Return code: 0 20:14:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:14: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')]} 20:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14: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')]} 20:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14: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', '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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:14: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', 'psutil>=3.1.1'] in /builds/slave/test/build 20:14: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 psutil>=3.1.1 20:14:00 INFO - Using env: {'DISPLAY': ':0', 20:14:00 INFO - 'HOME': '/home/cltbld', 20:14:00 INFO - 'LANG': 'en_US.UTF-8', 20:14:00 INFO - 'LANGUAGE': 'en_US:en', 20:14:00 INFO - 'LOGNAME': 'cltbld', 20:14:00 INFO - 'MAIL': '/var/mail/cltbld', 20:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:00 INFO - 'MOZ_NO_REMOTE': '1', 20:14:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:00 INFO - 'NO_EM_RESTART': '1', 20:14:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:00 INFO - 'PWD': '/builds/slave/test', 20:14:00 INFO - 'SHELL': '/bin/bash', 20:14:00 INFO - 'SHLVL': '1', 20:14:00 INFO - 'TERM': 'linux', 20:14:00 INFO - 'TMOUT': '86400', 20:14:00 INFO - 'USER': 'cltbld', 20:14:00 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:00 INFO - '_': '/tools/buildbot/bin/python'} 20:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:00 INFO - Downloading/unpacking psutil>=3.1.1 20:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:14:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:14:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:14:02 INFO - Installing collected packages: psutil 20:14:02 INFO - Running setup.py install for psutil 20:14:02 INFO - building 'psutil._psutil_linux' extension 20:14:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:14:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:14:02 INFO - building 'psutil._psutil_posix' extension 20:14:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:14:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:14:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:14:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:14:02 INFO - Successfully installed psutil 20:14:02 INFO - Cleaning up... 20:14:02 INFO - Return code: 0 20:14:02 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 20:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:02 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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:14:02 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 20:14:02 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 20:14:02 INFO - Using env: {'DISPLAY': ':0', 20:14:02 INFO - 'HOME': '/home/cltbld', 20:14:02 INFO - 'LANG': 'en_US.UTF-8', 20:14:02 INFO - 'LANGUAGE': 'en_US:en', 20:14:02 INFO - 'LOGNAME': 'cltbld', 20:14:02 INFO - 'MAIL': '/var/mail/cltbld', 20:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:02 INFO - 'MOZ_NO_REMOTE': '1', 20:14:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:02 INFO - 'NO_EM_RESTART': '1', 20:14:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:02 INFO - 'PWD': '/builds/slave/test', 20:14:02 INFO - 'SHELL': '/bin/bash', 20:14:02 INFO - 'SHLVL': '1', 20:14:02 INFO - 'TERM': 'linux', 20:14:02 INFO - 'TMOUT': '86400', 20:14:02 INFO - 'USER': 'cltbld', 20:14:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:02 INFO - '_': '/tools/buildbot/bin/python'} 20:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:02 INFO - Downloading/unpacking mozsystemmonitor==0.1 20:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:04 INFO - Downloading mozsystemmonitor-0.1.tar.gz 20:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 20:14:04 INFO - Installing collected packages: mozsystemmonitor 20:14:04 INFO - Running setup.py install for mozsystemmonitor 20:14:05 INFO - Successfully installed mozsystemmonitor 20:14:05 INFO - Cleaning up... 20:14:05 INFO - Return code: 0 20:14:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:14:05 INFO - Using env: {'DISPLAY': ':0', 20:14:05 INFO - 'HOME': '/home/cltbld', 20:14:05 INFO - 'LANG': 'en_US.UTF-8', 20:14:05 INFO - 'LANGUAGE': 'en_US:en', 20:14:05 INFO - 'LOGNAME': 'cltbld', 20:14:05 INFO - 'MAIL': '/var/mail/cltbld', 20:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:05 INFO - 'MOZ_NO_REMOTE': '1', 20:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:05 INFO - 'NO_EM_RESTART': '1', 20:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:05 INFO - 'PWD': '/builds/slave/test', 20:14:05 INFO - 'SHELL': '/bin/bash', 20:14:05 INFO - 'SHLVL': '1', 20:14:05 INFO - 'TERM': 'linux', 20:14:05 INFO - 'TMOUT': '86400', 20:14:05 INFO - 'USER': 'cltbld', 20:14:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:05 INFO - '_': '/tools/buildbot/bin/python'} 20:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:05 INFO - Downloading/unpacking blobuploader==1.2.4 20:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:07 INFO - Downloading blobuploader-1.2.4.tar.gz 20:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:14:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:14:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:07 INFO - Downloading docopt-0.6.1.tar.gz 20:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:14:07 INFO - Installing collected packages: blobuploader, requests, docopt 20:14:07 INFO - Running setup.py install for blobuploader 20:14:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:14:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:14:08 INFO - Running setup.py install for requests 20:14:08 INFO - Running setup.py install for docopt 20:14:08 INFO - Successfully installed blobuploader requests docopt 20:14:08 INFO - Cleaning up... 20:14:08 INFO - Return code: 0 20:14:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:08 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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:14:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:14:08 INFO - Using env: {'DISPLAY': ':0', 20:14:08 INFO - 'HOME': '/home/cltbld', 20:14:08 INFO - 'LANG': 'en_US.UTF-8', 20:14:08 INFO - 'LANGUAGE': 'en_US:en', 20:14:08 INFO - 'LOGNAME': 'cltbld', 20:14:08 INFO - 'MAIL': '/var/mail/cltbld', 20:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:08 INFO - 'MOZ_NO_REMOTE': '1', 20:14:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:08 INFO - 'NO_EM_RESTART': '1', 20:14:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:08 INFO - 'PWD': '/builds/slave/test', 20:14:08 INFO - 'SHELL': '/bin/bash', 20:14:08 INFO - 'SHLVL': '1', 20:14:08 INFO - 'TERM': 'linux', 20:14:08 INFO - 'TMOUT': '86400', 20:14:08 INFO - 'USER': 'cltbld', 20:14:08 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:08 INFO - '_': '/tools/buildbot/bin/python'} 20:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:14:08 INFO - Running setup.py (path:/tmp/pip-jPtnWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:14:08 INFO - Running setup.py (path:/tmp/pip-NON1yA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:14:08 INFO - Running setup.py (path:/tmp/pip-Ma5d8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:14:08 INFO - Running setup.py (path:/tmp/pip-yLElMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:14:09 INFO - Running setup.py (path:/tmp/pip-n_fs9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:14:09 INFO - Running setup.py (path:/tmp/pip-0ZWslY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:14:09 INFO - Running setup.py (path:/tmp/pip-vs_HqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:14:09 INFO - Running setup.py (path:/tmp/pip-9mPf82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:14:09 INFO - Running setup.py (path:/tmp/pip-jviOuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:14:09 INFO - Running setup.py (path:/tmp/pip-ImRUBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:14:09 INFO - Running setup.py (path:/tmp/pip-yn4GW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:14:09 INFO - Running setup.py (path:/tmp/pip-8HqVA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:14:09 INFO - Running setup.py (path:/tmp/pip-_RqhJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:14:09 INFO - Running setup.py (path:/tmp/pip-RUw_8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:14:09 INFO - Running setup.py (path:/tmp/pip-IP47Jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:14:09 INFO - Running setup.py (path:/tmp/pip-a5YWj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:14:09 INFO - Running setup.py (path:/tmp/pip-58p8Hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:14:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:14:10 INFO - Running setup.py install for manifestparser 20:14:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Running setup.py install for mozcrash 20:14:10 INFO - Running setup.py install for mozdebug 20:14:10 INFO - Running setup.py install for mozdevice 20:14:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Running setup.py install for mozfile 20:14:10 INFO - Running setup.py install for mozhttpd 20:14:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Running setup.py install for mozinfo 20:14:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Running setup.py install for mozInstall 20:14:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:14:10 INFO - Running setup.py install for mozleak 20:14:10 INFO - Running setup.py install for mozlog 20:14:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:14:11 INFO - Running setup.py install for moznetwork 20:14:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:14:11 INFO - Running setup.py install for mozprocess 20:14:11 INFO - Running setup.py install for mozprofile 20:14:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:14:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:14:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:14:11 INFO - Running setup.py install for mozrunner 20:14:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:14:11 INFO - Running setup.py install for mozscreenshot 20:14:11 INFO - Running setup.py install for moztest 20:14:11 INFO - Running setup.py install for mozversion 20:14:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:14:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:14:11 INFO - Cleaning up... 20:14:11 INFO - Return code: 0 20:14:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:11 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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:14:11 INFO - Using env: {'DISPLAY': ':0', 20:14:11 INFO - 'HOME': '/home/cltbld', 20:14:11 INFO - 'LANG': 'en_US.UTF-8', 20:14:11 INFO - 'LANGUAGE': 'en_US:en', 20:14:11 INFO - 'LOGNAME': 'cltbld', 20:14:11 INFO - 'MAIL': '/var/mail/cltbld', 20:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:11 INFO - 'MOZ_NO_REMOTE': '1', 20:14:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:11 INFO - 'NO_EM_RESTART': '1', 20:14:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:11 INFO - 'PWD': '/builds/slave/test', 20:14:11 INFO - 'SHELL': '/bin/bash', 20:14:11 INFO - 'SHLVL': '1', 20:14:11 INFO - 'TERM': 'linux', 20:14:11 INFO - 'TMOUT': '86400', 20:14:11 INFO - 'USER': 'cltbld', 20:14:11 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:11 INFO - '_': '/tools/buildbot/bin/python'} 20:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:14:12 INFO - Running setup.py (path:/tmp/pip-G2x1iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:14:12 INFO - Running setup.py (path:/tmp/pip-jT5Bm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:14:12 INFO - Running setup.py (path:/tmp/pip-ZGBk1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:14:12 INFO - Running setup.py (path:/tmp/pip-ewcqcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:14:12 INFO - Running setup.py (path:/tmp/pip-pDYlrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:14:12 INFO - Running setup.py (path:/tmp/pip-WqvVtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:14:12 INFO - Running setup.py (path:/tmp/pip-LKJ01k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:14:12 INFO - Running setup.py (path:/tmp/pip-_VBkhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:14:12 INFO - Running setup.py (path:/tmp/pip-HCXVUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:14:12 INFO - Running setup.py (path:/tmp/pip-MyYOnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:14:12 INFO - Running setup.py (path:/tmp/pip-0oRvwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:14:12 INFO - Running setup.py (path:/tmp/pip-sZCfXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:14:12 INFO - Running setup.py (path:/tmp/pip-fCsLac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:14:12 INFO - Running setup.py (path:/tmp/pip-9gHe8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:14:13 INFO - Running setup.py (path:/tmp/pip-VJG9Zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:14:13 INFO - Running setup.py (path:/tmp/pip-HNXjwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:14:13 INFO - Running setup.py (path:/tmp/pip-oxsPqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:14:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:14:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:15 INFO - Downloading blessings-1.6.tar.gz 20:14:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:14:15 INFO - Installing collected packages: blessings 20:14:15 INFO - Running setup.py install for blessings 20:14:15 INFO - Successfully installed blessings 20:14:15 INFO - Cleaning up... 20:14:15 INFO - Return code: 0 20:14:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:14:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:14:15 INFO - Reading from file tmpfile_stdout 20:14:15 INFO - Current package versions: 20:14:15 INFO - argparse == 1.2.1 20:14:15 INFO - blessings == 1.6 20:14:15 INFO - blobuploader == 1.2.4 20:14:15 INFO - docopt == 0.6.1 20:14:15 INFO - manifestparser == 1.1 20:14:15 INFO - mozInstall == 1.12 20:14:15 INFO - mozcrash == 0.17 20:14:15 INFO - mozdebug == 0.1 20:14:15 INFO - mozdevice == 0.48 20:14:15 INFO - mozfile == 1.2 20:14:15 INFO - mozhttpd == 0.7 20:14:15 INFO - mozinfo == 0.9 20:14:15 INFO - mozleak == 0.1 20:14:15 INFO - mozlog == 3.2 20:14:15 INFO - moznetwork == 0.27 20:14:15 INFO - mozprocess == 0.22 20:14:15 INFO - mozprofile == 0.28 20:14:15 INFO - mozrunner == 6.11 20:14:15 INFO - mozscreenshot == 0.1 20:14:15 INFO - mozsystemmonitor == 0.1 20:14:15 INFO - moztest == 0.7 20:14:15 INFO - mozversion == 1.4 20:14:15 INFO - psutil == 3.1.1 20:14:15 INFO - requests == 1.2.3 20:14:15 INFO - wsgiref == 0.1.2 20:14:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:15 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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:14:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:14:15 INFO - Using env: {'DISPLAY': ':0', 20:14:15 INFO - 'HOME': '/home/cltbld', 20:14:15 INFO - 'LANG': 'en_US.UTF-8', 20:14:15 INFO - 'LANGUAGE': 'en_US:en', 20:14:15 INFO - 'LOGNAME': 'cltbld', 20:14:15 INFO - 'MAIL': '/var/mail/cltbld', 20:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:15 INFO - 'MOZ_NO_REMOTE': '1', 20:14:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:15 INFO - 'NO_EM_RESTART': '1', 20:14:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:15 INFO - 'PWD': '/builds/slave/test', 20:14:15 INFO - 'SHELL': '/bin/bash', 20:14:15 INFO - 'SHLVL': '1', 20:14:15 INFO - 'TERM': 'linux', 20:14:15 INFO - 'TMOUT': '86400', 20:14:15 INFO - 'USER': 'cltbld', 20:14:15 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:15 INFO - '_': '/tools/buildbot/bin/python'} 20:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:14:15 INFO - Cleaning up... 20:14:15 INFO - Return code: 0 20:14:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:15 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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:14:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:14:15 INFO - Using env: {'DISPLAY': ':0', 20:14:15 INFO - 'HOME': '/home/cltbld', 20:14:15 INFO - 'LANG': 'en_US.UTF-8', 20:14:15 INFO - 'LANGUAGE': 'en_US:en', 20:14:15 INFO - 'LOGNAME': 'cltbld', 20:14:15 INFO - 'MAIL': '/var/mail/cltbld', 20:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:15 INFO - 'MOZ_NO_REMOTE': '1', 20:14:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:15 INFO - 'NO_EM_RESTART': '1', 20:14:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:15 INFO - 'PWD': '/builds/slave/test', 20:14:15 INFO - 'SHELL': '/bin/bash', 20:14:15 INFO - 'SHLVL': '1', 20:14:15 INFO - 'TERM': 'linux', 20:14:15 INFO - 'TMOUT': '86400', 20:14:15 INFO - 'USER': 'cltbld', 20:14:15 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:15 INFO - '_': '/tools/buildbot/bin/python'} 20:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:16 INFO - Downloading/unpacking jsonschema 20:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:14:17 INFO - Downloading/unpacking functools32 (from jsonschema) 20:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:18 INFO - Downloading functools32-3.2.3-2.tar.gz 20:14:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:14:18 INFO - warning: no files found matching '*.txt' 20:14:18 INFO - no previously-included directories found matching 'build' 20:14:18 INFO - no previously-included directories found matching 'dist' 20:14:18 INFO - no previously-included directories found matching '.git*' 20:14:18 INFO - Installing collected packages: jsonschema, functools32 20:14:18 INFO - Running setup.py install for functools32 20:14:18 INFO - warning: no files found matching '*.txt' 20:14:18 INFO - no previously-included directories found matching 'build' 20:14:18 INFO - no previously-included directories found matching 'dist' 20:14:18 INFO - no previously-included directories found matching '.git*' 20:14:18 INFO - Successfully installed jsonschema functools32 20:14:18 INFO - Cleaning up... 20:14:18 INFO - Return code: 0 20:14:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:18 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 0x7f9d4c36df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11be770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, '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': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:14:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 20:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 20:14:18 INFO - Using env: {'DISPLAY': ':0', 20:14:18 INFO - 'HOME': '/home/cltbld', 20:14:18 INFO - 'LANG': 'en_US.UTF-8', 20:14:18 INFO - 'LANGUAGE': 'en_US:en', 20:14:18 INFO - 'LOGNAME': 'cltbld', 20:14:18 INFO - 'MAIL': '/var/mail/cltbld', 20:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:18 INFO - 'MOZ_NO_REMOTE': '1', 20:14:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:18 INFO - 'NO_EM_RESTART': '1', 20:14:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:18 INFO - 'PWD': '/builds/slave/test', 20:14:18 INFO - 'SHELL': '/bin/bash', 20:14:18 INFO - 'SHLVL': '1', 20:14:18 INFO - 'TERM': 'linux', 20:14:18 INFO - 'TMOUT': '86400', 20:14:18 INFO - 'USER': 'cltbld', 20:14:18 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:18 INFO - '_': '/tools/buildbot/bin/python'} 20:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:18 INFO - Downloading/unpacking flake8 20:14: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 20:14: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 20:14: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 20:14: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 20:14: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 20:14: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 20:14:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:14:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:14:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:14:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:14:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:14:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:14:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:14:21 INFO - no previously-included directories found matching 'docs/_build' 20:14:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:14:21 INFO - Running setup.py install for pep8 20:14:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:14:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:14:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:14:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:14:21 INFO - no previously-included directories found matching 'docs/_build' 20:14:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:14:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:14:21 INFO - Cleaning up... 20:14:21 INFO - Return code: 0 20:14:21 INFO - Running post-action listener: _resource_record_post_action 20:14:21 INFO - Running post-action listener: _start_resource_monitoring 20:14:21 INFO - Starting resource monitoring. 20:14:21 INFO - ##### 20:14:21 INFO - ##### Finished create-virtualenv step (success) 20:14:21 INFO - ##### 20:14:21 INFO - ##### 20:14:21 INFO - ##### Running install step. 20:14:21 INFO - ##### 20:14:21 INFO - Running pre-action listener: _resource_record_pre_action 20:14:21 INFO - Running main action method: install 20:14:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:14:21 INFO - Reading from file tmpfile_stdout 20:14:21 INFO - Detecting whether we're running mozinstall >=1.0... 20:14:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:14:21 INFO - Reading from file tmpfile_stdout 20:14:21 INFO - Output received: 20:14:21 INFO - Usage: mozinstall [options] installer 20:14:21 INFO - Options: 20:14:21 INFO - -h, --help show this help message and exit 20:14:21 INFO - -d DEST, --destination=DEST 20:14:21 INFO - Directory to install application into. [default: 20:14:21 INFO - "/builds/slave/test"] 20:14:21 INFO - --app=APP Application being installed. [default: firefox] 20:14:21 INFO - mkdir: /builds/slave/test/build/application 20:14:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:14:38 INFO - Reading from file tmpfile_stdout 20:14:38 INFO - Output received: 20:14:38 INFO - /builds/slave/test/build/application/firefox/firefox 20:14:38 INFO - Running post-action listener: _resource_record_post_action 20:14:38 INFO - ##### 20:14:38 INFO - ##### Finished install step (success) 20:14:38 INFO - ##### 20:14:38 INFO - ##### 20:14:38 INFO - ##### Running run-tests step. 20:14:38 INFO - ##### 20:14:38 INFO - Running pre-action listener: _resource_record_pre_action 20:14:38 INFO - Running main action method: run_tests 20:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:14:38 INFO - Python 2.7.3 20:14:38 INFO - Return code: 0 20:14:38 INFO - grabbing minidump binary from tooltool 20:14: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')]} 20:14:38 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 0x124d9c0>, '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 0x124e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124ed50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:14:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:14:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:14:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:14:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:14:38 INFO - Return code: 0 20:14:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:14:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:14:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:14:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:14:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:14:39 INFO - Return code: 0 20:14:39 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/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:14:39 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/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:14:39 INFO - Using env: {'DISPLAY': ':0', 20:14:39 INFO - 'HOME': '/home/cltbld', 20:14:39 INFO - 'LANG': 'en_US.UTF-8', 20:14:39 INFO - 'LANGUAGE': 'en_US:en', 20:14:39 INFO - 'LOGNAME': 'cltbld', 20:14:39 INFO - 'MAIL': '/var/mail/cltbld', 20:14:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:14:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:39 INFO - 'MOZ_NO_REMOTE': '1', 20:14:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:14:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:14:39 INFO - 'NO_EM_RESTART': '1', 20:14:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:39 INFO - 'PWD': '/builds/slave/test', 20:14:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:14:39 INFO - 'SHELL': '/bin/bash', 20:14:39 INFO - 'SHLVL': '1', 20:14:39 INFO - 'TERM': 'linux', 20:14:39 INFO - 'TMOUT': '86400', 20:14:39 INFO - 'USER': 'cltbld', 20:14:39 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463194606.805281-182790938', 20:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:39 INFO - '_': '/tools/buildbot/bin/python'} 20:14:39 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/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:14:39 INFO - mozversion application_buildid: 20160513173055 20:14:39 INFO - mozversion application_changeset: 93d60e9db618bd817bdba3fec6151d701bd5627a 20:14:39 INFO - mozversion application_display_name: Nightly 20:14:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:14:39 INFO - mozversion application_name: Firefox 20:14:39 INFO - mozversion application_remotingname: firefox 20:14:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:14:39 INFO - mozversion application_vendor: Mozilla 20:14:39 INFO - mozversion application_version: 49.0a1 20:14:39 INFO - mozversion platform_buildid: 20160513173055 20:14:39 INFO - mozversion platform_changeset: 93d60e9db618bd817bdba3fec6151d701bd5627a 20:14:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:14:39 INFO - mozversion platform_version: 49.0a1 20:14:39 INFO - using testdate: 1463195679 20:14:39 INFO - actual date: 1463195679 20:14:39 INFO - starting webserver on 'localhost:58742' 20:14:39 INFO - SUITE-START | Running 2 tests 20:14:39 INFO - TEST-START | dromaeo_css 20:14:39 INFO - operating with platform_type : linux_ 20:14:39 INFO - Initialising browser for dromaeo_css test... 20:14:39 INFO - TEST-INFO | started process 17533 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoon1J5/profile http://localhost:58742/getInfo.html) 20:14:43 INFO - PROCESS | 17533 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 20:14:43 INFO - PROCESS | 17533 | __metrics Screen width/height:1600/1200 20:14:43 INFO - PROCESS | 17533 | colorDepth:24 20:14:43 INFO - PROCESS | 17533 | Browser inner width/height: 1024/697 20:14:43 INFO - PROCESS | 17533 | __metrics 20:14:44 ERROR - PROCESS | 17533 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:14:45 INFO - TEST-INFO | 17533: exit 0 20:14:45 INFO - Browser initialized. 20:14:45 INFO - Running cycle 1/1 for dromaeo_css test... 20:14:45 INFO - TEST-INFO | started process 17616 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoon1J5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:14:57 INFO - PROCESS | 17616 | RSS: Main: 152129536 20:14:57 INFO - PROCESS | 17616 | 20:15:48 INFO - PROCESS | 17616 | 1463195748995 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:17:56 INFO - PROCESS | 17616 | Cycle 1(1): loaded http://localhost:58742/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58742/tests/dromaeo/cssquery-ext.html) 20:17:57 INFO - PROCESS | 17616 | RSS: Main: 243900416 20:17:57 INFO - PROCESS | 17616 | 20:20:59 INFO - PROCESS | 17616 | Cycle 1(1): loaded http://localhost:58742/tests/dromaeo/cssquery-ext.html (next: http://localhost:58742/tests/dromaeo/cssquery-jquery.html) 20:21:00 INFO - PROCESS | 17616 | RSS: Main: 242888704 20:21:00 INFO - PROCESS | 17616 | 20:24:02 INFO - PROCESS | 17616 | Cycle 1(1): loaded http://localhost:58742/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58742/tests/dromaeo/cssquery-mootools.html) 20:24:03 INFO - PROCESS | 17616 | RSS: Main: 248582144 20:24:03 INFO - PROCESS | 17616 | 20:27:05 INFO - PROCESS | 17616 | Cycle 1(1): loaded http://localhost:58742/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58742/tests/dromaeo/cssquery-prototype.html) 20:27:05 INFO - PROCESS | 17616 | RSS: Main: 237621248 20:27:05 INFO - PROCESS | 17616 | 20:30:08 INFO - PROCESS | 17616 | Cycle 1(1): loaded http://localhost:58742/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58742/tests/dromaeo/cssquery-yui.html) 20:30:09 INFO - PROCESS | 17616 | RSS: Main: 222281728 20:30:09 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | Cycle 1(1): loaded http://localhost:58742/tests/dromaeo/cssquery-yui.html (next: http://localhost:58742/tests/dromaeo/cssquery-dojo.html) 20:33:11 INFO - PROCESS | 17616 | RSS: Main: 275091456 20:33:11 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | __start_tp_report 20:33:11 INFO - PROCESS | 17616 | _x_x_mozilla_page_load 20:33:11 INFO - PROCESS | 17616 | _x_x_mozilla_page_load_details 20:33:11 INFO - PROCESS | 17616 | |i|pagename|runs| 20:33:11 INFO - PROCESS | 17616 | |0;dojo.html;3150.6849315068494;3152.4325621388143;3194.8242846643425;3199.0242975892343;3230.628477725062;5245.10833158363;5327.653702509334;5334.946650533493;5412.783488660454;5462.754176062082;5309.044372013036;5336.17289320155;5349.197620356948;5354.9196762048505;5390.91913621295;4771.427428708551;4850.223964165734;4893.779779909894;4899.412070551539;4983.950160498403;4887.1447496688115;4926.876828079291;4932.704037757728;4939.308496810431;4987.775550100236;4762.28565715142;4812.181929072049;4828.324034635119;4846.7576621169155;4853.684510506823;4818.494058123917;4829.468758436569;4831.9275210871865;4833.854984350475;4925.802967881281;4840.080384726926;4866.537678920497;4870.123377791996;4884.780184891689;4902.828401005948;5126.897462050738;5187.870303242411;5235.397929238148;5239.528442440199;5301.151815709506;5718.256626638552;5734.942650573483;5746.712664366765;5747.1666608341975;5770.682612456313;5752.338481074688;5753.82738517841;5760.049591817345;5808.854778630526;5811.15738217956;5212.035773381936;5348.839534813962;5389.083855744533;5414.458554144554;5497.615166938356;5889.234399528034;5894.469497745138;5942.405759424023;5944.464998150101;5957.434043765836;5444.91832622511;5722.113072473773;5765.827025189251;5769.624974376652;5776.566757493205;5621.831345059573;5722.885542289131;5737.971310143506;5773.076307790733;5775.104858746901;5737.5983681141915;5761.308642962787;5770.394108618619;5770.5095066919785;5770.6249093808765;5567.220589117527;5630.831075067755;5763.682997435181;5764.164196191528;5774.393688662714;4326.870193894189;4342.500612429548;4354.608085272341;4399.032212913154;4405.854477835722;3780.722109938358;3878.4182372643763;3885.7668539887695;3900.8244628991765;3932.2528719543197;5245.737713114329;5283.418823929442;5392.78428862849;5449.182622606561;5496.835094947158;5666.490015898589;5687.9431205687415;5715.6284841486;5723.141528770634;5763.135529670583;5346.331708536433;5350.117230656976;5353.678779273256;5357.812476563302;5372.462753724596;4703.905921881543;4745.596624286905;4777.163996300703;4813.9037219255415;4831.685940413862;5948.10778383246;5957.940420595739;5963.19496867926;5963.999999999914;6000.729967151488;5800.361960184461;5811.360750317377;5820.796272130455;5821.185034095145;5831.79588714393;5710.743016564182;5824.70876456167;5853.239078919713;5882.735276912635;5941.435563621449;5657.2079908811975;5657.377688454185;5659.179418984307;5660.660360378391;5662.348829884616;5707.743151558107;5738.340090889435;5744.425557444222;5749.798757043483;5752.194692743103;5487.533559647474;5542.2795036644975;5586.441355864544;5603.243562119062;5644.322681278273;5629.521490673339;5629.662220266797;5632.239647647515;5632.408597097409;5633.549316054788;5382.165764306539;5509.063459211863;5531.778728850962;5554.444555544414;5562.110062389998;5598.216249725123;5662.971685141548;5673.489385955284;5749.108888122348;5754.136879468113;5527;5571.997040532743;5574.498295153294;5576.247206627054;5623.3533143689 20:33:11 INFO - PROCESS | 17616 | |1;ext.html;638.1735652330232;639.2009987515607;648.1120864415751;658.9703463344149;660.3924389561603;43268.783656081716;45088;45128.77435612821;48097;48219.517804822026;46006.769966150125;46309.768451157695;46363.30455043168;46469;46489.30266046012;8992.19070283676;9199.586018629145;9220.769480763001;9221.077892210793;9223.400478968846;8837.072107428723;9025.638974441048;9029.503377314246;9035.141661542142;9035.819283614324;9074.092590740938;9089.318301127441;9099.363044586882;9108.635654573809;9109.772255693595;9013.233875120623;9037.638494460214;9050.185483306468;9052.954735226347;9054.456732596065;8970;8977.32670049749;9038.54807259641;9044.32167587427;9051.094890510962;42585.78707106445;42991.35512967308;43005.784971075256;43016;43062.354064689054;3654.470101835219;3683.898983769139;3857.0550215197177;4064.7764372959477;4345.348197770328;33277.833610832036;33344.16639584005;33536.496952545735;33624;33814.32371352584;4370.322599996974;4433.623142032931;4439.289713645801;4454.799534020977;4469.039156581351;348282;348567;350549;351335;353174;61241.69379153076;61362.69318653468;61608.691956540824;61664.69167654133;61684;56284.71857640768;61538.69230653907;64146;64162;64248;16348.75476867848;17570.472885813448;17636.118194090242;17646.2059207336;17657.20542575587;13487.325633718276;13632.454701811816;13647.590572282847;13647.931760341135;13648.317584120754;16237.188140592923;17616.383426580007;18231.45305640833;18438.446846594623;18442.170102345426;6443.4523065539015;6469.838254043687;6480.222373315232;6592.373724496165;6641.369069938348;1034.3586976074882;1119.5242022140558;1140.8345789860423;1170.1341007654444;1174.1370092981651;33142.17144571357;33280.16799579981;33549.49675754866;33615.15962100967;34040.65959340424;1142.4858813533942;1205.0058701571063;1218.4760552962134;1220.2983284611385;1228.3980849383738;17606.559836004202;17650.205740741698;17682.734758978626;17707.822921770876;17717.29130834752;503653;508184;508384;509166;510790;58225.417745822844;59571.404285957455;59687.40312596905;59727.70136149292;59924.906382957066;1416.5962700630478;1418.1632836626234;1439.6688761584894;1440.3014537949086;1448.0949406620862;30915.381692365576;32842.67157328492;32892.83553582217;32990.34019319648;33601.15997100092;52568.7371563155;52692.736536317076;52769.47230527645;52843.73578132085;53006.4699353017;426755;435449;436262;436660;438662;36748.44877326896;41045.179096417305;43470.130597387244;43473.782631086644;43545.56454435415;46986.76506617445;47010.529894701984;47845.5215447855;47874.76062619664;47908.5209147918;40952;41415.7929210352;42067;45949.31076033929;46201.76899115483;42500.78749606356;42804.5719542813;42826.57173428226;43078.78460607677;43702.344464832415;41831.58168418277;42327;42522.5747742531;43889.34165987449;44827.022123520976;34888.47667285043;36861.81569092137;37261.81369093137;37964.810175948944;38031.80984095062;29081.563776542946;29328.41343173167;29456.410871782016;29554;29581.408371832873 20:33:11 INFO - PROCESS | 17616 | |2;jquery.html;388.20224438778297;395.2135250850808;397.3622336150478;406.751881352375;419.05921206890537;13786.011297077366;18126;18212.817871821277;19561.706574401378;19613.803861961394;17574.384896528623;17926.19332130054;18322;19470.221191152337;19532.70700939487;5673.149027645855;5853.122031695238;5855.268091488564;5870.295564532262;5892.793752218673;4248.405223268729;5789.131630255474;5804.216430781833;6143.846403839917;6477.384648458413;6420.357964203566;6516.967415162918;6548.657525207321;6559.147310849583;6561.77033803817;6315.242170939517;6372.362763723637;6470.611763294194;6475.643839588821;6488.0592314114665;5505.834824955257;5683.886322273573;5698.2592263005545;5698.6865722385255;5726.770929162829;23006.7699323008;23173;23250.65124023141;23423.882880585657;23542.821719067877;2411.3368823573483;2416.7945724613255;2472.9505409891703;2517.0435234610914;2913.956290655642;9655.806883862353;14167.574972750834;14242.857571424256;14297.78553321701;14962.700745985128;1881.736556882024;1966.852486063551;1966.9311574094982;2026.7973202679614;2028.219135632792;78155.21844781593;81848.18151818405;82075.58962205211;82564.58717706433;83414.16585834185;10627.628033018807;10674.679759607225;10782.406967616856;10829.187810914056;10848.132149428027;14789.186594737177;14913.478028268959;14936.626584335478;14946.92526537382;14949.850501495064;8147.877781833277;8337.416380853281;8424.663013479392;8435.409521333571;8576.228139467477;8162.14297498766;8175.79560510992;8185.3042491387705;8290.170982901782;8302.750917472486;4614.792334344963;4677.438707355139;4721.291806229093;4749.596284315803;4767.213409787416;1931.4978105692621;1945.7665080190184;1957.6378370001596;1964.4794129555394;2022.3427386099577;1006.8187726209353;1033.7829055898196;1066.9573217071231;1081.9404932728619;1142.5315620595682;18501.259949602136;19123.330683426015;19240.71138932917;19351.612967740566;19491.51271218231;1041.9462920432313;1047.675220681591;1062.6121465664976;1070.2775626452114;1081.1566977757361;10833.458327083614;10850.934946028397;10910.763293524808;10910.981468428366;10973.945130274298;523931;524644;528457;530209;530214;90182.44485065306;90296.54851725831;90969;91189.5440522793;91612.54193728989;1037.4242295525905;1060.6552870317332;1071.244772435431;1072.0458791675414;1081.6106201767357;16623.50129496117;16896;17070.914645426692;17166.227519761393;17452.301907923542;39414;40481.39277910892;40883.79558102191;41348.58651413448;42226.155476889675;54873;56720.432795673165;57041;57736.71131644315;58099.41900581109;8196.918030819616;8362;8406.495610263404;8517.124708257852;8590.14098590136;9377.202937750095;12551.121421500407;12864.485420583445;12918.935405322913;13080.149790263602;10827.891721082688;11272.774544509228;11517.769644607228;11543.422828858356;11748.236364636272;11094.334339939629;11169.609063682732;11247.437628118725;11324.886751132382;11457.770844583229;11544.538218471504;11553.191276610556;11631.185816992729;11748.118891083373;11805.704857378634;8230.21812927771;8261.917380826115;8329.083800781787;8329.208725171098;8554.312308149125;8629.09394513567;10633.255672103189;11207.887921120684;11354.886451135713;11371 20:33:11 INFO - PROCESS | 17616 | |3;mootools.html;1369.3769334952597;1573.2133933033483;1580.1072394097334;1759.1292310306392;1828.9725654115182;8726.563671816408;9077.455352678835;9113.225375843045;9379.484128372937;9406.623735050589;9145.359824812265;9347.906520934788;9356.953215233914;9358.204552613037;9365.157135857771;1973.8322242609395;2021.2117274263048;2021.6462119129167;2395.341281147685;2437.2200895713377;2296.8392212545127;2376.8811559422047;2378;2378.892949817254;2386.4033991502124;2354.799842013431;2377.7503362146986;2386.988065059681;2395.197608801054;2395.520895820834;2056.8148866601928;2322.2568777990964;2349.0721165139676;2360.3155085025323;2360.6459031145378;2404.1945948107405;2432.19737486629;2432.683751112344;2436.317831007303;2441.1333976438286;9159.725208243697;9205.67780127699;9213.67752128679;9216.861747073799;9224.723258302261;5374.328208973879;5532.502074813246;5539.972300138474;5549.889002219973;5553.4724201200825;3398.5242066110764;3456.70617997468;3610.0794297454245;3666.77999320039;3670.284294562561;1158.1198289300107;1160.2806260118696;1168.275669085164;1171.5372427890936;1180.3154170581042;13031.348432578428;13035.674108147277;13068.673283167902;13075.738485230337;13093.345332733325;4901.117798796251;4940.135476291634;5088.567471764867;5099.719515426687;5145.408278047998;10341.4312212829;10421.843672344921;10429.68710938673;10468.790624187473;10484.580616775396;5575.275214222125;5582.7766889323975;5585.078462053797;5589.776408943678;5593.804216852391;5075.949240507622;5224.869378265497;5292;5300.549453296513;5307.363116425976;3691.6862066724093;3767.679747221462;3783.621637836249;3814.771113733185;3824.694024478091;1116.7933932222566;1136.1478890831877;1158.843556119913;1162.4071723421116;1172.4841069929205;887.3477993674644;968.6754937096151;1034.446571084466;1034.9586016559404;1039.5581877701945;6107.992181290127;6199.442050215503;6315.463185629273;6320.747170113144;6337.6197428154455;431.41974044909546;442.6901169181521;447.17496219475015;448.605227399886;452.8596135198099;4458.509563947963;4462.152191083684;4485.237509623372;4490.236659767782;4503.932441013388;11210.27133236337;11483.42582870853;11495.310281383145;11544.134189935621;11598.072154227642;11109.222354435113;11400.00499912512;11452.427378631035;11474.196806223652;11768.52925882955;8527.232549070613;8615.836862023672;8645.221930026202;8657.177568131143;8669.08974557675;6265.968670156803;6319.96840015797;6515.869682606227;6528.445082168069;6584.473242140713;10292.125169360688;10588.576456941637;10635.032518984013;10646.73383165427;10789.298695584763;9579.281553883626;10559.472026398804;10854.728631784268;10860.619878304222;11187.496562654864;8002.359811214972;8272.793180170544;8523.573821308786;8554.31565474773;8558.186972237627;9010.14403631654;9014.323925705467;9051.683191088281;9064.365494415328;9098.317626178195;7641.617919104134;7887.921120788948;7928.603569821371;7929;7987.201279871966;7894.802629934297;8032.228383855874;8139.10469848328;8194.754157375288;8265.83468330642;7416.962915185605;7424.331810137113;7548.924510754822;7566;7582.62086895642;5167.379914410295;5262.578993680573;5266.78932842682;5349.384820745664;5378.381486128988;5365.3293338332705;5373.408925018167;5431.646942948696;5536.335639723259;5574.16387541872 20:33:11 INFO - PROCESS | 17616 | |4;prototype.html;329.8218961760651;337.5004992610936;341.16925286926335;341.8786330852547;347.23954539558383;5164.715940623269;5353.143497040474;5402.486763757437;5413.729313534327;5474.42518535554;5275.683458992463;5366.677999320043;5397.7031263280505;5405.216243644669;5410.972945135269;4002.3996400540004;4179.728317659343;4198.6011328923705;4202.621764041228;4219.451471308726;4038.3942408638645;4132.772697501636;4183.518895327046;4210.094829611633;4211.410402543646;4111.403846442264;4127.030147915238;4137.193247316774;4149.875503734877;4150.916981660365;3916.471276377681;3988.9800550997347;4131.710780245369;4136.255474014676;4137.262349813086;3625.148090198801;3629.7822130672243;3631.1466805300997;3635.2002559436883;3655.232401195752;5098.158803797369;5110.233464980282;5123.2827404163445;5125.2312153176945;5140.917928239069;4474.172278128524;4678.7660616969015;4703.529647035269;4705.670603057787;4743.31221972812;5511.5039646432015;5520.806771762969;5556.305461817273;5559.972200139014;5560.6941618210985;3988.142549351874;4014.8996275093064;4022.1352409232154;4031.153457773871;4065.7560546367017;5627.296587926509;5707.686077265748;5765.1352297155345;5801.7389217485315;5809.883802323972;4073.9796301018305;4106.507219133663;4107.67138628909;4111.259973204792;4134.627883490441;4157.875263742032;4169.791510424467;4185.853495127717;4192.434021407132;4210.347396153601;4714.339992401066;4764.331974094449;4780.330753694486;4788.473267940523;4798.880027999328;5250.18622113573;5542;5574.303212098488;5633.802816901389;5643.181738647873;1452.3319273134477;1470.5661829760247;1479.7262506436343;1481.888858335629;1492.7760835874706;942.3262367407336;969.3795970578835;973.7224890906199;979.3193730357476;982.6904525074578;5130.230465430215;5239.659422137549;5308.548773354231;5411.188321751769;5426.511613954684;5720.628159169724;5728.620114132682;5728.799492017843;5738.254026976467;5762.625429347163;5171.405288391883;5392.7057506198225;5400.972995134999;5425.159100339453;5429.239906413105;3001.529250667201;3017.336186039068;3025.7125573070525;3040.9543856842165;3058.9541156882665;5867.765289388377;5877.353491115974;5888.5289176865745;5888.617239879394;5895.20414744004;5671.914921276184;5805.332386775491;5828.5337173026055;5832.329282132524;5833.766649333979;5843.795467158629;5870.207521984563;5870.324912635102;5889.4993925515955;5903.970480147657;5426.375966763872;5693.206639908416;5718.714064296685;5723.170140329712;5728.914066289092;5632.5493960482245;5657.830265092054;5663.8867222656145;5680.176374425768;5732.742026608729;5706.971465142814;5936.436038576328;5944.167816505606;5953.226080609493;5956.463918247378;4065.654419374386;4159.875203743893;4189.685773566934;4216.756056963264;4219.177260434186;5582.106862901916;5686.9715651421475;5712.457316554921;5730.226419433323;5776.277965254343;5529.612927095226;5570.053090974463;5576.553875689854;5634.577406694432;5640.802571909963;5334.279872217357;5548.944510554843;5559.471850174227;5576.8846230754825;5578.30271216098;5235.685858848481;5427.4301198373605;5443;5446.319210098738;5459.69971651563;5431.85930954504;5601.7759289629585;5611.270534830446;5626.831195064155;5635.746391412315;5480.260164877691;5492.890142197213;5500.944990550043;5508.779648814003;5509.146082357241 20:33:11 INFO - PROCESS | 17616 | |5;yui.html;827.7351247600768;870.8084221471278;870.9085546017672;886.7206829848598;887.6715615222365;13285.800712989301;14994.625134371632;15148.1668508232;15160.090394576333;15197;13885.861141388583;13943.721125577482;13946;13955.860441395584;13962.860371396308;666.630020338713;788.5426452657445;813.8738495533202;817.6770175780563;819.7008092046419;799.1568894815966;800.8798680197988;809.1018968944467;809.2231457800489;809.5709274084744;705.6894966214875;842.0526907229367;847.063994286314;847.4957400346784;849.4096602861014;799.7001124578284;827.1356432527963;829.1335554345724;832.4339449174558;833.3791325462539;610.1457958857591;610.404855266112;610.554295364382;610.5939550199113;610.6305685060519;14226.857731422657;14230.359633816399;14271;14303.213323267217;14304.928475357661;13802.792958105636;13877.097988630707;14054.859451405457;14094.788578171336;14107;11945.820812687816;12056;12137.817932731015;12346.938265308616;12417.254964702146;4477.619402350804;4477.708948918343;4524.185646583614;4530.3431002504785;4619.884502887421;53297;53369.1994620081;53427.73286133583;53618.463815361734;53865;10994.230403871812;11128.1653875958;11225.550977960791;11308.886911130949;11329.546818127348;18625;20151.496212594804;20281.797182028287;20294.086766095563;20334.288299909433;8374;8477.872831907527;8500.48997060166;8510.829783404297;8723.258523025614;4799.832005879777;4869.975650121727;4938.407391113089;4981.778823716363;4992.525710057539;3484.756067075331;3552.698020668272;3560.732945029133;3562.5190599268767;3566.037169964147;610.4170517156106;647.5531883000715;650.3464018661215;672.4586707700348;698.8916717908733;663.3764261594087;713.8215446138465;715.3275920634587;778.3384123494984;784.2236186175645;8966.417182883091;9291.396059256127;9340.20608248293;9414.388064775905;9669.274804389559;630.9779157729457;663.6548994522917;697.5326531224092;749.3293502315506;755.6297414266969;4408.53710360414;4757.9286310705365;4786.712797232177;4795.856124316276;4879.192822426948;71314;71507;72898.27101729022;73003;73080.63459682775;45836.770816146374;48325.5167448321;58325;63124;64146.679266603365;56333.43666563282;56497.58756031132;56547.71726141343;56587;57744.133837992464;9339.299552533452;9550.283728720235;9572;9633.518324083909;9640.951795240979;37359.81320093491;37810.43284350682;37983.430248545745;38103.80948095242;38902;50562.747186265304;51081.74459127681;51486;51746.48253517417;52526.21210681919;11828.34944078084;12053.337066461077;12121.515139394678;12149.149559530748;12176.391180440765;13255;13464.93267533656;13606.387712553156;13921.79117313221;14086.436542538593;12200.633980980585;12546.309952952675;12599.433025514058;12710;12738.299393533445;13134;13221.405036773176;13297.335133243492;13376.732465350444;14482.275886205436;13108.803367949298;13163.341832908125;13381.264030478416;13775.517856874962;13832;11058.557657693602;11064.28082174656;11490.482928268415;11557.13321500874;12045.879541204476;11215.66353009411;11405.942970285096;11655.358955257543;11724.765504690029;11863.004962432891 20:33:11 INFO - PROCESS | 17616 | __end_tp_report 20:33:11 INFO - PROCESS | 17616 | __start_cc_report 20:33:11 INFO - PROCESS | 17616 | _x_x_mozilla_cycle_collect,835 20:33:11 INFO - PROCESS | 17616 | __end_cc_report 20:33:11 INFO - PROCESS | 17616 | __startTimestamp1463196791940__endTimestamp 20:33:11 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | ------- Summary: start ------- 20:33:11 INFO - PROCESS | 17616 | Number of tests: 6 20:33:11 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | [#0] dojo.html Cycles:165 Average:5321.90 Median:5554.44 stddev:608.74 (11.0%) stddev-sans-first:586.29 20:33:11 INFO - PROCESS | 17616 | Values: 3150.7 3152.4 3194.8 3199.0 3230.6 5245.1 5327.7 5334.9 5412.8 5462.8 5309.0 5336.2 5349.2 5354.9 5390.9 4771.4 4850.2 4893.8 4899.4 4984.0 4887.1 4926.9 4932.7 4939.3 4987.8 4762.3 4812.2 4828.3 4846.8 4853.7 4818.5 4829.5 4831.9 4833.9 4925.8 4840.1 4866.5 4870.1 4884.8 4902.8 5126.9 5187.9 5235.4 5239.5 5301.2 5718.3 5734.9 5746.7 5747.2 5770.7 5752.3 5753.8 5760.0 5808.9 5811.2 5212.0 5348.8 5389.1 5414.5 5497.6 5889.2 5894.5 5942.4 5944.5 5957.4 5444.9 5722.1 5765.8 5769.6 5776.6 5621.8 5722.9 5738.0 5773.1 5775.1 5737.6 5761.3 5770.4 5770.5 5770.6 5567.2 5630.8 5763.7 5764.2 5774.4 4326.9 4342.5 4354.6 4399.0 4405.9 3780.7 3878.4 3885.8 3900.8 3932.3 5245.7 5283.4 5392.8 5449.2 5496.8 5666.5 5687.9 5715.6 5723.1 5763.1 5346.3 5350.1 5353.7 5357.8 5372.5 4703.9 4745.6 4777.2 4813.9 4831.7 5948.1 5957.9 5963.2 5964.0 6000.7 5800.4 5811.4 5820.8 5821.2 5831.8 5710.7 5824.7 5853.2 5882.7 5941.4 5657.2 5657.4 5659.2 5660.7 5662.3 5707.7 5738.3 5744.4 5749.8 5752.2 5487.5 5542.3 5586.4 5603.2 5644.3 5629.5 5629.7 5632.2 5632.4 5633.5 5382.2 5509.1 5531.8 5554.4 5562.1 5598.2 5663.0 5673.5 5749.1 5754.1 5527.0 5572.0 5574.5 5576.2 5623.4 20:33:11 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | [#1] ext.html Cycles:180 Average:60552.89 Median:239461.39 stddev:115154.08 (48.1%) stddev-sans-first:115389.25 20:33:11 INFO - PROCESS | 17616 | Values: 638.2 639.2 648.1 659.0 660.4 43268.8 45088.0 45128.8 48097.0 48219.5 46006.8 46309.8 46363.3 46469.0 46489.3 8992.2 9199.6 9220.8 9221.1 9223.4 8837.1 9025.6 9029.5 9035.1 9035.8 9074.1 9089.3 9099.4 9108.6 9109.8 9013.2 9037.6 9050.2 9053.0 9054.5 8970.0 8977.3 9038.5 9044.3 9051.1 42585.8 42991.4 43005.8 43016.0 43062.4 3654.5 3683.9 3857.1 4064.8 4345.3 33277.8 33344.2 33536.5 33624.0 33814.3 4370.3 4433.6 4439.3 4454.8 4469.0 348282.0 348567.0 350549.0 351335.0 353174.0 61241.7 61362.7 61608.7 61664.7 61684.0 56284.7 61538.7 64146.0 64162.0 64248.0 16348.8 17570.5 17636.1 17646.2 17657.2 13487.3 13632.5 13647.6 13647.9 13648.3 16237.2 17616.4 18231.5 18438.4 18442.2 6443.5 6469.8 6480.2 6592.4 6641.4 1034.4 1119.5 1140.8 1170.1 1174.1 33142.2 33280.2 33549.5 33615.2 34040.7 1142.5 1205.0 1218.5 1220.3 1228.4 17606.6 17650.2 17682.7 17707.8 17717.3 503653.0 508184.0 508384.0 509166.0 510790.0 58225.4 59571.4 59687.4 59727.7 59924.9 1416.6 1418.2 1439.7 1440.3 1448.1 30915.4 32842.7 32892.8 32990.3 33601.2 52568.7 52692.7 52769.5 52843.7 53006.5 426755.0 435449.0 436262.0 436660.0 438662.0 36748.4 41045.2 43470.1 43473.8 43545.6 46986.8 47010.5 47845.5 47874.8 47908.5 40952.0 41415.8 42067.0 45949.3 46201.8 42500.8 42804.6 42826.6 43078.8 43702.3 41831.6 42327.0 42522.6 43889.3 44827.0 34888.5 36861.8 37261.8 37964.8 38031.8 29081.6 29328.4 29456.4 29554.0 29581.4 20:33:11 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | [#2] jquery.html Cycles:180 Average:29972.18 Median:23483.35 stddev:86748.91 (369.4%) stddev-sans-first:86963.82 20:33:11 INFO - PROCESS | 17616 | Values: 388.2 395.2 397.4 406.8 419.1 13786.0 18126.0 18212.8 19561.7 19613.8 17574.4 17926.2 18322.0 19470.2 19532.7 5673.1 5853.1 5855.3 5870.3 5892.8 4248.4 5789.1 5804.2 6143.8 6477.4 6420.4 6517.0 6548.7 6559.1 6561.8 6315.2 6372.4 6470.6 6475.6 6488.1 5505.8 5683.9 5698.3 5698.7 5726.8 23006.8 23173.0 23250.7 23423.9 23542.8 2411.3 2416.8 2473.0 2517.0 2914.0 9655.8 14167.6 14242.9 14297.8 14962.7 1881.7 1966.9 1966.9 2026.8 2028.2 78155.2 81848.2 82075.6 82564.6 83414.2 10627.6 10674.7 10782.4 10829.2 10848.1 14789.2 14913.5 14936.6 14946.9 14949.9 8147.9 8337.4 8424.7 8435.4 8576.2 8162.1 8175.8 8185.3 8290.2 8302.8 4614.8 4677.4 4721.3 4749.6 4767.2 1931.5 1945.8 1957.6 1964.5 2022.3 1006.8 1033.8 1067.0 1081.9 1142.5 18501.3 19123.3 19240.7 19351.6 19491.5 1041.9 1047.7 1062.6 1070.3 1081.2 10833.5 10850.9 10910.8 10911.0 10973.9 523931.0 524644.0 528457.0 530209.0 530214.0 90182.4 90296.5 90969.0 91189.5 91612.5 1037.4 1060.7 1071.2 1072.0 1081.6 16623.5 16896.0 17070.9 17166.2 17452.3 39414.0 40481.4 40883.8 41348.6 42226.2 54873.0 56720.4 57041.0 57736.7 58099.4 8196.9 8362.0 8406.5 8517.1 8590.1 9377.2 12551.1 12864.5 12918.9 13080.1 10827.9 11272.8 11517.8 11543.4 11748.2 11094.3 11169.6 11247.4 11324.9 11457.8 11544.5 11553.2 11631.2 11748.1 11805.7 8230.2 8261.9 8329.1 8329.2 8554.3 8629.1 10633.3 11207.9 11354.9 11371.0 20:33:11 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | [#3] mootools.html Cycles:180 Average:6067.65 Median:2478.40 stddev:3508.45 (141.6%) stddev-sans-first:3500.52 20:33:11 INFO - PROCESS | 17616 | Values: 1369.4 1573.2 1580.1 1759.1 1829.0 8726.6 9077.5 9113.2 9379.5 9406.6 9145.4 9347.9 9357.0 9358.2 9365.2 1973.8 2021.2 2021.6 2395.3 2437.2 2296.8 2376.9 2378.0 2378.9 2386.4 2354.8 2377.8 2387.0 2395.2 2395.5 2056.8 2322.3 2349.1 2360.3 2360.6 2404.2 2432.2 2432.7 2436.3 2441.1 9159.7 9205.7 9213.7 9216.9 9224.7 5374.3 5532.5 5540.0 5549.9 5553.5 3398.5 3456.7 3610.1 3666.8 3670.3 1158.1 1160.3 1168.3 1171.5 1180.3 13031.3 13035.7 13068.7 13075.7 13093.3 4901.1 4940.1 5088.6 5099.7 5145.4 10341.4 10421.8 10429.7 10468.8 10484.6 5575.3 5582.8 5585.1 5589.8 5593.8 5075.9 5224.9 5292.0 5300.5 5307.4 3691.7 3767.7 3783.6 3814.8 3824.7 1116.8 1136.1 1158.8 1162.4 1172.5 887.3 968.7 1034.4 1035.0 1039.6 6108.0 6199.4 6315.5 6320.7 6337.6 431.4 442.7 447.2 448.6 452.9 4458.5 4462.2 4485.2 4490.2 4503.9 11210.3 11483.4 11495.3 11544.1 11598.1 11109.2 11400.0 11452.4 11474.2 11768.5 8527.2 8615.8 8645.2 8657.2 8669.1 6266.0 6320.0 6515.9 6528.4 6584.5 10292.1 10588.6 10635.0 10646.7 10789.3 9579.3 10559.5 10854.7 10860.6 11187.5 8002.4 8272.8 8523.6 8554.3 8558.2 9010.1 9014.3 9051.7 9064.4 9098.3 7641.6 7887.9 7928.6 7929.0 7987.2 7894.8 8032.2 8139.1 8194.8 8265.8 7417.0 7424.3 7548.9 7566.0 7582.6 5167.4 5262.6 5266.8 5349.4 5378.4 5365.3 5373.4 5431.6 5536.3 5574.2 20:33:11 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | [#4] prototype.html Cycles:180 Average:4694.99 Median:5408.09 stddev:1369.64 (25.3%) stddev-sans-first:1333.72 20:33:11 INFO - PROCESS | 17616 | Values: 329.8 337.5 341.2 341.9 347.2 5164.7 5353.1 5402.5 5413.7 5474.4 5275.7 5366.7 5397.7 5405.2 5411.0 4002.4 4179.7 4198.6 4202.6 4219.5 4038.4 4132.8 4183.5 4210.1 4211.4 4111.4 4127.0 4137.2 4149.9 4150.9 3916.5 3989.0 4131.7 4136.3 4137.3 3625.1 3629.8 3631.1 3635.2 3655.2 5098.2 5110.2 5123.3 5125.2 5140.9 4474.2 4678.8 4703.5 4705.7 4743.3 5511.5 5520.8 5556.3 5560.0 5560.7 3988.1 4014.9 4022.1 4031.2 4065.8 5627.3 5707.7 5765.1 5801.7 5809.9 4074.0 4106.5 4107.7 4111.3 4134.6 4157.9 4169.8 4185.9 4192.4 4210.3 4714.3 4764.3 4780.3 4788.5 4798.9 5250.2 5542.0 5574.3 5633.8 5643.2 1452.3 1470.6 1479.7 1481.9 1492.8 942.3 969.4 973.7 979.3 982.7 5130.2 5239.7 5308.5 5411.2 5426.5 5720.6 5728.6 5728.8 5738.3 5762.6 5171.4 5392.7 5401.0 5425.2 5429.2 3001.5 3017.3 3025.7 3041.0 3059.0 5867.8 5877.4 5888.5 5888.6 5895.2 5671.9 5805.3 5828.5 5832.3 5833.8 5843.8 5870.2 5870.3 5889.5 5904.0 5426.4 5693.2 5718.7 5723.2 5728.9 5632.5 5657.8 5663.9 5680.2 5732.7 5707.0 5936.4 5944.2 5953.2 5956.5 4065.7 4159.9 4189.7 4216.8 4219.2 5582.1 5687.0 5712.5 5730.2 5776.3 5529.6 5570.1 5576.6 5634.6 5640.8 5334.3 5548.9 5559.5 5576.9 5578.3 5235.7 5427.4 5443.0 5446.3 5459.7 5431.9 5601.8 5611.3 5626.8 5635.7 5480.3 5492.9 5500.9 5508.8 5509.1 20:33:11 INFO - PROCESS | 17616 | 20:33:11 INFO - PROCESS | 17616 | [#5] yui.html Cycles:180 Average:15792.22 Median:26562.67 stddev:18734.71 (70.5%) stddev-sans-first:18753.57 20:33:11 INFO - PROCESS | 17616 | Values: 827.7 870.8 870.9 886.7 887.7 13285.8 14994.6 15148.2 15160.1 15197.0 13885.9 13943.7 13946.0 13955.9 13962.9 666.6 788.5 813.9 817.7 819.7 799.2 800.9 809.1 809.2 809.6 705.7 842.1 847.1 847.5 849.4 799.7 827.1 829.1 832.4 833.4 610.1 610.4 610.6 610.6 610.6 14226.9 14230.4 14271.0 14303.2 14304.9 13802.8 13877.1 14054.9 14094.8 14107.0 11945.8 12056.0 12137.8 12346.9 12417.3 4477.6 4477.7 4524.2 4530.3 4619.9 53297.0 53369.2 53427.7 53618.5 53865.0 10994.2 11128.2 11225.6 11308.9 11329.5 18625.0 20151.5 20281.8 20294.1 20334.3 8374.0 8477.9 8500.5 8510.8 8723.3 4799.8 4870.0 4938.4 4981.8 4992.5 3484.8 3552.7 3560.7 3562.5 3566.0 610.4 647.6 650.3 672.5 698.9 663.4 713.8 715.3 778.3 784.2 8966.4 9291.4 9340.2 9414.4 9669.3 631.0 663.7 697.5 749.3 755.6 4408.5 4757.9 4786.7 4795.9 4879.2 71314.0 71507.0 72898.3 73003.0 73080.6 45836.8 48325.5 58325.0 63124.0 64146.7 56333.4 56497.6 56547.7 56587.0 57744.1 9339.3 9550.3 9572.0 9633.5 9641.0 37359.8 37810.4 37983.4 38103.8 38902.0 50562.7 51081.7 51486.0 51746.5 52526.2 11828.3 12053.3 12121.5 12149.1 12176.4 13255.0 13464.9 13606.4 13921.8 14086.4 12200.6 12546.3 12599.4 12710.0 12738.3 13134.0 13221.4 13297.3 13376.7 14482.3 13108.8 13163.3 13381.3 13775.5 13832.0 11058.6 11064.3 11490.5 11557.1 12045.9 11215.7 11405.9 11655.4 11724.8 11863.0 20:33:11 INFO - PROCESS | 17616 | -------- Summary: end -------- 20:33:11 INFO - PROCESS | 17616 | 20:33:12 INFO - TEST-INFO | 17616: exit 0 20:33:12 INFO - TEST-OK | dromaeo_css | took 1113079ms 20:33:12 INFO - TEST-START | kraken 20:33:12 INFO - operating with platform_type : linux_ 20:33:12 INFO - Initialising browser for kraken test... 20:33:12 INFO - TEST-INFO | started process 17806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKjz1ae/profile http://localhost:58742/getInfo.html) 20:33:16 INFO - PROCESS | 17806 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 20:33:16 INFO - PROCESS | 17806 | __metrics Screen width/height:1600/1200 20:33:16 INFO - PROCESS | 17806 | colorDepth:24 20:33:16 INFO - PROCESS | 17806 | Browser inner width/height: 1024/697 20:33:16 INFO - PROCESS | 17806 | __metrics 20:33:17 ERROR - PROCESS | 17806 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:33:18 INFO - TEST-INFO | 17806: exit 0 20:33:18 INFO - Browser initialized. 20:33:18 INFO - Running cycle 1/1 for kraken test... 20:33:18 INFO - TEST-INFO | started process 17887 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKjz1ae/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:33:30 INFO - PROCESS | 17887 | RSS: Main: 152981504 20:33:30 INFO - PROCESS | 17887 | 20:33:32 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58742/tests/kraken/driver.html?testName=audio-beat-detection) 20:33:33 INFO - PROCESS | 17887 | RSS: Main: 208826368 20:33:33 INFO - PROCESS | 17887 | 20:33:36 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58742/tests/kraken/driver.html?testName=audio-dft) 20:33:37 INFO - PROCESS | 17887 | RSS: Main: 197328896 20:33:37 INFO - PROCESS | 17887 | 20:33:40 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58742/tests/kraken/driver.html?testName=audio-fft) 20:33:40 INFO - PROCESS | 17887 | RSS: Main: 265138176 20:33:40 INFO - PROCESS | 17887 | 20:33:43 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58742/tests/kraken/driver.html?testName=audio-oscillator) 20:33:43 INFO - PROCESS | 17887 | RSS: Main: 174993408 20:33:43 INFO - PROCESS | 17887 | 20:33:45 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58742/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:33:46 INFO - PROCESS | 17887 | RSS: Main: 179535872 20:33:46 INFO - PROCESS | 17887 | 20:33:49 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58742/tests/kraken/driver.html?testName=imaging-darkroom) 20:33:50 INFO - PROCESS | 17887 | RSS: Main: 215388160 20:33:50 INFO - PROCESS | 17887 | 20:33:53 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58742/tests/kraken/driver.html?testName=imaging-desaturate) 20:33:54 INFO - PROCESS | 17887 | RSS: Main: 212492288 20:33:54 INFO - PROCESS | 17887 | 20:33:57 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58742/tests/kraken/driver.html?testName=json-parse-financial) 20:33:58 INFO - PROCESS | 17887 | RSS: Main: 213692416 20:33:58 INFO - PROCESS | 17887 | 20:33:59 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58742/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:34:00 INFO - PROCESS | 17887 | RSS: Main: 203489280 20:34:00 INFO - PROCESS | 17887 | 20:34:02 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58742/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:34:02 INFO - PROCESS | 17887 | RSS: Main: 206401536 20:34:02 INFO - PROCESS | 17887 | 20:34:04 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58742/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:34:05 INFO - PROCESS | 17887 | RSS: Main: 217935872 20:34:05 INFO - PROCESS | 17887 | 20:34:08 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58742/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:34:08 INFO - PROCESS | 17887 | RSS: Main: 223342592 20:34:08 INFO - PROCESS | 17887 | 20:34:11 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58742/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:34:12 INFO - PROCESS | 17887 | RSS: Main: 219037696 20:34:12 INFO - PROCESS | 17887 | 20:34:13 INFO - PROCESS | 17887 | Cycle 1(1): loaded http://localhost:58742/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58742/tests/kraken/driver.html?testName=ai-astar) 20:34:14 INFO - PROCESS | 17887 | RSS: Main: 264495104 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | __start_tp_report 20:34:14 INFO - PROCESS | 17887 | _x_x_mozilla_page_load 20:34:14 INFO - PROCESS | 17887 | _x_x_mozilla_page_load_details 20:34:14 INFO - PROCESS | 17887 | |i|pagename|runs| 20:34:14 INFO - PROCESS | 17887 | |0;ai-astar;111;107;113;112;112;111;111;114;110;110 20:34:14 INFO - PROCESS | 17887 | |1;audio-beat-detection;160;123;149;204;124;154;153;127;176;123 20:34:14 INFO - PROCESS | 17887 | |2;audio-dft;160;160;160;161;161;159;161;163;161;161 20:34:14 INFO - PROCESS | 17887 | |3;audio-fft;74;75;77;155;76;75;75;75;129;75 20:34:14 INFO - PROCESS | 17887 | |4;audio-oscillator;92;92;150;92;92;92;130;92;92;124 20:34:14 INFO - PROCESS | 17887 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;117;116;117 20:34:14 INFO - PROCESS | 17887 | |6;imaging-darkroom;145;146;147;145;145;146;146;145;145;145 20:34:14 INFO - PROCESS | 17887 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;95;95 20:34:14 INFO - PROCESS | 17887 | |8;json-parse-financial;58;54;55;54;55;55;55;108;53;54 20:34:14 INFO - PROCESS | 17887 | |9;json-stringify-tinderbox;58;60;59;59;60;59;59;59;59;59 20:34:14 INFO - PROCESS | 17887 | |10;stanford-crypto-aes;77;79;79;78;77;80;80;85;79;84 20:34:14 INFO - PROCESS | 17887 | |11;stanford-crypto-ccm;126;129;118;126;127;127;117;118;187;121 20:34:14 INFO - PROCESS | 17887 | |12;stanford-crypto-pbkdf2;161;159;167;158;161;160;162;164;160;163 20:34:14 INFO - PROCESS | 17887 | |13;stanford-crypto-sha256-iterative;65;62;64;66;63;63;62;62;62;62 20:34:14 INFO - PROCESS | 17887 | __end_tp_report 20:34:14 INFO - PROCESS | 17887 | __start_cc_report 20:34:14 INFO - PROCESS | 17887 | _x_x_mozilla_cycle_collect,1842 20:34:14 INFO - PROCESS | 17887 | __end_cc_report 20:34:14 INFO - PROCESS | 17887 | __startTimestamp1463196854071__endTimestamp 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | ------- Summary: start ------- 20:34:14 INFO - PROCESS | 17887 | Number of tests: 14 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.50 stddev:1.91 (1.7%) stddev-sans-first:2.03 20:34:14 INFO - PROCESS | 17887 | Values: 111.0 107.0 113.0 112.0 112.0 111.0 111.0 114.0 110.0 110.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#1] audio-beat-detection Cycles:10 Average:149.30 Median:153.50 stddev:26.62 (17.3%) stddev-sans-first:27.95 20:34:14 INFO - PROCESS | 17887 | Values: 160.0 123.0 149.0 204.0 124.0 154.0 153.0 127.0 176.0 123.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.09 20:34:14 INFO - PROCESS | 17887 | Values: 160.0 160.0 160.0 161.0 161.0 159.0 161.0 163.0 161.0 161.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#3] audio-fft Cycles:10 Average:88.60 Median:75.00 stddev:28.81 (38.4%) stddev-sans-first:30.07 20:34:14 INFO - PROCESS | 17887 | Values: 74.0 75.0 77.0 155.0 76.0 75.0 75.0 75.0 129.0 75.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:92.00 stddev:21.59 (23.5%) stddev-sans-first:22.39 20:34:14 INFO - PROCESS | 17887 | Values: 92.0 92.0 150.0 92.0 92.0 92.0 130.0 92.0 92.0 124.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 20:34:14 INFO - PROCESS | 17887 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 117.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 20:34:14 INFO - PROCESS | 17887 | Values: 145.0 146.0 147.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:34:14 INFO - PROCESS | 17887 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:55.00 stddev:16.88 (30.7%) stddev-sans-first:17.89 20:34:14 INFO - PROCESS | 17887 | Values: 58.0 54.0 55.0 54.0 55.0 55.0 55.0 108.0 53.0 54.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.44 20:34:14 INFO - PROCESS | 17887 | Values: 58.0 60.0 59.0 59.0 60.0 59.0 59.0 59.0 59.0 59.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.50 stddev:2.70 (3.4%) stddev-sans-first:2.67 20:34:14 INFO - PROCESS | 17887 | Values: 77.0 79.0 79.0 78.0 77.0 80.0 80.0 85.0 79.0 84.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:126.50 stddev:20.65 (16.3%) stddev-sans-first:21.86 20:34:14 INFO - PROCESS | 17887 | Values: 126.0 129.0 118.0 126.0 127.0 127.0 117.0 118.0 187.0 121.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:161.50 stddev:2.64 (1.6%) stddev-sans-first:2.79 20:34:14 INFO - PROCESS | 17887 | Values: 161.0 159.0 167.0 158.0 161.0 160.0 162.0 164.0 160.0 163.0 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - PROCESS | 17887 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:1.45 (2.3%) stddev-sans-first:1.36 20:34:14 INFO - PROCESS | 17887 | Values: 65.0 62.0 64.0 66.0 63.0 63.0 62.0 62.0 62.0 62.0 20:34:14 INFO - PROCESS | 17887 | -------- Summary: end -------- 20:34:14 INFO - PROCESS | 17887 | 20:34:14 INFO - TEST-INFO | 17887: exit 0 20:34:15 INFO - TEST-OK | kraken | took 62394ms 20:34:15 INFO - SUITE-END | took 1175s 20:34:15 INFO - Completed test suite (00:19:35) 20:34:15 INFO - javascript benchmark 20:34:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.6849315068494, 3152.4325621388143, 3194.8242846643425, 3199.0242975892343, 3230.628477725062, 5245.10833158363, 5327.653702509334, 5334.946650533493, 5412.783488660454, 5462.754176062082, 5309.044372013036, 5336.17289320155, 5349.197620356948, 5354.9196762048505, 5390.91913621295, 4771.427428708551, 4850.223964165734, 4893.779779909894, 4899.412070551539, 4983.950160498403, 4887.1447496688115, 4926.876828079291, 4932.704037757728, 4939.308496810431, 4987.775550100236, 4762.28565715142, 4812.181929072049, 4828.324034635119, 4846.7576621169155, 4853.684510506823, 4818.494058123917, 4829.468758436569, 4831.9275210871865, 4833.854984350475, 4925.802967881281, 4840.080384726926, 4866.537678920497, 4870.123377791996, 4884.780184891689, 4902.828401005948, 5126.897462050738, 5187.870303242411, 5235.397929238148, 5239.528442440199, 5301.151815709506, 5718.256626638552, 5734.942650573483, 5746.712664366765, 5747.1666608341975, 5770.682612456313, 5752.338481074688, 5753.82738517841, 5760.049591817345, 5808.854778630526, 5811.15738217956, 5212.035773381936, 5348.839534813962, 5389.083855744533, 5414.458554144554, 5497.615166938356, 5889.234399528034, 5894.469497745138, 5942.405759424023, 5944.464998150101, 5957.434043765836, 5444.91832622511, 5722.113072473773, 5765.827025189251, 5769.624974376652, 5776.566757493205, 5621.831345059573, 5722.885542289131, 5737.971310143506, 5773.076307790733, 5775.104858746901, 5737.5983681141915, 5761.308642962787, 5770.394108618619, 5770.5095066919785, 5770.6249093808765, 5567.220589117527, 5630.831075067755, 5763.682997435181, 5764.164196191528, 5774.393688662714, 4326.870193894189, 4342.500612429548, 4354.608085272341, 4399.032212913154, 4405.854477835722, 3780.722109938358, 3878.4182372643763, 3885.7668539887695, 3900.8244628991765, 3932.2528719543197, 5245.737713114329, 5283.418823929442, 5392.78428862849, 5449.182622606561, 5496.835094947158, 5666.490015898589, 5687.9431205687415, 5715.6284841486, 5723.141528770634, 5763.135529670583, 5346.331708536433, 5350.117230656976, 5353.678779273256, 5357.812476563302, 5372.462753724596, 4703.905921881543, 4745.596624286905, 4777.163996300703, 4813.9037219255415, 4831.685940413862, 5948.10778383246, 5957.940420595739, 5963.19496867926, 5963.999999999914, 6000.729967151488, 5800.361960184461, 5811.360750317377, 5820.796272130455, 5821.185034095145, 5831.79588714393, 5710.743016564182, 5824.70876456167, 5853.239078919713, 5882.735276912635, 5941.435563621449, 5657.2079908811975, 5657.377688454185, 5659.179418984307, 5660.660360378391, 5662.348829884616, 5707.743151558107, 5738.340090889435, 5744.425557444222, 5749.798757043483, 5752.194692743103, 5487.533559647474, 5542.2795036644975, 5586.441355864544, 5603.243562119062, 5644.322681278273, 5629.521490673339, 5629.662220266797, 5632.239647647515, 5632.408597097409, 5633.549316054788, 5382.165764306539, 5509.063459211863, 5531.778728850962, 5554.444555544414, 5562.110062389998, 5598.216249725123, 5662.971685141548, 5673.489385955284, 5749.108888122348, 5754.136879468113, 5527.0, 5571.997040532743, 5574.498295153294, 5576.247206627054, 5623.3533143689], "value": 5281.212819355311, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.1735652330232, 639.2009987515607, 648.1120864415751, 658.9703463344149, 660.3924389561603, 43268.783656081716, 45088.0, 45128.77435612821, 48097.0, 48219.517804822026, 46006.769966150125, 46309.768451157695, 46363.30455043168, 46469.0, 46489.30266046012, 8992.19070283676, 9199.586018629145, 9220.769480763001, 9221.077892210793, 9223.400478968846, 8837.072107428723, 9025.638974441048, 9029.503377314246, 9035.141661542142, 9035.819283614324, 9074.092590740938, 9089.318301127441, 9099.363044586882, 9108.635654573809, 9109.772255693595, 9013.233875120623, 9037.638494460214, 9050.185483306468, 9052.954735226347, 9054.456732596065, 8970.0, 8977.32670049749, 9038.54807259641, 9044.32167587427, 9051.094890510962, 42585.78707106445, 42991.35512967308, 43005.784971075256, 43016.0, 43062.354064689054, 3654.470101835219, 3683.898983769139, 3857.0550215197177, 4064.7764372959477, 4345.348197770328, 33277.833610832036, 33344.16639584005, 33536.496952545735, 33624.0, 33814.32371352584, 4370.322599996974, 4433.623142032931, 4439.289713645801, 4454.799534020977, 4469.039156581351, 348282.0, 348567.0, 350549.0, 351335.0, 353174.0, 61241.69379153076, 61362.69318653468, 61608.691956540824, 61664.69167654133, 61684.0, 56284.71857640768, 61538.69230653907, 64146.0, 64162.0, 64248.0, 16348.75476867848, 17570.472885813448, 17636.118194090242, 17646.2059207336, 17657.20542575587, 13487.325633718276, 13632.454701811816, 13647.590572282847, 13647.931760341135, 13648.317584120754, 16237.188140592923, 17616.383426580007, 18231.45305640833, 18438.446846594623, 18442.170102345426, 6443.4523065539015, 6469.838254043687, 6480.222373315232, 6592.373724496165, 6641.369069938348, 1034.3586976074882, 1119.5242022140558, 1140.8345789860423, 1170.1341007654444, 1174.1370092981651, 33142.17144571357, 33280.16799579981, 33549.49675754866, 33615.15962100967, 34040.65959340424, 1142.4858813533942, 1205.0058701571063, 1218.4760552962134, 1220.2983284611385, 1228.3980849383738, 17606.559836004202, 17650.205740741698, 17682.734758978626, 17707.822921770876, 17717.29130834752, 503653.0, 508184.0, 508384.0, 509166.0, 510790.0, 58225.417745822844, 59571.404285957455, 59687.40312596905, 59727.70136149292, 59924.906382957066, 1416.5962700630478, 1418.1632836626234, 1439.6688761584894, 1440.3014537949086, 1448.0949406620862, 30915.381692365576, 32842.67157328492, 32892.83553582217, 32990.34019319648, 33601.15997100092, 52568.7371563155, 52692.736536317076, 52769.47230527645, 52843.73578132085, 53006.4699353017, 426755.0, 435449.0, 436262.0, 436660.0, 438662.0, 36748.44877326896, 41045.179096417305, 43470.130597387244, 43473.782631086644, 43545.56454435415, 46986.76506617445, 47010.529894701984, 47845.5215447855, 47874.76062619664, 47908.5209147918, 40952.0, 41415.7929210352, 42067.0, 45949.31076033929, 46201.76899115483, 42500.78749606356, 42804.5719542813, 42826.57173428226, 43078.78460607677, 43702.344464832415, 41831.58168418277, 42327.0, 42522.5747742531, 43889.34165987449, 44827.022123520976, 34888.47667285043, 36861.81569092137, 37261.81369093137, 37964.810175948944, 38031.80984095062, 29081.563776542946, 29328.41343173167, 29456.410871782016, 29554.0, 29581.408371832873], "value": 20926.51501461538, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.20224438778297, 395.2135250850808, 397.3622336150478, 406.751881352375, 419.05921206890537, 13786.011297077366, 18126.0, 18212.817871821277, 19561.706574401378, 19613.803861961394, 17574.384896528623, 17926.19332130054, 18322.0, 19470.221191152337, 19532.70700939487, 5673.149027645855, 5853.122031695238, 5855.268091488564, 5870.295564532262, 5892.793752218673, 4248.405223268729, 5789.131630255474, 5804.216430781833, 6143.846403839917, 6477.384648458413, 6420.357964203566, 6516.967415162918, 6548.657525207321, 6559.147310849583, 6561.77033803817, 6315.242170939517, 6372.362763723637, 6470.611763294194, 6475.643839588821, 6488.0592314114665, 5505.834824955257, 5683.886322273573, 5698.2592263005545, 5698.6865722385255, 5726.770929162829, 23006.7699323008, 23173.0, 23250.65124023141, 23423.882880585657, 23542.821719067877, 2411.3368823573483, 2416.7945724613255, 2472.9505409891703, 2517.0435234610914, 2913.956290655642, 9655.806883862353, 14167.574972750834, 14242.857571424256, 14297.78553321701, 14962.700745985128, 1881.736556882024, 1966.852486063551, 1966.9311574094982, 2026.7973202679614, 2028.219135632792, 78155.21844781593, 81848.18151818405, 82075.58962205211, 82564.58717706433, 83414.16585834185, 10627.628033018807, 10674.679759607225, 10782.406967616856, 10829.187810914056, 10848.132149428027, 14789.186594737177, 14913.478028268959, 14936.626584335478, 14946.92526537382, 14949.850501495064, 8147.877781833277, 8337.416380853281, 8424.663013479392, 8435.409521333571, 8576.228139467477, 8162.14297498766, 8175.79560510992, 8185.3042491387705, 8290.170982901782, 8302.750917472486, 4614.792334344963, 4677.438707355139, 4721.291806229093, 4749.596284315803, 4767.213409787416, 1931.4978105692621, 1945.7665080190184, 1957.6378370001596, 1964.4794129555394, 2022.3427386099577, 1006.8187726209353, 1033.7829055898196, 1066.9573217071231, 1081.9404932728619, 1142.5315620595682, 18501.259949602136, 19123.330683426015, 19240.71138932917, 19351.612967740566, 19491.51271218231, 1041.9462920432313, 1047.675220681591, 1062.6121465664976, 1070.2775626452114, 1081.1566977757361, 10833.458327083614, 10850.934946028397, 10910.763293524808, 10910.981468428366, 10973.945130274298, 523931.0, 524644.0, 528457.0, 530209.0, 530214.0, 90182.44485065306, 90296.54851725831, 90969.0, 91189.5440522793, 91612.54193728989, 1037.4242295525905, 1060.6552870317332, 1071.244772435431, 1072.0458791675414, 1081.6106201767357, 16623.50129496117, 16896.0, 17070.914645426692, 17166.227519761393, 17452.301907923542, 39414.0, 40481.39277910892, 40883.79558102191, 41348.58651413448, 42226.155476889675, 54873.0, 56720.432795673165, 57041.0, 57736.71131644315, 58099.41900581109, 8196.918030819616, 8362.0, 8406.495610263404, 8517.124708257852, 8590.14098590136, 9377.202937750095, 12551.121421500407, 12864.485420583445, 12918.935405322913, 13080.149790263602, 10827.891721082688, 11272.774544509228, 11517.769644607228, 11543.422828858356, 11748.236364636272, 11094.334339939629, 11169.609063682732, 11247.437628118725, 11324.886751132382, 11457.770844583229, 11544.538218471504, 11553.191276610556, 11631.185816992729, 11748.118891083373, 11805.704857378634, 8230.21812927771, 8261.917380826115, 8329.083800781787, 8329.208725171098, 8554.312308149125, 8629.09394513567, 10633.255672103189, 11207.887921120684, 11354.886451135713, 11371.0], "value": 9351.594647707572, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.3769334952597, 1573.2133933033483, 1580.1072394097334, 1759.1292310306392, 1828.9725654115182, 8726.563671816408, 9077.455352678835, 9113.225375843045, 9379.484128372937, 9406.623735050589, 9145.359824812265, 9347.906520934788, 9356.953215233914, 9358.204552613037, 9365.157135857771, 1973.8322242609395, 2021.2117274263048, 2021.6462119129167, 2395.341281147685, 2437.2200895713377, 2296.8392212545127, 2376.8811559422047, 2378.0, 2378.892949817254, 2386.4033991502124, 2354.799842013431, 2377.7503362146986, 2386.988065059681, 2395.197608801054, 2395.520895820834, 2056.8148866601928, 2322.2568777990964, 2349.0721165139676, 2360.3155085025323, 2360.6459031145378, 2404.1945948107405, 2432.19737486629, 2432.683751112344, 2436.317831007303, 2441.1333976438286, 9159.725208243697, 9205.67780127699, 9213.67752128679, 9216.861747073799, 9224.723258302261, 5374.328208973879, 5532.502074813246, 5539.972300138474, 5549.889002219973, 5553.4724201200825, 3398.5242066110764, 3456.70617997468, 3610.0794297454245, 3666.77999320039, 3670.284294562561, 1158.1198289300107, 1160.2806260118696, 1168.275669085164, 1171.5372427890936, 1180.3154170581042, 13031.348432578428, 13035.674108147277, 13068.673283167902, 13075.738485230337, 13093.345332733325, 4901.117798796251, 4940.135476291634, 5088.567471764867, 5099.719515426687, 5145.408278047998, 10341.4312212829, 10421.843672344921, 10429.68710938673, 10468.790624187473, 10484.580616775396, 5575.275214222125, 5582.7766889323975, 5585.078462053797, 5589.776408943678, 5593.804216852391, 5075.949240507622, 5224.869378265497, 5292.0, 5300.549453296513, 5307.363116425976, 3691.6862066724093, 3767.679747221462, 3783.621637836249, 3814.771113733185, 3824.694024478091, 1116.7933932222566, 1136.1478890831877, 1158.843556119913, 1162.4071723421116, 1172.4841069929205, 887.3477993674644, 968.6754937096151, 1034.446571084466, 1034.9586016559404, 1039.5581877701945, 6107.992181290127, 6199.442050215503, 6315.463185629273, 6320.747170113144, 6337.6197428154455, 431.41974044909546, 442.6901169181521, 447.17496219475015, 448.605227399886, 452.8596135198099, 4458.509563947963, 4462.152191083684, 4485.237509623372, 4490.236659767782, 4503.932441013388, 11210.27133236337, 11483.42582870853, 11495.310281383145, 11544.134189935621, 11598.072154227642, 11109.222354435113, 11400.00499912512, 11452.427378631035, 11474.196806223652, 11768.52925882955, 8527.232549070613, 8615.836862023672, 8645.221930026202, 8657.177568131143, 8669.08974557675, 6265.968670156803, 6319.96840015797, 6515.869682606227, 6528.445082168069, 6584.473242140713, 10292.125169360688, 10588.576456941637, 10635.032518984013, 10646.73383165427, 10789.298695584763, 9579.281553883626, 10559.472026398804, 10854.728631784268, 10860.619878304222, 11187.496562654864, 8002.359811214972, 8272.793180170544, 8523.573821308786, 8554.31565474773, 8558.186972237627, 9010.14403631654, 9014.323925705467, 9051.683191088281, 9064.365494415328, 9098.317626178195, 7641.617919104134, 7887.921120788948, 7928.603569821371, 7929.0, 7987.201279871966, 7894.802629934297, 8032.228383855874, 8139.10469848328, 8194.754157375288, 8265.83468330642, 7416.962915185605, 7424.331810137113, 7548.924510754822, 7566.0, 7582.62086895642, 5167.379914410295, 5262.578993680573, 5266.78932842682, 5349.384820745664, 5378.381486128988, 5365.3293338332705, 5373.408925018167, 5431.646942948696, 5536.335639723259, 5574.16387541872], "value": 4720.712122126182, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.8218961760651, 337.5004992610936, 341.16925286926335, 341.8786330852547, 347.23954539558383, 5164.715940623269, 5353.143497040474, 5402.486763757437, 5413.729313534327, 5474.42518535554, 5275.683458992463, 5366.677999320043, 5397.7031263280505, 5405.216243644669, 5410.972945135269, 4002.3996400540004, 4179.728317659343, 4198.6011328923705, 4202.621764041228, 4219.451471308726, 4038.3942408638645, 4132.772697501636, 4183.518895327046, 4210.094829611633, 4211.410402543646, 4111.403846442264, 4127.030147915238, 4137.193247316774, 4149.875503734877, 4150.916981660365, 3916.471276377681, 3988.9800550997347, 4131.710780245369, 4136.255474014676, 4137.262349813086, 3625.148090198801, 3629.7822130672243, 3631.1466805300997, 3635.2002559436883, 3655.232401195752, 5098.158803797369, 5110.233464980282, 5123.2827404163445, 5125.2312153176945, 5140.917928239069, 4474.172278128524, 4678.7660616969015, 4703.529647035269, 4705.670603057787, 4743.31221972812, 5511.5039646432015, 5520.806771762969, 5556.305461817273, 5559.972200139014, 5560.6941618210985, 3988.142549351874, 4014.8996275093064, 4022.1352409232154, 4031.153457773871, 4065.7560546367017, 5627.296587926509, 5707.686077265748, 5765.1352297155345, 5801.7389217485315, 5809.883802323972, 4073.9796301018305, 4106.507219133663, 4107.67138628909, 4111.259973204792, 4134.627883490441, 4157.875263742032, 4169.791510424467, 4185.853495127717, 4192.434021407132, 4210.347396153601, 4714.339992401066, 4764.331974094449, 4780.330753694486, 4788.473267940523, 4798.880027999328, 5250.18622113573, 5542.0, 5574.303212098488, 5633.802816901389, 5643.181738647873, 1452.3319273134477, 1470.5661829760247, 1479.7262506436343, 1481.888858335629, 1492.7760835874706, 942.3262367407336, 969.3795970578835, 973.7224890906199, 979.3193730357476, 982.6904525074578, 5130.230465430215, 5239.659422137549, 5308.548773354231, 5411.188321751769, 5426.511613954684, 5720.628159169724, 5728.620114132682, 5728.799492017843, 5738.254026976467, 5762.625429347163, 5171.405288391883, 5392.7057506198225, 5400.972995134999, 5425.159100339453, 5429.239906413105, 3001.529250667201, 3017.336186039068, 3025.7125573070525, 3040.9543856842165, 3058.9541156882665, 5867.765289388377, 5877.353491115974, 5888.5289176865745, 5888.617239879394, 5895.20414744004, 5671.914921276184, 5805.332386775491, 5828.5337173026055, 5832.329282132524, 5833.766649333979, 5843.795467158629, 5870.207521984563, 5870.324912635102, 5889.4993925515955, 5903.970480147657, 5426.375966763872, 5693.206639908416, 5718.714064296685, 5723.170140329712, 5728.914066289092, 5632.5493960482245, 5657.830265092054, 5663.8867222656145, 5680.176374425768, 5732.742026608729, 5706.971465142814, 5936.436038576328, 5944.167816505606, 5953.226080609493, 5956.463918247378, 4065.654419374386, 4159.875203743893, 4189.685773566934, 4216.756056963264, 4219.177260434186, 5582.106862901916, 5686.9715651421475, 5712.457316554921, 5730.226419433323, 5776.277965254343, 5529.612927095226, 5570.053090974463, 5576.553875689854, 5634.577406694432, 5640.802571909963, 5334.279872217357, 5548.944510554843, 5559.471850174227, 5576.8846230754825, 5578.30271216098, 5235.685858848481, 5427.4301198373605, 5443.0, 5446.319210098738, 5459.69971651563, 5431.85930954504, 5601.7759289629585, 5611.270534830446, 5626.831195064155, 5635.746391412315, 5480.260164877691, 5492.890142197213, 5500.944990550043, 5508.779648814003, 5509.146082357241], "value": 4262.9288884956995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.7351247600768, 870.8084221471278, 870.9085546017672, 886.7206829848598, 887.6715615222365, 13285.800712989301, 14994.625134371632, 15148.1668508232, 15160.090394576333, 15197.0, 13885.861141388583, 13943.721125577482, 13946.0, 13955.860441395584, 13962.860371396308, 666.630020338713, 788.5426452657445, 813.8738495533202, 817.6770175780563, 819.7008092046419, 799.1568894815966, 800.8798680197988, 809.1018968944467, 809.2231457800489, 809.5709274084744, 705.6894966214875, 842.0526907229367, 847.063994286314, 847.4957400346784, 849.4096602861014, 799.7001124578284, 827.1356432527963, 829.1335554345724, 832.4339449174558, 833.3791325462539, 610.1457958857591, 610.404855266112, 610.554295364382, 610.5939550199113, 610.6305685060519, 14226.857731422657, 14230.359633816399, 14271.0, 14303.213323267217, 14304.928475357661, 13802.792958105636, 13877.097988630707, 14054.859451405457, 14094.788578171336, 14107.0, 11945.820812687816, 12056.0, 12137.817932731015, 12346.938265308616, 12417.254964702146, 4477.619402350804, 4477.708948918343, 4524.185646583614, 4530.3431002504785, 4619.884502887421, 53297.0, 53369.1994620081, 53427.73286133583, 53618.463815361734, 53865.0, 10994.230403871812, 11128.1653875958, 11225.550977960791, 11308.886911130949, 11329.546818127348, 18625.0, 20151.496212594804, 20281.797182028287, 20294.086766095563, 20334.288299909433, 8374.0, 8477.872831907527, 8500.48997060166, 8510.829783404297, 8723.258523025614, 4799.832005879777, 4869.975650121727, 4938.407391113089, 4981.778823716363, 4992.525710057539, 3484.756067075331, 3552.698020668272, 3560.732945029133, 3562.5190599268767, 3566.037169964147, 610.4170517156106, 647.5531883000715, 650.3464018661215, 672.4586707700348, 698.8916717908733, 663.3764261594087, 713.8215446138465, 715.3275920634587, 778.3384123494984, 784.2236186175645, 8966.417182883091, 9291.396059256127, 9340.20608248293, 9414.388064775905, 9669.274804389559, 630.9779157729457, 663.6548994522917, 697.5326531224092, 749.3293502315506, 755.6297414266969, 4408.53710360414, 4757.9286310705365, 4786.712797232177, 4795.856124316276, 4879.192822426948, 71314.0, 71507.0, 72898.27101729022, 73003.0, 73080.63459682775, 45836.770816146374, 48325.5167448321, 58325.0, 63124.0, 64146.679266603365, 56333.43666563282, 56497.58756031132, 56547.71726141343, 56587.0, 57744.133837992464, 9339.299552533452, 9550.283728720235, 9572.0, 9633.518324083909, 9640.951795240979, 37359.81320093491, 37810.43284350682, 37983.430248545745, 38103.80948095242, 38902.0, 50562.747186265304, 51081.74459127681, 51486.0, 51746.48253517417, 52526.21210681919, 11828.34944078084, 12053.337066461077, 12121.515139394678, 12149.149559530748, 12176.391180440765, 13255.0, 13464.93267533656, 13606.387712553156, 13921.79117313221, 14086.436542538593, 12200.633980980585, 12546.309952952675, 12599.433025514058, 12710.0, 12738.299393533445, 13134.0, 13221.405036773176, 13297.335133243492, 13376.732465350444, 14482.275886205436, 13108.803367949298, 13163.341832908125, 13381.264030478416, 13775.517856874962, 13832.0, 11058.557657693602, 11064.28082174656, 11490.482928268415, 11557.13321500874, 12045.879541204476, 11215.66353009411, 11405.942970285096, 11655.358955257543, 11724.765504690029, 11863.004962432891], "value": 7016.612515889101, "unit": "score"}], "name": "dromaeo_css", "value": 7256.060638305118, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 113.0, 112.0, 112.0, 111.0, 111.0, 114.0, 110.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 123.0, 149.0, 204.0, 124.0, 154.0, 153.0, 127.0, 176.0, 123.0], "value": 149.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 161.0, 161.0, 159.0, 161.0, 163.0, 161.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 77.0, 155.0, 76.0, 75.0, 75.0, 75.0, 129.0, 75.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 150.0, 92.0, 92.0, 92.0, 130.0, 92.0, 92.0, 124.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 55.0, 54.0, 55.0, 55.0, 55.0, 108.0, 53.0, 54.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 79.0, 78.0, 77.0, 80.0, 80.0, 85.0, 79.0, 84.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 129.0, 118.0, 126.0, 127.0, 127.0, 117.0, 118.0, 187.0, 121.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 167.0, 158.0, 161.0, 160.0, 162.0, 164.0, 160.0, 163.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 64.0, 66.0, 63.0, 63.0, 62.0, 62.0, 62.0, 62.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1524.6999999999996, "alertThreshold": 2.0}]} 20:34:15 INFO - Return code: 0 20:34:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:34:15 ERROR - # TBPL SUCCESS # 20:34:15 INFO - Running post-action listener: _resource_record_post_action 20:34:15 INFO - ##### 20:34:15 INFO - ##### Finished run-tests step (success) 20:34:15 INFO - ##### 20:34:15 INFO - Running post-run listener: _resource_record_post_run 20:34:15 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536629248; Read time: 16; Write time: 411672 20:34:15 INFO - TinderboxPrint: CPU usage
13.1% 20:34:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 20:34:15 INFO - TinderboxPrint: I/O write bytes / time
536,629,248 / 411,672 20:34:15 INFO - TinderboxPrint: CPU idle
8,275.5 (86.9%) 20:34:15 INFO - TinderboxPrint: CPU user
1,202.1 (12.6%) 20:34:15 INFO - TinderboxPrint: Swap in / out
0 / 0 20:34:15 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 246468608; Read time: 0; Write time: 223040 20:34:15 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290160640; Read time: 16; Write time: 188632 20:34:16 INFO - Running post-run listener: _upload_blobber_files 20:34:16 INFO - Blob upload gear active. 20:34:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:34: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 20:34: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'] 20:34: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 20:34:16 INFO - (blobuploader) - INFO - Open directory for files ... 20:34:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:34:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:34:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:34:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:34:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:34:17 INFO - (blobuploader) - INFO - Done attempting. 20:34:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:34:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:34:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:34:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:34:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:34:21 INFO - (blobuploader) - INFO - Done attempting. 20:34:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:34:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:34:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:34:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:34:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:34:22 INFO - (blobuploader) - INFO - Done attempting. 20:34:22 INFO - (blobuploader) - INFO - Iteration through files over. 20:34:22 INFO - Return code: 0 20:34:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:34:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:34:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdc00006d817f1fc541a43bc33fe8865d625ac2cbed192ace73f4be64a4a8df90c69dd0a8b3f24fa048446f55ab9fda215b7f5aac5ec9cd496c43541abaa8071", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbcbc3fd1978590117d204a2ae348721f367ce0d5920ef3f956f7815bbb32f3942bc4f2b77263651b0fc6dc0cec955e3d64402a7efa4ee2ce4075fe74b3e68e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/461a91c975292493d406cf474a37b7f1398fc339daf98db1a28b00dcc068a8ed9891ffc3aa4d71ba1e52227e3ec9704747ac26253de78c11cfacd09b530ff398"} 20:34:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:34:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:34:22 INFO - Contents: 20:34:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdc00006d817f1fc541a43bc33fe8865d625ac2cbed192ace73f4be64a4a8df90c69dd0a8b3f24fa048446f55ab9fda215b7f5aac5ec9cd496c43541abaa8071", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbcbc3fd1978590117d204a2ae348721f367ce0d5920ef3f956f7815bbb32f3942bc4f2b77263651b0fc6dc0cec955e3d64402a7efa4ee2ce4075fe74b3e68e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/461a91c975292493d406cf474a37b7f1398fc339daf98db1a28b00dcc068a8ed9891ffc3aa4d71ba1e52227e3ec9704747ac26253de78c11cfacd09b530ff398"} 20:34:22 INFO - Running post-run listener: copy_logs_to_upload_dir 20:34:22 INFO - Copying logs to upload dir... 20:34:22 INFO - mkdir: /builds/slave/test/build/upload/logs 20:34:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.036375 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-05-13 20:34:22.308054) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 20:34:22.311137) ========= 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=4300f881736b422476174874000001bc-1463194606.805281-182790938 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/fdc00006d817f1fc541a43bc33fe8865d625ac2cbed192ace73f4be64a4a8df90c69dd0a8b3f24fa048446f55ab9fda215b7f5aac5ec9cd496c43541abaa8071", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbcbc3fd1978590117d204a2ae348721f367ce0d5920ef3f956f7815bbb32f3942bc4f2b77263651b0fc6dc0cec955e3d64402a7efa4ee2ce4075fe74b3e68e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/461a91c975292493d406cf474a37b7f1398fc339daf98db1a28b00dcc068a8ed9891ffc3aa4d71ba1e52227e3ec9704747ac26253de78c11cfacd09b530ff398"} build_url:https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/EFIEc_yvR0iQW8hF0hSF-w/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/fdc00006d817f1fc541a43bc33fe8865d625ac2cbed192ace73f4be64a4a8df90c69dd0a8b3f24fa048446f55ab9fda215b7f5aac5ec9cd496c43541abaa8071", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbcbc3fd1978590117d204a2ae348721f367ce0d5920ef3f956f7815bbb32f3942bc4f2b77263651b0fc6dc0cec955e3d64402a7efa4ee2ce4075fe74b3e68e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/461a91c975292493d406cf474a37b7f1398fc339daf98db1a28b00dcc068a8ed9891ffc3aa4d71ba1e52227e3ec9704747ac26253de78c11cfacd09b530ff398"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 20:34:22.335744) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 20:34:22.336087) ========= 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=4300f881736b422476174874000001bc-1463194606.805281-182790938 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 20:34:22.418499) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 20:34:22.418821) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 20:34:22.419113) ========= ========= Total master_lag: 0.16 =========