builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1474326557.86 results: success (0) buildid: 20160919065232 builduid: f89185a30a7343359f9c5b9acabffd00 revision: 5dddbefdf759f09b1411f33fa0920835b919fc81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:17.858027) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:17.858526) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:17.858862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:17.874335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:17.875022) ========= 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=0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 16:09:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|: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.9M=0s 2016-09-19 16:09:18 (42.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.073579 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:17.967435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:17.968167) ========= 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=0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014748 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:18.022747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 16:09:18.023730) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5dddbefdf759f09b1411f33fa0920835b919fc81 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5dddbefdf759f09b1411f33fa0920835b919fc81 --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=0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 16:09:18,214 truncating revision to first 12 chars 2016-09-19 16:09:18,214 Setting DEBUG logging. 2016-09-19 16:09:18,214 attempt 1/10 2016-09-19 16:09:18,215 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5dddbefdf759?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 16:09:18,954 unpacking tar archive at: mozilla-aurora-5dddbefdf759/testing/mozharness/ program finished with exit code 0 elapsedTime=1.095470 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 16:09:19.137406) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:19.138194) ========= script_repo_revision: 5dddbefdf759f09b1411f33fa0920835b919fc81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:19.139186) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:19.139834) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 16:09:19.150709) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-19 16:09:19.151435) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:09:19 INFO - MultiFileLogger online at 20160919 16:09:19 in /builds/slave/test 16:09:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:09:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:09:19 INFO - {'append_to_log': False, 16:09:19 INFO - 'base_work_dir': '/builds/slave/test', 16:09:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:09:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:09:19 INFO - 'branch': 'Mozilla-Aurora', 16:09:19 INFO - 'buildbot_json_path': 'buildprops.json', 16:09:19 INFO - 'config_files': ('talos/linux_config.py',), 16:09:19 INFO - 'default_actions': ('clobber', 16:09:19 INFO - 'read-buildbot-config', 16:09:19 INFO - 'download-and-extract', 16:09:19 INFO - 'populate-webroot', 16:09:19 INFO - 'create-virtualenv', 16:09:19 INFO - 'install', 16:09:19 INFO - 'run-tests'), 16:09:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:09:19 INFO - 'download_minidump_stackwalk': True, 16:09:19 INFO - 'download_symbols': 'ondemand', 16:09:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:09:19 INFO - 'tooltool.py': '/tools/tooltool.py', 16:09:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:09:19 INFO - '/tools/misc-python/virtualenv.py')}, 16:09:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:09:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:09:19 INFO - 'installer_path': 'installer.exe', 16:09:19 INFO - 'log_level': 'info', 16:09:19 INFO - 'log_name': 'talos', 16:09:19 INFO - 'log_to_console': True, 16:09:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:09:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:09:19 INFO - 'opt_config_files': (), 16:09:19 INFO - 'pip_index': False, 16:09:19 INFO - 'sps_profile': False, 16:09:19 INFO - 'sps_profile_interval': 0, 16:09:19 INFO - 'suite': 'dromaeojs', 16:09:19 INFO - 'system_bits': '32', 16:09:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:09:19 INFO - 'title': 'talos-linux64-ix-058', 16:09:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:09:19 INFO - 'use_talos_json': True, 16:09:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:09:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:09:19 INFO - 'work_dir': 'build'} 16:09:19 INFO - [mozharness: 2016-09-19 23:09:19.435797Z] Running clobber step. 16:09:19 INFO - Running pre-action listener: _resource_record_pre_action 16:09:19 INFO - Running main action method: clobber 16:09:19 INFO - rmtree: /builds/slave/test/build 16:09:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:09:19 INFO - Running post-action listener: _resource_record_post_action 16:09:19 INFO - [mozharness: 2016-09-19 23:09:19.886565Z] Finished clobber step (success) 16:09:19 INFO - [mozharness: 2016-09-19 23:09:19.886667Z] Running read-buildbot-config step. 16:09:19 INFO - Running pre-action listener: _resource_record_pre_action 16:09:19 INFO - Running main action method: read_buildbot_config 16:09:19 INFO - Using buildbot properties: 16:09:19 INFO - { 16:09:19 INFO - "project": "", 16:09:19 INFO - "product": "firefox", 16:09:19 INFO - "script_repo_revision": "production", 16:09:19 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:09:19 INFO - "repository": "", 16:09:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:09:19 INFO - "buildid": "20160919065232", 16:09:19 INFO - "stage_platform": "linux64-pgo", 16:09:19 INFO - "basedir": "/builds/slave/test", 16:09:19 INFO - "nightly_build": "True", 16:09:19 INFO - "buildnumber": 476, 16:09:19 INFO - "slavename": "talos-linux64-ix-058", 16:09:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:09:19 INFO - "platform": "ubuntu64_hw", 16:09:19 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:09:19 INFO - "branch": "mozilla-aurora", 16:09:19 INFO - "repo_path": "releases/mozilla-aurora", 16:09:19 INFO - "revision": "5dddbefdf759f09b1411f33fa0920835b919fc81", 16:09:19 INFO - "pgo_build": "True", 16:09:19 INFO - "builduid": "f89185a30a7343359f9c5b9acabffd00", 16:09:19 INFO - "slavebuilddir": "test" 16:09:19 INFO - } 16:09:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 16:09:19 INFO - Running post-action listener: _resource_record_post_action 16:09:19 INFO - [mozharness: 2016-09-19 23:09:19.889173Z] Finished read-buildbot-config step (success) 16:09:19 INFO - [mozharness: 2016-09-19 23:09:19.889261Z] Running download-and-extract step. 16:09:19 INFO - Running pre-action listener: _resource_record_pre_action 16:09:19 INFO - Running main action method: download_and_extract 16:09:19 INFO - mkdir: /builds/slave/test/build/tests 16:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:19 INFO - trying https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:09:19 INFO - Downloading https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:09:20 INFO - Downloaded 1361 bytes. 16:09:20 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:09:20 INFO - Using the following test package requirements: 16:09:20 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 16:09:20 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:09:20 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:09:20 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:09:20 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:09:20 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:09:20 INFO - u'jsshell-linux-x86_64.zip'], 16:09:20 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:09:20 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:09:20 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 16:09:20 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:09:20 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:09:20 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:09:20 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:09:20 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:09:20 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:09:20 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:09:20 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:09:20 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:09:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 16:09:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:09:20 INFO - Fetch https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 16:09:21 INFO - Expected file size: 21893796 16:09:25 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:09:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 16:09:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:09:25 INFO - Fetch https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 16:09:25 INFO - Expected file size: 21893796 16:09:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 16:09:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:09:30 INFO - Fetch https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 16:09:31 INFO - Expected file size: 14414059 16:09:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:31 INFO - trying https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:09:31 INFO - Downloading https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:09:32 INFO - Downloaded 63958077 bytes. 16:09:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:09:32 INFO - mkdir: /builds/slave/test/properties 16:09:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:09:32 INFO - Writing to file /builds/slave/test/properties/build_url 16:09:32 INFO - Contents: 16:09:32 INFO - build_url:https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:09:33 INFO - Running post-action listener: _resource_record_post_action 16:09:33 INFO - Running post-action listener: set_extra_try_arguments 16:09:33 INFO - [mozharness: 2016-09-19 23:09:33.599200Z] Finished download-and-extract step (success) 16:09:33 INFO - [mozharness: 2016-09-19 23:09:33.599429Z] Running populate-webroot step. 16:09:33 INFO - Running pre-action listener: _resource_record_pre_action 16:09:33 INFO - Running main action method: populate_webroot 16:09:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:09:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:09:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:09:33 INFO - '--tppagecycles', 16:09:33 INFO - '7'], 16:09:33 INFO - 'tests': ['tsvgm']}}, 16:09:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:09:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:09:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:09:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:09:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:33 INFO - 'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:33 INFO - 'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['damp', 'tps']}, 16:09:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:33 INFO - 'tests': ['damp', 'tps']}, 16:09:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['dromaeo_dom']}, 16:09:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:09:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['basic_compositor_video']}, 16:09:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:09:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['a11yr', 16:09:33 INFO - 'ts_paint', 16:09:33 INFO - 'tpaint', 16:09:33 INFO - 'sessionrestore', 16:09:33 INFO - 'sessionrestore_no_auto_restore', 16:09:33 INFO - 'tabpaint']}, 16:09:33 INFO - 'other-e10s': {'tests': ['a11yr', 16:09:33 INFO - 'ts_paint', 16:09:33 INFO - 'tpaint', 16:09:33 INFO - 'sessionrestore', 16:09:33 INFO - 'sessionrestore_no_auto_restore', 16:09:33 INFO - 'tabpaint']}, 16:09:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:09:33 INFO - 'ts_paint', 16:09:33 INFO - 'tpaint', 16:09:33 INFO - 'sessionrestore', 16:09:33 INFO - 'sessionrestore_no_auto_restore', 16:09:33 INFO - 'tabpaint']}, 16:09:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:09:33 INFO - 'ts_paint', 16:09:33 INFO - 'tpaint', 16:09:33 INFO - 'sessionrestore', 16:09:33 INFO - 'sessionrestore_no_auto_restore', 16:09:33 INFO - 'tabpaint']}, 16:09:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['a11yr', 16:09:33 INFO - 'ts_paint', 16:09:33 INFO - 'tpaint', 16:09:33 INFO - 'sessionrestore', 16:09:33 INFO - 'sessionrestore_no_auto_restore', 16:09:33 INFO - 'tabpaint']}, 16:09:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['a11yr', 16:09:33 INFO - 'ts_paint', 16:09:33 INFO - 'tpaint', 16:09:33 INFO - 'sessionrestore', 16:09:33 INFO - 'sessionrestore_no_auto_restore', 16:09:33 INFO - 'tabpaint']}, 16:09:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['tsvgx', 16:09:33 INFO - 'tsvgr_opacity', 16:09:33 INFO - 'tart', 16:09:33 INFO - 'tscrollx', 16:09:33 INFO - 'cart']}, 16:09:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:09:33 INFO - 'tsvgr_opacity', 16:09:33 INFO - 'tart', 16:09:33 INFO - 'tscrollx', 16:09:33 INFO - 'cart']}, 16:09:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:33 INFO - 'talos_options': ['--disable-e10s'], 16:09:33 INFO - 'tests': ['tp5o']}, 16:09:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:33 INFO - 'tests': ['tp5o']}, 16:09:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:33 INFO - 'talos_options': ['--disable-e10s', 16:09:33 INFO - '--xperf_path', 16:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:33 INFO - 'tests': ['tp5n']}, 16:09:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:33 INFO - 'talos_options': ['--xperf_path', 16:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:33 INFO - 'tests': ['tp5n']}}, 16:09:33 INFO - 'talos.zip': {'path': '', 16:09:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:09:33 INFO - Running post-action listener: _resource_record_post_action 16:09:33 INFO - [mozharness: 2016-09-19 23:09:33.617027Z] Finished populate-webroot step (success) 16:09:33 INFO - [mozharness: 2016-09-19 23:09:33.617116Z] Running create-virtualenv step. 16:09:33 INFO - Running pre-action listener: _resource_record_pre_action 16:09:33 INFO - Running main action method: create_virtualenv 16:09:33 INFO - Creating virtualenv /builds/slave/test/build/venv 16:09:33 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 16:09:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:09:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:09:33 INFO - Using real prefix '/usr' 16:09:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:09:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:09:36 INFO - Installing pip.................done. 16:09:36 INFO - Return code: 0 16:09:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:09:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:36 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 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:36 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 16:09:36 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 16:09:36 INFO - Using env: {'DISPLAY': ':0', 16:09:36 INFO - 'HOME': '/home/cltbld', 16:09:36 INFO - 'LANG': 'en_US.UTF-8', 16:09:36 INFO - 'LANGUAGE': 'en_US:en', 16:09:36 INFO - 'LOGNAME': 'cltbld', 16:09:36 INFO - 'MAIL': '/var/mail/cltbld', 16:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:36 INFO - 'MOZ_NO_REMOTE': '1', 16:09:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:36 INFO - 'NO_EM_RESTART': '1', 16:09:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:36 INFO - 'PWD': '/builds/slave/test', 16:09:36 INFO - 'SHELL': '/bin/bash', 16:09:36 INFO - 'SHLVL': '1', 16:09:36 INFO - 'TERM': 'linux', 16:09:36 INFO - 'TMOUT': '86400', 16:09:36 INFO - 'USER': 'cltbld', 16:09:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:36 INFO - '_': '/tools/buildbot/bin/python'} 16:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:36 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 16:09:36 INFO - Cleaning up... 16:09:36 INFO - Return code: 0 16:09:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:09:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:36 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 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:36 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 16:09:36 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 16:09:36 INFO - Using env: {'DISPLAY': ':0', 16:09:36 INFO - 'HOME': '/home/cltbld', 16:09:36 INFO - 'LANG': 'en_US.UTF-8', 16:09:36 INFO - 'LANGUAGE': 'en_US:en', 16:09:36 INFO - 'LOGNAME': 'cltbld', 16:09:36 INFO - 'MAIL': '/var/mail/cltbld', 16:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:36 INFO - 'MOZ_NO_REMOTE': '1', 16:09:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:36 INFO - 'NO_EM_RESTART': '1', 16:09:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:36 INFO - 'PWD': '/builds/slave/test', 16:09:36 INFO - 'SHELL': '/bin/bash', 16:09:36 INFO - 'SHLVL': '1', 16:09:36 INFO - 'TERM': 'linux', 16:09:36 INFO - 'TMOUT': '86400', 16:09:36 INFO - 'USER': 'cltbld', 16:09:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:36 INFO - '_': '/tools/buildbot/bin/python'} 16:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:36 INFO - Downloading/unpacking psutil>=3.1.1 16:09:36 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 16:09:36 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 16:09:36 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 16:09:36 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 16:09:36 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 16:09:36 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 16:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:09:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:38 INFO - Installing collected packages: psutil 16:09:38 INFO - Running setup.py install for psutil 16:09:38 INFO - building 'psutil._psutil_linux' extension 16:09:38 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 16:09:38 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 16:09:38 INFO - building 'psutil._psutil_posix' extension 16:09:38 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 16:09:39 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 16:09:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:39 INFO - Successfully installed psutil 16:09:39 INFO - Cleaning up... 16:09:39 INFO - Return code: 0 16:09:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:39 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 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:39 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 16:09:39 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 16:09:39 INFO - Using env: {'DISPLAY': ':0', 16:09:39 INFO - 'HOME': '/home/cltbld', 16:09:39 INFO - 'LANG': 'en_US.UTF-8', 16:09:39 INFO - 'LANGUAGE': 'en_US:en', 16:09:39 INFO - 'LOGNAME': 'cltbld', 16:09:39 INFO - 'MAIL': '/var/mail/cltbld', 16:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:39 INFO - 'MOZ_NO_REMOTE': '1', 16:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:39 INFO - 'NO_EM_RESTART': '1', 16:09:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:39 INFO - 'PWD': '/builds/slave/test', 16:09:39 INFO - 'SHELL': '/bin/bash', 16:09:39 INFO - 'SHLVL': '1', 16:09:39 INFO - 'TERM': 'linux', 16:09:39 INFO - 'TMOUT': '86400', 16:09:39 INFO - 'USER': 'cltbld', 16:09:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:39 INFO - '_': '/tools/buildbot/bin/python'} 16:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:09:39 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 16:09:39 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 16:09:39 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 16:09:39 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 16:09:39 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 16:09:39 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 16:09:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:09:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:09:41 INFO - Installing collected packages: mozsystemmonitor 16:09:41 INFO - Running setup.py install for mozsystemmonitor 16:09:41 INFO - Successfully installed mozsystemmonitor 16:09:41 INFO - Cleaning up... 16:09:41 INFO - Return code: 0 16:09:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:41 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:41 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==2.5.1'] in /builds/slave/test/build 16:09:41 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==2.5.1 16:09:41 INFO - Using env: {'DISPLAY': ':0', 16:09:41 INFO - 'HOME': '/home/cltbld', 16:09:41 INFO - 'LANG': 'en_US.UTF-8', 16:09:41 INFO - 'LANGUAGE': 'en_US:en', 16:09:41 INFO - 'LOGNAME': 'cltbld', 16:09:41 INFO - 'MAIL': '/var/mail/cltbld', 16:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:41 INFO - 'MOZ_NO_REMOTE': '1', 16:09:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:41 INFO - 'NO_EM_RESTART': '1', 16:09:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:41 INFO - 'PWD': '/builds/slave/test', 16:09:41 INFO - 'SHELL': '/bin/bash', 16:09:41 INFO - 'SHLVL': '1', 16:09:41 INFO - 'TERM': 'linux', 16:09:41 INFO - 'TMOUT': '86400', 16:09:41 INFO - 'USER': 'cltbld', 16:09:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:41 INFO - '_': '/tools/buildbot/bin/python'} 16:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:41 INFO - Downloading/unpacking jsonschema==2.5.1 16:09:41 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 16:09:41 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 16:09:41 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 16:09:41 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 16:09:41 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 16:09:41 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 16:09:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:09:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:43 INFO - Downloading functools32-3.2.3-2.tar.gz 16:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:09:43 INFO - warning: no files found matching '*.txt' 16:09:43 INFO - no previously-included directories found matching 'build' 16:09:43 INFO - no previously-included directories found matching 'dist' 16:09:43 INFO - no previously-included directories found matching '.git*' 16:09:43 INFO - Installing collected packages: jsonschema, functools32 16:09:43 INFO - Running setup.py install for functools32 16:09:44 INFO - warning: no files found matching '*.txt' 16:09:44 INFO - no previously-included directories found matching 'build' 16:09:44 INFO - no previously-included directories found matching 'dist' 16:09:44 INFO - no previously-included directories found matching '.git*' 16:09:44 INFO - Successfully installed jsonschema functools32 16:09:44 INFO - Cleaning up... 16:09:44 INFO - Return code: 0 16:09:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:09:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:44 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:44 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:09:44 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 functools32==3.2.3-2 16:09:44 INFO - Using env: {'DISPLAY': ':0', 16:09:44 INFO - 'HOME': '/home/cltbld', 16:09:44 INFO - 'LANG': 'en_US.UTF-8', 16:09:44 INFO - 'LANGUAGE': 'en_US:en', 16:09:44 INFO - 'LOGNAME': 'cltbld', 16:09:44 INFO - 'MAIL': '/var/mail/cltbld', 16:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:44 INFO - 'MOZ_NO_REMOTE': '1', 16:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:44 INFO - 'NO_EM_RESTART': '1', 16:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:44 INFO - 'PWD': '/builds/slave/test', 16:09:44 INFO - 'SHELL': '/bin/bash', 16:09:44 INFO - 'SHLVL': '1', 16:09:44 INFO - 'TERM': 'linux', 16:09:44 INFO - 'TMOUT': '86400', 16:09:44 INFO - 'USER': 'cltbld', 16:09:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:44 INFO - '_': '/tools/buildbot/bin/python'} 16:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:09:44 INFO - Cleaning up... 16:09:44 INFO - Return code: 0 16:09:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:09:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:44 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 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:44 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 16:09:44 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 16:09:44 INFO - Using env: {'DISPLAY': ':0', 16:09:44 INFO - 'HOME': '/home/cltbld', 16:09:44 INFO - 'LANG': 'en_US.UTF-8', 16:09:44 INFO - 'LANGUAGE': 'en_US:en', 16:09:44 INFO - 'LOGNAME': 'cltbld', 16:09:44 INFO - 'MAIL': '/var/mail/cltbld', 16:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:44 INFO - 'MOZ_NO_REMOTE': '1', 16:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:44 INFO - 'NO_EM_RESTART': '1', 16:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:44 INFO - 'PWD': '/builds/slave/test', 16:09:44 INFO - 'SHELL': '/bin/bash', 16:09:44 INFO - 'SHLVL': '1', 16:09:44 INFO - 'TERM': 'linux', 16:09:44 INFO - 'TMOUT': '86400', 16:09:44 INFO - 'USER': 'cltbld', 16:09:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:44 INFO - '_': '/tools/buildbot/bin/python'} 16:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:09:44 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 16:09:44 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 16:09:44 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 16:09:44 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 16:09:44 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 16:09:44 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 16:09:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:09:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:09:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:46 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 16:09:47 INFO - Downloading docopt-0.6.1.tar.gz 16:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:09:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:09:47 INFO - Running setup.py install for blobuploader 16:09:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:09:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:09:47 INFO - Running setup.py install for requests 16:09:47 INFO - Running setup.py install for docopt 16:09:47 INFO - Successfully installed blobuploader requests docopt 16:09:47 INFO - Cleaning up... 16:09:47 INFO - Return code: 0 16:09:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:47 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 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:47 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 16:09:47 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 16:09:47 INFO - Using env: {'DISPLAY': ':0', 16:09:47 INFO - 'HOME': '/home/cltbld', 16:09:47 INFO - 'LANG': 'en_US.UTF-8', 16:09:47 INFO - 'LANGUAGE': 'en_US:en', 16:09:47 INFO - 'LOGNAME': 'cltbld', 16:09:47 INFO - 'MAIL': '/var/mail/cltbld', 16:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:47 INFO - 'MOZ_NO_REMOTE': '1', 16:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:47 INFO - 'NO_EM_RESTART': '1', 16:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:47 INFO - 'PWD': '/builds/slave/test', 16:09:47 INFO - 'SHELL': '/bin/bash', 16:09:47 INFO - 'SHLVL': '1', 16:09:47 INFO - 'TERM': 'linux', 16:09:47 INFO - 'TMOUT': '86400', 16:09:47 INFO - 'USER': 'cltbld', 16:09:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:47 INFO - '_': '/tools/buildbot/bin/python'} 16:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:09:48 INFO - Running setup.py (path:/tmp/pip-YIrJQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:09:48 INFO - Running setup.py (path:/tmp/pip-tSeyI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:09:48 INFO - Running setup.py (path:/tmp/pip-dNu_o3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:09:48 INFO - Running setup.py (path:/tmp/pip-q4CfGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:09:48 INFO - Running setup.py (path:/tmp/pip-btZ6W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:09:48 INFO - Running setup.py (path:/tmp/pip-iymN1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:09:48 INFO - Running setup.py (path:/tmp/pip-MgrN0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:09:48 INFO - Running setup.py (path:/tmp/pip-GDGlOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:09:48 INFO - Running setup.py (path:/tmp/pip-nY5mq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:09:48 INFO - Running setup.py (path:/tmp/pip-jdsjnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:09:48 INFO - Running setup.py (path:/tmp/pip-zTjwf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:09:48 INFO - Running setup.py (path:/tmp/pip-DE3WQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:09:48 INFO - Running setup.py (path:/tmp/pip-largy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:09:48 INFO - Running setup.py (path:/tmp/pip-FRXKDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:49 INFO - Running setup.py (path:/tmp/pip-pLEDTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:09:49 INFO - Running setup.py (path:/tmp/pip-P2lAEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:09:49 INFO - Running setup.py (path:/tmp/pip-ehE9yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:09:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:09:49 INFO - Running setup.py install for manifestparser 16:09:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:09:49 INFO - Running setup.py install for mozcrash 16:09:49 INFO - Running setup.py install for mozdebug 16:09:49 INFO - Running setup.py install for mozdevice 16:09:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:09:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:09:49 INFO - Running setup.py install for mozfile 16:09:49 INFO - Running setup.py install for mozhttpd 16:09:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:09:49 INFO - Running setup.py install for mozinfo 16:09:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:09:49 INFO - Running setup.py install for mozInstall 16:09:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Running setup.py install for mozleak 16:09:50 INFO - Running setup.py install for mozlog 16:09:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Running setup.py install for moznetwork 16:09:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Running setup.py install for mozprocess 16:09:50 INFO - Running setup.py install for mozprofile 16:09:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Running setup.py install for mozrunner 16:09:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:09:50 INFO - Running setup.py install for mozscreenshot 16:09:50 INFO - Running setup.py install for moztest 16:09:50 INFO - Running setup.py install for mozversion 16:09:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:09:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:09:51 INFO - Cleaning up... 16:09:51 INFO - Return code: 0 16:09:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:51 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 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:51 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 16:09:51 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 16:09:51 INFO - Using env: {'DISPLAY': ':0', 16:09:51 INFO - 'HOME': '/home/cltbld', 16:09:51 INFO - 'LANG': 'en_US.UTF-8', 16:09:51 INFO - 'LANGUAGE': 'en_US:en', 16:09:51 INFO - 'LOGNAME': 'cltbld', 16:09:51 INFO - 'MAIL': '/var/mail/cltbld', 16:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:51 INFO - 'MOZ_NO_REMOTE': '1', 16:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:51 INFO - 'NO_EM_RESTART': '1', 16:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:51 INFO - 'PWD': '/builds/slave/test', 16:09:51 INFO - 'SHELL': '/bin/bash', 16:09:51 INFO - 'SHLVL': '1', 16:09:51 INFO - 'TERM': 'linux', 16:09:51 INFO - 'TMOUT': '86400', 16:09:51 INFO - 'USER': 'cltbld', 16:09:51 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:51 INFO - '_': '/tools/buildbot/bin/python'} 16:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:09:51 INFO - Running setup.py (path:/tmp/pip-xHsuiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:09:51 INFO - Running setup.py (path:/tmp/pip-fVHZHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:09:51 INFO - Running setup.py (path:/tmp/pip-tLUmzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:09:51 INFO - Running setup.py (path:/tmp/pip-8G5xno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:09:51 INFO - Running setup.py (path:/tmp/pip-1FX813-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:09:51 INFO - Running setup.py (path:/tmp/pip-mwi5bW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:09:51 INFO - Running setup.py (path:/tmp/pip-XZZr2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:09:51 INFO - Running setup.py (path:/tmp/pip-DyQoEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:09:51 INFO - Running setup.py (path:/tmp/pip-B3wctj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:09:51 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)) 16:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:09:51 INFO - Running setup.py (path:/tmp/pip-KhfUGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 16:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:09:52 INFO - Running setup.py (path:/tmp/pip-Bh_bzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:09:52 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)) 16:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:09:52 INFO - Running setup.py (path:/tmp/pip-cnYtm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:09:52 INFO - Running setup.py (path:/tmp/pip-bJ2MPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:09:52 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)) 16:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:09:52 INFO - Running setup.py (path:/tmp/pip-bEe7r7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 16:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:52 INFO - Running setup.py (path:/tmp/pip-s6y5jP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:52 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)) 16:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:09:52 INFO - Running setup.py (path:/tmp/pip-jvs0uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:09:52 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)) 16:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:09:52 INFO - Running setup.py (path:/tmp/pip-S6L5LV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:09:52 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)) 16:09:52 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)) 16:09:52 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)) 16:09:52 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)) 16:09:52 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)) 16:09:52 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)) 16:09:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09:54 INFO - Downloading blessings-1.6.tar.gz 16:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:09:54 INFO - Installing collected packages: blessings 16:09:54 INFO - Running setup.py install for blessings 16:09:54 INFO - Successfully installed blessings 16:09:54 INFO - Cleaning up... 16:09:54 INFO - Return code: 0 16:09:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:09:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:09:54 INFO - Reading from file tmpfile_stdout 16:09:54 INFO - Current package versions: 16:09:54 INFO - argparse == 1.2.1 16:09:54 INFO - blessings == 1.6 16:09:54 INFO - blobuploader == 1.2.4 16:09:54 INFO - docopt == 0.6.1 16:09:54 INFO - functools32 == 3.2.3-2 16:09:54 INFO - jsonschema == 2.5.1 16:09:54 INFO - manifestparser == 1.1 16:09:54 INFO - mozInstall == 1.12 16:09:54 INFO - mozcrash == 0.17 16:09:54 INFO - mozdebug == 0.1 16:09:54 INFO - mozdevice == 0.48 16:09:54 INFO - mozfile == 1.2 16:09:54 INFO - mozhttpd == 0.7 16:09:54 INFO - mozinfo == 0.9 16:09:54 INFO - mozleak == 0.1 16:09:54 INFO - mozlog == 3.4 16:09:54 INFO - moznetwork == 0.27 16:09:54 INFO - mozprocess == 0.23 16:09:54 INFO - mozprofile == 0.28 16:09:54 INFO - mozrunner == 6.12 16:09:54 INFO - mozscreenshot == 0.1 16:09:54 INFO - mozsystemmonitor == 0.3 16:09:54 INFO - moztest == 0.7 16:09:54 INFO - mozversion == 1.4 16:09:54 INFO - psutil == 3.1.1 16:09:54 INFO - requests == 1.2.3 16:09:54 INFO - wsgiref == 0.1.2 16:09:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:54 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 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09:54 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 16:09:54 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 16:09:54 INFO - Using env: {'DISPLAY': ':0', 16:09:54 INFO - 'HOME': '/home/cltbld', 16:09:54 INFO - 'LANG': 'en_US.UTF-8', 16:09:54 INFO - 'LANGUAGE': 'en_US:en', 16:09:54 INFO - 'LOGNAME': 'cltbld', 16:09:54 INFO - 'MAIL': '/var/mail/cltbld', 16:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:54 INFO - 'MOZ_NO_REMOTE': '1', 16:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:54 INFO - 'NO_EM_RESTART': '1', 16:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:54 INFO - 'PWD': '/builds/slave/test', 16:09:54 INFO - 'SHELL': '/bin/bash', 16:09:54 INFO - 'SHLVL': '1', 16:09:54 INFO - 'TERM': 'linux', 16:09:54 INFO - 'TMOUT': '86400', 16:09:54 INFO - 'USER': 'cltbld', 16:09:54 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:54 INFO - '_': '/tools/buildbot/bin/python'} 16:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 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)) 16:09:55 INFO - Cleaning up... 16:09:55 INFO - Return code: 0 16:09:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe939df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, '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': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', '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 16:09: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', 'jsonschema'] in /builds/slave/test/build 16:09: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 jsonschema 16:09:55 INFO - Using env: {'DISPLAY': ':0', 16:09:55 INFO - 'HOME': '/home/cltbld', 16:09:55 INFO - 'LANG': 'en_US.UTF-8', 16:09:55 INFO - 'LANGUAGE': 'en_US:en', 16:09:55 INFO - 'LOGNAME': 'cltbld', 16:09:55 INFO - 'MAIL': '/var/mail/cltbld', 16:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:55 INFO - 'MOZ_NO_REMOTE': '1', 16:09:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:55 INFO - 'NO_EM_RESTART': '1', 16:09:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:55 INFO - 'PWD': '/builds/slave/test', 16:09:55 INFO - 'SHELL': '/bin/bash', 16:09:55 INFO - 'SHLVL': '1', 16:09:55 INFO - 'TERM': 'linux', 16:09:55 INFO - 'TMOUT': '86400', 16:09:55 INFO - 'USER': 'cltbld', 16:09:55 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:55 INFO - '_': '/tools/buildbot/bin/python'} 16:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:09:55 INFO - Cleaning up... 16:09:55 INFO - Return code: 0 16:09:55 INFO - Running post-action listener: _resource_record_post_action 16:09:55 INFO - Running post-action listener: _start_resource_monitoring 16:09:55 INFO - Starting resource monitoring. 16:09:55 INFO - [mozharness: 2016-09-19 23:09:55.362841Z] Finished create-virtualenv step (success) 16:09:55 INFO - [mozharness: 2016-09-19 23:09:55.363299Z] Running install step. 16:09:55 INFO - Running pre-action listener: _resource_record_pre_action 16:09:55 INFO - Running main action method: install 16:09:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:09:55 INFO - Reading from file tmpfile_stdout 16:09:55 INFO - Detecting whether we're running mozinstall >=1.0... 16:09:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:09:55 INFO - Reading from file tmpfile_stdout 16:09:55 INFO - Output received: 16:09:55 INFO - Usage: mozinstall [options] installer 16:09:55 INFO - Options: 16:09:55 INFO - -h, --help show this help message and exit 16:09:55 INFO - -d DEST, --destination=DEST 16:09:55 INFO - Directory to install application into. [default: 16:09:55 INFO - "/builds/slave/test"] 16:09:55 INFO - --app=APP Application being installed. [default: firefox] 16:09:55 INFO - mkdir: /builds/slave/test/build/application 16:09:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:10:11 INFO - Reading from file tmpfile_stdout 16:10:11 INFO - Output received: 16:10:11 INFO - /builds/slave/test/build/application/firefox/firefox 16:10:11 INFO - Running post-action listener: _resource_record_post_action 16:10:11 INFO - [mozharness: 2016-09-19 23:10:11.717087Z] Finished install step (success) 16:10:11 INFO - [mozharness: 2016-09-19 23:10:11.717422Z] Running run-tests step. 16:10:11 INFO - Running pre-action listener: _resource_record_pre_action 16:10:11 INFO - Running main action method: run_tests 16:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:10:11 INFO - Python 2.7.3 16:10:11 INFO - Return code: 0 16:10:11 INFO - grabbing minidump binary from tooltool 16:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:11 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 0x20d6f60>, '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 0x20d7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d81d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:10:11 INFO - Return code: 0 16:10:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:10:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:10:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:10:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:10:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 16:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 16:10:11 INFO - Using env: {'DISPLAY': ':0', 16:10:11 INFO - 'HOME': '/home/cltbld', 16:10:11 INFO - 'LANG': 'en_US.UTF-8', 16:10:11 INFO - 'LANGUAGE': 'en_US:en', 16:10:11 INFO - 'LOGNAME': 'cltbld', 16:10:11 INFO - 'MAIL': '/var/mail/cltbld', 16:10:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:10:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:11 INFO - 'MOZ_NO_REMOTE': '1', 16:10:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:11 INFO - 'NO_EM_RESTART': '1', 16:10:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:11 INFO - 'PWD': '/builds/slave/test', 16:10:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:10:11 INFO - 'SHELL': '/bin/bash', 16:10:11 INFO - 'SHLVL': '1', 16:10:11 INFO - 'TERM': 'linux', 16:10:11 INFO - 'TMOUT': '86400', 16:10:11 INFO - 'USER': 'cltbld', 16:10:11 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727', 16:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:11 INFO - '_': '/tools/buildbot/bin/python'} 16:10:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 16:10:11 INFO - mozversion application_buildid: 20160919065232 16:10:11 INFO - mozversion application_changeset: 5dddbefdf759f09b1411f33fa0920835b919fc81 16:10:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:10:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:10:11 INFO - mozversion application_name: Firefox 16:10:11 INFO - mozversion application_remotingname: firefox-dev 16:10:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:10:11 INFO - mozversion application_vendor: Mozilla 16:10:11 INFO - mozversion application_version: 51.0a2 16:10:11 INFO - mozversion platform_buildid: 20160919065232 16:10:11 INFO - mozversion platform_changeset: 5dddbefdf759f09b1411f33fa0920835b919fc81 16:10:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:10:11 INFO - mozversion platform_version: 51.0a2 16:10:11 INFO - using testdate: 1474326611 16:10:11 INFO - actual date: 1474326611 16:10:11 INFO - starting webserver on 'localhost:38881' 16:10:11 INFO - SUITE-START | Running 2 tests 16:10:11 INFO - TEST-START | dromaeo_css 16:10:11 INFO - operating with platform_type : linux_ 16:10:11 INFO - Initialising browser for dromaeo_css test... 16:10:12 INFO - TEST-INFO | started process 1255 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHtWzwz/profile http://localhost:38881/getInfo.html) 16:10:16 INFO - PROCESS | 1255 | __metrics Screen width/height:1600/1200 16:10:16 INFO - PROCESS | 1255 | colorDepth:24 16:10:16 INFO - PROCESS | 1255 | Browser inner width/height: 1024/697 16:10:16 INFO - PROCESS | 1255 | __metrics 16:10:16 INFO - PROCESS | 1255 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:10:16 INFO - PROCESS | 1255 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:10:17 INFO - PROCESS | 1255 | console.error: PushService: 16:10:17 INFO - PROCESS | 1255 | stateChangeProcessEnqueue: Error transitioning state 16:10:17 INFO - PROCESS | 1255 | UnknownError 16:10:17 INFO - TEST-INFO | 1255: exit 0 16:10:17 INFO - Browser initialized. 16:10:17 INFO - Running cycle 1/1 for dromaeo_css test... 16:10:17 INFO - TEST-INFO | started process 1347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHtWzwz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:29 INFO - PROCESS | 1347 | RSS: Main: 145215488 16:10:29 INFO - PROCESS | 1347 | 16:10:36 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 16:10:48 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 16:11:07 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.16635 seconds. 16:11:16 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.04997 seconds. 16:11:20 INFO - PROCESS | 1347 | 1474326680485 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] 16:11:58 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 16:12:00 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.06711 seconds. 16:12:29 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01599 seconds. 16:12:34 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.1662 seconds. 16:12:52 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01616 seconds. 16:13:15 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01601 seconds. 16:13:29 INFO - PROCESS | 1347 | Cycle 1(1): loaded http://localhost:38881/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38881/tests/dromaeo/cssquery-ext.html) 16:13:30 INFO - PROCESS | 1347 | RSS: Main: 242778112 16:13:30 INFO - PROCESS | 1347 | 16:13:36 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.04986 seconds. 16:13:57 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 16:14:31 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01614 seconds. 16:14:35 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:14:42 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01709 seconds. 16:15:06 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 16:15:22 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 16:15:30 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00079 seconds. 16:15:35 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 16:15:36 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00066 seconds. 16:15:46 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 16:15:48 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 16:15:55 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01644 seconds. 16:16:00 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 16:16:30 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 16:16:32 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01652 seconds. 16:16:32 INFO - PROCESS | 1347 | Cycle 1(1): loaded http://localhost:38881/tests/dromaeo/cssquery-ext.html (next: http://localhost:38881/tests/dromaeo/cssquery-jquery.html) 16:16:33 INFO - PROCESS | 1347 | RSS: Main: 225906688 16:16:33 INFO - PROCESS | 1347 | 16:16:39 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 16:16:53 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00073 seconds. 16:16:59 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01598 seconds. 16:17:36 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 16:17:46 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:18:00 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00084 seconds. 16:18:04 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 16:18:16 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:18:22 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 16:18:35 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:19:00 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01681 seconds. 16:19:03 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01725 seconds. 16:19:07 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 16:19:19 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00194 seconds. 16:19:25 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 16:19:35 INFO - PROCESS | 1347 | Cycle 1(1): loaded http://localhost:38881/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38881/tests/dromaeo/cssquery-mootools.html) 16:19:36 INFO - PROCESS | 1347 | RSS: Main: 223084544 16:19:36 INFO - PROCESS | 1347 | 16:19:39 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:19:46 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 16:19:48 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01635 seconds. 16:20:37 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:21:25 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 16:21:39 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 16:21:57 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00068 seconds. 16:22:02 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01598 seconds. 16:22:08 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00071 seconds. 16:22:38 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.017 seconds. 16:22:38 INFO - PROCESS | 1347 | Cycle 1(1): loaded http://localhost:38881/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38881/tests/dromaeo/cssquery-prototype.html) 16:22:38 INFO - PROCESS | 1347 | RSS: Main: 175165440 16:22:38 INFO - PROCESS | 1347 | 16:22:55 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01711 seconds. 16:22:59 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:23:12 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01657 seconds. 16:23:15 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:23:29 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.05092 seconds. 16:23:31 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 16:23:42 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01681 seconds. 16:24:00 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01704 seconds. 16:24:12 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 16:24:33 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01648 seconds. 16:24:36 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 16:25:17 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00015 seconds. 16:25:39 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 16:25:41 INFO - PROCESS | 1347 | Cycle 1(1): loaded http://localhost:38881/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38881/tests/dromaeo/cssquery-yui.html) 16:25:41 INFO - PROCESS | 1347 | RSS: Main: 176238592 16:25:41 INFO - PROCESS | 1347 | 16:25:47 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.03312 seconds. 16:25:49 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 16:25:50 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00057 seconds. 16:25:59 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:26:10 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:26:40 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01621 seconds. 16:26:46 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 16:26:51 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01698 seconds. 16:27:19 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 16:27:37 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00031 seconds. 16:28:05 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.01628 seconds. 16:28:32 INFO - PROCESS | 1347 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:28:44 INFO - PROCESS | 1347 | Cycle 1(1): loaded http://localhost:38881/tests/dromaeo/cssquery-yui.html (next: http://localhost:38881/tests/dromaeo/cssquery-dojo.html) 16:28:44 INFO - PROCESS | 1347 | RSS: Main: 268439552 16:28:44 INFO - PROCESS | 1347 | 16:28:44 INFO - PROCESS | 1347 | __start_tp_report 16:28:44 INFO - PROCESS | 1347 | _x_x_mozilla_page_load 16:28:44 INFO - PROCESS | 1347 | _x_x_mozilla_page_load_details 16:28:44 INFO - PROCESS | 1347 | |i|pagename|runs| 16:28:44 INFO - PROCESS | 1347 | |0;dojo.html;3056.862441190146;3093.8607762650677;3118.5478105674665;3131.3737252549467;3155.605549306337;5492.176173573961;5575.330960284761;5580.748866301021;5611.242482264898;5618.550515958724;5546.223528705985;5612.803551875685;5615.185798059279;5625.240592520008;5635.520980716634;5033.773480193382;5053.343065401493;5097.286379906816;5122.769475373599;5135.075686376451;4844.467108618067;4859.878503037435;5089.058524173021;5129.820456284031;5132.5124113209185;4961.776720047606;4969.527894849984;5006.349174607314;5020.723860187688;5055.115354812911;5020.472850350716;5027.773750181233;5095.643304968654;5096.337476128079;5129;4679.040796636681;4986.326845875797;5007.6244281678655;5039.496050394968;5068;5192.299039629639;5398.2982212312545;5400.513953744182;5491.28613280275;5546.833594992157;5905.379935106838;5930.466258036798;6044.939550604482;6047.879042419127;6055.061465472859;5707.714614269312;6078.544109191828;6095.908561371583;6096.237970253718;6102.572819902609;5356.758945847446;5560.165975103726;5619.915701264485;5625.46558076982;5654;6183.659898705614;6191.752329906843;6194.566380353423;6198.659073750987;6223.37766223374;5628.183913332626;5955.731992060368;5963.642181469126;5966.642001479925;5975.850603734854;5746.051901436216;5842.269716285465;5874.9118763218585;5885.852853678607;5979.1629171915965;5899.321578018583;5900.64596124234;5903.3801450847905;5915.970420147872;5921.792737254175;5881.235439392937;5911.083782013795;5937.465628093492;5947.256592925884;5972.701364931736;4403.053343531156;4438.090191510797;4553.6357091433265;4567.748773817472;4581.289900065495;4067.247559201557;4082.0611259409907;4085.0885799809575;4110.568390319033;4119.9176016480105;5711.543076553866;5714.457126572969;5722.713864306851;5723.742431590588;5755.62588431759;5718.313802343745;5767.106098554731;5908.320543137508;5940.079287710412;6016.308124565643;5686.545076393879;5687.943120568824;5699.344575373885;5720.313562372458;5722.885542289131;5027.396712394536;5033.446320904697;5034.421041580265;5040.59675225981;5055.7219352935945;5700.258966334433;5816.5055970243;6100.1459795629535;6162.845928851814;6168.321484636686;5953.493953014043;6012.308584512837;6017.428344307284;6024.518038556993;6063.8180854572665;6182.381761823782;6185.84535386619;6190.907136393048;6192.59748116371;6199.938000620116;5489.204065410573;5901.616394934315;5906.232189815297;5919.881602368015;5920.970395147997;5669.829905102853;5898.20374249488;5936.732847021808;5951.345352011189;6056.90914636272;5818.214541036906;5845.064789633639;5877.559183061373;5878.941210587839;5881.206037184926;5873.236479257669;5874.236349274567;5879.118132280192;5884.676342801187;5886.941130588811;5453.945460545502;5565.1374037024325;5744.770209191585;5793.188953546591;5812.94187058141;5901.114832775118;5959.4934430573885;5966.463018328372;5973.522118230618;5985.820425387245;5465.207544906046;5556.16657501378;5815.651060936358;5819.098039803837;5822.912656310074 16:28:44 INFO - PROCESS | 1347 | |1;ext.html;592.51709856467;621.8880601491732;638.6359774928292;642.775028739941;667.7896462614273;35309.29381412373;36010;45123;45719.085618287616;45921.770391148;45034;49240.261396079;49303.753481232634;49361.50638493614;49431;9087.81824363514;9263.397879137869;9268.582913768896;9271.675491357804;9287.628494860232;8455.196756308142;8688.956555217215;10558.577656893714;10787.83818242727;10803.729906752355;11362.204645674807;11443;11445.771084578304;11446.198766086378;11447.313161210353;8433.957830210871;8642.913570864273;11175.497102630401;11186.384748838811;11221.607243746512;10221.591136354537;10454.52954617044;10466.581336746522;10472.109870661003;10477;37598.24803503915;37819.810900945595;37839.432408513894;37929.431058534145;37982.24035519274;3745.850165993384;3765.4728338032696;3794.677452416548;3796.658300752918;3941.5467221269632;34722.82638586816;34890.127746806276;34899.82550087259;35094.29811403783;35592.82203588966;4564.2240819060835;4658.394408726877;4698.271767875985;4728.527147285278;4768.1178981888115;382804.9999999972;383009;383122;383610;384215;62677.68661156665;63308.36691633117;63539.364606353345;63655.681721592024;63808.36191638117;40635;41422;41575.792121039805;43455.34816977748;43586.346204806316;19037.14332855025;19166.329178478965;19166.90416547908;19170.424887253124;19173.61652766937;13020.804687929689;13305.40125694346;15294.770578441332;15395.538133856002;15397.692046159014;19874;20647.793522064887;20650.690239646417;21232.256871009442;21389.25137620176;7412.740554080581;7585.62071896403;7629.8855517167285;7644.464887457936;7675.846483070419;1033.4677641014882;1052.5737076484036;1066.3814987307344;1112.3270421395102;1124.9493772780081;33970;34298.31403371968;34456.310873782386;34598;35067.298654026774;1109.678193323927;1123.1351859068473;1132.4620805117504;1210.1347536511262;1215.7325388414536;16089.865777142422;16870.15649217534;16901.492955211364;16961.321547138225;17020.914895425445;556786;561332;561773;562880;564167;50721;51435;51508.484915151115;51958;52222.47777522176;1292.2246652008907;1327.1373607155233;1386.7573174694387;1410.4710733474947;1472.433113251406;30095.69904300929;31181.8440907794;31395.686043139278;32165.517517237728;32508.674913250565;47366;47510.04979900452;47624;47713.28430073621;47776.283355748994;438404;439426;439966;440444;442827;30340.696593033786;31059.068227953197;31250.21874453157;32372;32686.34627307488;50673.23990140077;50707.55884770414;50805;51863;52284.21573676322;42616.57383426126;43045.784771075945;43247.35128973005;43841.34237986497;44914.32628510641;36679.26641467102;36922;37131.628683712814;37425;37610.247795044495;42390.15219695527;42459;43211;43368.56631433645;43781;36755.44866826946;37990;38641.6135838638;38688;39013;28802.711972880003;29100.854495727384;29315.267118322212;29611.851940740158;29717.405651887275 16:28:44 INFO - PROCESS | 1347 | |2;jquery.html;398.8484375937143;409.8012463954983;425.9765712885791;426.8847411198976;442.3342868982181;14955.775663365044;16427.4250401236;19891.403257902268;20437.489062773402;20693.999999999964;19133.425997220107;19334.419967400965;19395.418137455865;19530.90234548825;19660.311889083885;5833.270841144857;5842.094475356326;6018.548608854331;6093.238345206849;6097.115918191859;4001.9599804001878;6112.327643959161;6131.049687298476;6140.53945954053;6162.96918515409;4164.854230101947;6206.348333424993;6248.937510624904;6338.0492926061;6347.777827776029;6269.811905642838;6315.210598675166;6324.588901721373;6542.901856472109;6590.538662293641;5915.467607915309;5920.082387229986;5925.20009798676;5926.4073592640825;5926.822195334147;22739.431514212112;23717.644235336484;23741.169059082848;23806.285811425685;23965.760342396527;2392.5334559760854;2480.7519248075228;2489.5020995800733;2650.403659176689;2971.168072939559;10733.98027187416;10763.414366514697;11197.832032519518;11284.097272218203;11422.485988130555;2004.1296614175128;2006.7391239138965;2013.8489613278916;2068.6586713192305;2084.9061792219236;83175.58412207961;90078;91259.54370228107;93023;93964;11523.76952460946;11607.709807254887;11751;11773.17587768848;12069.646418948396;16393;16397.344106235618;16407.261673224733;16424;16424.75362869558;9027.23268522183;9084.40951338161;9168.816623667622;9184.403013804214;9359.812803743887;8926.598303076247;8994.325425593106;9012.935601895257;9173.58718857653;9234;5057.595392368602;5061.139606266944;5126.256692779526;5224.50367215114;5248.396434410015;2052.6305265052433;2135.2526615684387;2142.978570214309;2150.215171462436;2153.149505945143;755.4070055006794;882.8410886040446;931.7856892914667;985.6550207427487;1017.5827910556784;16802.075885826398;17151.313947442213;17245.827541724673;17374.565635859206;17527;952.6951375559754;964.0985678390587;978.5254151736402;981.102291403358;1066.2802608239563;9520.190783783317;9649.131578158;9792.510374481248;9835.311528192959;10008.349457285252;397432;403205;530049;533841;538941;57340.42659573351;67361.66319168471;100052;102080;102113.48943255385;1074.5594306334365;1074.9355038697704;1107.9335239885634;1114.4427786106946;1115.336374856975;13056.412461439068;15986.60033499172;17497;17874.19566119502;18097.18562664657;37504;39796;42441;44350.3347449795;45501;58207.126893097484;58720.70639646774;59192.40807591869;59495;61686;8036.1160272368825;8051.2351326623875;8154.796130096795;8567.700130495403;8639.784005399915;12314.630561083182;12685.492580296685;12857.71646400406;13610.455581776618;14125.788113178105;11381.601643942422;11805.173637845612;11938.701532461411;12216.205946613441;12484.063695222712;5738.512226460631;9910.554025068745;10843;11090.44547772592;11160.377311331667;12284.938575307066;12332.445039973043;12516.687082822638;12536.74926501483;12544.811827822408;8347.248747612744;9080.228180604458;9147.817043658957;9386.95306523463;9444.574994125418;6734.764283250062;12226.510939562319;12318.199317044364;12599.622011339674;12654.304013279358 16:28:44 INFO - PROCESS | 1347 | |3;mootools.html;1524.420720126352;1556.9688606227876;1646.7612196231546;1717.2444124585174;1792.7221280701497;8887.422317549363;9538.952305238481;9559.28305377096;9563.474008929516;9565.713028609143;7763.805904852367;7933.563653999027;7963.084245311791;8183.222593853575;9060.093990600937;2436.8172387070954;2507.0849140063838;2514.6228065790187;2539.1874600127967;2542.1229675761833;2534.2903986883703;2539.847609143448;2553.578659521186;2562.7565381288746;2567.961480577793;2544.5038217547585;2582.7417258274118;2590.0675756727564;2597.2078516052597;2621.9606705899428;2514.660520829683;2517.0309430869156;2517.7608127227886;2520.281719709883;2523.5331463679186;2395.317334559642;2418.8790560471907;2422.0190822716827;2448.755124487537;2453.9631905521433;8997.730068097968;9062.864057039149;9084.636614535411;9128.58921348541;9150.40522366044;5502.284702988626;5650.463205995424;5650.85872853182;5657.773689052433;5659.264295641581;3536.469529570559;3584.354816133095;3741.1769410729594;3763.5295588051495;3796.772193668389;1078.4769386847372;1105.160078340459;1110.5058249079163;1119.0879433261866;1121.5906194239126;13171.407286672124;13199.472021119143;13202.07585469027;13204.6698832529;13243.072984891061;4940.283658869444;4961.181405068123;5063.265826455144;5142.382914050338;5249.238860365225;11174.776504469864;11204.831927521094;11220.55117795279;11226.775464490664;11229.21395502307;5772.5670574707065;5783.884322313615;5790.768369265183;5796.88406231873;5800.187973683687;5155.278261043457;5190.40310364313;5221;5285.762140703678;5301.231321458367;4347.3261644445165;4351.586599273064;4431;4657.301404789308;4664.673472856935;1394.6095093373872;1398.5175114585452;1400.4818217259678;1406.1282005156868;1410.168000879486;991.861139440479;991.8710567626163;997.6607953295769;1005.5374527717332;1009.1170226052204;6440.130582371414;6485.967570162119;6530.118434011348;6596.317938925543;6702.430293425016;945.5886689290181;957.5690939077443;969.5879251318162;972.5963725054023;996.8155891160159;4117.5882411759185;4379.1022840317655;4407.6473882089995;4462.308342206963;4524.502304746476;11272;11616.593419230287;11617.954384105302;11627.360495172847;11832.88167118335;11673.708157295965;11675.591354302729;11679.299242045334;11807.704807379885;11848.76302473963;8394.916050839536;8429.494230346321;8560;8566.828663426697;8568.400211985101;6294.874102518015;6320.304766475776;6323.557350985387;6396.552241343247;6425.22897252314;10768.946155269174;11019;11079.667609971713;11113.944430278121;11128.833067504156;10397.272190946562;10641.04230619248;10952.178586606196;11165.330080195214;11169.664910052712;7952.125266220827;8109.310708589836;8301;8310.459820111915;8311.584420779058;8960;9250.074992500697;9314.301427393108;9318.347715659838;9318.62725491;7783;7905.169957154416;8075.555844428378;8152.144024877302;8305.584720763816;8048.154943730824;8151.470154439942;8165.305948994164;8231.753047408587;8232.300254478198;7042.823929401806;7124.679389427384;7158.642067896689;7159.606221657858;7170.67731952053;5441.401445841034;5449.455054494519;5493.478119578634;5517.089680202722;5645.181448689999;5583.776648933997;5585.329760428612;5624.521915637217;5691.117876729114;5708 16:28:44 INFO - PROCESS | 1347 | |4;prototype.html;344.7379991206683;350.41305812763625;359.75356880536833;367.6433859156617;374.68526437792246;5624.184493248476;5700.401457846923;5703.800366987156;5732.942670573293;5749.856253593663;5510.1459273812525;5534.308211473566;5656.349519805223;5669.518090962273;5675.943240567603;4150.3359462486005;4310.935335969963;4384.693071484998;4407.427034485512;4435.46774387074;4369.541198174194;4372.322290045048;4375.38744575758;4410.735355878642;4418.668599855007;4360.542143074979;4362.6727995400315;4372.8469503567385;4392.428984232045;4395.560443955611;4180.79096045198;4367.323064924942;4380.08018316154;4413.713108647928;4422.469303683546;3883.9029024274337;3891.6692081173132;3903.4535165076913;3910;3915.8237879295216;5464.371597266326;5473.480019398191;5489.670619762787;5515.7242137893345;5528.336599608073;4961.404631444249;5031.22016087507;5068.797248110071;5069.315642388304;5086.872828179288;5804.419558044204;5816.680082595498;5818.301803783531;5830;5848.1520179574145;4308.763018034007;4311.956880431187;4317.827286908519;4320.567943205686;4322.891927701801;5803.419658034205;5807.299605683335;5948.40515948406;6041.395860413967;6119.5410344224365;4360.215161270939;4480.260756975063;4486.551344865487;4488.887777805581;4524.434445694288;4580.656450766206;4590.9540904591195;4591.931121033187;4598.8620341389815;4603.654725895572;5070;5140.203268493389;5143.331366922276;5148.871278218075;5161.948380516222;5864.501517370986;5873.177755114288;5900.232969713909;5903.793367232126;5917.112433135064;1573.4728865830075;1590.4433448293005;1593.1158207195117;1600.343859017797;1607.1642745772133;961.2694352292308;1018.5416562546884;1025.3027941000043;1034.8602938603342;1039.7712503249272;5029.949700503022;5034.446210916796;5072.54347108762;5090.160123579642;5093.471958412461;5312.548433383128;5323.152113619195;5340.813071542555;5350.224217488442;5369.24830523727;4812.422509298906;5003.223855531234;5035.269885866602;5086.21163719624;5095.987085101364;2808.4804311202492;2815.746582807557;2829.2078218098964;2839.8154119982137;2850.6009158717798;5393.460653934653;5445.210444485607;5460.535854452337;5464.644798088111;5490.258815059916;5225.118957175414;5367.382750983609;5417.968261901787;5431.728413579305;5443.237946687467;5428.7285635718845;5439.238506609078;5441.455854414527;5457.590680698963;5465.338223390338;5258.605604579595;5273.868153296198;5315.916768274469;5374.946250537445;5684.8010319638615;6045;6047.516198704181;6062.42406971337;6065.72704228302;6077.4226448487325;5956.791512297049;6045.939540604538;6125.877482450237;6207.778710476919;6313.715882785194;4353;4369.781510924505;4380.802863871198;4400.559944005574;4404.779761012001;5764.308283006066;6097;6113.205283313141;6115.510759139347;6134.78528251509;5931.614445061057;5950.7917222896995;5951.464368206882;5952.107183922446;5969.940300597113;5941.31674857397;5942.524598032061;5948.494377977747;5950.375210602998;6014.308354539109;5718.742656580547;5904.852378690567;5933.614315069507;5946.613470124428;5962.373950735284;5911.231539899785;5955.136505206634;5956.315023772322;6015.338312785502;6025;5705.486506214461;5809.912851307318;5821.650700957956;5838.970805145947;5852.795152169653 16:28:44 INFO - PROCESS | 1347 | |5;yui.html;746.3432179681879;749.3106342165207;770.8573913825943;804.8953636027318;863.7149740585605;15042.548723538297;15978.68042639148;16130.435434759785;16136.031838089722;16138.919305403457;15673.216339183024;15881.444149454772;15904.761428578551;15910;15923.761143582855;853.8249658819942;873.4322690251324;875.9926085002267;877.7103555826594;878.0911756332198;838.6142374507734;845.1126317366771;863.2446609216935;864.3819668936709;865.3250464637592;792.1207459719707;793.888855560222;793.9642716077761;795.1809636074823;797.5334429358832;813.9633716482773;814.6293436486415;815.9796005099861;816.4692949582789;816.6651672814118;553.3608681972329;555.1173633922083;556.6576555418388;557.2421506750816;557.9832605021855;15296.770548441782;15306.464273750476;15311.310991005432;15317.693646127127;15321.310541025681;15557.299921503447;15607.531774046683;15646.530604081896;15686.607834804106;15973.840261597352;11924.70188245292;11952.820707689392;11968.461419236155;12011.759764804743;12190.451429685685;3986.4418981342637;3997.200559888034;4005.098852758106;4033.3546632538655;4057.7565346079036;71697;72113.63943180304;75941.62029189874;89139.55430222872;90435.09564904333;10886.727831804268;10980.945095274472;11056.502457389437;11229.550817967352;11745.941270293764;22722.659160112613;22795.430114247276;22899.08403663835;22974.65538016931;23006.654900176512;8856.424332418372;8981.775455613531;9013.819723605622;9021.097890210927;9114.705617887823;4788.95211047892;4795.568398844121;4833.154198015333;4894.559489645949;4944.505549445099;3823.655870971626;3973.443717879499;3987.860424885115;3997.700172487017;4000.419939108871;658.318640207383;659.7031335898864;670.540679634452;682.0315152483486;689.0972825598482;711.5019486359569;714.2072299747276;734.5372415378384;735.2757533829174;743.8177646476539;8700.651973920973;9594.712158635251;9736.853947190797;9990.350627209353;10045.447500387403;596.8179705189872;628.8113565930203;656.8554917918051;670.0953712488199;695.1796879681923;4398.296272596369;4788.04239152164;4975.814764887845;5326.201069839555;5450.945490545044;129347.35326323308;133524;133568.3321583386;135979.32010340082;137637;101236;110194;111154.88648124269;113342.43328783303;113552.43223783994;59107;61952;97333;103959.48020260001;104659;10471.738206544896;11052.11583073336;11063.170262230204;11136;11355.602553910574;41211;43483.347749784414;50100.498995009584;50697.74651126721;51466.74266628643;52846.735766320926;57160;68744.65627671829;70583.88248352558;71725;13768.65578360549;14116.153030818185;14180.290985450893;14287.285635717964;14590.708185836436;15207.391704331703;15361.769573456631;15418;15567.065976041475;15963.44127955516;14029.228392438514;14064.226467543975;14483.913706471845;14596.85403145955;14883.958122931292;14314.713705725619;14682;14771.556853294418;14860;15210.391584336945;14378.640533986734;14496.420143194155;14679.266036698336;15002.549923502313;15509.612259693598;11774.05807535378;12154.331511766937;12391.814122787984;12414.689632758891;12481.750364992831;12802.295873727035;12834.935825320814;12891.290978996245;13151.145175563557;13223.537176198786 16:28:44 INFO - PROCESS | 1347 | __end_tp_report 16:28:44 INFO - PROCESS | 1347 | __start_cc_report 16:28:44 INFO - PROCESS | 1347 | _x_x_mozilla_cycle_collect,819 16:28:44 INFO - PROCESS | 1347 | __end_cc_report 16:28:44 INFO - PROCESS | 1347 | __startTimestamp1474327724703__endTimestamp 16:28:44 INFO - PROCESS | 1347 | 16:28:44 INFO - PROCESS | 1347 | ------- Summary: start ------- 16:28:44 INFO - PROCESS | 1347 | Number of tests: 6 16:28:44 INFO - PROCESS | 1347 | 16:28:44 INFO - PROCESS | 1347 | [#0] dojo.html Cycles:165 Average:5519.36 Median:5722.89 stddev:652.86 (11.4%) stddev-sans-first:625.63 16:28:44 INFO - PROCESS | 1347 | Values: 3056.9 3093.9 3118.5 3131.4 3155.6 5492.2 5575.3 5580.7 5611.2 5618.6 5546.2 5612.8 5615.2 5625.2 5635.5 5033.8 5053.3 5097.3 5122.8 5135.1 4844.5 4859.9 5089.1 5129.8 5132.5 4961.8 4969.5 5006.3 5020.7 5055.1 5020.5 5027.8 5095.6 5096.3 5129.0 4679.0 4986.3 5007.6 5039.5 5068.0 5192.3 5398.3 5400.5 5491.3 5546.8 5905.4 5930.5 6044.9 6047.9 6055.1 5707.7 6078.5 6095.9 6096.2 6102.6 5356.8 5560.2 5619.9 5625.5 5654.0 6183.7 6191.8 6194.6 6198.7 6223.4 5628.2 5955.7 5963.6 5966.6 5975.9 5746.1 5842.3 5874.9 5885.9 5979.2 5899.3 5900.6 5903.4 5916.0 5921.8 5881.2 5911.1 5937.5 5947.3 5972.7 4403.1 4438.1 4553.6 4567.7 4581.3 4067.2 4082.1 4085.1 4110.6 4119.9 5711.5 5714.5 5722.7 5723.7 5755.6 5718.3 5767.1 5908.3 5940.1 6016.3 5686.5 5687.9 5699.3 5720.3 5722.9 5027.4 5033.4 5034.4 5040.6 5055.7 5700.3 5816.5 6100.1 6162.8 6168.3 5953.5 6012.3 6017.4 6024.5 6063.8 6182.4 6185.8 6190.9 6192.6 6199.9 5489.2 5901.6 5906.2 5919.9 5921.0 5669.8 5898.2 5936.7 5951.3 6056.9 5818.2 5845.1 5877.6 5878.9 5881.2 5873.2 5874.2 5879.1 5884.7 5886.9 5453.9 5565.1 5744.8 5793.2 5812.9 5901.1 5959.5 5966.5 5973.5 5985.8 5465.2 5556.2 5815.7 5819.1 5822.9 16:28:44 INFO - PROCESS | 1347 | 16:28:44 INFO - PROCESS | 1347 | [#1] ext.html Cycles:180 Average:62088.48 Median:3795.67 stddev:123924.54 (3264.9%) stddev-sans-first:124186.17 16:28:44 INFO - PROCESS | 1347 | Values: 592.5 621.9 638.6 642.8 667.8 35309.3 36010.0 45123.0 45719.1 45921.8 45034.0 49240.3 49303.8 49361.5 49431.0 9087.8 9263.4 9268.6 9271.7 9287.6 8455.2 8689.0 10558.6 10787.8 10803.7 11362.2 11443.0 11445.8 11446.2 11447.3 8434.0 8642.9 11175.5 11186.4 11221.6 10221.6 10454.5 10466.6 10472.1 10477.0 37598.2 37819.8 37839.4 37929.4 37982.2 3745.9 3765.5 3794.7 3796.7 3941.5 34722.8 34890.1 34899.8 35094.3 35592.8 4564.2 4658.4 4698.3 4728.5 4768.1 382805.0 383009.0 383122.0 383610.0 384215.0 62677.7 63308.4 63539.4 63655.7 63808.4 40635.0 41422.0 41575.8 43455.3 43586.3 19037.1 19166.3 19166.9 19170.4 19173.6 13020.8 13305.4 15294.8 15395.5 15397.7 19874.0 20647.8 20650.7 21232.3 21389.3 7412.7 7585.6 7629.9 7644.5 7675.8 1033.5 1052.6 1066.4 1112.3 1124.9 33970.0 34298.3 34456.3 34598.0 35067.3 1109.7 1123.1 1132.5 1210.1 1215.7 16089.9 16870.2 16901.5 16961.3 17020.9 556786.0 561332.0 561773.0 562880.0 564167.0 50721.0 51435.0 51508.5 51958.0 52222.5 1292.2 1327.1 1386.8 1410.5 1472.4 30095.7 31181.8 31395.7 32165.5 32508.7 47366.0 47510.0 47624.0 47713.3 47776.3 438404.0 439426.0 439966.0 440444.0 442827.0 30340.7 31059.1 31250.2 32372.0 32686.3 50673.2 50707.6 50805.0 51863.0 52284.2 42616.6 43045.8 43247.4 43841.3 44914.3 36679.3 36922.0 37131.6 37425.0 37610.2 42390.2 42459.0 43211.0 43368.6 43781.0 36755.4 37990.0 38641.6 38688.0 39013.0 28802.7 29100.9 29315.3 29611.9 29717.4 16:28:44 INFO - PROCESS | 1347 | 16:28:44 INFO - PROCESS | 1347 | [#2] jquery.html Cycles:180 Average:28937.69 Median:20097.42 stddev:80183.29 (399.0%) stddev-sans-first:80379.59 16:28:44 INFO - PROCESS | 1347 | Values: 398.8 409.8 426.0 426.9 442.3 14955.8 16427.4 19891.4 20437.5 20694.0 19133.4 19334.4 19395.4 19530.9 19660.3 5833.3 5842.1 6018.5 6093.2 6097.1 4002.0 6112.3 6131.0 6140.5 6163.0 4164.9 6206.3 6248.9 6338.0 6347.8 6269.8 6315.2 6324.6 6542.9 6590.5 5915.5 5920.1 5925.2 5926.4 5926.8 22739.4 23717.6 23741.2 23806.3 23965.8 2392.5 2480.8 2489.5 2650.4 2971.2 10734.0 10763.4 11197.8 11284.1 11422.5 2004.1 2006.7 2013.8 2068.7 2084.9 83175.6 90078.0 91259.5 93023.0 93964.0 11523.8 11607.7 11751.0 11773.2 12069.6 16393.0 16397.3 16407.3 16424.0 16424.8 9027.2 9084.4 9168.8 9184.4 9359.8 8926.6 8994.3 9012.9 9173.6 9234.0 5057.6 5061.1 5126.3 5224.5 5248.4 2052.6 2135.3 2143.0 2150.2 2153.1 755.4 882.8 931.8 985.7 1017.6 16802.1 17151.3 17245.8 17374.6 17527.0 952.7 964.1 978.5 981.1 1066.3 9520.2 9649.1 9792.5 9835.3 10008.3 397432.0 403205.0 530049.0 533841.0 538941.0 57340.4 67361.7 100052.0 102080.0 102113.5 1074.6 1074.9 1107.9 1114.4 1115.3 13056.4 15986.6 17497.0 17874.2 18097.2 37504.0 39796.0 42441.0 44350.3 45501.0 58207.1 58720.7 59192.4 59495.0 61686.0 8036.1 8051.2 8154.8 8567.7 8639.8 12314.6 12685.5 12857.7 13610.5 14125.8 11381.6 11805.2 11938.7 12216.2 12484.1 5738.5 9910.6 10843.0 11090.4 11160.4 12284.9 12332.4 12516.7 12536.7 12544.8 8347.2 9080.2 9147.8 9387.0 9444.6 6734.8 12226.5 12318.2 12599.6 12654.3 16:28:44 INFO - PROCESS | 1347 | 16:28:44 INFO - PROCESS | 1347 | [#3] mootools.html Cycles:180 Average:6184.24 Median:5148.83 stddev:3493.42 (67.8%) stddev-sans-first:3485.67 16:28:44 INFO - PROCESS | 1347 | Values: 1524.4 1557.0 1646.8 1717.2 1792.7 8887.4 9539.0 9559.3 9563.5 9565.7 7763.8 7933.6 7963.1 8183.2 9060.1 2436.8 2507.1 2514.6 2539.2 2542.1 2534.3 2539.8 2553.6 2562.8 2568.0 2544.5 2582.7 2590.1 2597.2 2622.0 2514.7 2517.0 2517.8 2520.3 2523.5 2395.3 2418.9 2422.0 2448.8 2454.0 8997.7 9062.9 9084.6 9128.6 9150.4 5502.3 5650.5 5650.9 5657.8 5659.3 3536.5 3584.4 3741.2 3763.5 3796.8 1078.5 1105.2 1110.5 1119.1 1121.6 13171.4 13199.5 13202.1 13204.7 13243.1 4940.3 4961.2 5063.3 5142.4 5249.2 11174.8 11204.8 11220.6 11226.8 11229.2 5772.6 5783.9 5790.8 5796.9 5800.2 5155.3 5190.4 5221.0 5285.8 5301.2 4347.3 4351.6 4431.0 4657.3 4664.7 1394.6 1398.5 1400.5 1406.1 1410.2 991.9 991.9 997.7 1005.5 1009.1 6440.1 6486.0 6530.1 6596.3 6702.4 945.6 957.6 969.6 972.6 996.8 4117.6 4379.1 4407.6 4462.3 4524.5 11272.0 11616.6 11618.0 11627.4 11832.9 11673.7 11675.6 11679.3 11807.7 11848.8 8394.9 8429.5 8560.0 8566.8 8568.4 6294.9 6320.3 6323.6 6396.6 6425.2 10768.9 11019.0 11079.7 11113.9 11128.8 10397.3 10641.0 10952.2 11165.3 11169.7 7952.1 8109.3 8301.0 8310.5 8311.6 8960.0 9250.1 9314.3 9318.3 9318.6 7783.0 7905.2 8075.6 8152.1 8305.6 8048.2 8151.5 8165.3 8231.8 8232.3 7042.8 7124.7 7158.6 7159.6 7170.7 5441.4 5449.5 5493.5 5517.1 5645.2 5583.8 5585.3 5624.5 5691.1 5708.0 16:28:44 INFO - PROCESS | 1347 | 16:28:44 INFO - PROCESS | 1347 | [#4] prototype.html Cycles:180 Average:4845.51 Median:5345.52 stddev:1389.82 (26.0%) stddev-sans-first:1352.04 16:28:44 INFO - PROCESS | 1347 | Values: 344.7 350.4 359.8 367.6 374.7 5624.2 5700.4 5703.8 5732.9 5749.9 5510.1 5534.3 5656.3 5669.5 5675.9 4150.3 4310.9 4384.7 4407.4 4435.5 4369.5 4372.3 4375.4 4410.7 4418.7 4360.5 4362.7 4372.8 4392.4 4395.6 4180.8 4367.3 4380.1 4413.7 4422.5 3883.9 3891.7 3903.5 3910.0 3915.8 5464.4 5473.5 5489.7 5515.7 5528.3 4961.4 5031.2 5068.8 5069.3 5086.9 5804.4 5816.7 5818.3 5830.0 5848.2 4308.8 4312.0 4317.8 4320.6 4322.9 5803.4 5807.3 5948.4 6041.4 6119.5 4360.2 4480.3 4486.6 4488.9 4524.4 4580.7 4591.0 4591.9 4598.9 4603.7 5070.0 5140.2 5143.3 5148.9 5161.9 5864.5 5873.2 5900.2 5903.8 5917.1 1573.5 1590.4 1593.1 1600.3 1607.2 961.3 1018.5 1025.3 1034.9 1039.8 5029.9 5034.4 5072.5 5090.2 5093.5 5312.5 5323.2 5340.8 5350.2 5369.2 4812.4 5003.2 5035.3 5086.2 5096.0 2808.5 2815.7 2829.2 2839.8 2850.6 5393.5 5445.2 5460.5 5464.6 5490.3 5225.1 5367.4 5418.0 5431.7 5443.2 5428.7 5439.2 5441.5 5457.6 5465.3 5258.6 5273.9 5315.9 5374.9 5684.8 6045.0 6047.5 6062.4 6065.7 6077.4 5956.8 6045.9 6125.9 6207.8 6313.7 4353.0 4369.8 4380.8 4400.6 4404.8 5764.3 6097.0 6113.2 6115.5 6134.8 5931.6 5950.8 5951.5 5952.1 5969.9 5941.3 5942.5 5948.5 5950.4 6014.3 5718.7 5904.9 5933.6 5946.6 5962.4 5911.2 5955.1 5956.3 6015.3 6025.0 5705.5 5809.9 5821.7 5839.0 5852.8 16:28:44 INFO - PROCESS | 1347 | 16:28:44 INFO - PROCESS | 1347 | [#5] yui.html Cycles:180 Average:21740.94 Median:3987.15 stddev:32243.05 (808.7%) stddev-sans-first:32294.96 16:28:44 INFO - PROCESS | 1347 | Values: 746.3 749.3 770.9 804.9 863.7 15042.5 15978.7 16130.4 16136.0 16138.9 15673.2 15881.4 15904.8 15910.0 15923.8 853.8 873.4 876.0 877.7 878.1 838.6 845.1 863.2 864.4 865.3 792.1 793.9 794.0 795.2 797.5 814.0 814.6 816.0 816.5 816.7 553.4 555.1 556.7 557.2 558.0 15296.8 15306.5 15311.3 15317.7 15321.3 15557.3 15607.5 15646.5 15686.6 15973.8 11924.7 11952.8 11968.5 12011.8 12190.5 3986.4 3997.2 4005.1 4033.4 4057.8 71697.0 72113.6 75941.6 89139.6 90435.1 10886.7 10980.9 11056.5 11229.6 11745.9 22722.7 22795.4 22899.1 22974.7 23006.7 8856.4 8981.8 9013.8 9021.1 9114.7 4789.0 4795.6 4833.2 4894.6 4944.5 3823.7 3973.4 3987.9 3997.7 4000.4 658.3 659.7 670.5 682.0 689.1 711.5 714.2 734.5 735.3 743.8 8700.7 9594.7 9736.9 9990.4 10045.4 596.8 628.8 656.9 670.1 695.2 4398.3 4788.0 4975.8 5326.2 5450.9 129347.4 133524.0 133568.3 135979.3 137637.0 101236.0 110194.0 111154.9 113342.4 113552.4 59107.0 61952.0 97333.0 103959.5 104659.0 10471.7 11052.1 11063.2 11136.0 11355.6 41211.0 43483.3 50100.5 50697.7 51466.7 52846.7 57160.0 68744.7 70583.9 71725.0 13768.7 14116.2 14180.3 14287.3 14590.7 15207.4 15361.8 15418.0 15567.1 15963.4 14029.2 14064.2 14483.9 14596.9 14884.0 14314.7 14682.0 14771.6 14860.0 15210.4 14378.6 14496.4 14679.3 15002.5 15509.6 11774.1 12154.3 12391.8 12414.7 12481.8 12802.3 12834.9 12891.3 13151.1 13223.5 16:28:44 INFO - PROCESS | 1347 | -------- Summary: end -------- 16:28:44 INFO - PROCESS | 1347 | 16:28:45 INFO - TEST-INFO | 1347: exit 0 16:28:45 INFO - TEST-OK | dromaeo_css | took 1113557ms 16:28:45 INFO - TEST-START | kraken 16:28:45 INFO - operating with platform_type : linux_ 16:28:45 INFO - Initialising browser for kraken test... 16:28:45 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ6jLAF/profile http://localhost:38881/getInfo.html) 16:28:49 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 16:28:49 INFO - PROCESS | 1670 | colorDepth:24 16:28:49 INFO - PROCESS | 1670 | Browser inner width/height: 1024/697 16:28:49 INFO - PROCESS | 1670 | __metrics 16:28:49 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:28:49 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:50 INFO - PROCESS | 1670 | console.error: PushService: 16:28:50 INFO - PROCESS | 1670 | stateChangeProcessEnqueue: Error transitioning state 16:28:50 INFO - PROCESS | 1670 | UnknownError 16:28:51 INFO - TEST-INFO | 1670: exit 0 16:28:51 INFO - Browser initialized. 16:28:51 INFO - Running cycle 1/1 for kraken test... 16:28:51 INFO - TEST-INFO | started process 1759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ6jLAF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:02 INFO - PROCESS | 1759 | RSS: Main: 144904192 16:29:02 INFO - PROCESS | 1759 | 16:29:05 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38881/tests/kraken/driver.html?testName=audio-beat-detection) 16:29:06 INFO - PROCESS | 1759 | RSS: Main: 186253312 16:29:06 INFO - PROCESS | 1759 | 16:29:08 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38881/tests/kraken/driver.html?testName=audio-dft) 16:29:09 INFO - PROCESS | 1759 | RSS: Main: 176058368 16:29:09 INFO - PROCESS | 1759 | 16:29:12 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38881/tests/kraken/driver.html?testName=audio-fft) 16:29:12 INFO - PROCESS | 1759 | RSS: Main: 266878976 16:29:12 INFO - PROCESS | 1759 | 16:29:14 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38881/tests/kraken/driver.html?testName=audio-oscillator) 16:29:15 INFO - PROCESS | 1759 | RSS: Main: 173334528 16:29:15 INFO - PROCESS | 1759 | 16:29:17 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38881/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:29:18 INFO - PROCESS | 1759 | RSS: Main: 174280704 16:29:18 INFO - PROCESS | 1759 | 16:29:21 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38881/tests/kraken/driver.html?testName=imaging-darkroom) 16:29:21 INFO - PROCESS | 1759 | RSS: Main: 210280448 16:29:21 INFO - PROCESS | 1759 | 16:29:25 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38881/tests/kraken/driver.html?testName=imaging-desaturate) 16:29:25 INFO - PROCESS | 1759 | RSS: Main: 209596416 16:29:25 INFO - PROCESS | 1759 | 16:29:28 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38881/tests/kraken/driver.html?testName=json-parse-financial) 16:29:29 INFO - PROCESS | 1759 | RSS: Main: 208236544 16:29:29 INFO - PROCESS | 1759 | 16:29:30 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38881/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:29:31 INFO - PROCESS | 1759 | RSS: Main: 171364352 16:29:31 INFO - PROCESS | 1759 | 16:29:32 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38881/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:29:33 INFO - PROCESS | 1759 | RSS: Main: 179642368 16:29:33 INFO - PROCESS | 1759 | 16:29:35 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38881/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:29:36 INFO - PROCESS | 1759 | RSS: Main: 183296000 16:29:36 INFO - PROCESS | 1759 | 16:29:38 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:29:39 INFO - PROCESS | 1759 | RSS: Main: 183664640 16:29:39 INFO - PROCESS | 1759 | 16:29:41 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:29:42 INFO - PROCESS | 1759 | RSS: Main: 182325248 16:29:42 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | Cycle 1(1): loaded http://localhost:38881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38881/tests/kraken/driver.html?testName=ai-astar) 16:29:44 INFO - PROCESS | 1759 | RSS: Main: 233635840 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | __start_tp_report 16:29:44 INFO - PROCESS | 1759 | _x_x_mozilla_page_load 16:29:44 INFO - PROCESS | 1759 | _x_x_mozilla_page_load_details 16:29:44 INFO - PROCESS | 1759 | |i|pagename|runs| 16:29:44 INFO - PROCESS | 1759 | |0;ai-astar;108;112;113;111;112;112;111;112;111;113 16:29:44 INFO - PROCESS | 1759 | |1;audio-beat-detection;149;117;118;154;122;120;122;124;153;117 16:29:44 INFO - PROCESS | 1759 | |2;audio-dft;163;162;162;162;163;160;162;160;160;161 16:29:44 INFO - PROCESS | 1759 | |3;audio-fft;75;76;77;105;75;77;77;78;97;75 16:29:44 INFO - PROCESS | 1759 | |4;audio-oscillator;82;82;109;82;82;82;99;82;81;123 16:29:44 INFO - PROCESS | 1759 | |5;imaging-gaussian-blur;117;117;117;117;118;117;118;117;116;117 16:29:44 INFO - PROCESS | 1759 | |6;imaging-darkroom;148;146;146;146;145;146;145;147;145;146 16:29:44 INFO - PROCESS | 1759 | |7;imaging-desaturate;95;94;95;95;95;95;94;95;95;95 16:29:44 INFO - PROCESS | 1759 | |8;json-parse-financial;51;50;51;54;49;49;50;50;62;49 16:29:44 INFO - PROCESS | 1759 | |9;json-stringify-tinderbox;59;61;57;57;58;58;58;59;58;58 16:29:44 INFO - PROCESS | 1759 | |10;stanford-crypto-aes;74;86;73;79;72;81;72;73;73;72 16:29:44 INFO - PROCESS | 1759 | |11;stanford-crypto-ccm;117;129;115;117;115;124;115;125;159;118 16:29:44 INFO - PROCESS | 1759 | |12;stanford-crypto-pbkdf2;152;149;149;152;152;154;152;149;149;151 16:29:44 INFO - PROCESS | 1759 | |13;stanford-crypto-sha256-iterative;63;62;61;61;63;60;60;68;60;68 16:29:44 INFO - PROCESS | 1759 | __end_tp_report 16:29:44 INFO - PROCESS | 1759 | __start_cc_report 16:29:44 INFO - PROCESS | 1759 | _x_x_mozilla_cycle_collect,1614 16:29:44 INFO - PROCESS | 1759 | __end_cc_report 16:29:44 INFO - PROCESS | 1759 | __startTimestamp1474327784272__endTimestamp 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | ------- Summary: start ------- 16:29:44 INFO - PROCESS | 1759 | Number of tests: 14 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.43 (1.3%) stddev-sans-first:0.78 16:29:44 INFO - PROCESS | 1759 | Values: 108.0 112.0 113.0 111.0 112.0 112.0 111.0 112.0 111.0 113.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:123.00 stddev:15.67 (12.7%) stddev-sans-first:14.97 16:29:44 INFO - PROCESS | 1759 | Values: 149.0 117.0 118.0 154.0 122.0 120.0 122.0 124.0 153.0 117.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.18 (0.7%) stddev-sans-first:1.12 16:29:44 INFO - PROCESS | 1759 | Values: 163.0 162.0 162.0 162.0 163.0 160.0 162.0 160.0 160.0 161.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#3] audio-fft Cycles:10 Average:81.20 Median:77.00 stddev:10.65 (13.8%) stddev-sans-first:11.06 16:29:44 INFO - PROCESS | 1759 | Values: 75.0 76.0 77.0 105.0 75.0 77.0 77.0 78.0 97.0 75.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:82.00 stddev:14.89 (18.2%) stddev-sans-first:15.48 16:29:44 INFO - PROCESS | 1759 | Values: 82.0 82.0 109.0 82.0 82.0 82.0 99.0 82.0 81.0 123.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 16:29:44 INFO - PROCESS | 1759 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 118.0 117.0 116.0 117.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.94 (0.6%) stddev-sans-first:0.67 16:29:44 INFO - PROCESS | 1759 | Values: 148.0 146.0 146.0 146.0 145.0 146.0 145.0 147.0 145.0 146.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:29:44 INFO - PROCESS | 1759 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:50.50 stddev:3.98 (7.9%) stddev-sans-first:4.22 16:29:44 INFO - PROCESS | 1759 | Values: 51.0 50.0 51.0 54.0 49.0 49.0 50.0 50.0 62.0 49.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.00 stddev:1.16 (2.0%) stddev-sans-first:1.20 16:29:44 INFO - PROCESS | 1759 | Values: 59.0 61.0 57.0 57.0 58.0 58.0 58.0 59.0 58.0 58.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:73.50 stddev:4.84 (6.6%) stddev-sans-first:5.10 16:29:44 INFO - PROCESS | 1759 | Values: 74.0 86.0 73.0 79.0 72.0 81.0 72.0 73.0 73.0 72.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#11] stanford-crypto-ccm Cycles:10 Average:123.40 Median:121.00 stddev:13.43 (11.1%) stddev-sans-first:14.05 16:29:44 INFO - PROCESS | 1759 | Values: 117.0 129.0 115.0 117.0 115.0 124.0 115.0 125.0 159.0 118.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.90 Median:152.00 stddev:1.79 (1.2%) stddev-sans-first:1.86 16:29:44 INFO - PROCESS | 1759 | Values: 152.0 149.0 149.0 152.0 152.0 154.0 152.0 149.0 149.0 151.0 16:29:44 INFO - PROCESS | 1759 | 16:29:44 INFO - PROCESS | 1759 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.50 stddev:3.06 (4.9%) stddev-sans-first:3.24 16:29:44 INFO - PROCESS | 1759 | Values: 63.0 62.0 61.0 61.0 63.0 60.0 60.0 68.0 60.0 68.0 16:29:44 INFO - PROCESS | 1759 | -------- Summary: end -------- 16:29:44 INFO - PROCESS | 1759 | 16:29:45 INFO - TEST-INFO | 1759: exit 0 16:29:45 INFO - TEST-OK | kraken | took 59766ms 16:29:45 INFO - SUITE-END | took 1173s 16:29:45 INFO - Completed test suite (00:19:33) 16:29:45 INFO - javascript benchmark 16:29:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3056.862441190146, 3093.8607762650677, 3118.5478105674665, 3131.3737252549467, 3155.605549306337, 5492.176173573961, 5575.330960284761, 5580.748866301021, 5611.242482264898, 5618.550515958724, 5546.223528705985, 5612.803551875685, 5615.185798059279, 5625.240592520008, 5635.520980716634, 5033.773480193382, 5053.343065401493, 5097.286379906816, 5122.769475373599, 5135.075686376451, 4844.467108618067, 4859.878503037435, 5089.058524173021, 5129.820456284031, 5132.5124113209185, 4961.776720047606, 4969.527894849984, 5006.349174607314, 5020.723860187688, 5055.115354812911, 5020.472850350716, 5027.773750181233, 5095.643304968654, 5096.337476128079, 5129.0, 4679.040796636681, 4986.326845875797, 5007.6244281678655, 5039.496050394968, 5068.0, 5192.299039629639, 5398.2982212312545, 5400.513953744182, 5491.28613280275, 5546.833594992157, 5905.379935106838, 5930.466258036798, 6044.939550604482, 6047.879042419127, 6055.061465472859, 5707.714614269312, 6078.544109191828, 6095.908561371583, 6096.237970253718, 6102.572819902609, 5356.758945847446, 5560.165975103726, 5619.915701264485, 5625.46558076982, 5654.0, 6183.659898705614, 6191.752329906843, 6194.566380353423, 6198.659073750987, 6223.37766223374, 5628.183913332626, 5955.731992060368, 5963.642181469126, 5966.642001479925, 5975.850603734854, 5746.051901436216, 5842.269716285465, 5874.9118763218585, 5885.852853678607, 5979.1629171915965, 5899.321578018583, 5900.64596124234, 5903.3801450847905, 5915.970420147872, 5921.792737254175, 5881.235439392937, 5911.083782013795, 5937.465628093492, 5947.256592925884, 5972.701364931736, 4403.053343531156, 4438.090191510797, 4553.6357091433265, 4567.748773817472, 4581.289900065495, 4067.247559201557, 4082.0611259409907, 4085.0885799809575, 4110.568390319033, 4119.9176016480105, 5711.543076553866, 5714.457126572969, 5722.713864306851, 5723.742431590588, 5755.62588431759, 5718.313802343745, 5767.106098554731, 5908.320543137508, 5940.079287710412, 6016.308124565643, 5686.545076393879, 5687.943120568824, 5699.344575373885, 5720.313562372458, 5722.885542289131, 5027.396712394536, 5033.446320904697, 5034.421041580265, 5040.59675225981, 5055.7219352935945, 5700.258966334433, 5816.5055970243, 6100.1459795629535, 6162.845928851814, 6168.321484636686, 5953.493953014043, 6012.308584512837, 6017.428344307284, 6024.518038556993, 6063.8180854572665, 6182.381761823782, 6185.84535386619, 6190.907136393048, 6192.59748116371, 6199.938000620116, 5489.204065410573, 5901.616394934315, 5906.232189815297, 5919.881602368015, 5920.970395147997, 5669.829905102853, 5898.20374249488, 5936.732847021808, 5951.345352011189, 6056.90914636272, 5818.214541036906, 5845.064789633639, 5877.559183061373, 5878.941210587839, 5881.206037184926, 5873.236479257669, 5874.236349274567, 5879.118132280192, 5884.676342801187, 5886.941130588811, 5453.945460545502, 5565.1374037024325, 5744.770209191585, 5793.188953546591, 5812.94187058141, 5901.114832775118, 5959.4934430573885, 5966.463018328372, 5973.522118230618, 5985.820425387245, 5465.207544906046, 5556.16657501378, 5815.651060936358, 5819.098039803837, 5822.912656310074], "value": 5473.593662917454, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.51709856467, 621.8880601491732, 638.6359774928292, 642.775028739941, 667.7896462614273, 35309.29381412373, 36010.0, 45123.0, 45719.085618287616, 45921.770391148, 45034.0, 49240.261396079, 49303.753481232634, 49361.50638493614, 49431.0, 9087.81824363514, 9263.397879137869, 9268.582913768896, 9271.675491357804, 9287.628494860232, 8455.196756308142, 8688.956555217215, 10558.577656893714, 10787.83818242727, 10803.729906752355, 11362.204645674807, 11443.0, 11445.771084578304, 11446.198766086378, 11447.313161210353, 8433.957830210871, 8642.913570864273, 11175.497102630401, 11186.384748838811, 11221.607243746512, 10221.591136354537, 10454.52954617044, 10466.581336746522, 10472.109870661003, 10477.0, 37598.24803503915, 37819.810900945595, 37839.432408513894, 37929.431058534145, 37982.24035519274, 3745.850165993384, 3765.4728338032696, 3794.677452416548, 3796.658300752918, 3941.5467221269632, 34722.82638586816, 34890.127746806276, 34899.82550087259, 35094.29811403783, 35592.82203588966, 4564.2240819060835, 4658.394408726877, 4698.271767875985, 4728.527147285278, 4768.1178981888115, 382804.9999999972, 383009.0, 383122.0, 383610.0, 384215.0, 62677.68661156665, 63308.36691633117, 63539.364606353345, 63655.681721592024, 63808.36191638117, 40635.0, 41422.0, 41575.792121039805, 43455.34816977748, 43586.346204806316, 19037.14332855025, 19166.329178478965, 19166.90416547908, 19170.424887253124, 19173.61652766937, 13020.804687929689, 13305.40125694346, 15294.770578441332, 15395.538133856002, 15397.692046159014, 19874.0, 20647.793522064887, 20650.690239646417, 21232.256871009442, 21389.25137620176, 7412.740554080581, 7585.62071896403, 7629.8855517167285, 7644.464887457936, 7675.846483070419, 1033.4677641014882, 1052.5737076484036, 1066.3814987307344, 1112.3270421395102, 1124.9493772780081, 33970.0, 34298.31403371968, 34456.310873782386, 34598.0, 35067.298654026774, 1109.678193323927, 1123.1351859068473, 1132.4620805117504, 1210.1347536511262, 1215.7325388414536, 16089.865777142422, 16870.15649217534, 16901.492955211364, 16961.321547138225, 17020.914895425445, 556786.0, 561332.0, 561773.0, 562880.0, 564167.0, 50721.0, 51435.0, 51508.484915151115, 51958.0, 52222.47777522176, 1292.2246652008907, 1327.1373607155233, 1386.7573174694387, 1410.4710733474947, 1472.433113251406, 30095.69904300929, 31181.8440907794, 31395.686043139278, 32165.517517237728, 32508.674913250565, 47366.0, 47510.04979900452, 47624.0, 47713.28430073621, 47776.283355748994, 438404.0, 439426.0, 439966.0, 440444.0, 442827.0, 30340.696593033786, 31059.068227953197, 31250.21874453157, 32372.0, 32686.34627307488, 50673.23990140077, 50707.55884770414, 50805.0, 51863.0, 52284.21573676322, 42616.57383426126, 43045.784771075945, 43247.35128973005, 43841.34237986497, 44914.32628510641, 36679.26641467102, 36922.0, 37131.628683712814, 37425.0, 37610.247795044495, 42390.15219695527, 42459.0, 43211.0, 43368.56631433645, 43781.0, 36755.44866826946, 37990.0, 38641.6135838638, 38688.0, 39013.0, 28802.711972880003, 29100.854495727384, 29315.267118322212, 29611.851940740158, 29717.405651887275], "value": 20935.925321564362, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.8484375937143, 409.8012463954983, 425.9765712885791, 426.8847411198976, 442.3342868982181, 14955.775663365044, 16427.4250401236, 19891.403257902268, 20437.489062773402, 20693.999999999964, 19133.425997220107, 19334.419967400965, 19395.418137455865, 19530.90234548825, 19660.311889083885, 5833.270841144857, 5842.094475356326, 6018.548608854331, 6093.238345206849, 6097.115918191859, 4001.9599804001878, 6112.327643959161, 6131.049687298476, 6140.53945954053, 6162.96918515409, 4164.854230101947, 6206.348333424993, 6248.937510624904, 6338.0492926061, 6347.777827776029, 6269.811905642838, 6315.210598675166, 6324.588901721373, 6542.901856472109, 6590.538662293641, 5915.467607915309, 5920.082387229986, 5925.20009798676, 5926.4073592640825, 5926.822195334147, 22739.431514212112, 23717.644235336484, 23741.169059082848, 23806.285811425685, 23965.760342396527, 2392.5334559760854, 2480.7519248075228, 2489.5020995800733, 2650.403659176689, 2971.168072939559, 10733.98027187416, 10763.414366514697, 11197.832032519518, 11284.097272218203, 11422.485988130555, 2004.1296614175128, 2006.7391239138965, 2013.8489613278916, 2068.6586713192305, 2084.9061792219236, 83175.58412207961, 90078.0, 91259.54370228107, 93023.0, 93964.0, 11523.76952460946, 11607.709807254887, 11751.0, 11773.17587768848, 12069.646418948396, 16393.0, 16397.344106235618, 16407.261673224733, 16424.0, 16424.75362869558, 9027.23268522183, 9084.40951338161, 9168.816623667622, 9184.403013804214, 9359.812803743887, 8926.598303076247, 8994.325425593106, 9012.935601895257, 9173.58718857653, 9234.0, 5057.595392368602, 5061.139606266944, 5126.256692779526, 5224.50367215114, 5248.396434410015, 2052.6305265052433, 2135.2526615684387, 2142.978570214309, 2150.215171462436, 2153.149505945143, 755.4070055006794, 882.8410886040446, 931.7856892914667, 985.6550207427487, 1017.5827910556784, 16802.075885826398, 17151.313947442213, 17245.827541724673, 17374.565635859206, 17527.0, 952.6951375559754, 964.0985678390587, 978.5254151736402, 981.102291403358, 1066.2802608239563, 9520.190783783317, 9649.131578158, 9792.510374481248, 9835.311528192959, 10008.349457285252, 397432.0, 403205.0, 530049.0, 533841.0, 538941.0, 57340.42659573351, 67361.66319168471, 100052.0, 102080.0, 102113.48943255385, 1074.5594306334365, 1074.9355038697704, 1107.9335239885634, 1114.4427786106946, 1115.336374856975, 13056.412461439068, 15986.60033499172, 17497.0, 17874.19566119502, 18097.18562664657, 37504.0, 39796.0, 42441.0, 44350.3347449795, 45501.0, 58207.126893097484, 58720.70639646774, 59192.40807591869, 59495.0, 61686.0, 8036.1160272368825, 8051.2351326623875, 8154.796130096795, 8567.700130495403, 8639.784005399915, 12314.630561083182, 12685.492580296685, 12857.71646400406, 13610.455581776618, 14125.788113178105, 11381.601643942422, 11805.173637845612, 11938.701532461411, 12216.205946613441, 12484.063695222712, 5738.512226460631, 9910.554025068745, 10843.0, 11090.44547772592, 11160.377311331667, 12284.938575307066, 12332.445039973043, 12516.687082822638, 12536.74926501483, 12544.811827822408, 8347.248747612744, 9080.228180604458, 9147.817043658957, 9386.95306523463, 9444.574994125418, 6734.764283250062, 12226.510939562319, 12318.199317044364, 12599.622011339674, 12654.304013279358], "value": 9418.138572543989, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.420720126352, 1556.9688606227876, 1646.7612196231546, 1717.2444124585174, 1792.7221280701497, 8887.422317549363, 9538.952305238481, 9559.28305377096, 9563.474008929516, 9565.713028609143, 7763.805904852367, 7933.563653999027, 7963.084245311791, 8183.222593853575, 9060.093990600937, 2436.8172387070954, 2507.0849140063838, 2514.6228065790187, 2539.1874600127967, 2542.1229675761833, 2534.2903986883703, 2539.847609143448, 2553.578659521186, 2562.7565381288746, 2567.961480577793, 2544.5038217547585, 2582.7417258274118, 2590.0675756727564, 2597.2078516052597, 2621.9606705899428, 2514.660520829683, 2517.0309430869156, 2517.7608127227886, 2520.281719709883, 2523.5331463679186, 2395.317334559642, 2418.8790560471907, 2422.0190822716827, 2448.755124487537, 2453.9631905521433, 8997.730068097968, 9062.864057039149, 9084.636614535411, 9128.58921348541, 9150.40522366044, 5502.284702988626, 5650.463205995424, 5650.85872853182, 5657.773689052433, 5659.264295641581, 3536.469529570559, 3584.354816133095, 3741.1769410729594, 3763.5295588051495, 3796.772193668389, 1078.4769386847372, 1105.160078340459, 1110.5058249079163, 1119.0879433261866, 1121.5906194239126, 13171.407286672124, 13199.472021119143, 13202.07585469027, 13204.6698832529, 13243.072984891061, 4940.283658869444, 4961.181405068123, 5063.265826455144, 5142.382914050338, 5249.238860365225, 11174.776504469864, 11204.831927521094, 11220.55117795279, 11226.775464490664, 11229.21395502307, 5772.5670574707065, 5783.884322313615, 5790.768369265183, 5796.88406231873, 5800.187973683687, 5155.278261043457, 5190.40310364313, 5221.0, 5285.762140703678, 5301.231321458367, 4347.3261644445165, 4351.586599273064, 4431.0, 4657.301404789308, 4664.673472856935, 1394.6095093373872, 1398.5175114585452, 1400.4818217259678, 1406.1282005156868, 1410.168000879486, 991.861139440479, 991.8710567626163, 997.6607953295769, 1005.5374527717332, 1009.1170226052204, 6440.130582371414, 6485.967570162119, 6530.118434011348, 6596.317938925543, 6702.430293425016, 945.5886689290181, 957.5690939077443, 969.5879251318162, 972.5963725054023, 996.8155891160159, 4117.5882411759185, 4379.1022840317655, 4407.6473882089995, 4462.308342206963, 4524.502304746476, 11272.0, 11616.593419230287, 11617.954384105302, 11627.360495172847, 11832.88167118335, 11673.708157295965, 11675.591354302729, 11679.299242045334, 11807.704807379885, 11848.76302473963, 8394.916050839536, 8429.494230346321, 8560.0, 8566.828663426697, 8568.400211985101, 6294.874102518015, 6320.304766475776, 6323.557350985387, 6396.552241343247, 6425.22897252314, 10768.946155269174, 11019.0, 11079.667609971713, 11113.944430278121, 11128.833067504156, 10397.272190946562, 10641.04230619248, 10952.178586606196, 11165.330080195214, 11169.664910052712, 7952.125266220827, 8109.310708589836, 8301.0, 8310.459820111915, 8311.584420779058, 8960.0, 9250.074992500697, 9314.301427393108, 9318.347715659838, 9318.62725491, 7783.0, 7905.169957154416, 8075.555844428378, 8152.144024877302, 8305.584720763816, 8048.154943730824, 8151.470154439942, 8165.305948994164, 8231.753047408587, 8232.300254478198, 7042.823929401806, 7124.679389427384, 7158.642067896689, 7159.606221657858, 7170.67731952053, 5441.401445841034, 5449.455054494519, 5493.478119578634, 5517.089680202722, 5645.181448689999, 5583.776648933997, 5585.329760428612, 5624.521915637217, 5691.117876729114, 5708.0], "value": 4952.833733354586, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.7379991206683, 350.41305812763625, 359.75356880536833, 367.6433859156617, 374.68526437792246, 5624.184493248476, 5700.401457846923, 5703.800366987156, 5732.942670573293, 5749.856253593663, 5510.1459273812525, 5534.308211473566, 5656.349519805223, 5669.518090962273, 5675.943240567603, 4150.3359462486005, 4310.935335969963, 4384.693071484998, 4407.427034485512, 4435.46774387074, 4369.541198174194, 4372.322290045048, 4375.38744575758, 4410.735355878642, 4418.668599855007, 4360.542143074979, 4362.6727995400315, 4372.8469503567385, 4392.428984232045, 4395.560443955611, 4180.79096045198, 4367.323064924942, 4380.08018316154, 4413.713108647928, 4422.469303683546, 3883.9029024274337, 3891.6692081173132, 3903.4535165076913, 3910.0, 3915.8237879295216, 5464.371597266326, 5473.480019398191, 5489.670619762787, 5515.7242137893345, 5528.336599608073, 4961.404631444249, 5031.22016087507, 5068.797248110071, 5069.315642388304, 5086.872828179288, 5804.419558044204, 5816.680082595498, 5818.301803783531, 5830.0, 5848.1520179574145, 4308.763018034007, 4311.956880431187, 4317.827286908519, 4320.567943205686, 4322.891927701801, 5803.419658034205, 5807.299605683335, 5948.40515948406, 6041.395860413967, 6119.5410344224365, 4360.215161270939, 4480.260756975063, 4486.551344865487, 4488.887777805581, 4524.434445694288, 4580.656450766206, 4590.9540904591195, 4591.931121033187, 4598.8620341389815, 4603.654725895572, 5070.0, 5140.203268493389, 5143.331366922276, 5148.871278218075, 5161.948380516222, 5864.501517370986, 5873.177755114288, 5900.232969713909, 5903.793367232126, 5917.112433135064, 1573.4728865830075, 1590.4433448293005, 1593.1158207195117, 1600.343859017797, 1607.1642745772133, 961.2694352292308, 1018.5416562546884, 1025.3027941000043, 1034.8602938603342, 1039.7712503249272, 5029.949700503022, 5034.446210916796, 5072.54347108762, 5090.160123579642, 5093.471958412461, 5312.548433383128, 5323.152113619195, 5340.813071542555, 5350.224217488442, 5369.24830523727, 4812.422509298906, 5003.223855531234, 5035.269885866602, 5086.21163719624, 5095.987085101364, 2808.4804311202492, 2815.746582807557, 2829.2078218098964, 2839.8154119982137, 2850.6009158717798, 5393.460653934653, 5445.210444485607, 5460.535854452337, 5464.644798088111, 5490.258815059916, 5225.118957175414, 5367.382750983609, 5417.968261901787, 5431.728413579305, 5443.237946687467, 5428.7285635718845, 5439.238506609078, 5441.455854414527, 5457.590680698963, 5465.338223390338, 5258.605604579595, 5273.868153296198, 5315.916768274469, 5374.946250537445, 5684.8010319638615, 6045.0, 6047.516198704181, 6062.42406971337, 6065.72704228302, 6077.4226448487325, 5956.791512297049, 6045.939540604538, 6125.877482450237, 6207.778710476919, 6313.715882785194, 4353.0, 4369.781510924505, 4380.802863871198, 4400.559944005574, 4404.779761012001, 5764.308283006066, 6097.0, 6113.205283313141, 6115.510759139347, 6134.78528251509, 5931.614445061057, 5950.7917222896995, 5951.464368206882, 5952.107183922446, 5969.940300597113, 5941.31674857397, 5942.524598032061, 5948.494377977747, 5950.375210602998, 6014.308354539109, 5718.742656580547, 5904.852378690567, 5933.614315069507, 5946.613470124428, 5962.373950735284, 5911.231539899785, 5955.136505206634, 5956.315023772322, 6015.338312785502, 6025.0, 5705.486506214461, 5809.912851307318, 5821.650700957956, 5838.970805145947, 5852.795152169653], "value": 4410.5635399582725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.3432179681879, 749.3106342165207, 770.8573913825943, 804.8953636027318, 863.7149740585605, 15042.548723538297, 15978.68042639148, 16130.435434759785, 16136.031838089722, 16138.919305403457, 15673.216339183024, 15881.444149454772, 15904.761428578551, 15910.0, 15923.761143582855, 853.8249658819942, 873.4322690251324, 875.9926085002267, 877.7103555826594, 878.0911756332198, 838.6142374507734, 845.1126317366771, 863.2446609216935, 864.3819668936709, 865.3250464637592, 792.1207459719707, 793.888855560222, 793.9642716077761, 795.1809636074823, 797.5334429358832, 813.9633716482773, 814.6293436486415, 815.9796005099861, 816.4692949582789, 816.6651672814118, 553.3608681972329, 555.1173633922083, 556.6576555418388, 557.2421506750816, 557.9832605021855, 15296.770548441782, 15306.464273750476, 15311.310991005432, 15317.693646127127, 15321.310541025681, 15557.299921503447, 15607.531774046683, 15646.530604081896, 15686.607834804106, 15973.840261597352, 11924.70188245292, 11952.820707689392, 11968.461419236155, 12011.759764804743, 12190.451429685685, 3986.4418981342637, 3997.200559888034, 4005.098852758106, 4033.3546632538655, 4057.7565346079036, 71697.0, 72113.63943180304, 75941.62029189874, 89139.55430222872, 90435.09564904333, 10886.727831804268, 10980.945095274472, 11056.502457389437, 11229.550817967352, 11745.941270293764, 22722.659160112613, 22795.430114247276, 22899.08403663835, 22974.65538016931, 23006.654900176512, 8856.424332418372, 8981.775455613531, 9013.819723605622, 9021.097890210927, 9114.705617887823, 4788.95211047892, 4795.568398844121, 4833.154198015333, 4894.559489645949, 4944.505549445099, 3823.655870971626, 3973.443717879499, 3987.860424885115, 3997.700172487017, 4000.419939108871, 658.318640207383, 659.7031335898864, 670.540679634452, 682.0315152483486, 689.0972825598482, 711.5019486359569, 714.2072299747276, 734.5372415378384, 735.2757533829174, 743.8177646476539, 8700.651973920973, 9594.712158635251, 9736.853947190797, 9990.350627209353, 10045.447500387403, 596.8179705189872, 628.8113565930203, 656.8554917918051, 670.0953712488199, 695.1796879681923, 4398.296272596369, 4788.04239152164, 4975.814764887845, 5326.201069839555, 5450.945490545044, 129347.35326323308, 133524.0, 133568.3321583386, 135979.32010340082, 137637.0, 101236.0, 110194.0, 111154.88648124269, 113342.43328783303, 113552.43223783994, 59107.0, 61952.0, 97333.0, 103959.48020260001, 104659.0, 10471.738206544896, 11052.11583073336, 11063.170262230204, 11136.0, 11355.602553910574, 41211.0, 43483.347749784414, 50100.498995009584, 50697.74651126721, 51466.74266628643, 52846.735766320926, 57160.0, 68744.65627671829, 70583.88248352558, 71725.0, 13768.65578360549, 14116.153030818185, 14180.290985450893, 14287.285635717964, 14590.708185836436, 15207.391704331703, 15361.769573456631, 15418.0, 15567.065976041475, 15963.44127955516, 14029.228392438514, 14064.226467543975, 14483.913706471845, 14596.85403145955, 14883.958122931292, 14314.713705725619, 14682.0, 14771.556853294418, 14860.0, 15210.391584336945, 14378.640533986734, 14496.420143194155, 14679.266036698336, 15002.549923502313, 15509.612259693598, 11774.05807535378, 12154.331511766937, 12391.814122787984, 12414.689632758891, 12481.750364992831, 12802.295873727035, 12834.935825320814, 12891.290978996245, 13151.145175563557, 13223.537176198786], "value": 7838.506200223744, "unit": "score"}], "name": "dromaeo_css", "value": 7547.310830115618, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 113.0, 111.0, 112.0, 112.0, 111.0, 112.0, 111.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 118.0, 154.0, 122.0, 120.0, 122.0, 124.0, 153.0, 117.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 162.0, 163.0, 160.0, 162.0, 160.0, 160.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 105.0, 75.0, 77.0, 77.0, 78.0, 97.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 109.0, 82.0, 82.0, 82.0, 99.0, 82.0, 81.0, 123.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 147.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 54.0, 49.0, 49.0, 50.0, 50.0, 62.0, 49.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 57.0, 57.0, 58.0, 58.0, 58.0, 59.0, 58.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 86.0, 73.0, 79.0, 72.0, 81.0, 72.0, 73.0, 73.0, 72.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 129.0, 115.0, 117.0, 115.0, 124.0, 115.0, 125.0, 159.0, 118.0], "value": 123.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 149.0, 152.0, 152.0, 154.0, 152.0, 149.0, 149.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 61.0, 63.0, 60.0, 60.0, 68.0, 60.0, 68.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1454.3000000000002, "alertThreshold": 2.0}]} 16:29:45 INFO - Return code: 0 16:29:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:29:45 INFO - # TBPL SUCCESS # 16:29:45 INFO - Running post-action listener: _resource_record_post_action 16:29:45 INFO - [mozharness: 2016-09-19 23:29:45.573253Z] Finished run-tests step (success) 16:29:45 INFO - Running post-run listener: _resource_record_post_run 16:29:45 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 270336; Write bytes: 360853504; Read time: 24; Write time: 226768 16:29:45 INFO - TinderboxPrint: CPU usage
13.0% 16:29:45 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 24 16:29:45 INFO - TinderboxPrint: I/O write bytes / time
360,853,504 / 226,768 16:29:45 INFO - TinderboxPrint: CPU idle
8,261.6 (87.0%) 16:29:45 INFO - TinderboxPrint: CPU user
1,189.3 (12.5%) 16:29:45 INFO - TinderboxPrint: Swap in / out
0 / 0 16:29:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107921408; Read time: 0; Write time: 99148 16:29:45 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 252932096; Read time: 16; Write time: 127620 16:29:46 INFO - Running post-run listener: _upload_blobber_files 16:29:46 INFO - Blob upload gear active. 16:29:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:29:46 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 16:29:46 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:29:46 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:29:46 INFO - (blobuploader) - INFO - Open directory for files ... 16:29:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:29:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:29:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:47 INFO - (blobuploader) - INFO - Done attempting. 16:29:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:29:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:29:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:48 INFO - (blobuploader) - INFO - Done attempting. 16:29:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:29:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:29:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:52 INFO - (blobuploader) - INFO - Done attempting. 16:29:52 INFO - (blobuploader) - INFO - Iteration through files over. 16:29:52 INFO - Return code: 0 16:29:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:29:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:29:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/165fe42b2fb248235b78322478111b38211f970e8f16f90842f13d8b531998953f54b99cce0ade39d582148d00d221ac5581ef257ab044f673faffa21f005bf8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6383b268b5193237432e58dd2c0b18e9e189a59dc446449524499c04ba27bb144bec1af6e517a8899f6ef5e287beb9b074cb94e26bf53eb26b1c977e29d3b20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05b15550f57faef2cdf45c62c2a8c8010af729a0f4691f822ce56a66bd558b1408e073f4edd3566b1e6fe323596784d2889f64e4012a603f35f0211f2ea51263"} 16:29:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:29:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:29:52 INFO - Contents: 16:29:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/165fe42b2fb248235b78322478111b38211f970e8f16f90842f13d8b531998953f54b99cce0ade39d582148d00d221ac5581ef257ab044f673faffa21f005bf8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6383b268b5193237432e58dd2c0b18e9e189a59dc446449524499c04ba27bb144bec1af6e517a8899f6ef5e287beb9b074cb94e26bf53eb26b1c977e29d3b20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05b15550f57faef2cdf45c62c2a8c8010af729a0f4691f822ce56a66bd558b1408e073f4edd3566b1e6fe323596784d2889f64e4012a603f35f0211f2ea51263"} 16:29:52 INFO - Running post-run listener: copy_logs_to_upload_dir 16:29:52 INFO - Copying logs to upload dir... 16:29:52 INFO - mkdir: /builds/slave/test/build/upload/logs 16:29:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.377031 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-19 16:29:52.550542) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 16:29:52.554426) ========= 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=0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/165fe42b2fb248235b78322478111b38211f970e8f16f90842f13d8b531998953f54b99cce0ade39d582148d00d221ac5581ef257ab044f673faffa21f005bf8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6383b268b5193237432e58dd2c0b18e9e189a59dc446449524499c04ba27bb144bec1af6e517a8899f6ef5e287beb9b074cb94e26bf53eb26b1c977e29d3b20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05b15550f57faef2cdf45c62c2a8c8010af729a0f4691f822ce56a66bd558b1408e073f4edd3566b1e6fe323596784d2889f64e4012a603f35f0211f2ea51263"} build_url:https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011280 build_url: 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/165fe42b2fb248235b78322478111b38211f970e8f16f90842f13d8b531998953f54b99cce0ade39d582148d00d221ac5581ef257ab044f673faffa21f005bf8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6383b268b5193237432e58dd2c0b18e9e189a59dc446449524499c04ba27bb144bec1af6e517a8899f6ef5e287beb9b074cb94e26bf53eb26b1c977e29d3b20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05b15550f57faef2cdf45c62c2a8c8010af729a0f4691f822ce56a66bd558b1408e073f4edd3566b1e6fe323596784d2889f64e4012a603f35f0211f2ea51263"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 16:29:52.585662) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:29:52.586534) ========= 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=0e2a256762fed810e2f0f810000001b3-1474326557.213054-454072727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:29:52.669562) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 16:29:52.670323) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 16:29:52.671047) ========= ========= Total master_lag: 0.20 =========