builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1464193832.81 results: success (0) buildid: 20160525063710 builduid: 67e7610a129d4ea4b040c5ef98e6020f revision: d6d4e8417d2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.811452) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.811945) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.812228) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.828075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.828672) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-25 09:30:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.8M=0s 2016-05-25 09:30:33 (42.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086370 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.930051) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.930332) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018963 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.986208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:32.986735) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d6d4e8417d2f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d6d4e8417d2f --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=7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-25 09:30:33,347 Setting DEBUG logging. 2016-05-25 09:30:33,347 attempt 1/10 2016-05-25 09:30:33,347 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d6d4e8417d2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 09:30:33,664 unpacking tar archive at: mozilla-central-d6d4e8417d2f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.668125 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:33.669212) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:33.669547) ========= script_repo_revision: d6d4e8417d2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:33.669963) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:33.670244) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 09:30:33.679975) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-25 09:30:33.680370) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:30:34 INFO - MultiFileLogger online at 20160525 09:30:34 in /builds/slave/test 09:30:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:30:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:34 INFO - {'append_to_log': False, 09:30:34 INFO - 'base_work_dir': '/builds/slave/test', 09:30:34 INFO - 'blob_upload_branch': 'Firefox', 09:30:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:34 INFO - 'branch': 'Firefox', 09:30:34 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:34 INFO - 'config_files': ('talos/linux_config.py',), 09:30:34 INFO - 'default_actions': ('clobber', 09:30:34 INFO - 'read-buildbot-config', 09:30:34 INFO - 'download-and-extract', 09:30:34 INFO - 'populate-webroot', 09:30:34 INFO - 'create-virtualenv', 09:30:34 INFO - 'install', 09:30:34 INFO - 'run-tests'), 09:30:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:34 INFO - 'download_minidump_stackwalk': True, 09:30:34 INFO - 'download_symbols': 'ondemand', 09:30:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:34 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:34 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:34 INFO - 'installer_path': 'installer.exe', 09:30:34 INFO - 'log_level': 'info', 09:30:34 INFO - 'log_name': 'talos', 09:30:34 INFO - 'log_to_console': True, 09:30:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:30:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:30:34 INFO - 'opt_config_files': (), 09:30:34 INFO - 'pip_index': False, 09:30:34 INFO - 'sps_profile': False, 09:30:34 INFO - 'sps_profile_interval': 0, 09:30:34 INFO - 'suite': 'dromaeojs', 09:30:34 INFO - 'system_bits': '32', 09:30:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:34 INFO - 'title': 'talos-linux64-ix-005', 09:30:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:34 INFO - 'use_talos_json': True, 09:30:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:34 INFO - 'work_dir': 'build'} 09:30:34 INFO - ##### 09:30:34 INFO - ##### Running clobber step. 09:30:34 INFO - ##### 09:30:34 INFO - Running pre-action listener: _resource_record_pre_action 09:30:34 INFO - Running main action method: clobber 09:30:34 INFO - rmtree: /builds/slave/test/build 09:30:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:34 INFO - Running post-action listener: _resource_record_post_action 09:30:34 INFO - ##### 09:30:34 INFO - ##### Finished clobber step (success) 09:30:34 INFO - ##### 09:30:34 INFO - ##### 09:30:34 INFO - ##### Running read-buildbot-config step. 09:30:34 INFO - ##### 09:30:34 INFO - Running pre-action listener: _resource_record_pre_action 09:30:34 INFO - Running main action method: read_buildbot_config 09:30:34 INFO - Using buildbot properties: 09:30:34 INFO - { 09:30:34 INFO - "project": "", 09:30:34 INFO - "product": "firefox", 09:30:34 INFO - "script_repo_revision": "production", 09:30:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:30:34 INFO - "repository": "", 09:30:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:30:34 INFO - "buildid": "20160525063710", 09:30:34 INFO - "stage_platform": "linux64-pgo", 09:30:34 INFO - "basedir": "/builds/slave/test", 09:30:34 INFO - "nightly_build": "True", 09:30:34 INFO - "buildnumber": 457, 09:30:34 INFO - "slavename": "talos-linux64-ix-005", 09:30:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:30:34 INFO - "platform": "ubuntu64_hw", 09:30:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:30:34 INFO - "branch": "mozilla-central", 09:30:34 INFO - "repo_path": "mozilla-central", 09:30:34 INFO - "revision": "d6d4e8417d2f", 09:30:34 INFO - "pgo_build": "True", 09:30:34 INFO - "builduid": "67e7610a129d4ea4b040c5ef98e6020f", 09:30:34 INFO - "slavebuilddir": "test" 09:30:34 INFO - } 09:30:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 09:30:34 INFO - Running post-action listener: _resource_record_post_action 09:30:34 INFO - ##### 09:30:34 INFO - ##### Finished read-buildbot-config step (success) 09:30:34 INFO - ##### 09:30:34 INFO - ##### 09:30:34 INFO - ##### Running download-and-extract step. 09:30:34 INFO - ##### 09:30:34 INFO - Running pre-action listener: _resource_record_pre_action 09:30:34 INFO - Running main action method: download_and_extract 09:30:34 INFO - mkdir: /builds/slave/test/build/tests 09:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:34 INFO - https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:30:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:30:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:30:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:30:35 INFO - Downloaded 1361 bytes. 09:30:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:30:35 INFO - Using the following test package requirements: 09:30:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:30:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:30:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:30:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'jsshell-linux-x86_64.zip'], 09:30:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:30:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:30:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:30:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:30:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:30:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:30:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:35 INFO - https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:30:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:30:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:30:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:30:36 INFO - Downloaded 24420760 bytes. 09:30:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:30:37 INFO - Return code: 0 09:30:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:37 INFO - https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:30:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:30:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:30:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:30:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:30:44 INFO - Downloaded 24420760 bytes. 09:30:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:30:45 INFO - Return code: 0 09:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:45 INFO - https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:30:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:30:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:30:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:30:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:30:46 INFO - Downloaded 10834034 bytes. 09:30:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:30:47 INFO - Return code: 0 09:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:47 INFO - https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:30:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:30:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:30:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:30:49 INFO - Downloaded 58629588 bytes. 09:30:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:30:49 INFO - mkdir: /builds/slave/test/properties 09:30:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:30:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:30:49 INFO - Contents: 09:30:49 INFO - build_url:https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:30:49 INFO - Running post-action listener: _resource_record_post_action 09:30:49 INFO - Running post-action listener: set_extra_try_arguments 09:30:49 INFO - ##### 09:30:49 INFO - ##### Finished download-and-extract step (success) 09:30:49 INFO - ##### 09:30:49 INFO - ##### 09:30:49 INFO - ##### Running populate-webroot step. 09:30:49 INFO - ##### 09:30:49 INFO - Running pre-action listener: _resource_record_pre_action 09:30:49 INFO - Running main action method: populate_webroot 09:30:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:30:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:30:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:30:49 INFO - '--tppagecycles', 09:30:49 INFO - '7'], 09:30:49 INFO - 'tests': ['tsvgm']}}, 09:30:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:30:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:30:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:30:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:30:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:49 INFO - 'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:49 INFO - 'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['damp', 'tps']}, 09:30:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:49 INFO - 'tests': ['damp', 'tps']}, 09:30:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['dromaeo_dom']}, 09:30:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:30:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['a11yr', 09:30:49 INFO - 'ts_paint', 09:30:49 INFO - 'tpaint', 09:30:49 INFO - 'sessionrestore', 09:30:49 INFO - 'sessionrestore_no_auto_restore', 09:30:49 INFO - 'tabpaint']}, 09:30:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:30:49 INFO - 'ts_paint', 09:30:49 INFO - 'tpaint', 09:30:49 INFO - 'sessionrestore', 09:30:49 INFO - 'sessionrestore_no_auto_restore', 09:30:49 INFO - 'tabpaint']}, 09:30:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:30:49 INFO - 'ts_paint', 09:30:49 INFO - 'tpaint', 09:30:49 INFO - 'sessionrestore', 09:30:49 INFO - 'sessionrestore_no_auto_restore', 09:30:49 INFO - 'tabpaint']}, 09:30:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:30:49 INFO - 'ts_paint', 09:30:49 INFO - 'tpaint', 09:30:49 INFO - 'sessionrestore', 09:30:49 INFO - 'sessionrestore_no_auto_restore', 09:30:49 INFO - 'tabpaint']}, 09:30:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['a11yr', 09:30:49 INFO - 'ts_paint', 09:30:49 INFO - 'tpaint', 09:30:49 INFO - 'sessionrestore', 09:30:49 INFO - 'sessionrestore_no_auto_restore', 09:30:49 INFO - 'tabpaint']}, 09:30:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['a11yr', 09:30:49 INFO - 'ts_paint', 09:30:49 INFO - 'tpaint', 09:30:49 INFO - 'sessionrestore', 09:30:49 INFO - 'sessionrestore_no_auto_restore', 09:30:49 INFO - 'tabpaint']}, 09:30:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['tsvgx', 09:30:49 INFO - 'tsvgr_opacity', 09:30:49 INFO - 'tart', 09:30:49 INFO - 'tscrollx', 09:30:49 INFO - 'cart']}, 09:30:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:30:49 INFO - 'tsvgr_opacity', 09:30:49 INFO - 'tart', 09:30:49 INFO - 'tscrollx', 09:30:49 INFO - 'cart']}, 09:30:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:49 INFO - 'talos_options': ['--disable-e10s'], 09:30:49 INFO - 'tests': ['tp5o']}, 09:30:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:49 INFO - 'tests': ['tp5o']}, 09:30:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:49 INFO - 'talos_options': ['--disable-e10s', 09:30:49 INFO - '--xperf_path', 09:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:49 INFO - 'tests': ['tp5n']}, 09:30:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:49 INFO - 'talos_options': ['--xperf_path', 09:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:49 INFO - 'tests': ['tp5n']}}, 09:30:49 INFO - 'talos.zip': {'path': '', 09:30:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:30:49 INFO - Running post-action listener: _resource_record_post_action 09:30:49 INFO - ##### 09:30:49 INFO - ##### Finished populate-webroot step (success) 09:30:49 INFO - ##### 09:30:49 INFO - ##### 09:30:49 INFO - ##### Running create-virtualenv step. 09:30:49 INFO - ##### 09:30:49 INFO - Running pre-action listener: _resource_record_pre_action 09:30:49 INFO - Running main action method: create_virtualenv 09:30:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:30:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:30:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:30:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:30:49 INFO - Using real prefix '/usr' 09:30:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:30:50 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:52 INFO - Installing pip.................done. 09:30:52 INFO - Return code: 0 09:30:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:30: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')]} 09:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30: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')]} 09:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:30:52 INFO - Using env: {'DISPLAY': ':0', 09:30:52 INFO - 'HOME': '/home/cltbld', 09:30:52 INFO - 'LANG': 'en_US.UTF-8', 09:30:52 INFO - 'LANGUAGE': 'en_US:en', 09:30:52 INFO - 'LOGNAME': 'cltbld', 09:30:52 INFO - 'MAIL': '/var/mail/cltbld', 09:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:52 INFO - 'MOZ_NO_REMOTE': '1', 09:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:52 INFO - 'NO_EM_RESTART': '1', 09:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:52 INFO - 'PWD': '/builds/slave/test', 09:30:52 INFO - 'SHELL': '/bin/bash', 09:30:52 INFO - 'SHLVL': '1', 09:30:52 INFO - 'TERM': 'linux', 09:30:52 INFO - 'TMOUT': '86400', 09:30:52 INFO - 'USER': 'cltbld', 09:30:52 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:52 INFO - '_': '/tools/buildbot/bin/python'} 09:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:30:52 INFO - Cleaning up... 09:30:52 INFO - Return code: 0 09:30:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:30: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')]} 09:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30: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')]} 09:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:30:52 INFO - Using env: {'DISPLAY': ':0', 09:30:52 INFO - 'HOME': '/home/cltbld', 09:30:52 INFO - 'LANG': 'en_US.UTF-8', 09:30:52 INFO - 'LANGUAGE': 'en_US:en', 09:30:52 INFO - 'LOGNAME': 'cltbld', 09:30:52 INFO - 'MAIL': '/var/mail/cltbld', 09:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:52 INFO - 'MOZ_NO_REMOTE': '1', 09:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:52 INFO - 'NO_EM_RESTART': '1', 09:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:52 INFO - 'PWD': '/builds/slave/test', 09:30:52 INFO - 'SHELL': '/bin/bash', 09:30:52 INFO - 'SHLVL': '1', 09:30:52 INFO - 'TERM': 'linux', 09:30:52 INFO - 'TMOUT': '86400', 09:30:52 INFO - 'USER': 'cltbld', 09:30:52 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:52 INFO - '_': '/tools/buildbot/bin/python'} 09:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:52 INFO - Downloading/unpacking psutil>=3.1.1 09:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:54 INFO - Installing collected packages: psutil 09:30:54 INFO - Running setup.py install for psutil 09:30:54 INFO - building 'psutil._psutil_linux' extension 09:30:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:30:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:30:55 INFO - building 'psutil._psutil_posix' extension 09:30:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:30:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:30:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:55 INFO - Successfully installed psutil 09:30:55 INFO - Cleaning up... 09:30:55 INFO - Return code: 0 09:30:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:30: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')]} 09:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30: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')]} 09:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:30:55 INFO - Using env: {'DISPLAY': ':0', 09:30:55 INFO - 'HOME': '/home/cltbld', 09:30:55 INFO - 'LANG': 'en_US.UTF-8', 09:30:55 INFO - 'LANGUAGE': 'en_US:en', 09:30:55 INFO - 'LOGNAME': 'cltbld', 09:30:55 INFO - 'MAIL': '/var/mail/cltbld', 09:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:55 INFO - 'MOZ_NO_REMOTE': '1', 09:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:55 INFO - 'NO_EM_RESTART': '1', 09:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:55 INFO - 'PWD': '/builds/slave/test', 09:30:55 INFO - 'SHELL': '/bin/bash', 09:30:55 INFO - 'SHLVL': '1', 09:30:55 INFO - 'TERM': 'linux', 09:30:55 INFO - 'TMOUT': '86400', 09:30:55 INFO - 'USER': 'cltbld', 09:30:55 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:55 INFO - '_': '/tools/buildbot/bin/python'} 09:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:30:57 INFO - Installing collected packages: mozsystemmonitor 09:30:57 INFO - Running setup.py install for mozsystemmonitor 09:30:57 INFO - Successfully installed mozsystemmonitor 09:30:57 INFO - Cleaning up... 09:30:57 INFO - Return code: 0 09:30:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:57 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 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:30:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:30:57 INFO - Using env: {'DISPLAY': ':0', 09:30:57 INFO - 'HOME': '/home/cltbld', 09:30:57 INFO - 'LANG': 'en_US.UTF-8', 09:30:57 INFO - 'LANGUAGE': 'en_US:en', 09:30:57 INFO - 'LOGNAME': 'cltbld', 09:30:57 INFO - 'MAIL': '/var/mail/cltbld', 09:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:57 INFO - 'MOZ_NO_REMOTE': '1', 09:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:57 INFO - 'NO_EM_RESTART': '1', 09:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:57 INFO - 'PWD': '/builds/slave/test', 09:30:57 INFO - 'SHELL': '/bin/bash', 09:30:57 INFO - 'SHLVL': '1', 09:30:57 INFO - 'TERM': 'linux', 09:30:57 INFO - 'TMOUT': '86400', 09:30:57 INFO - 'USER': 'cltbld', 09:30:57 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:57 INFO - '_': '/tools/buildbot/bin/python'} 09:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:59 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31:00 INFO - Downloading docopt-0.6.1.tar.gz 09:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:00 INFO - Running setup.py install for blobuploader 09:31:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:00 INFO - Running setup.py install for requests 09:31:00 INFO - Running setup.py install for docopt 09:31:01 INFO - Successfully installed blobuploader requests docopt 09:31:01 INFO - Cleaning up... 09:31:01 INFO - Return code: 0 09:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:01 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 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:31:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:01 INFO - Using env: {'DISPLAY': ':0', 09:31:01 INFO - 'HOME': '/home/cltbld', 09:31:01 INFO - 'LANG': 'en_US.UTF-8', 09:31:01 INFO - 'LANGUAGE': 'en_US:en', 09:31:01 INFO - 'LOGNAME': 'cltbld', 09:31:01 INFO - 'MAIL': '/var/mail/cltbld', 09:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:01 INFO - 'MOZ_NO_REMOTE': '1', 09:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:01 INFO - 'NO_EM_RESTART': '1', 09:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:01 INFO - 'PWD': '/builds/slave/test', 09:31:01 INFO - 'SHELL': '/bin/bash', 09:31:01 INFO - 'SHLVL': '1', 09:31:01 INFO - 'TERM': 'linux', 09:31:01 INFO - 'TMOUT': '86400', 09:31:01 INFO - 'USER': 'cltbld', 09:31:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:01 INFO - '_': '/tools/buildbot/bin/python'} 09:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:01 INFO - Running setup.py (path:/tmp/pip-Dz_VqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:01 INFO - Running setup.py (path:/tmp/pip-4lqeio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:01 INFO - Running setup.py (path:/tmp/pip-0DsVFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:01 INFO - Running setup.py (path:/tmp/pip-tWrRuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:01 INFO - Running setup.py (path:/tmp/pip-nrcJxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:01 INFO - Running setup.py (path:/tmp/pip-ynUTVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:01 INFO - Running setup.py (path:/tmp/pip-ias3Zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:01 INFO - Running setup.py (path:/tmp/pip-VJXVWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:01 INFO - Running setup.py (path:/tmp/pip-JvLVUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:01 INFO - Running setup.py (path:/tmp/pip-vv1h8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:02 INFO - Running setup.py (path:/tmp/pip-jvUaHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:02 INFO - Running setup.py (path:/tmp/pip-nbDjJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:02 INFO - Running setup.py (path:/tmp/pip-UrjuGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:02 INFO - Running setup.py (path:/tmp/pip-iDb7iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:02 INFO - Running setup.py (path:/tmp/pip-394euQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:02 INFO - Running setup.py (path:/tmp/pip-vvNYP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:02 INFO - Running setup.py (path:/tmp/pip-oXepbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:02 INFO - Running setup.py install for manifestparser 09:31:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:02 INFO - Running setup.py install for mozcrash 09:31:02 INFO - Running setup.py install for mozdebug 09:31:02 INFO - Running setup.py install for mozdevice 09:31:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:31:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:02 INFO - Running setup.py install for mozfile 09:31:03 INFO - Running setup.py install for mozhttpd 09:31:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozinfo 09:31:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozInstall 09:31:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozleak 09:31:03 INFO - Running setup.py install for mozlog 09:31:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for moznetwork 09:31:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozprocess 09:31:03 INFO - Running setup.py install for mozprofile 09:31:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozrunner 09:31:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozscreenshot 09:31:04 INFO - Running setup.py install for moztest 09:31:04 INFO - Running setup.py install for mozversion 09:31:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:31:04 INFO - Cleaning up... 09:31:04 INFO - Return code: 0 09:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:04 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 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:31:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:04 INFO - Using env: {'DISPLAY': ':0', 09:31:04 INFO - 'HOME': '/home/cltbld', 09:31:04 INFO - 'LANG': 'en_US.UTF-8', 09:31:04 INFO - 'LANGUAGE': 'en_US:en', 09:31:04 INFO - 'LOGNAME': 'cltbld', 09:31:04 INFO - 'MAIL': '/var/mail/cltbld', 09:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:04 INFO - 'MOZ_NO_REMOTE': '1', 09:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:04 INFO - 'NO_EM_RESTART': '1', 09:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:04 INFO - 'PWD': '/builds/slave/test', 09:31:04 INFO - 'SHELL': '/bin/bash', 09:31:04 INFO - 'SHLVL': '1', 09:31:04 INFO - 'TERM': 'linux', 09:31:04 INFO - 'TMOUT': '86400', 09:31:04 INFO - 'USER': 'cltbld', 09:31:04 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:04 INFO - '_': '/tools/buildbot/bin/python'} 09:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:04 INFO - Running setup.py (path:/tmp/pip-I6SEU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:04 INFO - Running setup.py (path:/tmp/pip-ORos18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:04 INFO - Running setup.py (path:/tmp/pip-sPxiJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:04 INFO - Running setup.py (path:/tmp/pip-HvPgmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:04 INFO - Running setup.py (path:/tmp/pip-ud0FOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:04 INFO - Running setup.py (path:/tmp/pip-dLnmr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:04 INFO - Running setup.py (path:/tmp/pip-iPezw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:05 INFO - Running setup.py (path:/tmp/pip-6IE_dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:05 INFO - Running setup.py (path:/tmp/pip-FtgmnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:05 INFO - Running setup.py (path:/tmp/pip-1Dtd5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:05 INFO - Running setup.py (path:/tmp/pip-U8hxNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:05 INFO - Running setup.py (path:/tmp/pip-CPxcy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:05 INFO - Running setup.py (path:/tmp/pip-yTCB_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:05 INFO - Running setup.py (path:/tmp/pip-SGdyCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:05 INFO - Running setup.py (path:/tmp/pip-bQF6Xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:05 INFO - Running setup.py (path:/tmp/pip-ZJ4mmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:05 INFO - Running setup.py (path:/tmp/pip-fncwFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31:07 INFO - Downloading blessings-1.6.tar.gz 09:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:31:07 INFO - Installing collected packages: blessings 09:31:07 INFO - Running setup.py install for blessings 09:31:07 INFO - Successfully installed blessings 09:31:07 INFO - Cleaning up... 09:31:07 INFO - Return code: 0 09:31:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:08 INFO - Reading from file tmpfile_stdout 09:31:08 INFO - Current package versions: 09:31:08 INFO - argparse == 1.2.1 09:31:08 INFO - blessings == 1.6 09:31:08 INFO - blobuploader == 1.2.4 09:31:08 INFO - docopt == 0.6.1 09:31:08 INFO - manifestparser == 1.1 09:31:08 INFO - mozInstall == 1.12 09:31:08 INFO - mozcrash == 0.17 09:31:08 INFO - mozdebug == 0.1 09:31:08 INFO - mozdevice == 0.48 09:31:08 INFO - mozfile == 1.2 09:31:08 INFO - mozhttpd == 0.7 09:31:08 INFO - mozinfo == 0.9 09:31:08 INFO - mozleak == 0.1 09:31:08 INFO - mozlog == 3.2 09:31:08 INFO - moznetwork == 0.27 09:31:08 INFO - mozprocess == 0.22 09:31:08 INFO - mozprofile == 0.28 09:31:08 INFO - mozrunner == 6.11 09:31:08 INFO - mozscreenshot == 0.1 09:31:08 INFO - mozsystemmonitor == 0.3 09:31:08 INFO - moztest == 0.7 09:31:08 INFO - mozversion == 1.4 09:31:08 INFO - psutil == 3.1.1 09:31:08 INFO - requests == 1.2.3 09:31:08 INFO - wsgiref == 0.1.2 09:31:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:08 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 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:08 INFO - Using env: {'DISPLAY': ':0', 09:31:08 INFO - 'HOME': '/home/cltbld', 09:31:08 INFO - 'LANG': 'en_US.UTF-8', 09:31:08 INFO - 'LANGUAGE': 'en_US:en', 09:31:08 INFO - 'LOGNAME': 'cltbld', 09:31:08 INFO - 'MAIL': '/var/mail/cltbld', 09:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:08 INFO - 'MOZ_NO_REMOTE': '1', 09:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:08 INFO - 'NO_EM_RESTART': '1', 09:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:08 INFO - 'PWD': '/builds/slave/test', 09:31:08 INFO - 'SHELL': '/bin/bash', 09:31:08 INFO - 'SHLVL': '1', 09:31:08 INFO - 'TERM': 'linux', 09:31:08 INFO - 'TMOUT': '86400', 09:31:08 INFO - 'USER': 'cltbld', 09:31:08 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:08 INFO - '_': '/tools/buildbot/bin/python'} 09:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:08 INFO - Cleaning up... 09:31:08 INFO - Return code: 0 09:31:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:08 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 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:31:08 INFO - Using env: {'DISPLAY': ':0', 09:31:08 INFO - 'HOME': '/home/cltbld', 09:31:08 INFO - 'LANG': 'en_US.UTF-8', 09:31:08 INFO - 'LANGUAGE': 'en_US:en', 09:31:08 INFO - 'LOGNAME': 'cltbld', 09:31:08 INFO - 'MAIL': '/var/mail/cltbld', 09:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:08 INFO - 'MOZ_NO_REMOTE': '1', 09:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:08 INFO - 'NO_EM_RESTART': '1', 09:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:08 INFO - 'PWD': '/builds/slave/test', 09:31:08 INFO - 'SHELL': '/bin/bash', 09:31:08 INFO - 'SHLVL': '1', 09:31:08 INFO - 'TERM': 'linux', 09:31:08 INFO - 'TMOUT': '86400', 09:31:08 INFO - 'USER': 'cltbld', 09:31:08 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:08 INFO - '_': '/tools/buildbot/bin/python'} 09:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:08 INFO - Downloading/unpacking jsonschema 09:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:10 INFO - Downloading/unpacking functools32 (from jsonschema) 09:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:10 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:10 INFO - warning: no files found matching '*.txt' 09:31:10 INFO - no previously-included directories found matching 'build' 09:31:10 INFO - no previously-included directories found matching 'dist' 09:31:10 INFO - no previously-included directories found matching '.git*' 09:31:10 INFO - Installing collected packages: jsonschema, functools32 09:31:10 INFO - Running setup.py install for functools32 09:31:10 INFO - warning: no files found matching '*.txt' 09:31:10 INFO - no previously-included directories found matching 'build' 09:31:10 INFO - no previously-included directories found matching 'dist' 09:31:10 INFO - no previously-included directories found matching '.git*' 09:31:10 INFO - Successfully installed jsonschema functools32 09:31:10 INFO - Cleaning up... 09:31:10 INFO - Return code: 0 09:31:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:10 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 0x7f35c0024f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:31:10 INFO - Using env: {'DISPLAY': ':0', 09:31:10 INFO - 'HOME': '/home/cltbld', 09:31:10 INFO - 'LANG': 'en_US.UTF-8', 09:31:10 INFO - 'LANGUAGE': 'en_US:en', 09:31:10 INFO - 'LOGNAME': 'cltbld', 09:31:10 INFO - 'MAIL': '/var/mail/cltbld', 09:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:10 INFO - 'MOZ_NO_REMOTE': '1', 09:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:10 INFO - 'NO_EM_RESTART': '1', 09:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:10 INFO - 'PWD': '/builds/slave/test', 09:31:10 INFO - 'SHELL': '/bin/bash', 09:31:10 INFO - 'SHLVL': '1', 09:31:10 INFO - 'TERM': 'linux', 09:31:10 INFO - 'TMOUT': '86400', 09:31:10 INFO - 'USER': 'cltbld', 09:31:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:10 INFO - '_': '/tools/buildbot/bin/python'} 09:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:11 INFO - Downloading/unpacking flake8 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:31:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:31:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:31:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:13 INFO - no previously-included directories found matching 'docs/_build' 09:31:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:31:13 INFO - Running setup.py install for pep8 09:31:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:13 INFO - no previously-included directories found matching 'docs/_build' 09:31:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:31:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:31:13 INFO - Cleaning up... 09:31:13 INFO - Return code: 0 09:31:13 INFO - Running post-action listener: _resource_record_post_action 09:31:13 INFO - Running post-action listener: _start_resource_monitoring 09:31:13 INFO - Starting resource monitoring. 09:31:13 INFO - ##### 09:31:13 INFO - ##### Finished create-virtualenv step (success) 09:31:13 INFO - ##### 09:31:13 INFO - ##### 09:31:13 INFO - ##### Running install step. 09:31:13 INFO - ##### 09:31:13 INFO - Running pre-action listener: _resource_record_pre_action 09:31:13 INFO - Running main action method: install 09:31:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:14 INFO - Reading from file tmpfile_stdout 09:31:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:31:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:31:14 INFO - Reading from file tmpfile_stdout 09:31:14 INFO - Output received: 09:31:14 INFO - Usage: mozinstall [options] installer 09:31:14 INFO - Options: 09:31:14 INFO - -h, --help show this help message and exit 09:31:14 INFO - -d DEST, --destination=DEST 09:31:14 INFO - Directory to install application into. [default: 09:31:14 INFO - "/builds/slave/test"] 09:31:14 INFO - --app=APP Application being installed. [default: firefox] 09:31:14 INFO - mkdir: /builds/slave/test/build/application 09:31:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:31:30 INFO - Reading from file tmpfile_stdout 09:31:30 INFO - Output received: 09:31:30 INFO - /builds/slave/test/build/application/firefox/firefox 09:31:30 INFO - Running post-action listener: _resource_record_post_action 09:31:30 INFO - ##### 09:31:30 INFO - ##### Finished install step (success) 09:31:30 INFO - ##### 09:31:30 INFO - ##### 09:31:30 INFO - ##### Running run-tests step. 09:31:30 INFO - ##### 09:31:30 INFO - Running pre-action listener: _resource_record_pre_action 09:31:30 INFO - Running main action method: run_tests 09:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:31:30 INFO - Python 2.7.3 09:31:30 INFO - Return code: 0 09:31:30 INFO - grabbing minidump binary from tooltool 09:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:30 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 0x1a409c0>, '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 0x1a418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a41d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:31:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:31:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:31:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:31:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:31 INFO - Return code: 0 09:31:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:31:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:31:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:31:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:31:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:31:31 INFO - Return code: 0 09:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:31:31 INFO - Using env: {'DISPLAY': ':0', 09:31:31 INFO - 'HOME': '/home/cltbld', 09:31:31 INFO - 'LANG': 'en_US.UTF-8', 09:31:31 INFO - 'LANGUAGE': 'en_US:en', 09:31:31 INFO - 'LOGNAME': 'cltbld', 09:31:31 INFO - 'MAIL': '/var/mail/cltbld', 09:31:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:31:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:31 INFO - 'MOZ_NO_REMOTE': '1', 09:31:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:31:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:31 INFO - 'NO_EM_RESTART': '1', 09:31:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:31 INFO - 'PWD': '/builds/slave/test', 09:31:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:31:31 INFO - 'SHELL': '/bin/bash', 09:31:31 INFO - 'SHLVL': '1', 09:31:31 INFO - 'TERM': 'linux', 09:31:31 INFO - 'TMOUT': '86400', 09:31:31 INFO - 'USER': 'cltbld', 09:31:31 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971', 09:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:31 INFO - '_': '/tools/buildbot/bin/python'} 09:31:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:31:32 INFO - mozversion application_buildid: 20160525063710 09:31:32 INFO - mozversion application_changeset: d6d4e8417d2fd71fdf47c319b7a217f6ace9d5a5 09:31:32 INFO - mozversion application_display_name: Nightly 09:31:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:32 INFO - mozversion application_name: Firefox 09:31:32 INFO - mozversion application_remotingname: firefox 09:31:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:31:32 INFO - mozversion application_vendor: Mozilla 09:31:32 INFO - mozversion application_version: 49.0a1 09:31:32 INFO - mozversion platform_buildid: 20160525063710 09:31:32 INFO - mozversion platform_changeset: d6d4e8417d2fd71fdf47c319b7a217f6ace9d5a5 09:31:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:31:32 INFO - mozversion platform_version: 49.0a1 09:31:32 INFO - using testdate: 1464193892 09:31:32 INFO - actual date: 1464193892 09:31:32 INFO - starting webserver on 'localhost:56921' 09:31:32 INFO - SUITE-START | Running 2 tests 09:31:32 INFO - TEST-START | dromaeo_css 09:31:32 INFO - operating with platform_type : linux_ 09:31:32 INFO - Initialising browser for dromaeo_css test... 09:31:32 INFO - TEST-INFO | started process 15266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqpwCbE/profile http://localhost:56921/getInfo.html) 09:31:36 INFO - PROCESS | 15266 | __metrics Screen width/height:1600/1200 09:31:36 INFO - PROCESS | 15266 | colorDepth:24 09:31:36 INFO - PROCESS | 15266 | Browser inner width/height: 1024/697 09:31:36 INFO - PROCESS | 15266 | __metrics 09:31:37 ERROR - PROCESS | 15266 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:31:37 INFO - TEST-INFO | 15266: exit 0 09:31:37 INFO - Browser initialized. 09:31:37 INFO - Running cycle 1/1 for dromaeo_css test... 09:31:37 INFO - TEST-INFO | started process 15347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqpwCbE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:49 INFO - PROCESS | 15347 | RSS: Main: 155025408 09:31:49 INFO - PROCESS | 15347 | 09:32:40 INFO - PROCESS | 15347 | 1464193960416 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:34:49 INFO - PROCESS | 15347 | Cycle 1(1): loaded http://localhost:56921/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56921/tests/dromaeo/cssquery-ext.html) 09:34:50 INFO - PROCESS | 15347 | RSS: Main: 278409216 09:34:50 INFO - PROCESS | 15347 | 09:37:53 INFO - PROCESS | 15347 | Cycle 1(1): loaded http://localhost:56921/tests/dromaeo/cssquery-ext.html (next: http://localhost:56921/tests/dromaeo/cssquery-jquery.html) 09:37:53 INFO - PROCESS | 15347 | RSS: Main: 247812096 09:37:53 INFO - PROCESS | 15347 | 09:40:55 INFO - PROCESS | 15347 | Cycle 1(1): loaded http://localhost:56921/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56921/tests/dromaeo/cssquery-mootools.html) 09:40:56 INFO - PROCESS | 15347 | RSS: Main: 242032640 09:40:56 INFO - PROCESS | 15347 | 09:43:58 INFO - PROCESS | 15347 | Cycle 1(1): loaded http://localhost:56921/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56921/tests/dromaeo/cssquery-prototype.html) 09:43:59 INFO - PROCESS | 15347 | RSS: Main: 241180672 09:43:59 INFO - PROCESS | 15347 | 09:47:01 INFO - PROCESS | 15347 | Cycle 1(1): loaded http://localhost:56921/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56921/tests/dromaeo/cssquery-yui.html) 09:47:02 INFO - PROCESS | 15347 | RSS: Main: 203583488 09:47:02 INFO - PROCESS | 15347 | 09:50:04 INFO - PROCESS | 15347 | Cycle 1(1): loaded http://localhost:56921/tests/dromaeo/cssquery-yui.html (next: http://localhost:56921/tests/dromaeo/cssquery-dojo.html) 09:50:05 INFO - PROCESS | 15347 | RSS: Main: 325398528 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - PROCESS | 15347 | __start_tp_report 09:50:05 INFO - PROCESS | 15347 | _x_x_mozilla_page_load 09:50:05 INFO - PROCESS | 15347 | _x_x_mozilla_page_load_details 09:50:05 INFO - PROCESS | 15347 | |i|pagename|runs| 09:50:05 INFO - PROCESS | 15347 | |0;dojo.html;3062.188520042189;3071.155432256129;3101.922451938701;3102.9534556981635;3137.670544592818;5220;5284.31303930489;5310.150375939846;5366.114591092475;5420.674759514432;5373.274607927929;5436.483534064258;5444.50999410053;5487.122060470326;5512.531434828034;4808.591269742076;4896.069746748107;4897.33885925399;4912.705237685733;5112.642115051948;5025.447200807908;5044.268581055745;5061.19020956647;5065.1642478990925;5065.772040258197;4850.854474365775;4897.265410188465;4899.583535399495;4920.311156438083;4953.455119936804;4949.133901567211;4950.826721064782;4951.455339912643;4951.925721114186;4955.231939049453;4908.656394052417;4925.729084900329;4925.7783399747095;4930.161872481651;4936.456989731127;5216.426193118754;5235.58115350771;5288.153895376721;5344.091504444254;5353.785848566053;5680.801171958961;5684.744186511617;5725.427457254283;5795.391483894215;5828.1549175369755;5811.6222445541325;5872.031114866042;5881.382454842265;5883.941160588382;5886.646801191942;5266.499682530153;5333.626646134733;5355.2770375999135;5425.077736784717;5483.396826349099;5866;5964.045752679551;5976.462118409364;5998.4601385875485;6004.489618382487;5718.7426565804635;5750.2237197978575;5751.9424805751405;5752.453516915886;5757.337906140764;5653.689047102366;5655.943440565624;5702.572307076903;5826.417358264224;5837.6497410155525;5712.65724056558;5713.05734553794;5713.285839270091;5716.371199168088;5759.963206622764;5750.6262092963825;5753.568482363839;5758.3665796762325;5759.827205183851;5764.596478246566;4297.871063868089;4326.913461730811;4332.523422423531;4372.497162826316;4384.868453946387;3909.9609003909595;3922.2155568886337;3935.252302062599;3970.2456533258587;3987.6809855211513;5421.078416669096;5460.372057213392;5463.754131064112;5463.890722185613;5470.097433923438;5759.136129580596;5772.076467765138;5800.24596802413;5806.361300256968;5809.0705487122705;5513.393526712059;5520.751566179531;5564.443555644403;5573.164025396223;5593.133064375028;4800.423949126127;4807.062622788522;4833.879153021132;4838.806447742051;4888.291197776302;5837.6497410155525;5925.555583331267;6040.033594624839;6047.697615119227;6056.757729690851;5806.4193580641595;5898.705064746659;5903.793367232126;5905.911411328748;5921.822345329647;6077.270727512727;6080.452759251689;6082;6085.939140608537;6096.207493025879;5547.084731019498;5705.515031222392;5725.742341594721;5743.655380677005;5760.1935728998815;5809.36097029318;5837.241158649518;5838.2702162229725;5843.883122337615;5876.735546900314;5682.37493875665;5692.601517893708;5715.342735585461;5719.599628025998;5725;5521.944780552143;5618.073017951992;5650.886982260414;5655.2648155741135;5676.233708449472;5604.691741954069;5607.43925607436;5611.915821262766;5614.803481878114;5614.943850561605;5707.258056452801;5722.427757224411;5767.365589785204;5778.508826749773;5801.825945221482;5428.728563571726;5544.695041772741;5589.329280486368;5593.021221286177;5632.746526406239 09:50:05 INFO - PROCESS | 15347 | |1;ext.html;590.9172715819786;650.2846868444711;651.3811878715222;657.5660064357525;661.5005670718605;30630.69369306306;42778.14443711128;43299;43313.35029974553;43492;44681.553184468146;45225.99999999992;45363.319550206776;45365.319520207224;45388;8816.603252853605;8983.46099234048;9013.23387512059;9014.639414423415;9023.684171054014;8768.473891566495;8857.822843543125;8934.910650893473;8942.955285223565;8945.507997060158;8968.237699795493;9008.234300084501;9018.59416326267;9025.774355641095;9026.23277021454;8947.64209431622;8985.77535561613;8991.910080899173;9003.369764116514;9006.729798106067;8829.0729473405;8916.598753056129;8926.062763409878;8927.151920567536;8927.732168034969;40956.18087638231;41331.38002929959;41366.1726765466;41426.58573414257;41427.1714565707;3503.2292895562937;3692.51997240357;4044.8584299549375;4161.10536234711;4313.654907607384;31772.57690953363;32156.356872862645;32295.354092918245;32537;32705.34589308224;4169.812358443877;4223.556526564728;4268.893277668083;4296.613304802551;4381.912361752779;337348;337486.99999999756;337628;338376.3081184603;338605;50717.49282507202;54013.45986540163;55111.44888551143;55664.443355566735;56168.438315616324;59486;59536.40463595395;59598.40401596015;59964;60016;13380.330983450789;13496.797548036788;13499.662508437368;13515.459381624822;13518.932405338108;9559.283053770994;10305.536250868729;10472;10472.68581942543;10613.681589552325;16034;16106.436274730328;16157.111358875374;16253.268602912898;16350.83649163517;6362.936370636235;6378.170837791057;6395.936040639534;6496.870062598721;6557.278699343068;970.9611615535299;1064.3826580583243;1071.49104175516;1074.4788777442934;1146.2033886448837;32549.349013019608;32558.83720581382;32741.345173096404;32808.50787238193;33412.33175336479;1111.699841042914;1115.8712762777755;1126.5550107707409;1133.1954312438097;1169.9473523691454;16592.25334859934;16639.916800415922;16914.577135571464;17244.827551724575;17433.564160895825;534324;540395;541679;541827;544135;58712.7064364684;58863;58882.41117588769;58913.70543147257;58982.11526827101;1273.7006803401193;1331.081553727944;1369.1784929042694;1382.9723405532034;1413.0603148905861;32012.51981220237;33070;33249.50125748164;33276;33834.49248261327;48509;49009.019819604124;49153.508464916325;49215.507844922526;49222;406107;406270;406328;407793;410269;34903.476447852794;35172.648273516934;40064.59935400725;40809.79595102005;42900.356494651984;46963.765181175244;47544.763159470516;47907.76046119747;48739.26891096566;52412.47587524076;39206.803965981126;42037.36943945904;42726.572734273504;43075.353869692604;43144.137117256854;39224;41148.588514114475;41585.37621935734;43221.3516797242;44402.55597443984;40300.596994029685;40704.54011409063;40893.59106408898;41405.378919316834;41682;35632.28735425225;36339.45490817693;36665.26669466542;36698.63301367059;36811.26377472489;27545.173644790688;27991.860040699667;28191.57712634353;28288;28329.150125496268 09:50:05 INFO - PROCESS | 15347 | |2;jquery.html;374.8350725680702;377.4336503510431;390.39293897988625;391.70230624725235;402.7724335750301;14787.778183327258;17320.56698582536;17332;20056.59886802263;20222.483313751472;18700.53248668784;19041;19506.512337191543;19607.80392196081;19627.11677974491;5317.46825317469;5438.945610543893;5566.610337276392;5616.438356164372;5619.915701264485;5463.672179669213;5471.960327537755;5490.258815059976;5506.80726174584;5518.530924871391;5252.553532949685;5420.945790542103;5439.265699130626;5468.699221542814;5470.370907345647;5368.597355198356;5477.986572484103;5495.313085864267;5512.145617429285;5513.50378465938;5225.869353266199;5627.831165065055;5630.183623374588;5635.943640563582;5637.689927054011;13042.478300867953;13376.598702038955;13428.32858357088;22405.66391504129;22696.659550106597;2217.090992692988;2449.8530088194766;2751.8899244030213;2904.8257104573795;2947.0716724231797;10002.299839011199;10376.118029967387;10835.891641083566;11132.777344453147;14356.49752258666;1698.9915050424793;1778.4042345814169;1887.7923428422862;1950.473372189515;1978.9412664224715;78871;81391;81685;82002.17997820005;82286.17713822845;9959.850602240973;10623.575056997788;10837.620683276047;10932.56269749201;10979.670609881716;15111.773323400157;15202.771958420633;15263.92368038175;15265.389384424721;15271.541853744406;7295.6352182390665;7421.443391745641;7513.060867391576;7575.204603516661;7593.848123037508;6944.409725173316;7068.020954132851;7179.174394944544;7221.277872212737;7231.674574644154;4580.954190458053;4644.535546445329;4665.230237010923;4739.644526660514;4743.1225223333795;1961.1272983522197;1966.3707613563522;1972.6646470100118;2000.5398758285385;2056.5269987902866;932.2728271947891;1012.792377562598;1014.3558840136419;1058.5765693722572;1112.5660992213043;18122.184501697193;18824.717629235573;19032.809671903105;19223.807761922202;19582.412527624194;1006.4464544500496;1027.5684212630713;1030.3663247102977;1034.4483275087368;1059.7615536504225;10484.947575262075;10809.78380432387;10897.182711296684;10897.83653245202;10907.400092994962;382694;384811;385688;518284;551905;74598;84342;87739;88066.55966720125;95962.52018739862;933.4772527384687;968.5544649461327;990.2966896937256;1027.337367398039;1039.9896001040045;16083.75874361909;16911.408100716417;17047.40334088301;17272.527428605707;17367.30530778755;36323.81838090793;39517.40723889202;39917.401238980856;40992.38511422391;41080.79459602803;47601.523984761094;57999;59194.70402648131;59680.701596493476;59718;7954.125046245024;7978.361731061617;8290.46112002718;8433.578321084044;8570.48577085377;12182.234075494634;12561.748765024466;12598.811017834556;12671.366431678563;12805.423755931191;10894.237403381687;10967.638769513504;11435.771284574095;11442.885571144181;11516.099843525724;5574;9728.221742260745;11140.498677559368;11229.494672739584;11280.32318060886;11210.327380357205;11337.829932551183;11526.59656912004;11536.134789890624;11833.112516561465;6723.4285085766305;7396.819724852659;7479.289467500579;7647.388208943383;8516.318694504549;10486.685399438029;11480.311181329147;11559.942200288944;11738.765224695288;11795.29228246308 09:50:05 INFO - PROCESS | 15347 | |3;mootools.html;1338.9464421423143;1454.1202572443667;1515.1136585097718;1663.359606551478;1855.183719163568;8314.542700151489;8615.870761938568;8634.999999999993;8638.136186381358;8856.601452934618;8252.174782521744;8502.787430314245;8758.518281494515;8761.780955476117;8771.473711577293;2058.6809044598112;2110.915563377471;2266.897989590464;2274.9545009099766;2330.883455827202;2343.3907184132077;2370.395549134968;2372.8457650252763;2373.288013595923;2375.5605213035556;2326.9418264543438;2336.1122773346106;2345.331580499558;2352.764723527642;2353.470469144446;1990.9601807963832;2208.7349518057777;2276.1236923784295;2280.1905323610213;2289.8053665438374;2312.2022902098747;2337.158622895773;2349.2012715676583;2359.1507057459303;2359.587072262347;8773.12268773124;8809.427387219874;8813.911860881373;8815.647374105029;8847.46915185091;5240.475952404767;5308.70802105884;5364.088105022117;5370.597205209625;5380.704061276629;3327.38443387974;3434.759566830323;3477.4609935460044;3477.895663130085;3492.8777492787885;1110.4947249001684;1117.804384232756;1135.9545618175264;1137.5734099712608;1155.4453862146133;11901.523939042429;12008.819867701992;12016.879831201664;12045;12045.096617753616;4573.696496498482;4626.814927402934;4737.076270127292;4737.336772851803;4755.429348478205;10196.949015254877;10231.7442063949;10243.180545556337;10249.180065594735;10315.381077135396;5332.866678333073;5333.493318134771;5341.572674186055;5342.519173274424;5343.5457986071615;4108.404281379183;4778.116048531033;4869;4900;4945.7774400152075;4066.471358723347;4126;4186.518550366747;4190.224808410454;4212.094399704079;1287.575100216926;1304.2565737529517;1311.4557458654551;1319.399673148715;1327.2700014991715;979.9265055120894;1007.3351587951917;1008.4503945349802;1008.6973907827769;1030.1038096855784;6103.694815259308;6129.540284478682;6161.84595385119;6204.7828326008375;6330.208723909512;406.85149920279474;424.67300178862104;430.262100497648;430.9676774241882;432.2565187876847;4162.8334866605;4186.35111557709;4237.322028475429;4241.1093670328955;4256.382824490432;10519.737006574744;10717.46412679363;10728.946355268174;10800.78398432027;10816.188785841094;10640.308379955279;10648.467576621139;10681.732956676145;10696.465176741132;10712.089472394779;7918.326942209977;8083.151269116775;8165.755027349189;8218.21926916942;8233.876491852627;6162.815115546541;6390.552661313849;6417.8716425672155;6423.42189202974;6572.572782769104;10176.440295783506;10216.642417515648;10408.271421000458;10445.68662940113;10446.582136714447;10033.397996120257;10502.10732087781;10503.579856805947;10653.680389588324;10672.62645807393;7701.576413297323;7827.882581761164;7946.046474423106;7975.4018448615425;7976.242256985577;8263.256306932404;8264.21489958453;8300.04549476818;8304.626291816761;8348.415610907177;7168.4265258780215;7386.593737344498;7435.479516434014;7466.552006879605;7494.512856664517;7427.183009869018;7485.101787785283;7485.214052524407;7561.5085019475755;7648.273414025659;7013.544119632207;7047.647617619201;7065.964670176616;7080.398166155935;7082.256363081802;4961.975190124026;5087.771050302819;5143.305653736697;5150.098732721834;5247.160454327442;5118.948810511847;5131.692098474104;5156.04613146569;5210.47895210476;5254.343207099113 09:50:05 INFO - PROCESS | 15347 | |4;prototype.html;304.7881722203067;318.0490333901634;324.8749231545854;325.0314064089014;325.5166078373615;5181.222816577506;5202.32369791927;5251.973740131294;5268.841934741954;5269.6574722643045;5225.111731005729;5235.267062611228;5254.211868219769;5256.789728410859;5257;4125.195586860564;4135.751854888701;4137.358709400048;4141.171765646867;4148;4035.515738111431;4044.413560033793;4110.609492098246;4145.79271036447;4151.190517849021;4034.3948407738926;4080.9387859182134;4091.672666186713;4103.671706263507;4118.773467459288;4056.776877271749;4065.532463766675;4069.47096877407;4086.3053280942313;4089.9591004089875;3602.225521512861;3608.5746130278653;3611.8081033258964;3613.5483064616924;3631.8184090795617;4834.371531700892;4997.275395067695;4998.3502144721315;5027.622928280357;5030.861883699413;4603.7698115094445;4611.210217818239;4625.514320996314;4629.77311012582;4656.790444430008;5334.386545547234;5342.252084708105;5368.785248590091;5382.219578161144;5410.891782164374;3836.846526138951;3928.5857091447056;3939.960600393988;3943.940840887389;3949.2891279569667;5465.234867118606;5489.533389661923;5659.264295641581;5695.778646327935;5720.827230417796;4000.8399664013696;4008.7594744315434;4012.859549915739;4041.4341992121126;4043.6765058795227;3885.1646895917515;3964.623360780721;4053.148838743838;4058.736182148151;4069.4709687740406;4601.4248218972625;4605.792739326738;4615.676902616851;4628.467726211461;4628.652851036119;5461.42655021225;5463.808766693147;5464.398916119224;5491.341039075336;5507.531859791882;1366.1598117157876;1373.230990645222;1389.4650559534653;1404.6558593144746;1406.1492796857958;883.8730618461385;909.981800363989;916.1251005289932;931.7437704631145;934.2572654739498;4667.6965997210755;4682.719036857799;4712.410948631421;4713.95286047142;4749.931265465312;4900.534449227318;4939.703617782944;4977.701337919736;4984.451710311862;5012.373453318335;4501.954980450219;4635.566327677929;4708.364370809965;4720.33915251865;4755.262934245197;2600.9609855852177;2707.6615423072117;2708.986455067712;2724.6730392353043;2726.6591676040493;5039.269305950616;5107.514786095315;5125.589952803842;5133.614978876599;5156.716380599028;4822.927656085161;5035.546800787946;5068.467810879878;5125.436741793714;5170.302009228782;5123.462036486189;5146.665466744649;5178.6374953752875;5179.430262671103;5189.221616757516;5056.469070747518;5067.442581316126;5079.2888995539915;5079.517445842639;5437.238786569805;5371.946280537145;5529.142982837667;5550.27846379968;5593.580481463988;5615.719214039364;5786.971065144648;5808.564357673107;5830.82507524775;5869.765209391577;5891.8510890375965;3905.1018265798457;3989.2819292527624;3999.7600143991453;4010.558838527818;4034.7377420467574;5550.5282051026115;5581.553475722014;5636.915446268391;5680.289963754531;5709.429057094258;5451.509364157245;5476.123820188751;5512.476314750093;5515.696636685021;5522.873693628063;5236.764345604533;5384.300040994803;5430.456954304538;5468.589855760913;5489.39616642161;5280.471952804689;5392.514673679388;5407.188921661782;5408.918866216931;5430.42980487059;5258.868528286824;5454.236406903116;5484.341878974548;5490.505854473116;5501.889962200654;5380.219868119023;5389.622726409114;5409.404965453876;5418.729063546885;5453.427390123979 09:50:05 INFO - PROCESS | 15347 | |5;yui.html;832.5878690048427;835.2399316621871;846.9237768600832;868.617808164408;875.8905136857893;13260.403281852316;14096.71806563868;14098.083624564397;14123.081999670014;14140.71718565628;13199.802002969964;13356.065075444723;13388.59834204973;13396.598102056929;14154.150750954925;682.6484360554293;805.5005896344245;820.5609998650708;825.4923222218323;830.8421399934024;827.755812035451;833.5082301442147;834.0533494483774;834.1491678487969;834.5701963488808;874.0210963720642;874.8381549413378;877.398981697536;878.7232021913077;879.6701237036111;712.6223101756077;835.778518692547;860.4450129666393;873.2184694698241;874.0647507167333;626.2078470734524;628.7296462521114;628.9119523266746;629.0564153769346;629.6820105846564;14337.49818756349;14449.638759031108;14466.855331446655;14468.927655361656;14492.565223043326;13071.738565228643;13461.865381346159;13518.459261629523;13609;13819.930900345435;11366.772664546745;11411.42942852854;12079.939600301943;12177.33024683642;12271.57049503272;4501.324801279801;4570.7257564546235;4577.771111444415;4592.47186573545;4731.432228132611;52860.73569632127;53683;59330.703346483424;63783;64112.35887641157;11211.103111751041;11214.271072380432;11403.42982850854;11415.20093593457;11448.656540303638;19148.712769308473;19255;19935;19946.501337466678;19950.50123746889;8073.515589064675;8101;8266.751997440086;8337.541435221121;8531.616077276538;4661.720296782204;4749.121412538691;4820.204666230103;4850.417949846041;4990.4011518618;3461.6884480396884;3577.5706915170344;3584.0502266899293;3585.94621080684;3587.282543491312;580.5848818095083;584.7281014328357;586.2378907420422;587.1163898332982;588.1853632718716;659.5976454362835;770.6570576093696;774.6204359863626;802.4623502253504;809.536298004363;9008.279337652972;9022.323325750594;9625.181859542017;9655.39921074664;9844.655437059668;670.7585269303047;748.9063867016623;764.495433014208;770.8689522781141;828.1139181076311;4529.755844363227;4576.313552967082;4606.792694328763;4678.578927896506;4683.81264749413;65161.674191628736;65855.70721463907;66173.669131655;68978.31021689718;69274.65362673154;59665.70167149136;60004.69997649984;60327;60519;63163.6841815788;52702;52967;54123.72938135284;54408.45591544113;54596;8693.261072808882;8761.693340733043;8801.207891289558;8965.229568274464;8992.820143597222;36418.27163456769;36577.63422365742;37118.8144059278;37277.440838387985;38089.809550952064;46395;47297;48423;48894.26658600195;48984.510154897995;11196.496157672762;11198.608048718255;11298.60454884075;11344.546218151181;11630.6510804676;12497.750044998867;12700.491980320683;12766.68083297925;13233.470661173831;13363.665908352368;11805.730985462156;12094.75810483803;12165.270083795001;12181.08641851847;12509.249445033327;11591.24656897299;12020.459079341275;12033.398330083286;12141;12340.074494412775;12167.817482737943;12235.59290681583;12337.444814983526;12535.686607834878;12568.308743018855;9412.858807118037;9434.150926416656;9450.952745236229;9758.85361719589;9770.609175632895;9218.492982885737;9379.577918993798;9445.244380449685;10250.38497690141;10276.948615256875 09:50:05 INFO - PROCESS | 15347 | __end_tp_report 09:50:05 INFO - PROCESS | 15347 | __start_cc_report 09:50:05 INFO - PROCESS | 15347 | _x_x_mozilla_cycle_collect,1040 09:50:05 INFO - PROCESS | 15347 | __end_cc_report 09:50:05 INFO - PROCESS | 15347 | __startTimestamp1464195005083__endTimestamp 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - PROCESS | 15347 | ------- Summary: start ------- 09:50:05 INFO - PROCESS | 15347 | Number of tests: 6 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - PROCESS | 15347 | [#0] dojo.html Cycles:165 Average:5378.35 Median:5607.44 stddev:627.72 (11.2%) stddev-sans-first:602.77 09:50:05 INFO - PROCESS | 15347 | Values: 3062.2 3071.2 3101.9 3103.0 3137.7 5220.0 5284.3 5310.2 5366.1 5420.7 5373.3 5436.5 5444.5 5487.1 5512.5 4808.6 4896.1 4897.3 4912.7 5112.6 5025.4 5044.3 5061.2 5065.2 5065.8 4850.9 4897.3 4899.6 4920.3 4953.5 4949.1 4950.8 4951.5 4951.9 4955.2 4908.7 4925.7 4925.8 4930.2 4936.5 5216.4 5235.6 5288.2 5344.1 5353.8 5680.8 5684.7 5725.4 5795.4 5828.2 5811.6 5872.0 5881.4 5883.9 5886.6 5266.5 5333.6 5355.3 5425.1 5483.4 5866.0 5964.0 5976.5 5998.5 6004.5 5718.7 5750.2 5751.9 5752.5 5757.3 5653.7 5655.9 5702.6 5826.4 5837.6 5712.7 5713.1 5713.3 5716.4 5760.0 5750.6 5753.6 5758.4 5759.8 5764.6 4297.9 4326.9 4332.5 4372.5 4384.9 3910.0 3922.2 3935.3 3970.2 3987.7 5421.1 5460.4 5463.8 5463.9 5470.1 5759.1 5772.1 5800.2 5806.4 5809.1 5513.4 5520.8 5564.4 5573.2 5593.1 4800.4 4807.1 4833.9 4838.8 4888.3 5837.6 5925.6 6040.0 6047.7 6056.8 5806.4 5898.7 5903.8 5905.9 5921.8 6077.3 6080.5 6082.0 6085.9 6096.2 5547.1 5705.5 5725.7 5743.7 5760.2 5809.4 5837.2 5838.3 5843.9 5876.7 5682.4 5692.6 5715.3 5719.6 5725.0 5521.9 5618.1 5650.9 5655.3 5676.2 5604.7 5607.4 5611.9 5614.8 5614.9 5707.3 5722.4 5767.4 5778.5 5801.8 5428.7 5544.7 5589.3 5593.0 5632.7 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - PROCESS | 15347 | [#1] ext.html Cycles:180 Average:59181.06 Median:22925.91 stddev:115757.78 (504.9%) stddev-sans-first:115998.93 09:50:05 INFO - PROCESS | 15347 | Values: 590.9 650.3 651.4 657.6 661.5 30630.7 42778.1 43299.0 43313.4 43492.0 44681.6 45226.0 45363.3 45365.3 45388.0 8816.6 8983.5 9013.2 9014.6 9023.7 8768.5 8857.8 8934.9 8943.0 8945.5 8968.2 9008.2 9018.6 9025.8 9026.2 8947.6 8985.8 8991.9 9003.4 9006.7 8829.1 8916.6 8926.1 8927.2 8927.7 40956.2 41331.4 41366.2 41426.6 41427.2 3503.2 3692.5 4044.9 4161.1 4313.7 31772.6 32156.4 32295.4 32537.0 32705.3 4169.8 4223.6 4268.9 4296.6 4381.9 337348.0 337487.0 337628.0 338376.3 338605.0 50717.5 54013.5 55111.4 55664.4 56168.4 59486.0 59536.4 59598.4 59964.0 60016.0 13380.3 13496.8 13499.7 13515.5 13518.9 9559.3 10305.5 10472.0 10472.7 10613.7 16034.0 16106.4 16157.1 16253.3 16350.8 6362.9 6378.2 6395.9 6496.9 6557.3 971.0 1064.4 1071.5 1074.5 1146.2 32549.3 32558.8 32741.3 32808.5 33412.3 1111.7 1115.9 1126.6 1133.2 1169.9 16592.3 16639.9 16914.6 17244.8 17433.6 534324.0 540395.0 541679.0 541827.0 544135.0 58712.7 58863.0 58882.4 58913.7 58982.1 1273.7 1331.1 1369.2 1383.0 1413.1 32012.5 33070.0 33249.5 33276.0 33834.5 48509.0 49009.0 49153.5 49215.5 49222.0 406107.0 406270.0 406328.0 407793.0 410269.0 34903.5 35172.6 40064.6 40809.8 42900.4 46963.8 47544.8 47907.8 48739.3 52412.5 39206.8 42037.4 42726.6 43075.4 43144.1 39224.0 41148.6 41585.4 43221.4 44402.6 40300.6 40704.5 40893.6 41405.4 41682.0 35632.3 36339.5 36665.3 36698.6 36811.3 27545.2 27991.9 28191.6 28288.0 28329.2 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - PROCESS | 15347 | [#2] jquery.html Cycles:180 Average:27099.77 Median:2828.36 stddev:74570.05 (2636.5%) stddev-sans-first:74752.24 09:50:05 INFO - PROCESS | 15347 | Values: 374.8 377.4 390.4 391.7 402.8 14787.8 17320.6 17332.0 20056.6 20222.5 18700.5 19041.0 19506.5 19607.8 19627.1 5317.5 5438.9 5566.6 5616.4 5619.9 5463.7 5472.0 5490.3 5506.8 5518.5 5252.6 5420.9 5439.3 5468.7 5470.4 5368.6 5478.0 5495.3 5512.1 5513.5 5225.9 5627.8 5630.2 5635.9 5637.7 13042.5 13376.6 13428.3 22405.7 22696.7 2217.1 2449.9 2751.9 2904.8 2947.1 10002.3 10376.1 10835.9 11132.8 14356.5 1699.0 1778.4 1887.8 1950.5 1978.9 78871.0 81391.0 81685.0 82002.2 82286.2 9959.9 10623.6 10837.6 10932.6 10979.7 15111.8 15202.8 15263.9 15265.4 15271.5 7295.6 7421.4 7513.1 7575.2 7593.8 6944.4 7068.0 7179.2 7221.3 7231.7 4581.0 4644.5 4665.2 4739.6 4743.1 1961.1 1966.4 1972.7 2000.5 2056.5 932.3 1012.8 1014.4 1058.6 1112.6 18122.2 18824.7 19032.8 19223.8 19582.4 1006.4 1027.6 1030.4 1034.4 1059.8 10484.9 10809.8 10897.2 10897.8 10907.4 382694.0 384811.0 385688.0 518284.0 551905.0 74598.0 84342.0 87739.0 88066.6 95962.5 933.5 968.6 990.3 1027.3 1040.0 16083.8 16911.4 17047.4 17272.5 17367.3 36323.8 39517.4 39917.4 40992.4 41080.8 47601.5 57999.0 59194.7 59680.7 59718.0 7954.1 7978.4 8290.5 8433.6 8570.5 12182.2 12561.7 12598.8 12671.4 12805.4 10894.2 10967.6 11435.8 11442.9 11516.1 5574.0 9728.2 11140.5 11229.5 11280.3 11210.3 11337.8 11526.6 11536.1 11833.1 6723.4 7396.8 7479.3 7647.4 8516.3 10486.7 11480.3 11559.9 11738.8 11795.3 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - PROCESS | 15347 | [#3] mootools.html Cycles:180 Average:5789.95 Median:4248.75 stddev:3280.07 (77.2%) stddev-sans-first:3272.22 09:50:05 INFO - PROCESS | 15347 | Values: 1338.9 1454.1 1515.1 1663.4 1855.2 8314.5 8615.9 8635.0 8638.1 8856.6 8252.2 8502.8 8758.5 8761.8 8771.5 2058.7 2110.9 2266.9 2275.0 2330.9 2343.4 2370.4 2372.8 2373.3 2375.6 2326.9 2336.1 2345.3 2352.8 2353.5 1991.0 2208.7 2276.1 2280.2 2289.8 2312.2 2337.2 2349.2 2359.2 2359.6 8773.1 8809.4 8813.9 8815.6 8847.5 5240.5 5308.7 5364.1 5370.6 5380.7 3327.4 3434.8 3477.5 3477.9 3492.9 1110.5 1117.8 1136.0 1137.6 1155.4 11901.5 12008.8 12016.9 12045.0 12045.1 4573.7 4626.8 4737.1 4737.3 4755.4 10196.9 10231.7 10243.2 10249.2 10315.4 5332.9 5333.5 5341.6 5342.5 5343.5 4108.4 4778.1 4869.0 4900.0 4945.8 4066.5 4126.0 4186.5 4190.2 4212.1 1287.6 1304.3 1311.5 1319.4 1327.3 979.9 1007.3 1008.5 1008.7 1030.1 6103.7 6129.5 6161.8 6204.8 6330.2 406.9 424.7 430.3 431.0 432.3 4162.8 4186.4 4237.3 4241.1 4256.4 10519.7 10717.5 10728.9 10800.8 10816.2 10640.3 10648.5 10681.7 10696.5 10712.1 7918.3 8083.2 8165.8 8218.2 8233.9 6162.8 6390.6 6417.9 6423.4 6572.6 10176.4 10216.6 10408.3 10445.7 10446.6 10033.4 10502.1 10503.6 10653.7 10672.6 7701.6 7827.9 7946.0 7975.4 7976.2 8263.3 8264.2 8300.0 8304.6 8348.4 7168.4 7386.6 7435.5 7466.6 7494.5 7427.2 7485.1 7485.2 7561.5 7648.3 7013.5 7047.6 7066.0 7080.4 7082.3 4962.0 5087.8 5143.3 5150.1 5247.2 5118.9 5131.7 5156.0 5210.5 5254.3 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - PROCESS | 15347 | [#4] prototype.html Cycles:180 Average:4481.65 Median:5033.20 stddev:1298.99 (25.8%) stddev-sans-first:1264.24 09:50:05 INFO - PROCESS | 15347 | Values: 304.8 318.0 324.9 325.0 325.5 5181.2 5202.3 5252.0 5268.8 5269.7 5225.1 5235.3 5254.2 5256.8 5257.0 4125.2 4135.8 4137.4 4141.2 4148.0 4035.5 4044.4 4110.6 4145.8 4151.2 4034.4 4080.9 4091.7 4103.7 4118.8 4056.8 4065.5 4069.5 4086.3 4090.0 3602.2 3608.6 3611.8 3613.5 3631.8 4834.4 4997.3 4998.4 5027.6 5030.9 4603.8 4611.2 4625.5 4629.8 4656.8 5334.4 5342.3 5368.8 5382.2 5410.9 3836.8 3928.6 3940.0 3943.9 3949.3 5465.2 5489.5 5659.3 5695.8 5720.8 4000.8 4008.8 4012.9 4041.4 4043.7 3885.2 3964.6 4053.1 4058.7 4069.5 4601.4 4605.8 4615.7 4628.5 4628.7 5461.4 5463.8 5464.4 5491.3 5507.5 1366.2 1373.2 1389.5 1404.7 1406.1 883.9 910.0 916.1 931.7 934.3 4667.7 4682.7 4712.4 4714.0 4749.9 4900.5 4939.7 4977.7 4984.5 5012.4 4502.0 4635.6 4708.4 4720.3 4755.3 2601.0 2707.7 2709.0 2724.7 2726.7 5039.3 5107.5 5125.6 5133.6 5156.7 4822.9 5035.5 5068.5 5125.4 5170.3 5123.5 5146.7 5178.6 5179.4 5189.2 5056.5 5067.4 5079.3 5079.5 5437.2 5371.9 5529.1 5550.3 5593.6 5615.7 5787.0 5808.6 5830.8 5869.8 5891.9 3905.1 3989.3 3999.8 4010.6 4034.7 5550.5 5581.6 5636.9 5680.3 5709.4 5451.5 5476.1 5512.5 5515.7 5522.9 5236.8 5384.3 5430.5 5468.6 5489.4 5280.5 5392.5 5407.2 5408.9 5430.4 5258.9 5454.2 5484.3 5490.5 5501.9 5380.2 5389.6 5409.4 5418.7 5453.4 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - PROCESS | 15347 | [#5] yui.html Cycles:180 Average:15410.36 Median:26987.46 stddev:18581.93 (68.9%) stddev-sans-first:18601.81 09:50:05 INFO - PROCESS | 15347 | Values: 832.6 835.2 846.9 868.6 875.9 13260.4 14096.7 14098.1 14123.1 14140.7 13199.8 13356.1 13388.6 13396.6 14154.2 682.6 805.5 820.6 825.5 830.8 827.8 833.5 834.1 834.1 834.6 874.0 874.8 877.4 878.7 879.7 712.6 835.8 860.4 873.2 874.1 626.2 628.7 628.9 629.1 629.7 14337.5 14449.6 14466.9 14468.9 14492.6 13071.7 13461.9 13518.5 13609.0 13819.9 11366.8 11411.4 12079.9 12177.3 12271.6 4501.3 4570.7 4577.8 4592.5 4731.4 52860.7 53683.0 59330.7 63783.0 64112.4 11211.1 11214.3 11403.4 11415.2 11448.7 19148.7 19255.0 19935.0 19946.5 19950.5 8073.5 8101.0 8266.8 8337.5 8531.6 4661.7 4749.1 4820.2 4850.4 4990.4 3461.7 3577.6 3584.1 3585.9 3587.3 580.6 584.7 586.2 587.1 588.2 659.6 770.7 774.6 802.5 809.5 9008.3 9022.3 9625.2 9655.4 9844.7 670.8 748.9 764.5 770.9 828.1 4529.8 4576.3 4606.8 4678.6 4683.8 65161.7 65855.7 66173.7 68978.3 69274.7 59665.7 60004.7 60327.0 60519.0 63163.7 52702.0 52967.0 54123.7 54408.5 54596.0 8693.3 8761.7 8801.2 8965.2 8992.8 36418.3 36577.6 37118.8 37277.4 38089.8 46395.0 47297.0 48423.0 48894.3 48984.5 11196.5 11198.6 11298.6 11344.5 11630.7 12497.8 12700.5 12766.7 13233.5 13363.7 11805.7 12094.8 12165.3 12181.1 12509.2 11591.2 12020.5 12033.4 12141.0 12340.1 12167.8 12235.6 12337.4 12535.7 12568.3 9412.9 9434.2 9451.0 9758.9 9770.6 9218.5 9379.6 9445.2 10250.4 10276.9 09:50:05 INFO - PROCESS | 15347 | -------- Summary: end -------- 09:50:05 INFO - PROCESS | 15347 | 09:50:05 INFO - TEST-INFO | 15347: exit 0 09:50:05 INFO - TEST-OK | dromaeo_css | took 1113615ms 09:50:05 INFO - TEST-START | kraken 09:50:05 INFO - operating with platform_type : linux_ 09:50:05 INFO - Initialising browser for kraken test... 09:50:05 INFO - TEST-INFO | started process 15534 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpESNFi0/profile http://localhost:56921/getInfo.html) 09:50:09 INFO - PROCESS | 15534 | __metrics Screen width/height:1600/1200 09:50:09 INFO - PROCESS | 15534 | colorDepth:24 09:50:09 INFO - PROCESS | 15534 | Browser inner width/height: 1024/697 09:50:09 INFO - PROCESS | 15534 | __metrics 09:50:10 ERROR - PROCESS | 15534 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:50:11 INFO - TEST-INFO | 15534: exit 0 09:50:11 INFO - Browser initialized. 09:50:11 INFO - Running cycle 1/1 for kraken test... 09:50:11 INFO - TEST-INFO | started process 15616 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpESNFi0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:23 INFO - PROCESS | 15616 | RSS: Main: 154484736 09:50:23 INFO - PROCESS | 15616 | 09:50:25 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56921/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:26 INFO - PROCESS | 15616 | RSS: Main: 211554304 09:50:26 INFO - PROCESS | 15616 | 09:50:29 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56921/tests/kraken/driver.html?testName=audio-dft) 09:50:29 INFO - PROCESS | 15616 | RSS: Main: 199942144 09:50:29 INFO - PROCESS | 15616 | 09:50:33 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56921/tests/kraken/driver.html?testName=audio-fft) 09:50:33 INFO - PROCESS | 15616 | RSS: Main: 267780096 09:50:33 INFO - PROCESS | 15616 | 09:50:35 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56921/tests/kraken/driver.html?testName=audio-oscillator) 09:50:36 INFO - PROCESS | 15616 | RSS: Main: 177283072 09:50:36 INFO - PROCESS | 15616 | 09:50:38 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56921/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:50:39 INFO - PROCESS | 15616 | RSS: Main: 181010432 09:50:39 INFO - PROCESS | 15616 | 09:50:42 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56921/tests/kraken/driver.html?testName=imaging-darkroom) 09:50:42 INFO - PROCESS | 15616 | RSS: Main: 217563136 09:50:42 INFO - PROCESS | 15616 | 09:50:46 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56921/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:46 INFO - PROCESS | 15616 | RSS: Main: 217677824 09:50:46 INFO - PROCESS | 15616 | 09:50:49 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56921/tests/kraken/driver.html?testName=json-parse-financial) 09:50:50 INFO - PROCESS | 15616 | RSS: Main: 218189824 09:50:50 INFO - PROCESS | 15616 | 09:50:51 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56921/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:50:52 INFO - PROCESS | 15616 | RSS: Main: 204935168 09:50:52 INFO - PROCESS | 15616 | 09:50:54 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56921/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:54 INFO - PROCESS | 15616 | RSS: Main: 209956864 09:50:54 INFO - PROCESS | 15616 | 09:50:56 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56921/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:57 INFO - PROCESS | 15616 | RSS: Main: 218808320 09:50:57 INFO - PROCESS | 15616 | 09:51:00 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56921/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:51:00 INFO - PROCESS | 15616 | RSS: Main: 227467264 09:51:00 INFO - PROCESS | 15616 | 09:51:03 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56921/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:04 INFO - PROCESS | 15616 | RSS: Main: 224022528 09:51:04 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | Cycle 1(1): loaded http://localhost:56921/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56921/tests/kraken/driver.html?testName=ai-astar) 09:51:05 INFO - PROCESS | 15616 | RSS: Main: 270692352 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | __start_tp_report 09:51:05 INFO - PROCESS | 15616 | _x_x_mozilla_page_load 09:51:05 INFO - PROCESS | 15616 | _x_x_mozilla_page_load_details 09:51:05 INFO - PROCESS | 15616 | |i|pagename|runs| 09:51:05 INFO - PROCESS | 15616 | |0;ai-astar;111;104;109;109;111;112;111;109;112;110 09:51:05 INFO - PROCESS | 15616 | |1;audio-beat-detection;154;124;129;185;125;126;156;123;155;125 09:51:05 INFO - PROCESS | 15616 | |2;audio-dft;161;161;161;160;159;163;161;161;162;162 09:51:05 INFO - PROCESS | 15616 | |3;audio-fft;75;73;75;113;75;75;75;75;109;74 09:51:05 INFO - PROCESS | 15616 | |4;audio-oscillator;83;82;118;83;84;86;119;83;82;117 09:51:05 INFO - PROCESS | 15616 | |5;imaging-gaussian-blur;116;117;116;117;116;117;117;117;117;116 09:51:05 INFO - PROCESS | 15616 | |6;imaging-darkroom;145;147;147;145;146;147;145;145;145;147 09:51:05 INFO - PROCESS | 15616 | |7;imaging-desaturate;94;94;95;95;95;95;95;94;94;94 09:51:05 INFO - PROCESS | 15616 | |8;json-parse-financial;55;57;54;54;53;54;55;69;52;51 09:51:05 INFO - PROCESS | 15616 | |9;json-stringify-tinderbox;61;62;62;62;63;63;62;63;62;62 09:51:05 INFO - PROCESS | 15616 | |10;stanford-crypto-aes;79;79;80;79;80;80;83;80;85;80 09:51:05 INFO - PROCESS | 15616 | |11;stanford-crypto-ccm;126;120;119;125;122;119;119;122;180;139 09:51:05 INFO - PROCESS | 15616 | |12;stanford-crypto-pbkdf2;170;162;163;162;163;164;166;168;166;167 09:51:05 INFO - PROCESS | 15616 | |13;stanford-crypto-sha256-iterative;62;63;64;62;63;64;63;66;65;61 09:51:05 INFO - PROCESS | 15616 | __end_tp_report 09:51:05 INFO - PROCESS | 15616 | __start_cc_report 09:51:05 INFO - PROCESS | 15616 | _x_x_mozilla_cycle_collect,1708 09:51:05 INFO - PROCESS | 15616 | __end_cc_report 09:51:05 INFO - PROCESS | 15616 | __startTimestamp1464195065910__endTimestamp 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | ------- Summary: start ------- 09:51:05 INFO - PROCESS | 15616 | Number of tests: 14 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#0] ai-astar Cycles:10 Average:109.80 Median:111.00 stddev:2.35 (2.1%) stddev-sans-first:2.45 09:51:05 INFO - PROCESS | 15616 | Values: 111.0 104.0 109.0 109.0 111.0 112.0 111.0 109.0 112.0 110.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:141.50 stddev:21.12 (14.9%) stddev-sans-first:21.80 09:51:05 INFO - PROCESS | 15616 | Values: 154.0 124.0 129.0 185.0 125.0 126.0 156.0 123.0 155.0 125.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 09:51:05 INFO - PROCESS | 15616 | Values: 161.0 161.0 161.0 160.0 159.0 163.0 161.0 161.0 162.0 162.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.00 stddev:15.38 (20.5%) stddev-sans-first:16.11 09:51:05 INFO - PROCESS | 15616 | Values: 75.0 73.0 75.0 113.0 75.0 75.0 75.0 75.0 109.0 74.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:83.00 stddev:16.81 (20.3%) stddev-sans-first:17.38 09:51:05 INFO - PROCESS | 15616 | Values: 83.0 82.0 118.0 83.0 84.0 86.0 119.0 83.0 82.0 117.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:51:05 INFO - PROCESS | 15616 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.50 stddev:0.99 (0.7%) stddev-sans-first:1.00 09:51:05 INFO - PROCESS | 15616 | Values: 145.0 147.0 147.0 145.0 146.0 147.0 145.0 145.0 145.0 147.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 09:51:05 INFO - PROCESS | 15616 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:54.50 stddev:5.06 (9.3%) stddev-sans-first:5.36 09:51:05 INFO - PROCESS | 15616 | Values: 55.0 57.0 54.0 54.0 53.0 54.0 55.0 69.0 52.0 51.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 09:51:05 INFO - PROCESS | 15616 | Values: 61.0 62.0 62.0 62.0 63.0 63.0 62.0 63.0 62.0 62.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.00 stddev:1.96 (2.4%) stddev-sans-first:2.00 09:51:05 INFO - PROCESS | 15616 | Values: 79.0 79.0 80.0 79.0 80.0 80.0 83.0 80.0 85.0 80.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#11] stanford-crypto-ccm Cycles:10 Average:129.10 Median:123.50 stddev:18.87 (15.3%) stddev-sans-first:19.98 09:51:05 INFO - PROCESS | 15616 | Values: 126.0 120.0 119.0 125.0 122.0 119.0 119.0 122.0 180.0 139.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:166.00 stddev:2.73 (1.6%) stddev-sans-first:2.24 09:51:05 INFO - PROCESS | 15616 | Values: 170.0 162.0 163.0 162.0 163.0 164.0 166.0 168.0 166.0 167.0 09:51:05 INFO - PROCESS | 15616 | 09:51:05 INFO - PROCESS | 15616 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:1.49 (2.4%) stddev-sans-first:1.51 09:51:05 INFO - PROCESS | 15616 | Values: 62.0 63.0 64.0 62.0 63.0 64.0 63.0 66.0 65.0 61.0 09:51:05 INFO - PROCESS | 15616 | -------- Summary: end -------- 09:51:05 INFO - PROCESS | 15616 | 09:51:06 INFO - TEST-INFO | 15616: exit 0 09:51:06 INFO - TEST-OK | kraken | took 61138ms 09:51:06 INFO - SUITE-END | took 1174s 09:51:07 INFO - Completed test suite (00:19:34) 09:51:07 INFO - javascript benchmark 09:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3062.188520042189, 3071.155432256129, 3101.922451938701, 3102.9534556981635, 3137.670544592818, 5220.0, 5284.31303930489, 5310.150375939846, 5366.114591092475, 5420.674759514432, 5373.274607927929, 5436.483534064258, 5444.50999410053, 5487.122060470326, 5512.531434828034, 4808.591269742076, 4896.069746748107, 4897.33885925399, 4912.705237685733, 5112.642115051948, 5025.447200807908, 5044.268581055745, 5061.19020956647, 5065.1642478990925, 5065.772040258197, 4850.854474365775, 4897.265410188465, 4899.583535399495, 4920.311156438083, 4953.455119936804, 4949.133901567211, 4950.826721064782, 4951.455339912643, 4951.925721114186, 4955.231939049453, 4908.656394052417, 4925.729084900329, 4925.7783399747095, 4930.161872481651, 4936.456989731127, 5216.426193118754, 5235.58115350771, 5288.153895376721, 5344.091504444254, 5353.785848566053, 5680.801171958961, 5684.744186511617, 5725.427457254283, 5795.391483894215, 5828.1549175369755, 5811.6222445541325, 5872.031114866042, 5881.382454842265, 5883.941160588382, 5886.646801191942, 5266.499682530153, 5333.626646134733, 5355.2770375999135, 5425.077736784717, 5483.396826349099, 5866.0, 5964.045752679551, 5976.462118409364, 5998.4601385875485, 6004.489618382487, 5718.7426565804635, 5750.2237197978575, 5751.9424805751405, 5752.453516915886, 5757.337906140764, 5653.689047102366, 5655.943440565624, 5702.572307076903, 5826.417358264224, 5837.6497410155525, 5712.65724056558, 5713.05734553794, 5713.285839270091, 5716.371199168088, 5759.963206622764, 5750.6262092963825, 5753.568482363839, 5758.3665796762325, 5759.827205183851, 5764.596478246566, 4297.871063868089, 4326.913461730811, 4332.523422423531, 4372.497162826316, 4384.868453946387, 3909.9609003909595, 3922.2155568886337, 3935.252302062599, 3970.2456533258587, 3987.6809855211513, 5421.078416669096, 5460.372057213392, 5463.754131064112, 5463.890722185613, 5470.097433923438, 5759.136129580596, 5772.076467765138, 5800.24596802413, 5806.361300256968, 5809.0705487122705, 5513.393526712059, 5520.751566179531, 5564.443555644403, 5573.164025396223, 5593.133064375028, 4800.423949126127, 4807.062622788522, 4833.879153021132, 4838.806447742051, 4888.291197776302, 5837.6497410155525, 5925.555583331267, 6040.033594624839, 6047.697615119227, 6056.757729690851, 5806.4193580641595, 5898.705064746659, 5903.793367232126, 5905.911411328748, 5921.822345329647, 6077.270727512727, 6080.452759251689, 6082.0, 6085.939140608537, 6096.207493025879, 5547.084731019498, 5705.515031222392, 5725.742341594721, 5743.655380677005, 5760.1935728998815, 5809.36097029318, 5837.241158649518, 5838.2702162229725, 5843.883122337615, 5876.735546900314, 5682.37493875665, 5692.601517893708, 5715.342735585461, 5719.599628025998, 5725.0, 5521.944780552143, 5618.073017951992, 5650.886982260414, 5655.2648155741135, 5676.233708449472, 5604.691741954069, 5607.43925607436, 5611.915821262766, 5614.803481878114, 5614.943850561605, 5707.258056452801, 5722.427757224411, 5767.365589785204, 5778.508826749773, 5801.825945221482, 5428.728563571726, 5544.695041772741, 5589.329280486368, 5593.021221286177, 5632.746526406239], "value": 5334.714171358245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.9172715819786, 650.2846868444711, 651.3811878715222, 657.5660064357525, 661.5005670718605, 30630.69369306306, 42778.14443711128, 43299.0, 43313.35029974553, 43492.0, 44681.553184468146, 45225.99999999992, 45363.319550206776, 45365.319520207224, 45388.0, 8816.603252853605, 8983.46099234048, 9013.23387512059, 9014.639414423415, 9023.684171054014, 8768.473891566495, 8857.822843543125, 8934.910650893473, 8942.955285223565, 8945.507997060158, 8968.237699795493, 9008.234300084501, 9018.59416326267, 9025.774355641095, 9026.23277021454, 8947.64209431622, 8985.77535561613, 8991.910080899173, 9003.369764116514, 9006.729798106067, 8829.0729473405, 8916.598753056129, 8926.062763409878, 8927.151920567536, 8927.732168034969, 40956.18087638231, 41331.38002929959, 41366.1726765466, 41426.58573414257, 41427.1714565707, 3503.2292895562937, 3692.51997240357, 4044.8584299549375, 4161.10536234711, 4313.654907607384, 31772.57690953363, 32156.356872862645, 32295.354092918245, 32537.0, 32705.34589308224, 4169.812358443877, 4223.556526564728, 4268.893277668083, 4296.613304802551, 4381.912361752779, 337348.0, 337486.99999999756, 337628.0, 338376.3081184603, 338605.0, 50717.49282507202, 54013.45986540163, 55111.44888551143, 55664.443355566735, 56168.438315616324, 59486.0, 59536.40463595395, 59598.40401596015, 59964.0, 60016.0, 13380.330983450789, 13496.797548036788, 13499.662508437368, 13515.459381624822, 13518.932405338108, 9559.283053770994, 10305.536250868729, 10472.0, 10472.68581942543, 10613.681589552325, 16034.0, 16106.436274730328, 16157.111358875374, 16253.268602912898, 16350.83649163517, 6362.936370636235, 6378.170837791057, 6395.936040639534, 6496.870062598721, 6557.278699343068, 970.9611615535299, 1064.3826580583243, 1071.49104175516, 1074.4788777442934, 1146.2033886448837, 32549.349013019608, 32558.83720581382, 32741.345173096404, 32808.50787238193, 33412.33175336479, 1111.699841042914, 1115.8712762777755, 1126.5550107707409, 1133.1954312438097, 1169.9473523691454, 16592.25334859934, 16639.916800415922, 16914.577135571464, 17244.827551724575, 17433.564160895825, 534324.0, 540395.0, 541679.0, 541827.0, 544135.0, 58712.7064364684, 58863.0, 58882.41117588769, 58913.70543147257, 58982.11526827101, 1273.7006803401193, 1331.081553727944, 1369.1784929042694, 1382.9723405532034, 1413.0603148905861, 32012.51981220237, 33070.0, 33249.50125748164, 33276.0, 33834.49248261327, 48509.0, 49009.019819604124, 49153.508464916325, 49215.507844922526, 49222.0, 406107.0, 406270.0, 406328.0, 407793.0, 410269.0, 34903.476447852794, 35172.648273516934, 40064.59935400725, 40809.79595102005, 42900.356494651984, 46963.765181175244, 47544.763159470516, 47907.76046119747, 48739.26891096566, 52412.47587524076, 39206.803965981126, 42037.36943945904, 42726.572734273504, 43075.353869692604, 43144.137117256854, 39224.0, 41148.588514114475, 41585.37621935734, 43221.3516797242, 44402.55597443984, 40300.596994029685, 40704.54011409063, 40893.59106408898, 41405.378919316834, 41682.0, 35632.28735425225, 36339.45490817693, 36665.26669466542, 36698.63301367059, 36811.26377472489, 27545.173644790688, 27991.860040699667, 28191.57712634353, 28288.0, 28329.150125496268], "value": 19966.224359610143, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.8350725680702, 377.4336503510431, 390.39293897988625, 391.70230624725235, 402.7724335750301, 14787.778183327258, 17320.56698582536, 17332.0, 20056.59886802263, 20222.483313751472, 18700.53248668784, 19041.0, 19506.512337191543, 19607.80392196081, 19627.11677974491, 5317.46825317469, 5438.945610543893, 5566.610337276392, 5616.438356164372, 5619.915701264485, 5463.672179669213, 5471.960327537755, 5490.258815059976, 5506.80726174584, 5518.530924871391, 5252.553532949685, 5420.945790542103, 5439.265699130626, 5468.699221542814, 5470.370907345647, 5368.597355198356, 5477.986572484103, 5495.313085864267, 5512.145617429285, 5513.50378465938, 5225.869353266199, 5627.831165065055, 5630.183623374588, 5635.943640563582, 5637.689927054011, 13042.478300867953, 13376.598702038955, 13428.32858357088, 22405.66391504129, 22696.659550106597, 2217.090992692988, 2449.8530088194766, 2751.8899244030213, 2904.8257104573795, 2947.0716724231797, 10002.299839011199, 10376.118029967387, 10835.891641083566, 11132.777344453147, 14356.49752258666, 1698.9915050424793, 1778.4042345814169, 1887.7923428422862, 1950.473372189515, 1978.9412664224715, 78871.0, 81391.0, 81685.0, 82002.17997820005, 82286.17713822845, 9959.850602240973, 10623.575056997788, 10837.620683276047, 10932.56269749201, 10979.670609881716, 15111.773323400157, 15202.771958420633, 15263.92368038175, 15265.389384424721, 15271.541853744406, 7295.6352182390665, 7421.443391745641, 7513.060867391576, 7575.204603516661, 7593.848123037508, 6944.409725173316, 7068.020954132851, 7179.174394944544, 7221.277872212737, 7231.674574644154, 4580.954190458053, 4644.535546445329, 4665.230237010923, 4739.644526660514, 4743.1225223333795, 1961.1272983522197, 1966.3707613563522, 1972.6646470100118, 2000.5398758285385, 2056.5269987902866, 932.2728271947891, 1012.792377562598, 1014.3558840136419, 1058.5765693722572, 1112.5660992213043, 18122.184501697193, 18824.717629235573, 19032.809671903105, 19223.807761922202, 19582.412527624194, 1006.4464544500496, 1027.5684212630713, 1030.3663247102977, 1034.4483275087368, 1059.7615536504225, 10484.947575262075, 10809.78380432387, 10897.182711296684, 10897.83653245202, 10907.400092994962, 382694.0, 384811.0, 385688.0, 518284.0, 551905.0, 74598.0, 84342.0, 87739.0, 88066.55966720125, 95962.52018739862, 933.4772527384687, 968.5544649461327, 990.2966896937256, 1027.337367398039, 1039.9896001040045, 16083.75874361909, 16911.408100716417, 17047.40334088301, 17272.527428605707, 17367.30530778755, 36323.81838090793, 39517.40723889202, 39917.401238980856, 40992.38511422391, 41080.79459602803, 47601.523984761094, 57999.0, 59194.70402648131, 59680.701596493476, 59718.0, 7954.125046245024, 7978.361731061617, 8290.46112002718, 8433.578321084044, 8570.48577085377, 12182.234075494634, 12561.748765024466, 12598.811017834556, 12671.366431678563, 12805.423755931191, 10894.237403381687, 10967.638769513504, 11435.771284574095, 11442.885571144181, 11516.099843525724, 5574.0, 9728.221742260745, 11140.498677559368, 11229.494672739584, 11280.32318060886, 11210.327380357205, 11337.829932551183, 11526.59656912004, 11536.134789890624, 11833.112516561465, 6723.4285085766305, 7396.819724852659, 7479.289467500579, 7647.388208943383, 8516.318694504549, 10486.685399438029, 11480.311181329147, 11559.942200288944, 11738.765224695288, 11795.29228246308], "value": 8895.763929817975, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.9464421423143, 1454.1202572443667, 1515.1136585097718, 1663.359606551478, 1855.183719163568, 8314.542700151489, 8615.870761938568, 8634.999999999993, 8638.136186381358, 8856.601452934618, 8252.174782521744, 8502.787430314245, 8758.518281494515, 8761.780955476117, 8771.473711577293, 2058.6809044598112, 2110.915563377471, 2266.897989590464, 2274.9545009099766, 2330.883455827202, 2343.3907184132077, 2370.395549134968, 2372.8457650252763, 2373.288013595923, 2375.5605213035556, 2326.9418264543438, 2336.1122773346106, 2345.331580499558, 2352.764723527642, 2353.470469144446, 1990.9601807963832, 2208.7349518057777, 2276.1236923784295, 2280.1905323610213, 2289.8053665438374, 2312.2022902098747, 2337.158622895773, 2349.2012715676583, 2359.1507057459303, 2359.587072262347, 8773.12268773124, 8809.427387219874, 8813.911860881373, 8815.647374105029, 8847.46915185091, 5240.475952404767, 5308.70802105884, 5364.088105022117, 5370.597205209625, 5380.704061276629, 3327.38443387974, 3434.759566830323, 3477.4609935460044, 3477.895663130085, 3492.8777492787885, 1110.4947249001684, 1117.804384232756, 1135.9545618175264, 1137.5734099712608, 1155.4453862146133, 11901.523939042429, 12008.819867701992, 12016.879831201664, 12045.0, 12045.096617753616, 4573.696496498482, 4626.814927402934, 4737.076270127292, 4737.336772851803, 4755.429348478205, 10196.949015254877, 10231.7442063949, 10243.180545556337, 10249.180065594735, 10315.381077135396, 5332.866678333073, 5333.493318134771, 5341.572674186055, 5342.519173274424, 5343.5457986071615, 4108.404281379183, 4778.116048531033, 4869.0, 4900.0, 4945.7774400152075, 4066.471358723347, 4126.0, 4186.518550366747, 4190.224808410454, 4212.094399704079, 1287.575100216926, 1304.2565737529517, 1311.4557458654551, 1319.399673148715, 1327.2700014991715, 979.9265055120894, 1007.3351587951917, 1008.4503945349802, 1008.6973907827769, 1030.1038096855784, 6103.694815259308, 6129.540284478682, 6161.84595385119, 6204.7828326008375, 6330.208723909512, 406.85149920279474, 424.67300178862104, 430.262100497648, 430.9676774241882, 432.2565187876847, 4162.8334866605, 4186.35111557709, 4237.322028475429, 4241.1093670328955, 4256.382824490432, 10519.737006574744, 10717.46412679363, 10728.946355268174, 10800.78398432027, 10816.188785841094, 10640.308379955279, 10648.467576621139, 10681.732956676145, 10696.465176741132, 10712.089472394779, 7918.326942209977, 8083.151269116775, 8165.755027349189, 8218.21926916942, 8233.876491852627, 6162.815115546541, 6390.552661313849, 6417.8716425672155, 6423.42189202974, 6572.572782769104, 10176.440295783506, 10216.642417515648, 10408.271421000458, 10445.68662940113, 10446.582136714447, 10033.397996120257, 10502.10732087781, 10503.579856805947, 10653.680389588324, 10672.62645807393, 7701.576413297323, 7827.882581761164, 7946.046474423106, 7975.4018448615425, 7976.242256985577, 8263.256306932404, 8264.21489958453, 8300.04549476818, 8304.626291816761, 8348.415610907177, 7168.4265258780215, 7386.593737344498, 7435.479516434014, 7466.552006879605, 7494.512856664517, 7427.183009869018, 7485.101787785283, 7485.214052524407, 7561.5085019475755, 7648.273414025659, 7013.544119632207, 7047.647617619201, 7065.964670176616, 7080.398166155935, 7082.256363081802, 4961.975190124026, 5087.771050302819, 5143.305653736697, 5150.098732721834, 5247.160454327442, 5118.948810511847, 5131.692098474104, 5156.04613146569, 5210.47895210476, 5254.343207099113], "value": 4558.528371546936, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.7881722203067, 318.0490333901634, 324.8749231545854, 325.0314064089014, 325.5166078373615, 5181.222816577506, 5202.32369791927, 5251.973740131294, 5268.841934741954, 5269.6574722643045, 5225.111731005729, 5235.267062611228, 5254.211868219769, 5256.789728410859, 5257.0, 4125.195586860564, 4135.751854888701, 4137.358709400048, 4141.171765646867, 4148.0, 4035.515738111431, 4044.413560033793, 4110.609492098246, 4145.79271036447, 4151.190517849021, 4034.3948407738926, 4080.9387859182134, 4091.672666186713, 4103.671706263507, 4118.773467459288, 4056.776877271749, 4065.532463766675, 4069.47096877407, 4086.3053280942313, 4089.9591004089875, 3602.225521512861, 3608.5746130278653, 3611.8081033258964, 3613.5483064616924, 3631.8184090795617, 4834.371531700892, 4997.275395067695, 4998.3502144721315, 5027.622928280357, 5030.861883699413, 4603.7698115094445, 4611.210217818239, 4625.514320996314, 4629.77311012582, 4656.790444430008, 5334.386545547234, 5342.252084708105, 5368.785248590091, 5382.219578161144, 5410.891782164374, 3836.846526138951, 3928.5857091447056, 3939.960600393988, 3943.940840887389, 3949.2891279569667, 5465.234867118606, 5489.533389661923, 5659.264295641581, 5695.778646327935, 5720.827230417796, 4000.8399664013696, 4008.7594744315434, 4012.859549915739, 4041.4341992121126, 4043.6765058795227, 3885.1646895917515, 3964.623360780721, 4053.148838743838, 4058.736182148151, 4069.4709687740406, 4601.4248218972625, 4605.792739326738, 4615.676902616851, 4628.467726211461, 4628.652851036119, 5461.42655021225, 5463.808766693147, 5464.398916119224, 5491.341039075336, 5507.531859791882, 1366.1598117157876, 1373.230990645222, 1389.4650559534653, 1404.6558593144746, 1406.1492796857958, 883.8730618461385, 909.981800363989, 916.1251005289932, 931.7437704631145, 934.2572654739498, 4667.6965997210755, 4682.719036857799, 4712.410948631421, 4713.95286047142, 4749.931265465312, 4900.534449227318, 4939.703617782944, 4977.701337919736, 4984.451710311862, 5012.373453318335, 4501.954980450219, 4635.566327677929, 4708.364370809965, 4720.33915251865, 4755.262934245197, 2600.9609855852177, 2707.6615423072117, 2708.986455067712, 2724.6730392353043, 2726.6591676040493, 5039.269305950616, 5107.514786095315, 5125.589952803842, 5133.614978876599, 5156.716380599028, 4822.927656085161, 5035.546800787946, 5068.467810879878, 5125.436741793714, 5170.302009228782, 5123.462036486189, 5146.665466744649, 5178.6374953752875, 5179.430262671103, 5189.221616757516, 5056.469070747518, 5067.442581316126, 5079.2888995539915, 5079.517445842639, 5437.238786569805, 5371.946280537145, 5529.142982837667, 5550.27846379968, 5593.580481463988, 5615.719214039364, 5786.971065144648, 5808.564357673107, 5830.82507524775, 5869.765209391577, 5891.8510890375965, 3905.1018265798457, 3989.2819292527624, 3999.7600143991453, 4010.558838527818, 4034.7377420467574, 5550.5282051026115, 5581.553475722014, 5636.915446268391, 5680.289963754531, 5709.429057094258, 5451.509364157245, 5476.123820188751, 5512.476314750093, 5515.696636685021, 5522.873693628063, 5236.764345604533, 5384.300040994803, 5430.456954304538, 5468.589855760913, 5489.39616642161, 5280.471952804689, 5392.514673679388, 5407.188921661782, 5408.918866216931, 5430.42980487059, 5258.868528286824, 5454.236406903116, 5484.341878974548, 5490.505854473116, 5501.889962200654, 5380.219868119023, 5389.622726409114, 5409.404965453876, 5418.729063546885, 5453.427390123979], "value": 4067.8100992909135, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.5878690048427, 835.2399316621871, 846.9237768600832, 868.617808164408, 875.8905136857893, 13260.403281852316, 14096.71806563868, 14098.083624564397, 14123.081999670014, 14140.71718565628, 13199.802002969964, 13356.065075444723, 13388.59834204973, 13396.598102056929, 14154.150750954925, 682.6484360554293, 805.5005896344245, 820.5609998650708, 825.4923222218323, 830.8421399934024, 827.755812035451, 833.5082301442147, 834.0533494483774, 834.1491678487969, 834.5701963488808, 874.0210963720642, 874.8381549413378, 877.398981697536, 878.7232021913077, 879.6701237036111, 712.6223101756077, 835.778518692547, 860.4450129666393, 873.2184694698241, 874.0647507167333, 626.2078470734524, 628.7296462521114, 628.9119523266746, 629.0564153769346, 629.6820105846564, 14337.49818756349, 14449.638759031108, 14466.855331446655, 14468.927655361656, 14492.565223043326, 13071.738565228643, 13461.865381346159, 13518.459261629523, 13609.0, 13819.930900345435, 11366.772664546745, 11411.42942852854, 12079.939600301943, 12177.33024683642, 12271.57049503272, 4501.324801279801, 4570.7257564546235, 4577.771111444415, 4592.47186573545, 4731.432228132611, 52860.73569632127, 53683.0, 59330.703346483424, 63783.0, 64112.35887641157, 11211.103111751041, 11214.271072380432, 11403.42982850854, 11415.20093593457, 11448.656540303638, 19148.712769308473, 19255.0, 19935.0, 19946.501337466678, 19950.50123746889, 8073.515589064675, 8101.0, 8266.751997440086, 8337.541435221121, 8531.616077276538, 4661.720296782204, 4749.121412538691, 4820.204666230103, 4850.417949846041, 4990.4011518618, 3461.6884480396884, 3577.5706915170344, 3584.0502266899293, 3585.94621080684, 3587.282543491312, 580.5848818095083, 584.7281014328357, 586.2378907420422, 587.1163898332982, 588.1853632718716, 659.5976454362835, 770.6570576093696, 774.6204359863626, 802.4623502253504, 809.536298004363, 9008.279337652972, 9022.323325750594, 9625.181859542017, 9655.39921074664, 9844.655437059668, 670.7585269303047, 748.9063867016623, 764.495433014208, 770.8689522781141, 828.1139181076311, 4529.755844363227, 4576.313552967082, 4606.792694328763, 4678.578927896506, 4683.81264749413, 65161.674191628736, 65855.70721463907, 66173.669131655, 68978.31021689718, 69274.65362673154, 59665.70167149136, 60004.69997649984, 60327.0, 60519.0, 63163.6841815788, 52702.0, 52967.0, 54123.72938135284, 54408.45591544113, 54596.0, 8693.261072808882, 8761.693340733043, 8801.207891289558, 8965.229568274464, 8992.820143597222, 36418.27163456769, 36577.63422365742, 37118.8144059278, 37277.440838387985, 38089.809550952064, 46395.0, 47297.0, 48423.0, 48894.26658600195, 48984.510154897995, 11196.496157672762, 11198.608048718255, 11298.60454884075, 11344.546218151181, 11630.6510804676, 12497.750044998867, 12700.491980320683, 12766.68083297925, 13233.470661173831, 13363.665908352368, 11805.730985462156, 12094.75810483803, 12165.270083795001, 12181.08641851847, 12509.249445033327, 11591.24656897299, 12020.459079341275, 12033.398330083286, 12141.0, 12340.074494412775, 12167.817482737943, 12235.59290681583, 12337.444814983526, 12535.686607834878, 12568.308743018855, 9412.858807118037, 9434.150926416656, 9450.952745236229, 9758.85361719589, 9770.609175632895, 9218.492982885737, 9379.577918993798, 9445.244380449685, 10250.38497690141, 10276.948615256875], "value": 6847.890167778878, "unit": "score"}], "name": "dromaeo_css", "value": 7026.3509732908415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 109.0, 109.0, 111.0, 112.0, 111.0, 109.0, 112.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 129.0, 185.0, 125.0, 126.0, 156.0, 123.0, 155.0, 125.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 159.0, 163.0, 161.0, 161.0, 162.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 75.0, 113.0, 75.0, 75.0, 75.0, 75.0, 109.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 118.0, 83.0, 84.0, 86.0, 119.0, 83.0, 82.0, 117.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 147.0, 145.0, 146.0, 147.0, 145.0, 145.0, 145.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 54.0, 54.0, 53.0, 54.0, 55.0, 69.0, 52.0, 51.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 63.0, 63.0, 62.0, 63.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 79.0, 80.0, 80.0, 83.0, 80.0, 85.0, 80.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 120.0, 119.0, 125.0, 122.0, 119.0, 119.0, 122.0, 180.0, 139.0], "value": 129.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 162.0, 163.0, 162.0, 163.0, 164.0, 166.0, 168.0, 166.0, 167.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 64.0, 62.0, 63.0, 64.0, 63.0, 66.0, 65.0, 61.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1499.2999999999997, "alertThreshold": 2.0}]} 09:51:07 INFO - Return code: 0 09:51:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:51:07 ERROR - # TBPL SUCCESS # 09:51:07 INFO - Running post-action listener: _resource_record_post_action 09:51:07 INFO - ##### 09:51:07 INFO - ##### Finished run-tests step (success) 09:51:07 INFO - ##### 09:51:07 INFO - Running post-run listener: _resource_record_post_run 09:51:07 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 539578368; Read time: 8; Write time: 373288 09:51:07 INFO - TinderboxPrint: CPU usage
13.2% 09:51:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:51:07 INFO - TinderboxPrint: I/O write bytes / time
539,578,368 / 373,288 09:51:07 INFO - TinderboxPrint: CPU idle
8,264.7 (86.8%) 09:51:07 INFO - TinderboxPrint: CPU user
1,214.1 (12.7%) 09:51:07 INFO - TinderboxPrint: Swap in / out
0 / 0 09:51:07 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247164928; Read time: 0; Write time: 210964 09:51:07 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292413440; Read time: 8; Write time: 162324 09:51:07 INFO - Running post-run listener: _upload_blobber_files 09:51:07 INFO - Blob upload gear active. 09:51:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:51:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:51:08 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:09 INFO - (blobuploader) - INFO - Done attempting. 09:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:09 INFO - (blobuploader) - INFO - Done attempting. 09:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:51:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:14 INFO - (blobuploader) - INFO - Done attempting. 09:51:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:51:14 INFO - Return code: 0 09:51:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:51:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:51:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ec0d06bfe066fbee170bbdc983b46dbbb30f55b383f8a8bd1daacdc38de6412372d021516c93e8a7547fb10126877f646548bd5098ca0e9d7ebe8b2e78b28b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1673f588100a3f2944a359dede71037eb83cc1e68dc61b7af4a96da11ed8c74bfbf3fbda526da60ef3823727777bb5f7eee563b456491120399bcc3f93740f0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8933cae6c64a67df30ea3c7d62841e023b3ef15d6834f1b3476c2c04ab671dd055c63ec366eba927328ca4707200985b936ca405c5a949ddc9a8394da6e57604"} 09:51:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:51:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:51:14 INFO - Contents: 09:51:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ec0d06bfe066fbee170bbdc983b46dbbb30f55b383f8a8bd1daacdc38de6412372d021516c93e8a7547fb10126877f646548bd5098ca0e9d7ebe8b2e78b28b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1673f588100a3f2944a359dede71037eb83cc1e68dc61b7af4a96da11ed8c74bfbf3fbda526da60ef3823727777bb5f7eee563b456491120399bcc3f93740f0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8933cae6c64a67df30ea3c7d62841e023b3ef15d6834f1b3476c2c04ab671dd055c63ec366eba927328ca4707200985b936ca405c5a949ddc9a8394da6e57604"} 09:51:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:51:14 INFO - Copying logs to upload dir... 09:51:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:51:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.297741 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-25 09:51:13.997214) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 09:51:14.002361) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ec0d06bfe066fbee170bbdc983b46dbbb30f55b383f8a8bd1daacdc38de6412372d021516c93e8a7547fb10126877f646548bd5098ca0e9d7ebe8b2e78b28b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1673f588100a3f2944a359dede71037eb83cc1e68dc61b7af4a96da11ed8c74bfbf3fbda526da60ef3823727777bb5f7eee563b456491120399bcc3f93740f0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8933cae6c64a67df30ea3c7d62841e023b3ef15d6834f1b3476c2c04ab671dd055c63ec366eba927328ca4707200985b936ca405c5a949ddc9a8394da6e57604"} build_url:https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/bKwnsZTBQmy6K8TpHRLwMQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ec0d06bfe066fbee170bbdc983b46dbbb30f55b383f8a8bd1daacdc38de6412372d021516c93e8a7547fb10126877f646548bd5098ca0e9d7ebe8b2e78b28b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1673f588100a3f2944a359dede71037eb83cc1e68dc61b7af4a96da11ed8c74bfbf3fbda526da60ef3823727777bb5f7eee563b456491120399bcc3f93740f0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8933cae6c64a67df30ea3c7d62841e023b3ef15d6834f1b3476c2c04ab671dd055c63ec366eba927328ca4707200985b936ca405c5a949ddc9a8394da6e57604"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 09:51:14.035756) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:51:14.036058) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1464192254.25719-1488255971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:51:14.116376) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 09:51:14.116715) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 09:51:14.117022) ========= ========= Total master_lag: 0.18 =========