builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1474351025.75 results: success (0) buildid: 20160919200817 builduid: 47fb44262e294c658195f9dc5dcf656e revision: ef0680dc3d4feb015a07b3edefe0b9d2a095bdec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:05.747415) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:05.748261) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:05.748679) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:05.779248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:05.779817) ========= 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=cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 22:57:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 229M=0s 2016-09-19 22:57:06 (229 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.180778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:05.975726) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:05.976050) ========= 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=cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016173 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:06.026329) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 22:57:06.027098) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ef0680dc3d4feb015a07b3edefe0b9d2a095bdec --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 ef0680dc3d4feb015a07b3edefe0b9d2a095bdec --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=cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 22:57:06,318 truncating revision to first 12 chars 2016-09-19 22:57:06,318 Setting DEBUG logging. 2016-09-19 22:57:06,318 attempt 1/10 2016-09-19 22:57:06,318 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ef0680dc3d4f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 22:57:07,202 unpacking tar archive at: mozilla-aurora-ef0680dc3d4f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.211172 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 22:57:07.253283) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:07.253623) ========= script_repo_revision: ef0680dc3d4feb015a07b3edefe0b9d2a095bdec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:07.254061) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:07.254363) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 22:57:07.263109) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-19 22:57:07.263491) ========= /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=cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:57:07 INFO - MultiFileLogger online at 20160919 22:57:07 in /builds/slave/test 22:57:07 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 22:57:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:57:07 INFO - {'append_to_log': False, 22:57:07 INFO - 'base_work_dir': '/builds/slave/test', 22:57:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:57:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:57:07 INFO - 'branch': 'Mozilla-Aurora', 22:57:07 INFO - 'buildbot_json_path': 'buildprops.json', 22:57:07 INFO - 'config_files': ('talos/linux_config.py',), 22:57:07 INFO - 'default_actions': ('clobber', 22:57:07 INFO - 'read-buildbot-config', 22:57:07 INFO - 'download-and-extract', 22:57:07 INFO - 'populate-webroot', 22:57:07 INFO - 'create-virtualenv', 22:57:07 INFO - 'install', 22:57:07 INFO - 'run-tests'), 22:57:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:57:07 INFO - 'download_minidump_stackwalk': True, 22:57:07 INFO - 'download_symbols': 'ondemand', 22:57:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:57:07 INFO - 'tooltool.py': '/tools/tooltool.py', 22:57:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:57:07 INFO - '/tools/misc-python/virtualenv.py')}, 22:57:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:57:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:57:07 INFO - 'installer_path': 'installer.exe', 22:57:07 INFO - 'log_level': 'info', 22:57:07 INFO - 'log_name': 'talos', 22:57:07 INFO - 'log_to_console': True, 22:57:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:57:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:57:07 INFO - 'opt_config_files': (), 22:57:07 INFO - 'pip_index': False, 22:57:07 INFO - 'sps_profile': False, 22:57:07 INFO - 'sps_profile_interval': 0, 22:57:07 INFO - 'suite': 'dromaeojs', 22:57:07 INFO - 'system_bits': '32', 22:57:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:57:07 INFO - 'title': 'talos-linux64-ix-054', 22:57:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:57:07 INFO - 'use_talos_json': True, 22:57:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:57:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:57:07 INFO - 'work_dir': 'build'} 22:57:07 INFO - [mozharness: 2016-09-20 05:57:07.774759Z] Running clobber step. 22:57:07 INFO - Running pre-action listener: _resource_record_pre_action 22:57:07 INFO - Running main action method: clobber 22:57:07 INFO - rmtree: /builds/slave/test/build 22:57:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:57:08 INFO - Running post-action listener: _resource_record_post_action 22:57:08 INFO - [mozharness: 2016-09-20 05:57:08.188822Z] Finished clobber step (success) 22:57:08 INFO - [mozharness: 2016-09-20 05:57:08.188924Z] Running read-buildbot-config step. 22:57:08 INFO - Running pre-action listener: _resource_record_pre_action 22:57:08 INFO - Running main action method: read_buildbot_config 22:57:08 INFO - Using buildbot properties: 22:57:08 INFO - { 22:57:08 INFO - "project": "", 22:57:08 INFO - "product": "firefox", 22:57:08 INFO - "script_repo_revision": "production", 22:57:08 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:57:08 INFO - "repository": "", 22:57:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:57:08 INFO - "buildid": "20160919200817", 22:57:08 INFO - "pgo_build": "True", 22:57:08 INFO - "basedir": "/builds/slave/test", 22:57:08 INFO - "buildnumber": 763, 22:57:08 INFO - "slavename": "talos-linux64-ix-054", 22:57:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:57:08 INFO - "platform": "ubuntu64_hw", 22:57:08 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:57:08 INFO - "branch": "mozilla-aurora", 22:57:08 INFO - "repo_path": "releases/mozilla-aurora", 22:57:08 INFO - "revision": "ef0680dc3d4feb015a07b3edefe0b9d2a095bdec", 22:57:08 INFO - "stage_platform": "linux64-pgo", 22:57:08 INFO - "builduid": "47fb44262e294c658195f9dc5dcf656e", 22:57:08 INFO - "slavebuilddir": "test" 22:57:08 INFO - } 22:57:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 22:57:08 INFO - Running post-action listener: _resource_record_post_action 22:57:08 INFO - [mozharness: 2016-09-20 05:57:08.191417Z] Finished read-buildbot-config step (success) 22:57:08 INFO - [mozharness: 2016-09-20 05:57:08.191508Z] Running download-and-extract step. 22:57:08 INFO - Running pre-action listener: _resource_record_pre_action 22:57:08 INFO - Running main action method: download_and_extract 22:57:08 INFO - mkdir: /builds/slave/test/build/tests 22:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:08 INFO - trying https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 22:57:08 INFO - Downloading https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/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 22:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/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 22:57:08 INFO - Downloaded 1361 bytes. 22:57:08 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 22:57:08 INFO - Using the following test package requirements: 22:57:08 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 22:57:08 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:08 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:57:08 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:08 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:57:08 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:08 INFO - u'jsshell-linux-x86_64.zip'], 22:57:08 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:08 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:57:08 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 22:57:08 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:08 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:57:08 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:08 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:57:08 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:08 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:57:08 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:08 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:57:08 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:57:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 22:57:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:57:08 INFO - Fetch https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:57:09 INFO - Expected file size: 21894046 22:57:13 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 22:57:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 22:57:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:57:13 INFO - Fetch https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:57:14 INFO - Expected file size: 21894046 22:57:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 22:57:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:57:19 INFO - Fetch https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 22:57:19 INFO - Expected file size: 14414059 22:57:20 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')]} 22:57:20 INFO - trying https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:57:20 INFO - Downloading https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/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 22:57:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/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 22:57:21 INFO - Downloaded 63972244 bytes. 22:57:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:57:21 INFO - mkdir: /builds/slave/test/properties 22:57:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:57:21 INFO - Writing to file /builds/slave/test/properties/build_url 22:57:21 INFO - Contents: 22:57:21 INFO - build_url:https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:57:22 INFO - Running post-action listener: _resource_record_post_action 22:57:22 INFO - Running post-action listener: set_extra_try_arguments 22:57:22 INFO - [mozharness: 2016-09-20 05:57:22.367961Z] Finished download-and-extract step (success) 22:57:22 INFO - [mozharness: 2016-09-20 05:57:22.368269Z] Running populate-webroot step. 22:57:22 INFO - Running pre-action listener: _resource_record_pre_action 22:57:22 INFO - Running main action method: populate_webroot 22:57:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:57:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:57:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:57:22 INFO - '--tppagecycles', 22:57:22 INFO - '7'], 22:57:22 INFO - 'tests': ['tsvgm']}}, 22:57:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:57:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:57:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:57:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:57:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:22 INFO - 'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:57:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:57:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:22 INFO - 'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['damp', 'tps']}, 22:57:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:22 INFO - 'tests': ['damp', 'tps']}, 22:57:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['dromaeo_dom']}, 22:57:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:57:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['basic_compositor_video']}, 22:57:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:57:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['a11yr', 22:57:22 INFO - 'ts_paint', 22:57:22 INFO - 'tpaint', 22:57:22 INFO - 'sessionrestore', 22:57:22 INFO - 'sessionrestore_no_auto_restore', 22:57:22 INFO - 'tabpaint']}, 22:57:22 INFO - 'other-e10s': {'tests': ['a11yr', 22:57:22 INFO - 'ts_paint', 22:57:22 INFO - 'tpaint', 22:57:22 INFO - 'sessionrestore', 22:57:22 INFO - 'sessionrestore_no_auto_restore', 22:57:22 INFO - 'tabpaint']}, 22:57:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:57:22 INFO - 'ts_paint', 22:57:22 INFO - 'tpaint', 22:57:22 INFO - 'sessionrestore', 22:57:22 INFO - 'sessionrestore_no_auto_restore', 22:57:22 INFO - 'tabpaint']}, 22:57:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:57:22 INFO - 'ts_paint', 22:57:22 INFO - 'tpaint', 22:57:22 INFO - 'sessionrestore', 22:57:22 INFO - 'sessionrestore_no_auto_restore', 22:57:22 INFO - 'tabpaint']}, 22:57:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['a11yr', 22:57:22 INFO - 'ts_paint', 22:57:22 INFO - 'tpaint', 22:57:22 INFO - 'sessionrestore', 22:57:22 INFO - 'sessionrestore_no_auto_restore', 22:57:22 INFO - 'tabpaint']}, 22:57:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['a11yr', 22:57:22 INFO - 'ts_paint', 22:57:22 INFO - 'tpaint', 22:57:22 INFO - 'sessionrestore', 22:57:22 INFO - 'sessionrestore_no_auto_restore', 22:57:22 INFO - 'tabpaint']}, 22:57:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['tsvgx', 22:57:22 INFO - 'tsvgr_opacity', 22:57:22 INFO - 'tart', 22:57:22 INFO - 'tscrollx', 22:57:22 INFO - 'cart']}, 22:57:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:57:22 INFO - 'tsvgr_opacity', 22:57:22 INFO - 'tart', 22:57:22 INFO - 'tscrollx', 22:57:22 INFO - 'cart']}, 22:57:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:22 INFO - 'talos_options': ['--disable-e10s'], 22:57:22 INFO - 'tests': ['tp5o']}, 22:57:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:22 INFO - 'tests': ['tp5o']}, 22:57:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:22 INFO - 'talos_options': ['--disable-e10s', 22:57:22 INFO - '--xperf_path', 22:57:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:57:22 INFO - 'tests': ['tp5n']}, 22:57:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:22 INFO - 'talos_options': ['--xperf_path', 22:57:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:57:22 INFO - 'tests': ['tp5n']}}, 22:57:22 INFO - 'talos.zip': {'path': '', 22:57:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:57:22 INFO - Running post-action listener: _resource_record_post_action 22:57:22 INFO - [mozharness: 2016-09-20 05:57:22.388902Z] Finished populate-webroot step (success) 22:57:22 INFO - [mozharness: 2016-09-20 05:57:22.388996Z] Running create-virtualenv step. 22:57:22 INFO - Running pre-action listener: _resource_record_pre_action 22:57:22 INFO - Running main action method: create_virtualenv 22:57:22 INFO - Creating virtualenv /builds/slave/test/build/venv 22:57:22 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 22:57:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:57:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:57:22 INFO - Using real prefix '/usr' 22:57:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:57:23 INFO - Installing distribute.............................................................................................................................................................................................done. 22:57:25 INFO - Installing pip.................done. 22:57:25 INFO - Return code: 0 22:57:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:57:25 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')]} 22:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:25 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')]} 22:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:25 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:25 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 22:57:25 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 22:57:25 INFO - Using env: {'DISPLAY': ':0', 22:57:25 INFO - 'HOME': '/home/cltbld', 22:57:25 INFO - 'LANG': 'en_US.UTF-8', 22:57:25 INFO - 'LANGUAGE': 'en_US:en', 22:57:25 INFO - 'LOGNAME': 'cltbld', 22:57:25 INFO - 'MAIL': '/var/mail/cltbld', 22:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:25 INFO - 'MOZ_NO_REMOTE': '1', 22:57:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:25 INFO - 'NO_EM_RESTART': '1', 22:57:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:25 INFO - 'PWD': '/builds/slave/test', 22:57:25 INFO - 'SHELL': '/bin/bash', 22:57:25 INFO - 'SHLVL': '1', 22:57:25 INFO - 'TERM': 'linux', 22:57:25 INFO - 'TMOUT': '86400', 22:57:25 INFO - 'USER': 'cltbld', 22:57:25 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:25 INFO - '_': '/tools/buildbot/bin/python'} 22:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:26 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 22:57:26 INFO - Cleaning up... 22:57:26 INFO - Return code: 0 22:57:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:26 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:26 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 22:57:26 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 22:57:26 INFO - Using env: {'DISPLAY': ':0', 22:57:26 INFO - 'HOME': '/home/cltbld', 22:57:26 INFO - 'LANG': 'en_US.UTF-8', 22:57:26 INFO - 'LANGUAGE': 'en_US:en', 22:57:26 INFO - 'LOGNAME': 'cltbld', 22:57:26 INFO - 'MAIL': '/var/mail/cltbld', 22:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:26 INFO - 'MOZ_NO_REMOTE': '1', 22:57:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:26 INFO - 'NO_EM_RESTART': '1', 22:57:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:26 INFO - 'PWD': '/builds/slave/test', 22:57:26 INFO - 'SHELL': '/bin/bash', 22:57:26 INFO - 'SHLVL': '1', 22:57:26 INFO - 'TERM': 'linux', 22:57:26 INFO - 'TMOUT': '86400', 22:57:26 INFO - 'USER': 'cltbld', 22:57:26 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:26 INFO - '_': '/tools/buildbot/bin/python'} 22:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:26 INFO - Downloading/unpacking psutil>=3.1.1 22:57:26 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 22:57:26 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 22:57:26 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 22:57:26 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 22:57:26 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 22:57:26 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 22:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:57:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:57:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:57:28 INFO - Installing collected packages: psutil 22:57:28 INFO - Running setup.py install for psutil 22:57:29 INFO - building 'psutil._psutil_linux' extension 22:57:29 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 22:57:30 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 22:57:30 INFO - building 'psutil._psutil_posix' extension 22:57:30 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 22:57:30 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 22:57:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:57:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:57:30 INFO - Successfully installed psutil 22:57:30 INFO - Cleaning up... 22:57:30 INFO - Return code: 0 22:57:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:30 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:30 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 22:57:30 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 22:57:30 INFO - Using env: {'DISPLAY': ':0', 22:57:30 INFO - 'HOME': '/home/cltbld', 22:57:30 INFO - 'LANG': 'en_US.UTF-8', 22:57:30 INFO - 'LANGUAGE': 'en_US:en', 22:57:30 INFO - 'LOGNAME': 'cltbld', 22:57:30 INFO - 'MAIL': '/var/mail/cltbld', 22:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:30 INFO - 'MOZ_NO_REMOTE': '1', 22:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:30 INFO - 'NO_EM_RESTART': '1', 22:57:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:30 INFO - 'PWD': '/builds/slave/test', 22:57:30 INFO - 'SHELL': '/bin/bash', 22:57:30 INFO - 'SHLVL': '1', 22:57:30 INFO - 'TERM': 'linux', 22:57:30 INFO - 'TMOUT': '86400', 22:57:30 INFO - 'USER': 'cltbld', 22:57:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:30 INFO - '_': '/tools/buildbot/bin/python'} 22:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:57:31 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 22:57:31 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 22:57:31 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 22:57:31 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 22:57:31 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 22:57:31 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 22:57:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:57:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:57:33 INFO - Installing collected packages: mozsystemmonitor 22:57:33 INFO - Running setup.py install for mozsystemmonitor 22:57:33 INFO - Successfully installed mozsystemmonitor 22:57:33 INFO - Cleaning up... 22:57:33 INFO - Return code: 0 22:57:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:57:33 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')]} 22:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:33 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')]} 22:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:33 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:33 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 22:57:33 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 22:57:33 INFO - Using env: {'DISPLAY': ':0', 22:57:33 INFO - 'HOME': '/home/cltbld', 22:57:33 INFO - 'LANG': 'en_US.UTF-8', 22:57:33 INFO - 'LANGUAGE': 'en_US:en', 22:57:33 INFO - 'LOGNAME': 'cltbld', 22:57:33 INFO - 'MAIL': '/var/mail/cltbld', 22:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:33 INFO - 'MOZ_NO_REMOTE': '1', 22:57:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:33 INFO - 'NO_EM_RESTART': '1', 22:57:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:33 INFO - 'PWD': '/builds/slave/test', 22:57:33 INFO - 'SHELL': '/bin/bash', 22:57:33 INFO - 'SHLVL': '1', 22:57:33 INFO - 'TERM': 'linux', 22:57:33 INFO - 'TMOUT': '86400', 22:57:33 INFO - 'USER': 'cltbld', 22:57:33 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:33 INFO - '_': '/tools/buildbot/bin/python'} 22:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:33 INFO - Downloading/unpacking jsonschema==2.5.1 22:57:33 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 22:57:33 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 22:57:33 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 22:57:33 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 22:57:33 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 22:57:33 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 22:57:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:57:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:57:35 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 22:57:35 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 22:57:35 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 22:57:35 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 22:57:35 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 22:57:35 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 22:57:35 INFO - Downloading functools32-3.2.3-2.tar.gz 22:57:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:57:35 INFO - warning: no files found matching '*.txt' 22:57:35 INFO - no previously-included directories found matching 'build' 22:57:35 INFO - no previously-included directories found matching 'dist' 22:57:35 INFO - no previously-included directories found matching '.git*' 22:57:35 INFO - Installing collected packages: jsonschema, functools32 22:57:35 INFO - Running setup.py install for functools32 22:57:35 INFO - warning: no files found matching '*.txt' 22:57:35 INFO - no previously-included directories found matching 'build' 22:57:35 INFO - no previously-included directories found matching 'dist' 22:57:35 INFO - no previously-included directories found matching '.git*' 22:57:35 INFO - Successfully installed jsonschema functools32 22:57:35 INFO - Cleaning up... 22:57:35 INFO - Return code: 0 22:57:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:35 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:35 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 22:57:35 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 22:57:35 INFO - Using env: {'DISPLAY': ':0', 22:57:35 INFO - 'HOME': '/home/cltbld', 22:57:35 INFO - 'LANG': 'en_US.UTF-8', 22:57:35 INFO - 'LANGUAGE': 'en_US:en', 22:57:35 INFO - 'LOGNAME': 'cltbld', 22:57:35 INFO - 'MAIL': '/var/mail/cltbld', 22:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:35 INFO - 'MOZ_NO_REMOTE': '1', 22:57:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:35 INFO - 'NO_EM_RESTART': '1', 22:57:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:35 INFO - 'PWD': '/builds/slave/test', 22:57:35 INFO - 'SHELL': '/bin/bash', 22:57:35 INFO - 'SHLVL': '1', 22:57:35 INFO - 'TERM': 'linux', 22:57:35 INFO - 'TMOUT': '86400', 22:57:35 INFO - 'USER': 'cltbld', 22:57:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:35 INFO - '_': '/tools/buildbot/bin/python'} 22:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:57:36 INFO - Cleaning up... 22:57:36 INFO - Return code: 0 22:57:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:57: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')]} 22:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57: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')]} 22:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57: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', '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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:57: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 blobuploader==1.2.4 22:57:36 INFO - Using env: {'DISPLAY': ':0', 22:57:36 INFO - 'HOME': '/home/cltbld', 22:57:36 INFO - 'LANG': 'en_US.UTF-8', 22:57:36 INFO - 'LANGUAGE': 'en_US:en', 22:57:36 INFO - 'LOGNAME': 'cltbld', 22:57:36 INFO - 'MAIL': '/var/mail/cltbld', 22:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:36 INFO - 'MOZ_NO_REMOTE': '1', 22:57:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:36 INFO - 'NO_EM_RESTART': '1', 22:57:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:36 INFO - 'PWD': '/builds/slave/test', 22:57:36 INFO - 'SHELL': '/bin/bash', 22:57:36 INFO - 'SHLVL': '1', 22:57:36 INFO - 'TERM': 'linux', 22:57:36 INFO - 'TMOUT': '86400', 22:57:36 INFO - 'USER': 'cltbld', 22:57:36 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:36 INFO - '_': '/tools/buildbot/bin/python'} 22:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:36 INFO - Downloading/unpacking blobuploader==1.2.4 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57:38 INFO - Downloading blobuploader-1.2.4.tar.gz 22:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:57:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:57:38 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 22:57:38 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 22:57:38 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 22:57:38 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 22:57:38 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 22:57:38 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 22:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:57:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:57:38 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 22:57:38 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 22:57:38 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 22:57:38 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 22:57:38 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 22:57:38 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 22:57:39 INFO - Downloading docopt-0.6.1.tar.gz 22:57:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:57:39 INFO - Installing collected packages: blobuploader, requests, docopt 22:57:39 INFO - Running setup.py install for blobuploader 22:57:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:57:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:57:39 INFO - Running setup.py install for requests 22:57:39 INFO - Running setup.py install for docopt 22:57:39 INFO - Successfully installed blobuploader requests docopt 22:57:39 INFO - Cleaning up... 22:57:39 INFO - Return code: 0 22:57:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57: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')]} 22:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57: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')]} 22:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:39 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:39 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 22:57:39 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 22:57:39 INFO - Using env: {'DISPLAY': ':0', 22:57:39 INFO - 'HOME': '/home/cltbld', 22:57:39 INFO - 'LANG': 'en_US.UTF-8', 22:57:39 INFO - 'LANGUAGE': 'en_US:en', 22:57:39 INFO - 'LOGNAME': 'cltbld', 22:57:39 INFO - 'MAIL': '/var/mail/cltbld', 22:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:39 INFO - 'MOZ_NO_REMOTE': '1', 22:57:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:39 INFO - 'NO_EM_RESTART': '1', 22:57:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:39 INFO - 'PWD': '/builds/slave/test', 22:57:39 INFO - 'SHELL': '/bin/bash', 22:57:39 INFO - 'SHLVL': '1', 22:57:39 INFO - 'TERM': 'linux', 22:57:39 INFO - 'TMOUT': '86400', 22:57:39 INFO - 'USER': 'cltbld', 22:57:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:39 INFO - '_': '/tools/buildbot/bin/python'} 22:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:57:39 INFO - Running setup.py (path:/tmp/pip-Ok35rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:57:40 INFO - Running setup.py (path:/tmp/pip-Zl4Sxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:57:40 INFO - Running setup.py (path:/tmp/pip-Is5h6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:57:40 INFO - Running setup.py (path:/tmp/pip-dcnYIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:57:40 INFO - Running setup.py (path:/tmp/pip-o8l412-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:57:40 INFO - Running setup.py (path:/tmp/pip-us4gtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:57:40 INFO - Running setup.py (path:/tmp/pip-IzP3If-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:57:40 INFO - Running setup.py (path:/tmp/pip-o9qPNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:57:40 INFO - Running setup.py (path:/tmp/pip-y3JmhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:57:40 INFO - Running setup.py (path:/tmp/pip-k2puHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:57:40 INFO - Running setup.py (path:/tmp/pip-yhAgd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:57:40 INFO - Running setup.py (path:/tmp/pip-gVrakM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:57:40 INFO - Running setup.py (path:/tmp/pip-thJwjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:57:40 INFO - Running setup.py (path:/tmp/pip-oN5fuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:40 INFO - Running setup.py (path:/tmp/pip-dhpHWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:57:41 INFO - Running setup.py (path:/tmp/pip-ThqNV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:57:41 INFO - Running setup.py (path:/tmp/pip-5d0Adw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:57:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:57:41 INFO - Running setup.py install for manifestparser 22:57:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Running setup.py install for mozcrash 22:57:41 INFO - Running setup.py install for mozdebug 22:57:41 INFO - Running setup.py install for mozdevice 22:57:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Running setup.py install for mozfile 22:57:41 INFO - Running setup.py install for mozhttpd 22:57:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Running setup.py install for mozinfo 22:57:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Running setup.py install for mozInstall 22:57:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:57:41 INFO - Running setup.py install for mozleak 22:57:42 INFO - Running setup.py install for mozlog 22:57:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:57:42 INFO - Running setup.py install for moznetwork 22:57:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:57:42 INFO - Running setup.py install for mozprocess 22:57:42 INFO - Running setup.py install for mozprofile 22:57:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:57:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:57:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:57:42 INFO - Running setup.py install for mozrunner 22:57:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:57:42 INFO - Running setup.py install for mozscreenshot 22:57:42 INFO - Running setup.py install for moztest 22:57:42 INFO - Running setup.py install for mozversion 22:57:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:57:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:57:42 INFO - Cleaning up... 22:57:42 INFO - Return code: 0 22:57:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:57:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:42 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:42 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 22:57:42 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 22:57:42 INFO - Using env: {'DISPLAY': ':0', 22:57:42 INFO - 'HOME': '/home/cltbld', 22:57:42 INFO - 'LANG': 'en_US.UTF-8', 22:57:42 INFO - 'LANGUAGE': 'en_US:en', 22:57:42 INFO - 'LOGNAME': 'cltbld', 22:57:42 INFO - 'MAIL': '/var/mail/cltbld', 22:57:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:42 INFO - 'MOZ_NO_REMOTE': '1', 22:57:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:42 INFO - 'NO_EM_RESTART': '1', 22:57:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:42 INFO - 'PWD': '/builds/slave/test', 22:57:42 INFO - 'SHELL': '/bin/bash', 22:57:42 INFO - 'SHLVL': '1', 22:57:42 INFO - 'TERM': 'linux', 22:57:42 INFO - 'TMOUT': '86400', 22:57:42 INFO - 'USER': 'cltbld', 22:57:42 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:42 INFO - '_': '/tools/buildbot/bin/python'} 22:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:57:43 INFO - Running setup.py (path:/tmp/pip-At4zZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:57:43 INFO - Running setup.py (path:/tmp/pip-2BTAdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:57:43 INFO - Running setup.py (path:/tmp/pip-MZGtMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:57:43 INFO - Running setup.py (path:/tmp/pip-ca9x1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:57:43 INFO - Running setup.py (path:/tmp/pip-jNsrZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:57:43 INFO - Running setup.py (path:/tmp/pip-zBcqKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:57:43 INFO - Running setup.py (path:/tmp/pip-Usvm2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:57:43 INFO - Running setup.py (path:/tmp/pip-Ayg1Iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:57:43 INFO - Running setup.py (path:/tmp/pip-z4NAz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:57:43 INFO - Running setup.py (path:/tmp/pip-OCeY5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:57:43 INFO - Running setup.py (path:/tmp/pip-eNqVtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:57:43 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)) 22:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:57:43 INFO - Running setup.py (path:/tmp/pip-bKoWtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:57:44 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)) 22:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:57:44 INFO - Running setup.py (path:/tmp/pip-HylKce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:57:44 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)) 22:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:57:44 INFO - Running setup.py (path:/tmp/pip-bUbipb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:57:44 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)) 22:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:44 INFO - Running setup.py (path:/tmp/pip-JfSf6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:44 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)) 22:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:57:44 INFO - Running setup.py (path:/tmp/pip-FJyWkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:57:44 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)) 22:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:57:44 INFO - Running setup.py (path:/tmp/pip-04n9tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:57:44 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)) 22:57:44 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)) 22:57:44 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)) 22:57:44 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)) 22:57:44 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)) 22:57:44 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)) 22:57:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57:46 INFO - Downloading blessings-1.6.tar.gz 22:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:57:46 INFO - Installing collected packages: blessings 22:57:46 INFO - Running setup.py install for blessings 22:57:46 INFO - Successfully installed blessings 22:57:46 INFO - Cleaning up... 22:57:46 INFO - Return code: 0 22:57:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:57:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:57:46 INFO - Reading from file tmpfile_stdout 22:57:46 INFO - Current package versions: 22:57:46 INFO - argparse == 1.2.1 22:57:46 INFO - blessings == 1.6 22:57:46 INFO - blobuploader == 1.2.4 22:57:46 INFO - docopt == 0.6.1 22:57:46 INFO - functools32 == 3.2.3-2 22:57:46 INFO - jsonschema == 2.5.1 22:57:46 INFO - manifestparser == 1.1 22:57:46 INFO - mozInstall == 1.12 22:57:46 INFO - mozcrash == 0.17 22:57:46 INFO - mozdebug == 0.1 22:57:46 INFO - mozdevice == 0.48 22:57:46 INFO - mozfile == 1.2 22:57:46 INFO - mozhttpd == 0.7 22:57:46 INFO - mozinfo == 0.9 22:57:46 INFO - mozleak == 0.1 22:57:46 INFO - mozlog == 3.4 22:57:46 INFO - moznetwork == 0.27 22:57:46 INFO - mozprocess == 0.23 22:57:46 INFO - mozprofile == 0.28 22:57:46 INFO - mozrunner == 6.12 22:57:46 INFO - mozscreenshot == 0.1 22:57:46 INFO - mozsystemmonitor == 0.3 22:57:46 INFO - moztest == 0.7 22:57:46 INFO - mozversion == 1.4 22:57:46 INFO - psutil == 3.1.1 22:57:46 INFO - requests == 1.2.3 22:57:46 INFO - wsgiref == 0.1.2 22:57:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57:46 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')]} 22:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:46 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')]} 22:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:46 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:46 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 22:57:46 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 22:57:46 INFO - Using env: {'DISPLAY': ':0', 22:57:46 INFO - 'HOME': '/home/cltbld', 22:57:46 INFO - 'LANG': 'en_US.UTF-8', 22:57:46 INFO - 'LANGUAGE': 'en_US:en', 22:57:46 INFO - 'LOGNAME': 'cltbld', 22:57:46 INFO - 'MAIL': '/var/mail/cltbld', 22:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:46 INFO - 'MOZ_NO_REMOTE': '1', 22:57:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:46 INFO - 'NO_EM_RESTART': '1', 22:57:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:46 INFO - 'PWD': '/builds/slave/test', 22:57:46 INFO - 'SHELL': '/bin/bash', 22:57:46 INFO - 'SHLVL': '1', 22:57:46 INFO - 'TERM': 'linux', 22:57:46 INFO - 'TMOUT': '86400', 22:57:46 INFO - 'USER': 'cltbld', 22:57:46 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:46 INFO - '_': '/tools/buildbot/bin/python'} 22:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 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)) 22:57:46 INFO - Cleaning up... 22:57:46 INFO - Return code: 0 22:57:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:57:46 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')]} 22:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:46 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')]} 22:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:46 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 0x7f9aa3a5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2093ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', '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 22:57:46 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 22:57:46 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 22:57:46 INFO - Using env: {'DISPLAY': ':0', 22:57:46 INFO - 'HOME': '/home/cltbld', 22:57:46 INFO - 'LANG': 'en_US.UTF-8', 22:57:46 INFO - 'LANGUAGE': 'en_US:en', 22:57:46 INFO - 'LOGNAME': 'cltbld', 22:57:46 INFO - 'MAIL': '/var/mail/cltbld', 22:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:46 INFO - 'MOZ_NO_REMOTE': '1', 22:57:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:46 INFO - 'NO_EM_RESTART': '1', 22:57:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:46 INFO - 'PWD': '/builds/slave/test', 22:57:46 INFO - 'SHELL': '/bin/bash', 22:57:46 INFO - 'SHLVL': '1', 22:57:46 INFO - 'TERM': 'linux', 22:57:46 INFO - 'TMOUT': '86400', 22:57:46 INFO - 'USER': 'cltbld', 22:57:46 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:46 INFO - '_': '/tools/buildbot/bin/python'} 22:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:57:47 INFO - Cleaning up... 22:57:47 INFO - Return code: 0 22:57:47 INFO - Running post-action listener: _resource_record_post_action 22:57:47 INFO - Running post-action listener: _start_resource_monitoring 22:57:47 INFO - Starting resource monitoring. 22:57:47 INFO - [mozharness: 2016-09-20 05:57:47.233259Z] Finished create-virtualenv step (success) 22:57:47 INFO - [mozharness: 2016-09-20 05:57:47.233926Z] Running install step. 22:57:47 INFO - Running pre-action listener: _resource_record_pre_action 22:57:47 INFO - Running main action method: install 22:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:57:47 INFO - Reading from file tmpfile_stdout 22:57:47 INFO - Detecting whether we're running mozinstall >=1.0... 22:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:57:47 INFO - Reading from file tmpfile_stdout 22:57:47 INFO - Output received: 22:57:47 INFO - Usage: mozinstall [options] installer 22:57:47 INFO - Options: 22:57:47 INFO - -h, --help show this help message and exit 22:57:47 INFO - -d DEST, --destination=DEST 22:57:47 INFO - Directory to install application into. [default: 22:57:47 INFO - "/builds/slave/test"] 22:57:47 INFO - --app=APP Application being installed. [default: firefox] 22:57:47 INFO - mkdir: /builds/slave/test/build/application 22:57:47 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'] 22:57:47 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 22:58:03 INFO - Reading from file tmpfile_stdout 22:58:03 INFO - Output received: 22:58:03 INFO - /builds/slave/test/build/application/firefox/firefox 22:58:03 INFO - Running post-action listener: _resource_record_post_action 22:58:03 INFO - [mozharness: 2016-09-20 05:58:03.481744Z] Finished install step (success) 22:58:03 INFO - [mozharness: 2016-09-20 05:58:03.481995Z] Running run-tests step. 22:58:03 INFO - Running pre-action listener: _resource_record_pre_action 22:58:03 INFO - Running main action method: run_tests 22:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:58:03 INFO - Python 2.7.3 22:58:03 INFO - Return code: 0 22:58:03 INFO - grabbing minidump binary from tooltool 22:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:03 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 0x2090f60>, '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 0x2091d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20921d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:58:03 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 22:58:03 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 22:58:03 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 22:58:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:58:03 INFO - Return code: 0 22:58:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:58:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:58:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:58:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:58:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:58:03 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/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 22:58:03 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/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 22:58:03 INFO - Using env: {'DISPLAY': ':0', 22:58:03 INFO - 'HOME': '/home/cltbld', 22:58:03 INFO - 'LANG': 'en_US.UTF-8', 22:58:03 INFO - 'LANGUAGE': 'en_US:en', 22:58:03 INFO - 'LOGNAME': 'cltbld', 22:58:03 INFO - 'MAIL': '/var/mail/cltbld', 22:58:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:58:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:03 INFO - 'MOZ_NO_REMOTE': '1', 22:58:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:58:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:03 INFO - 'NO_EM_RESTART': '1', 22:58:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:03 INFO - 'PWD': '/builds/slave/test', 22:58:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:58:03 INFO - 'SHELL': '/bin/bash', 22:58:03 INFO - 'SHLVL': '1', 22:58:03 INFO - 'TERM': 'linux', 22:58:03 INFO - 'TMOUT': '86400', 22:58:03 INFO - 'USER': 'cltbld', 22:58:03 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367', 22:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:03 INFO - '_': '/tools/buildbot/bin/python'} 22:58:03 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/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 22:58:04 INFO - mozversion application_buildid: 20160919200817 22:58:04 INFO - mozversion application_changeset: ef0680dc3d4feb015a07b3edefe0b9d2a095bdec 22:58:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:58:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:58:04 INFO - mozversion application_name: Firefox 22:58:04 INFO - mozversion application_remotingname: firefox-dev 22:58:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:58:04 INFO - mozversion application_vendor: Mozilla 22:58:04 INFO - mozversion application_version: 51.0a2 22:58:04 INFO - mozversion platform_buildid: 20160919200817 22:58:04 INFO - mozversion platform_changeset: ef0680dc3d4feb015a07b3edefe0b9d2a095bdec 22:58:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:58:04 INFO - mozversion platform_version: 51.0a2 22:58:04 INFO - using testdate: 1474351084 22:58:04 INFO - actual date: 1474351084 22:58:04 INFO - starting webserver on 'localhost:59315' 22:58:04 INFO - SUITE-START | Running 2 tests 22:58:04 INFO - TEST-START | dromaeo_css 22:58:04 INFO - operating with platform_type : linux_ 22:58:04 INFO - Initialising browser for dromaeo_css test... 22:58:04 INFO - TEST-INFO | started process 3305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgKb_2A/profile http://localhost:59315/getInfo.html) 22:58:09 INFO - PROCESS | 3305 | __metrics Screen width/height:1600/1200 22:58:09 INFO - PROCESS | 3305 | colorDepth:24 22:58:09 INFO - PROCESS | 3305 | Browser inner width/height: 1024/697 22:58:09 INFO - PROCESS | 3305 | __metrics 22:58:10 INFO - PROCESS | 3305 | 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" 22:58:10 INFO - PROCESS | 3305 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:58:11 INFO - PROCESS | 3305 | console.error: PushService: 22:58:11 INFO - PROCESS | 3305 | stateChangeProcessEnqueue: Error transitioning state 22:58:11 INFO - PROCESS | 3305 | UnknownError 22:58:11 INFO - TEST-INFO | 3305: exit 0 22:58:11 INFO - Browser initialized. 22:58:11 INFO - Running cycle 1/1 for dromaeo_css test... 22:58:11 INFO - TEST-INFO | started process 3396 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgKb_2A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:58:23 INFO - PROCESS | 3396 | RSS: Main: 144330752 22:58:23 INFO - PROCESS | 3396 | 22:58:27 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.13314 seconds. 22:58:36 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 22:59:10 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.05047 seconds. 22:59:14 INFO - PROCESS | 3396 | 1474351154476 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] 22:59:21 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.05046 seconds. 22:59:27 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.03294 seconds. 22:59:31 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.03317 seconds. 22:59:36 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01689 seconds. 22:59:42 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.05012 seconds. 22:59:49 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01718 seconds. 22:59:53 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01707 seconds. 23:00:00 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.05044 seconds. 23:00:06 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.03289 seconds. 23:00:40 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 23:00:42 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 23:00:45 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0003 seconds. 23:01:08 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01608 seconds. 23:01:17 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 23:01:19 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 23:01:23 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:59315/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59315/tests/dromaeo/cssquery-ext.html) 23:01:24 INFO - PROCESS | 3396 | RSS: Main: 244359168 23:01:24 INFO - PROCESS | 3396 | 23:01:28 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01608 seconds. 23:01:49 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 23:01:51 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 23:02:01 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 23:02:19 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00078 seconds. 23:02:23 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0163 seconds. 23:02:26 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.04975 seconds. 23:02:35 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01691 seconds. 23:03:16 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00019 seconds. 23:03:40 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 23:04:05 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0003 seconds. 23:04:17 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 23:04:27 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:59315/tests/dromaeo/cssquery-ext.html (next: http://localhost:59315/tests/dromaeo/cssquery-jquery.html) 23:04:27 INFO - PROCESS | 3396 | RSS: Main: 242880512 23:04:27 INFO - PROCESS | 3396 | 23:04:31 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.03324 seconds. 23:04:33 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.03371 seconds. 23:04:44 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 23:04:56 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00079 seconds. 23:05:14 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 23:05:42 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 23:05:46 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0007 seconds. 23:06:00 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 2.00008 seconds. 23:06:00 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 2.00008 seconds. 23:06:09 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01613 seconds. 23:06:27 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 23:06:35 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 23:06:47 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01716 seconds. 23:06:57 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 23:06:59 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 23:07:07 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 23:07:29 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:59315/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59315/tests/dromaeo/cssquery-mootools.html) 23:07:30 INFO - PROCESS | 3396 | RSS: Main: 195276800 23:07:30 INFO - PROCESS | 3396 | 23:07:33 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01609 seconds. 23:07:37 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01651 seconds. 23:07:47 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 23:08:07 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 23:08:17 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.017 seconds. 23:08:21 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 23:08:47 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 23:08:53 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00042 seconds. 23:09:23 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 23:09:40 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00082 seconds. 23:09:52 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 23:10:10 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01629 seconds. 23:10:26 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 23:10:28 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 23:10:32 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:59315/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59315/tests/dromaeo/cssquery-prototype.html) 23:10:33 INFO - PROCESS | 3396 | RSS: Main: 220680192 23:10:33 INFO - PROCESS | 3396 | 23:10:36 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 23:10:45 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 23:10:49 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.05041 seconds. 23:10:53 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 23:11:06 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 23:11:48 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.04979 seconds. 23:11:58 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 23:12:01 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00074 seconds. 23:12:16 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00019 seconds. 23:12:24 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 23:12:30 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 2.0161 seconds. 23:12:30 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 2.0161 seconds. 23:12:36 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00033 seconds. 23:12:47 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01595 seconds. 23:13:01 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00055 seconds. 23:13:09 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 23:13:14 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 23:13:35 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:59315/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59315/tests/dromaeo/cssquery-yui.html) 23:13:36 INFO - PROCESS | 3396 | RSS: Main: 176041984 23:13:36 INFO - PROCESS | 3396 | 23:13:42 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 23:13:53 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01611 seconds. 23:14:12 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01653 seconds. 23:14:33 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00061 seconds. 23:14:43 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 23:14:51 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 23:15:01 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 23:15:07 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 23:15:39 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.0009 seconds. 23:15:54 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 23:16:16 INFO - PROCESS | 3396 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 23:16:38 INFO - PROCESS | 3396 | Cycle 1(1): loaded http://localhost:59315/tests/dromaeo/cssquery-yui.html (next: http://localhost:59315/tests/dromaeo/cssquery-dojo.html) 23:16:39 INFO - PROCESS | 3396 | RSS: Main: 262455296 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - PROCESS | 3396 | __start_tp_report 23:16:39 INFO - PROCESS | 3396 | _x_x_mozilla_page_load 23:16:39 INFO - PROCESS | 3396 | _x_x_mozilla_page_load_details 23:16:39 INFO - PROCESS | 3396 | |i|pagename|runs| 23:16:39 INFO - PROCESS | 3396 | |0;dojo.html;3026.4854974654313;3058.5412188171763;3069.969300306996;3082.8150310981327;3108.222944263934;5284.524392804647;5411.945880541199;5438.891222175564;5445.373782015069;5457.5633949284065;5471.480209380103;5545.584081193906;5554.638948468347;5555.944440555593;5565.0817615093465;4995.35060442142;5005.0990821652085;5046.116929537335;5082.110630639641;5092;4860.05228980349;4866.172750632401;5050.949490505103;5110.156824124015;5110.591152707793;4895.902081958358;4908.214685650297;4952.900941981158;4966.180580204262;4991.376077990251;4916.803327866899;4925.876853078666;4982.800687972477;4988.5260900214425;5009.799608015675;4796.880077998043;4912.287718280829;4929.334539837112;4937.382827146606;4961.925571116436;5259.947400525984;5281.603879709037;5288.365396152486;5307.177387504943;5326.307580014612;5659.915101273484;5672.347680016768;5750.424957504258;5782.508486778629;5792.710364481801;5661.716914154275;5842.094475356284;5846.853828654318;5857.970710146422;5858.384869588717;5287.550558202557;5416.756245968941;5459.372172200208;5478.91781623276;5507.421720719347;6050.818475445743;6077.696115194222;6079.604825686405;6083.756649733961;6086.087086936906;5479.397266300703;5784.219130417424;5791.88416231673;5796.275465566804;5799;5562.443755624405;5784.4504772046585;5815.185873977646;5868.501177399883;5968.791092311835;5898.970505147533;5909.527237820964;5923.703814809242;5932.555058370639;5937.0203916354185;5886.793962211302;5949.881002380015;5953.940460595425;5954.55340849438;5982.790602328984;4400.4939431965095;4426.336049592587;4448.332750087513;4467.01725620363;4478.081993191388;3967.2263908537557;3989.92020159601;4021.617946295097;4021.9798901005306;4033.092554175287;5555.194496797942;5560.304961879765;5560.388357280696;5562.777488900399;5591;5687.488126068673;5734.827955161351;5831.096180092092;5854.2974843018255;5871.4715675589405;5649.915251271234;5650.039493286069;5657.4908258256955;5672.347680016768;5685.886282274331;5152.098382782998;5155.819546315861;5156.845294641167;5161.483851614884;5182.352205974254;5756.165356023437;5779.306483221956;6072.392760723892;6157.599756015934;6169.907451388232;5897.439743224387;5982.820515384546;5983.431574000463;6003.729832157476;6014.669193194416;6119.4798442132915;6123.326434092335;6131.570790044654;6189.319174890848;6192.225971753531;5638.069718496402;5684.459976302244;5709.429057094258;5756.30924289088;5763.538916886555;5786.479216870502;5809.099589563624;5828.504577110993;5917.437843404869;5930.229070220844;5748.338941021669;5800.100984347433;5805.042168042226;5812.447817457504;5821.563382746226;5512.889742205213;5784.50831679312;5797.420257974169;5800.361960184461;5806.65160090396;5502.0371434997915;5612.663240205601;5650.152477128299;5653.802116925888;5752.424757524214;5743.253377061123;5785.27684039495;5786.161006654096;5791.218185545066;5798;5524.558035357242;5573.080441727069;5612.8877422450505;5614.6631202128;5617.045102332531 23:16:39 INFO - PROCESS | 3396 | |1;ext.html;590.1472372421853;624.3818619566629;647.304148040856;653.9346065393459;678.084585809158;35788.82105589472;46693.299600505976;47907.281390779164;47909.281360779576;48056.51943480564;49746.50253497473;50013;50101.74949125259;50137.74931125349;50188.247176292294;8445.535495547743;9260.861087083682;9400.62397504099;9411.200047995928;9412.435253884754;8450.323974082059;10993.120550355992;11224.887751122507;11239.438028098602;11688.707782305426;11322.830157547643;11386.146039047104;11397.601083962063;11402.543898244061;11405.429728513582;11159.888401116006;11184.217104802667;11222.102231821435;11269.54921803131;11271.380074095921;11025.83461248074;11092.500837462252;11116.610918617891;11122;11130.944345278222;45970.31044534335;46095.84760380984;46164.845878853295;46253.07493850104;46254.53745462569;3738.8317525711136;3815.9427608585893;4424.446944131983;4437.667174961858;4530.8867278317975;35406.46890296647;35532.11169720752;35535.466967995504;35548.28903421943;36050;4369.890752731207;4451.532589078132;4624.791884365211;4777.06847164803;4787.425508938914;364705;366492;368127;368501;372337;35671.64328356683;36526.634733652325;60347.39652603505;60662.69668651628;60746.392536074076;41602.37596436056;42008.15983680309;42018;42027.57972420298;42052.78973605174;17026.31894724222;17132.229049692793;17146.82853171477;17148.571285717706;17164.141792910305;13851.722965540834;14107.435702571987;14785.704285914222;15196.088234705952;15301.846981530265;9457.196138328181;9523.666671666459;9608.807823843485;9663.226941844636;9715.659951901649;3624.637536246354;3661.9816900915857;3671.57776855665;3674.2651469706166;3754.755940863835;1064.8136576099153;1129.4352823588206;1144.1590431033185;1169.1465230381868;1207.9335636540075;34557.334759807105;34920.65079349225;35219.82390088033;35470.82264588712;35626;1125.6285425809463;1130.0564029035847;1161.3960740414936;1213.3751118174148;1227.5028613411582;18180.727289090675;18406.63186736258;18445.815541844677;18491.907540462213;18683.532911677044;560665;567798;568227;569655;571416;65161.348386516474;65333.673331633036;65801;65912;65944;1330.8070329802124;1389.7151084027953;1432.1192466633154;1437.2454461407845;1450.1081834671602;31823.840880795447;35295.647043529236;35561;36067.81966090153;36154.4576831353;54524.45475545194;54959.45040549543;55063.44936550743;55083.72458137684;55096.72451637877;413526;418041;418238;418385;419932;30154.944576939703;31647.36705265836;31675.841620791747;33246.66753332532;33352.1661958453;52747.736261318445;52767;53011.20483192832;53023.46976530185;53085.734571326895;42851;43892.78053609712;44222.336664949406;44307.77846110878;45772.08455830931;44676.10647786961;45510.08979820451;45517.08965820731;46423.76788116038;46752;43637.12725745531;44133.11733765371;44888.77555612201;44895.55104449044;44976.77511612421;36960.26079478342;39051.80474097611;39481.605183947795;39611.60388396079;39765.80117099396;29795.510224489124;30286.84856575703;30289;30447.238819029702;30526 23:16:39 INFO - PROCESS | 3396 | |2;jquery.html;389.35047005130076;429.1138798381342;433.3131985802503;434.3084174034978;449.58638052991245;16790.664186716273;19328.613427731438;20820.063097160626;21701.45746356342;21852;20586.279480218196;20758.377248682526;21108;21289.467763305925;21412.25057123001;5855.79504717335;5929.318128415222;6077.270727512682;6077.4530292273685;6123.969380153093;6007.579469437142;6178.3512731163255;6186.536009799283;6187.4431301182885;6189.907151392733;6131.325554189036;6174.475169610589;6186.597871138384;6700.128983232173;6711.094002309675;6604.6130312634405;6731.798046058626;6757.763478278286;6757.932420675779;6760.1549806274215;5964.910526342108;5994.820155395345;6022.608530445551;6024;6024.518038556948;14942.25288735559;15045.473408430653;15102.093874367572;17920;24135.7586424137;2335.521218150275;2372.8220383471134;2462.8645424501647;2562.4362640219124;2583.6124581312765;10898;10937.343759374464;10994.505247263893;11438.313701177956;11476.655700329004;2035.460602940222;2061.2167376397024;2062.7834077421953;2064.1846470644014;2069.5240094778287;85927.14072859316;87402.56298718466;87584.56207718984;89218.55390723005;90192;10529.84205236922;11620.941895290469;11805.586804461802;11900.821487677691;12120.27278363301;15528.84471155274;15659.138747369005;16383.918080409521;16412.09733464671;17171.914140429468;8638;8767.693130740521;8849;8901.065388134282;8929.776755581162;8422.957885210657;8444.915550844413;8452.78868028292;8658;8661.91338086611;5033.798648054037;5085.821996230115;5110.872228194325;5192.532672059458;5247.5569218465225;2068.5449201175716;2081.0011194626663;2082.7188329575615;2098.6747054206317;2128.542363391878;974.2108891797667;991.9752006199902;992.538469611634;1015.7765291635828;1032.912202462784;19763.901180494006;20943.266985655428;20979.895100524704;21068.473288167614;22326.665100023514;1012.2610494339174;1042.2443728297076;1043.2905624175482;1071.4321409652896;1098.1050443888205;10867.603133523799;12144.757104857854;12172.878271217352;12398.008159347231;12488.625341239778;530896;542366;551224;573249;573291;95858.52070739742;103056.48471757594;103700.48149759203;104112;106501;459.6231090505753;483.09902032708743;485.2405984634107;1103.9889601104046;1112.3770688414352;11705.356205408443;11937.46281417321;12192.146549741434;12491.87508124907;12605.369731513205;29999.850000749855;39345.21309573735;39993.40009899912;41428.96427589334;43710.12579748323;58690.4130958685;58698.70650646719;61073.69463152656;61862;62481;9450.574724137568;9488.288378371788;9514.619415223591;9600.375975561567;9617.134457898823;13427.395767190696;13749.793753093512;13975.371108299947;14122.78815817784;14211.928940355232;6063.484603808725;8600.698975535826;11016.512770775842;12147.210431321935;12624.873751262738;11574.247673901169;11686.474108664961;11936.820947685965;11997.340146292037;12123.454444550192;12107.152499324962;12589.055820813292;12624.818502128099;12836.294003829506;12839.743205135657;9327.766805829908;9429.905700942903;9440.10319019692;9748.317617766688;9783.559739811582;9658.3722058066;12012.399380031138;12192.878071219175;12306.569270075504;12755.107142499935 23:16:39 INFO - PROCESS | 3396 | |3;mootools.html;1474.063969379444;1528.3275358842109;1688.8733344999127;1830.4417152768401;1870.4575673054806;9101.27189824814;9859.75350616235;9872.654457094002;9873.851892221604;9877.753056173598;8328.125546817588;8622.568871556428;8907.688230911903;9122.543872806365;9137.771555711111;2454.1410506322777;2481.10680155144;2492.339530024536;2497.12600589793;2508.7240403555593;2150.204424362988;2460.3480077779313;2535.062027049985;2539.606361014046;2542.0975553678454;2251.3696165073725;2441.353041444019;2529.304441278654;2533.556627590174;2555.5783295756178;2439.5364880672614;2483.8261321707487;2484.5900426429616;2487.950240995188;2490.2155820916446;2496.8002559795355;2501.6747822782922;2504;2504.261242933339;2504.8497090174465;9488.857667134998;9659.32384733069;9673.129418352382;9673.613055477843;9682;5552.5280351170195;5704.857378565527;5706.229658996065;5711.857203569903;5715.685637289947;3472.49648800925;3532.646735326447;3570.393033184365;3585.15748799032;3593.7484376093685;1256.0893352319586;1258.0564576567574;1261.9495220191181;1275.7065874848745;1291.3930452687223;13387.062905596613;13469.797953030713;13486.460541578423;13500.122492037986;13519.391627376792;4514.571115743999;4738.3840100787365;4818.518148185223;4824.614030877521;4838.95161048392;10313.896861031444;10322.48387580618;10323.690289291333;10327.380357178594;10334.328268662513;5593;5606.271184745957;5606.97196514023;5611.5230205433;5616.045272303636;5076.213186956028;5164.56101231392;5244.737763111905;5245.396779370345;5323.334583177103;3915.138669492707;3931.68546516278;3975.4434379186937;4013.3578627420056;4018.1561872006623;1152.6023521884936;1210.9212901161573;1212.078820096733;1224.9693757655953;1232.2544860359533;899.4063917814342;1034.2036631793476;1036.2176556699644;1049.3284298049255;1057.2704833664748;6346.30190679025;6346.746130154835;6526.510511711641;6588.242352129567;6597.554174633502;962.7737481691797;987.7086259553449;988.7874107067015;1009.9697009089739;1046.1421634259834;4466.888327791831;4557.817687292538;4561.429821272341;4567.977160114178;4594.287885377772;11279.379634120038;11773.17587768848;11918;12098.153129281041;12121.69695757613;11413.429328533706;11823;11851.46668399924;11875.584354547549;11883.287002779862;8728.25809806161;8788.91211087881;8887.289016878634;8935.865962010575;8938.329625278131;6462.256840463407;6571.671416429063;6579;6602.656584249617;6604.108445360007;10882.891171088188;10906.61826836057;10972.177086718688;11058.612948546763;11117.888821112008;10420.478976051016;10952;11214.831777523506;11331.263467874562;11339.603113890975;7867;8254.587270636324;8256.422050456651;8258.793530161554;8266.669333226844;9040.773980650536;9074.909250907407;9141.497217653094;9211.217046551119;9227;8103.1491693371345;8175.79560510992;8195.67217311301;8353.582320884054;8395.202455766692;7618.8095297618;7789.532628042336;7889.842203156019;7908.604569771604;7923.564203968837;7069.752558660422;7122.358987691133;7122.501424900207;7122.821929451805;7189.496735228484;5331.653442526223;5517.36550296721;5525;5528.612997090165;5750.137479378127;5233.110371236823;5280.313559237428;5286.709230992333;5465.863353416196;5494.09347457665 23:16:39 INFO - PROCESS | 3396 | |4;prototype.html;324.1927600275314;326.0869565217391;329.6094128457776;335.6492465373684;338.7865644643874;5565.193047008182;5566.328162482011;5582.325302409276;5610.859728506789;5653.180288858113;5224.817131400402;5331.920021199676;5349.839504814852;5486.506214440699;5582.330120385549;4281.507626622947;4295.742255464682;4308.97845510772;4316.52518222996;4349.304111342186;4294.97852510737;4319.50325712544;4326.891827704301;4338.240807858628;4341;3949.782761948092;4066.369712694537;4202.516710578277;4225.8520951766695;4247.469066366704;4233.899186211607;4244.448221731155;4247.341662042389;4251.277282861905;4269.466316710411;3796.335641262768;3809.561900381464;3813.389857622767;3819.7899115548635;3820.87941192638;5239.266502689627;5385.165299378603;5394.406615272317;5397.460253974571;5419.187121931742;4912.214045752698;4929.433115191763;4931.80272789088;4932.260160975882;4954.083494553519;5752.223449834302;5760.107183386582;5774.047282198432;5784.2769653793275;5821.767129314865;4058.3100872851687;4174.1443004184375;4179.913222562156;4180.331147016463;4184.790760461965;6003.759849606011;6004.729787159588;6006.309274433453;6010.338862725097;6012.264419689031;4253.128108737701;4264.978675106605;4279.144171165779;4283.828646854154;4284.121755040209;4198.139381426861;4244.511881133647;4269.167512335065;4285.035866929916;4318.179545886272;4880.926786098212;4882.780274887638;4892;4893.584045356113;4910.8772280693265;5824.359320474744;5825.650460972355;5844.240248767633;5869.325027621793;5899.056151015817;1379.7723375642906;1382.654336415896;1439.6107756015494;1453.404104317225;1468.4933697874217;924.690228773355;971.6842026341467;1001.9749506262256;1004.8794144702537;1004.9648262310931;5667;5672.716364181774;5674.467893668686;5695.914561281584;5745.109508026179;5826.417358264224;6008.128821320884;6022.457978781843;6023.45788879003;6039.214902062704;5617.21359009739;5643.576731745054;5647.745851436695;5649.350324712628;5657.236273103161;3143.6953664229545;3174.333389988128;3176.7935084219453;3189.7607679424136;3191.6170059592996;5884.6469211847425;6043.456088952015;6101.8474538136015;6116.938830611726;6121.469632591852;5997.790077347272;6033.5173186145;6033.668148251888;6049;6102.938970610237;5925.73334199962;6113.296970848321;6144.815655530341;6159.3224745277985;6173.629582225081;5870.911936321044;5886.440788125293;5905.051333059994;5917.526597872074;5951.137085122719;5828.883422331614;5875.588708790344;5908.527317814651;5927.436893495111;5957.463828255304;5907.755053079803;6042.848928776816;6084.148219249397;6091.7258723358445;6094.268687757321;4107.958920410758;4144.445832812695;4151;4152.294110001368;4175.937360939528;5691.7438715257085;5972.223610930551;5983.850403739767;5986.88026239464;5990.3710110439515;5699.401562835843;5732.828015159552;5734.971325143348;5788.7395067222915;5828.105865593614;5694.857628559319;5714.3714191438075;5731.942680573141;5755.165501002415;5772.278465191851;5410.323709536308;5525.254090697705;5698;5760.193572899713;5769;5862.736176871966;5884.823455296348;5952.589201373451;5954.136650185612;5966.49284810796;5564;5710.600257981901;5790.363060063474;5810.593258471867;5822.525426693723 23:16:39 INFO - PROCESS | 3396 | |5;yui.html;841.8021764885251;853.5945425922691;888.6401007591925;902;922.2391526990233;15805.367785288588;16778.58053548662;16791.83208167918;16836.494905152835;16845.49463516095;15470.303836327364;15593.92203038986;15608.687826243467;15611.687766244668;15617.84382156178;710.5168485429906;718.2458418660411;839.5634270179503;847.3644766425181;865.6321063547967;859.9656013759474;871.2507243770352;871.6426265231256;872.2934423117264;872.3544577013029;925.763930197802;929.488781170357;930.2557953637097;930.3441074042784;930.3673502018625;753.6872198037835;771.5370777533491;878.7363790862779;932.4312169576586;932.9440233585973;658.1378394303478;661.4609093588709;662.2715013485176;662.6620423583959;662.7382184037326;15366.769498457532;15414.537563873102;15418.22908854564;15447.382104715798;15539.45611903578;15672.92163539175;15828.28772705231;15871.841281587152;15978.440754573534;16280.511584652479;14232.288385580783;14404.423823047066;14439.566812995627;14537.636559086108;14585.70828583433;4460.732356058615;4469.7541635210055;4539.931901021488;4577.084583083363;4644;70807;84862;85857.14142858554;86674;87695;10409;11298.717532061764;11361.091112711129;11412.144089193178;11605.419729013516;23177.420564485685;23198;23202.3039308821;23208.18771342995;23385.88307058454;9278.582463789146;9321.533923303808;9321.813563728687;9444.574994125282;9652.034796520291;5086.389633243961;5112.335396398445;5136.255242989746;5249.580033597303;5371.194320851825;3569.214772749991;3747.793871337048;3774.867879624255;3782.8108594570162;3784.148566572499;604.6825416656203;612.935641757609;615.7290792051483;622.9470495007885;630.7098734582052;640.69887153038;645.9386358296049;719.3561762222781;733.8972543843867;751.1662055118813;9526.666566670134;9928.411454167299;10025.982268589176;10183.490825458697;10530.684079477627;724.5435375713267;734.346431675809;735.3528894572742;735.7682330066012;749.568997826252;4818.277258411196;4919.877003074952;4970.229614409773;4975.527324904201;4985.800567977313;78349.60825195952;108009.4599527013;126290.36854815666;141845;146659;67923.32076679269;98896.50551747339;107256.46371768248;107854.46072769586;108501;86092.56953715191;87246.56376718076;96887.51556242033;98185;101911;10178.643747468803;10210.333265037974;10408.271421000458;10990.835137472784;11149.721256968316;39262.607373927036;40773;55848.441515585946;56018.719906400205;56833.71583142058;85868;86084.1391586076;86726.1327386718;89839.55080224818;90220.5488972551;14289.64275893111;14354.138751674933;14365.64085897861;14472.782908256175;14674.706505870035;15115.848841511444;15575.92212038978;15649.374025039326;15813.920930395734;16382.197958224348;14663.266836657911;14718.264086795403;15618;15801.13093779853;15807.762883556987;14141.787873182117;14298.642533936734;14641.633959151106;14677.55967320982;14747.852521474648;13842.377093031038;13930.164190148624;14240;14274.429022838971;14600.12399256048;11806.645800625995;12212.328321942023;12239.53125624895;12971.35143242853;13014.544490942772;12031.879681203425;12522.812157817822;12546.811797823222;12678.492860285854;13287.66780830487 23:16:39 INFO - PROCESS | 3396 | __end_tp_report 23:16:39 INFO - PROCESS | 3396 | __start_cc_report 23:16:39 INFO - PROCESS | 3396 | _x_x_mozilla_cycle_collect,878 23:16:39 INFO - PROCESS | 3396 | __end_cc_report 23:16:39 INFO - PROCESS | 3396 | __startTimestamp1474352199135__endTimestamp 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - PROCESS | 3396 | ------- Summary: start ------- 23:16:39 INFO - PROCESS | 3396 | Number of tests: 6 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - PROCESS | 3396 | [#0] dojo.html Cycles:165 Average:5438.54 Median:5650.04 stddev:636.38 (11.3%) stddev-sans-first:609.55 23:16:39 INFO - PROCESS | 3396 | Values: 3026.5 3058.5 3070.0 3082.8 3108.2 5284.5 5411.9 5438.9 5445.4 5457.6 5471.5 5545.6 5554.6 5555.9 5565.1 4995.4 5005.1 5046.1 5082.1 5092.0 4860.1 4866.2 5050.9 5110.2 5110.6 4895.9 4908.2 4952.9 4966.2 4991.4 4916.8 4925.9 4982.8 4988.5 5009.8 4796.9 4912.3 4929.3 4937.4 4961.9 5259.9 5281.6 5288.4 5307.2 5326.3 5659.9 5672.3 5750.4 5782.5 5792.7 5661.7 5842.1 5846.9 5858.0 5858.4 5287.6 5416.8 5459.4 5478.9 5507.4 6050.8 6077.7 6079.6 6083.8 6086.1 5479.4 5784.2 5791.9 5796.3 5799.0 5562.4 5784.5 5815.2 5868.5 5968.8 5899.0 5909.5 5923.7 5932.6 5937.0 5886.8 5949.9 5953.9 5954.6 5982.8 4400.5 4426.3 4448.3 4467.0 4478.1 3967.2 3989.9 4021.6 4022.0 4033.1 5555.2 5560.3 5560.4 5562.8 5591.0 5687.5 5734.8 5831.1 5854.3 5871.5 5649.9 5650.0 5657.5 5672.3 5685.9 5152.1 5155.8 5156.8 5161.5 5182.4 5756.2 5779.3 6072.4 6157.6 6169.9 5897.4 5982.8 5983.4 6003.7 6014.7 6119.5 6123.3 6131.6 6189.3 6192.2 5638.1 5684.5 5709.4 5756.3 5763.5 5786.5 5809.1 5828.5 5917.4 5930.2 5748.3 5800.1 5805.0 5812.4 5821.6 5512.9 5784.5 5797.4 5800.4 5806.7 5502.0 5612.7 5650.2 5653.8 5752.4 5743.3 5785.3 5786.2 5791.2 5798.0 5524.6 5573.1 5612.9 5614.7 5617.0 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - PROCESS | 3396 | [#1] ext.html Cycles:180 Average:62018.06 Median:39266.70 stddev:121633.77 (309.8%) stddev-sans-first:121887.55 23:16:39 INFO - PROCESS | 3396 | Values: 590.1 624.4 647.3 653.9 678.1 35788.8 46693.3 47907.3 47909.3 48056.5 49746.5 50013.0 50101.7 50137.7 50188.2 8445.5 9260.9 9400.6 9411.2 9412.4 8450.3 10993.1 11224.9 11239.4 11688.7 11322.8 11386.1 11397.6 11402.5 11405.4 11159.9 11184.2 11222.1 11269.5 11271.4 11025.8 11092.5 11116.6 11122.0 11130.9 45970.3 46095.8 46164.8 46253.1 46254.5 3738.8 3815.9 4424.4 4437.7 4530.9 35406.5 35532.1 35535.5 35548.3 36050.0 4369.9 4451.5 4624.8 4777.1 4787.4 364705.0 366492.0 368127.0 368501.0 372337.0 35671.6 36526.6 60347.4 60662.7 60746.4 41602.4 42008.2 42018.0 42027.6 42052.8 17026.3 17132.2 17146.8 17148.6 17164.1 13851.7 14107.4 14785.7 15196.1 15301.8 9457.2 9523.7 9608.8 9663.2 9715.7 3624.6 3662.0 3671.6 3674.3 3754.8 1064.8 1129.4 1144.2 1169.1 1207.9 34557.3 34920.7 35219.8 35470.8 35626.0 1125.6 1130.1 1161.4 1213.4 1227.5 18180.7 18406.6 18445.8 18491.9 18683.5 560665.0 567798.0 568227.0 569655.0 571416.0 65161.3 65333.7 65801.0 65912.0 65944.0 1330.8 1389.7 1432.1 1437.2 1450.1 31823.8 35295.6 35561.0 36067.8 36154.5 54524.5 54959.5 55063.4 55083.7 55096.7 413526.0 418041.0 418238.0 418385.0 419932.0 30154.9 31647.4 31675.8 33246.7 33352.2 52747.7 52767.0 53011.2 53023.5 53085.7 42851.0 43892.8 44222.3 44307.8 45772.1 44676.1 45510.1 45517.1 46423.8 46752.0 43637.1 44133.1 44888.8 44895.6 44976.8 36960.3 39051.8 39481.6 39611.6 39765.8 29795.5 30286.8 30289.0 30447.2 30526.0 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - PROCESS | 3396 | [#2] jquery.html Cycles:180 Average:31389.35 Median:13254.29 stddev:91420.60 (689.7%) stddev-sans-first:91647.42 23:16:39 INFO - PROCESS | 3396 | Values: 389.4 429.1 433.3 434.3 449.6 16790.7 19328.6 20820.1 21701.5 21852.0 20586.3 20758.4 21108.0 21289.5 21412.3 5855.8 5929.3 6077.3 6077.5 6124.0 6007.6 6178.4 6186.5 6187.4 6189.9 6131.3 6174.5 6186.6 6700.1 6711.1 6604.6 6731.8 6757.8 6757.9 6760.2 5964.9 5994.8 6022.6 6024.0 6024.5 14942.3 15045.5 15102.1 17920.0 24135.8 2335.5 2372.8 2462.9 2562.4 2583.6 10898.0 10937.3 10994.5 11438.3 11476.7 2035.5 2061.2 2062.8 2064.2 2069.5 85927.1 87402.6 87584.6 89218.6 90192.0 10529.8 11620.9 11805.6 11900.8 12120.3 15528.8 15659.1 16383.9 16412.1 17171.9 8638.0 8767.7 8849.0 8901.1 8929.8 8423.0 8444.9 8452.8 8658.0 8661.9 5033.8 5085.8 5110.9 5192.5 5247.6 2068.5 2081.0 2082.7 2098.7 2128.5 974.2 992.0 992.5 1015.8 1032.9 19763.9 20943.3 20979.9 21068.5 22326.7 1012.3 1042.2 1043.3 1071.4 1098.1 10867.6 12144.8 12172.9 12398.0 12488.6 530896.0 542366.0 551224.0 573249.0 573291.0 95858.5 103056.5 103700.5 104112.0 106501.0 459.6 483.1 485.2 1104.0 1112.4 11705.4 11937.5 12192.1 12491.9 12605.4 29999.9 39345.2 39993.4 41429.0 43710.1 58690.4 58698.7 61073.7 61862.0 62481.0 9450.6 9488.3 9514.6 9600.4 9617.1 13427.4 13749.8 13975.4 14122.8 14211.9 6063.5 8600.7 11016.5 12147.2 12624.9 11574.2 11686.5 11936.8 11997.3 12123.5 12107.2 12589.1 12624.8 12836.3 12839.7 9327.8 9429.9 9440.1 9748.3 9783.6 9658.4 12012.4 12192.9 12306.6 12755.1 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - PROCESS | 3396 | [#3] mootools.html Cycles:180 Average:6188.20 Median:4581.13 stddev:3549.29 (77.5%) stddev-sans-first:3541.57 23:16:39 INFO - PROCESS | 3396 | Values: 1474.1 1528.3 1688.9 1830.4 1870.5 9101.3 9859.8 9872.7 9873.9 9877.8 8328.1 8622.6 8907.7 9122.5 9137.8 2454.1 2481.1 2492.3 2497.1 2508.7 2150.2 2460.3 2535.1 2539.6 2542.1 2251.4 2441.4 2529.3 2533.6 2555.6 2439.5 2483.8 2484.6 2488.0 2490.2 2496.8 2501.7 2504.0 2504.3 2504.8 9488.9 9659.3 9673.1 9673.6 9682.0 5552.5 5704.9 5706.2 5711.9 5715.7 3472.5 3532.6 3570.4 3585.2 3593.7 1256.1 1258.1 1261.9 1275.7 1291.4 13387.1 13469.8 13486.5 13500.1 13519.4 4514.6 4738.4 4818.5 4824.6 4839.0 10313.9 10322.5 10323.7 10327.4 10334.3 5593.0 5606.3 5607.0 5611.5 5616.0 5076.2 5164.6 5244.7 5245.4 5323.3 3915.1 3931.7 3975.4 4013.4 4018.2 1152.6 1210.9 1212.1 1225.0 1232.3 899.4 1034.2 1036.2 1049.3 1057.3 6346.3 6346.7 6526.5 6588.2 6597.6 962.8 987.7 988.8 1010.0 1046.1 4466.9 4557.8 4561.4 4568.0 4594.3 11279.4 11773.2 11918.0 12098.2 12121.7 11413.4 11823.0 11851.5 11875.6 11883.3 8728.3 8788.9 8887.3 8935.9 8938.3 6462.3 6571.7 6579.0 6602.7 6604.1 10882.9 10906.6 10972.2 11058.6 11117.9 10420.5 10952.0 11214.8 11331.3 11339.6 7867.0 8254.6 8256.4 8258.8 8266.7 9040.8 9074.9 9141.5 9211.2 9227.0 8103.1 8175.8 8195.7 8353.6 8395.2 7618.8 7789.5 7889.8 7908.6 7923.6 7069.8 7122.4 7122.5 7122.8 7189.5 5331.7 5517.4 5525.0 5528.6 5750.1 5233.1 5280.3 5286.7 5465.9 5494.1 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - PROCESS | 3396 | [#4] prototype.html Cycles:180 Average:4874.59 Median:5630.40 stddev:1443.10 (25.6%) stddev-sans-first:1406.15 23:16:39 INFO - PROCESS | 3396 | Values: 324.2 326.1 329.6 335.6 338.8 5565.2 5566.3 5582.3 5610.9 5653.2 5224.8 5331.9 5349.8 5486.5 5582.3 4281.5 4295.7 4309.0 4316.5 4349.3 4295.0 4319.5 4326.9 4338.2 4341.0 3949.8 4066.4 4202.5 4225.9 4247.5 4233.9 4244.4 4247.3 4251.3 4269.5 3796.3 3809.6 3813.4 3819.8 3820.9 5239.3 5385.2 5394.4 5397.5 5419.2 4912.2 4929.4 4931.8 4932.3 4954.1 5752.2 5760.1 5774.0 5784.3 5821.8 4058.3 4174.1 4179.9 4180.3 4184.8 6003.8 6004.7 6006.3 6010.3 6012.3 4253.1 4265.0 4279.1 4283.8 4284.1 4198.1 4244.5 4269.2 4285.0 4318.2 4880.9 4882.8 4892.0 4893.6 4910.9 5824.4 5825.7 5844.2 5869.3 5899.1 1379.8 1382.7 1439.6 1453.4 1468.5 924.7 971.7 1002.0 1004.9 1005.0 5667.0 5672.7 5674.5 5695.9 5745.1 5826.4 6008.1 6022.5 6023.5 6039.2 5617.2 5643.6 5647.7 5649.4 5657.2 3143.7 3174.3 3176.8 3189.8 3191.6 5884.6 6043.5 6101.8 6116.9 6121.5 5997.8 6033.5 6033.7 6049.0 6102.9 5925.7 6113.3 6144.8 6159.3 6173.6 5870.9 5886.4 5905.1 5917.5 5951.1 5828.9 5875.6 5908.5 5927.4 5957.5 5907.8 6042.8 6084.1 6091.7 6094.3 4108.0 4144.4 4151.0 4152.3 4175.9 5691.7 5972.2 5983.9 5986.9 5990.4 5699.4 5732.8 5735.0 5788.7 5828.1 5694.9 5714.4 5731.9 5755.2 5772.3 5410.3 5525.3 5698.0 5760.2 5769.0 5862.7 5884.8 5952.6 5954.1 5966.5 5564.0 5710.6 5790.4 5810.6 5822.5 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - PROCESS | 3396 | [#5] yui.html Cycles:180 Average:22227.05 Median:21523.38 stddev:32119.22 (149.2%) stddev-sans-first:32169.18 23:16:39 INFO - PROCESS | 3396 | Values: 841.8 853.6 888.6 902.0 922.2 15805.4 16778.6 16791.8 16836.5 16845.5 15470.3 15593.9 15608.7 15611.7 15617.8 710.5 718.2 839.6 847.4 865.6 860.0 871.3 871.6 872.3 872.4 925.8 929.5 930.3 930.3 930.4 753.7 771.5 878.7 932.4 932.9 658.1 661.5 662.3 662.7 662.7 15366.8 15414.5 15418.2 15447.4 15539.5 15672.9 15828.3 15871.8 15978.4 16280.5 14232.3 14404.4 14439.6 14537.6 14585.7 4460.7 4469.8 4539.9 4577.1 4644.0 70807.0 84862.0 85857.1 86674.0 87695.0 10409.0 11298.7 11361.1 11412.1 11605.4 23177.4 23198.0 23202.3 23208.2 23385.9 9278.6 9321.5 9321.8 9444.6 9652.0 5086.4 5112.3 5136.3 5249.6 5371.2 3569.2 3747.8 3774.9 3782.8 3784.1 604.7 612.9 615.7 622.9 630.7 640.7 645.9 719.4 733.9 751.2 9526.7 9928.4 10026.0 10183.5 10530.7 724.5 734.3 735.4 735.8 749.6 4818.3 4919.9 4970.2 4975.5 4985.8 78349.6 108009.5 126290.4 141845.0 146659.0 67923.3 98896.5 107256.5 107854.5 108501.0 86092.6 87246.6 96887.5 98185.0 101911.0 10178.6 10210.3 10408.3 10990.8 11149.7 39262.6 40773.0 55848.4 56018.7 56833.7 85868.0 86084.1 86726.1 89839.6 90220.5 14289.6 14354.1 14365.6 14472.8 14674.7 15115.8 15575.9 15649.4 15813.9 16382.2 14663.3 14718.3 15618.0 15801.1 15807.8 14141.8 14298.6 14641.6 14677.6 14747.9 13842.4 13930.2 14240.0 14274.4 14600.1 11806.6 12212.3 12239.5 12971.4 13014.5 12031.9 12522.8 12546.8 12678.5 13287.7 23:16:39 INFO - PROCESS | 3396 | -------- Summary: end -------- 23:16:39 INFO - PROCESS | 3396 | 23:16:39 INFO - TEST-INFO | 3396: exit 0 23:16:39 INFO - TEST-OK | dromaeo_css | took 1115837ms 23:16:39 INFO - TEST-START | kraken 23:16:39 INFO - operating with platform_type : linux_ 23:16:39 INFO - Initialising browser for kraken test... 23:16:39 INFO - TEST-INFO | started process 3714 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprFgfwC/profile http://localhost:59315/getInfo.html) 23:16:44 INFO - PROCESS | 3714 | __metrics Screen width/height:1600/1200 23:16:44 INFO - PROCESS | 3714 | colorDepth:24 23:16:44 INFO - PROCESS | 3714 | Browser inner width/height: 1024/697 23:16:44 INFO - PROCESS | 3714 | __metrics 23:16:44 INFO - PROCESS | 3714 | 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" 23:16:44 INFO - PROCESS | 3714 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:16:45 INFO - PROCESS | 3714 | console.error: PushService: 23:16:45 INFO - PROCESS | 3714 | stateChangeProcessEnqueue: Error transitioning state 23:16:45 INFO - PROCESS | 3714 | UnknownError 23:16:46 INFO - TEST-INFO | 3714: exit 0 23:16:46 INFO - Browser initialized. 23:16:46 INFO - Running cycle 1/1 for kraken test... 23:16:46 INFO - TEST-INFO | started process 3806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprFgfwC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:16:57 INFO - PROCESS | 3806 | RSS: Main: 144125952 23:16:57 INFO - PROCESS | 3806 | 23:17:00 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59315/tests/kraken/driver.html?testName=audio-beat-detection) 23:17:01 INFO - PROCESS | 3806 | RSS: Main: 186056704 23:17:01 INFO - PROCESS | 3806 | 23:17:03 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59315/tests/kraken/driver.html?testName=audio-dft) 23:17:04 INFO - PROCESS | 3806 | RSS: Main: 174985216 23:17:04 INFO - PROCESS | 3806 | 23:17:07 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59315/tests/kraken/driver.html?testName=audio-fft) 23:17:07 INFO - PROCESS | 3806 | RSS: Main: 266366976 23:17:07 INFO - PROCESS | 3806 | 23:17:09 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59315/tests/kraken/driver.html?testName=audio-oscillator) 23:17:10 INFO - PROCESS | 3806 | RSS: Main: 172421120 23:17:10 INFO - PROCESS | 3806 | 23:17:12 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59315/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:17:13 INFO - PROCESS | 3806 | RSS: Main: 174776320 23:17:13 INFO - PROCESS | 3806 | 23:17:16 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59315/tests/kraken/driver.html?testName=imaging-darkroom) 23:17:16 INFO - PROCESS | 3806 | RSS: Main: 211406848 23:17:16 INFO - PROCESS | 3806 | 23:17:20 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59315/tests/kraken/driver.html?testName=imaging-desaturate) 23:17:20 INFO - PROCESS | 3806 | RSS: Main: 210407424 23:17:20 INFO - PROCESS | 3806 | 23:17:23 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59315/tests/kraken/driver.html?testName=json-parse-financial) 23:17:23 INFO - PROCESS | 3806 | RSS: Main: 209186816 23:17:23 INFO - PROCESS | 3806 | 23:17:25 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59315/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:17:26 INFO - PROCESS | 3806 | RSS: Main: 171020288 23:17:26 INFO - PROCESS | 3806 | 23:17:27 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59315/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:17:28 INFO - PROCESS | 3806 | RSS: Main: 178737152 23:17:28 INFO - PROCESS | 3806 | 23:17:30 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59315/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:17:30 INFO - PROCESS | 3806 | RSS: Main: 183455744 23:17:30 INFO - PROCESS | 3806 | 23:17:33 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59315/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:17:34 INFO - PROCESS | 3806 | RSS: Main: 183685120 23:17:34 INFO - PROCESS | 3806 | 23:17:36 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59315/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:17:37 INFO - PROCESS | 3806 | RSS: Main: 181592064 23:17:37 INFO - PROCESS | 3806 | 23:17:38 INFO - PROCESS | 3806 | Cycle 1(1): loaded http://localhost:59315/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59315/tests/kraken/driver.html?testName=ai-astar) 23:17:39 INFO - PROCESS | 3806 | RSS: Main: 234151936 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | __start_tp_report 23:17:39 INFO - PROCESS | 3806 | _x_x_mozilla_page_load 23:17:39 INFO - PROCESS | 3806 | _x_x_mozilla_page_load_details 23:17:39 INFO - PROCESS | 3806 | |i|pagename|runs| 23:17:39 INFO - PROCESS | 3806 | |0;ai-astar;109;111;112;112;111;112;112;113;113;112 23:17:39 INFO - PROCESS | 3806 | |1;audio-beat-detection;142;117;126;164;118;122;117;116;155;120 23:17:39 INFO - PROCESS | 3806 | |2;audio-dft;160;161;159;159;161;159;159;160;160;161 23:17:39 INFO - PROCESS | 3806 | |3;audio-fft;75;74;75;103;79;76;76;75;102;76 23:17:39 INFO - PROCESS | 3806 | |4;audio-oscillator;82;81;122;83;82;81;101;82;83;114 23:17:39 INFO - PROCESS | 3806 | |5;imaging-gaussian-blur;117;117;117;116;117;116;116;117;116;117 23:17:39 INFO - PROCESS | 3806 | |6;imaging-darkroom;146;146;146;146;146;145;146;147;146;145 23:17:39 INFO - PROCESS | 3806 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 23:17:39 INFO - PROCESS | 3806 | |8;json-parse-financial;50;52;50;51;51;51;52;50;50;50 23:17:39 INFO - PROCESS | 3806 | |9;json-stringify-tinderbox;59;59;60;60;59;59;58;59;59;59 23:17:39 INFO - PROCESS | 3806 | |10;stanford-crypto-aes;73;74;72;73;73;74;73;80;73;73 23:17:39 INFO - PROCESS | 3806 | |11;stanford-crypto-ccm;119;117;117;117;116;117;115;117;162;127 23:17:39 INFO - PROCESS | 3806 | |12;stanford-crypto-pbkdf2;154;150;155;161;156;151;151;152;152;152 23:17:39 INFO - PROCESS | 3806 | |13;stanford-crypto-sha256-iterative;63;62;63;62;62;64;63;66;62;62 23:17:39 INFO - PROCESS | 3806 | __end_tp_report 23:17:39 INFO - PROCESS | 3806 | __start_cc_report 23:17:39 INFO - PROCESS | 3806 | _x_x_mozilla_cycle_collect,1618 23:17:39 INFO - PROCESS | 3806 | __end_cc_report 23:17:39 INFO - PROCESS | 3806 | __startTimestamp1474352259112__endTimestamp 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | ------- Summary: start ------- 23:17:39 INFO - PROCESS | 3806 | Number of tests: 14 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.16 (1.0%) stddev-sans-first:0.71 23:17:39 INFO - PROCESS | 3806 | Values: 109.0 111.0 112.0 112.0 111.0 112.0 112.0 113.0 113.0 112.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:124.00 stddev:17.58 (14.2%) stddev-sans-first:18.08 23:17:39 INFO - PROCESS | 3806 | Values: 142.0 117.0 126.0 164.0 118.0 122.0 117.0 116.0 155.0 120.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 23:17:39 INFO - PROCESS | 3806 | Values: 160.0 161.0 159.0 159.0 161.0 159.0 159.0 160.0 160.0 161.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.50 stddev:11.36 (15.0%) stddev-sans-first:11.83 23:17:39 INFO - PROCESS | 3806 | Values: 75.0 74.0 75.0 103.0 79.0 76.0 76.0 75.0 102.0 76.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:82.00 stddev:15.50 (18.9%) stddev-sans-first:16.08 23:17:39 INFO - PROCESS | 3806 | Values: 82.0 81.0 122.0 83.0 82.0 81.0 101.0 82.0 83.0 114.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:17:39 INFO - PROCESS | 3806 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 116.0 117.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 23:17:39 INFO - PROCESS | 3806 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 147.0 146.0 145.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:17:39 INFO - PROCESS | 3806 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.83 23:17:39 INFO - PROCESS | 3806 | Values: 50.0 52.0 50.0 51.0 51.0 51.0 52.0 50.0 50.0 50.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 23:17:39 INFO - PROCESS | 3806 | Values: 59.0 59.0 60.0 60.0 59.0 59.0 58.0 59.0 59.0 59.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:73.00 stddev:2.25 (3.1%) stddev-sans-first:2.37 23:17:39 INFO - PROCESS | 3806 | Values: 73.0 74.0 72.0 73.0 73.0 74.0 73.0 80.0 73.0 73.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#11] stanford-crypto-ccm Cycles:10 Average:122.40 Median:117.00 stddev:14.31 (12.2%) stddev-sans-first:15.12 23:17:39 INFO - PROCESS | 3806 | Values: 119.0 117.0 117.0 117.0 116.0 117.0 115.0 117.0 162.0 127.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:153.00 stddev:3.27 (2.1%) stddev-sans-first:3.46 23:17:39 INFO - PROCESS | 3806 | Values: 154.0 150.0 155.0 161.0 156.0 151.0 151.0 152.0 152.0 152.0 23:17:39 INFO - PROCESS | 3806 | 23:17:39 INFO - PROCESS | 3806 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:1.29 (2.0%) stddev-sans-first:1.36 23:17:39 INFO - PROCESS | 3806 | Values: 63.0 62.0 63.0 62.0 62.0 64.0 63.0 66.0 62.0 62.0 23:17:39 INFO - PROCESS | 3806 | -------- Summary: end -------- 23:17:39 INFO - PROCESS | 3806 | 23:17:40 INFO - TEST-INFO | 3806: exit 0 23:17:40 INFO - TEST-OK | kraken | took 60154ms 23:17:40 INFO - SUITE-END | took 1175s 23:17:40 INFO - Completed test suite (00:19:36) 23:17:40 INFO - javascript benchmark 23:17:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3026.4854974654313, 3058.5412188171763, 3069.969300306996, 3082.8150310981327, 3108.222944263934, 5284.524392804647, 5411.945880541199, 5438.891222175564, 5445.373782015069, 5457.5633949284065, 5471.480209380103, 5545.584081193906, 5554.638948468347, 5555.944440555593, 5565.0817615093465, 4995.35060442142, 5005.0990821652085, 5046.116929537335, 5082.110630639641, 5092.0, 4860.05228980349, 4866.172750632401, 5050.949490505103, 5110.156824124015, 5110.591152707793, 4895.902081958358, 4908.214685650297, 4952.900941981158, 4966.180580204262, 4991.376077990251, 4916.803327866899, 4925.876853078666, 4982.800687972477, 4988.5260900214425, 5009.799608015675, 4796.880077998043, 4912.287718280829, 4929.334539837112, 4937.382827146606, 4961.925571116436, 5259.947400525984, 5281.603879709037, 5288.365396152486, 5307.177387504943, 5326.307580014612, 5659.915101273484, 5672.347680016768, 5750.424957504258, 5782.508486778629, 5792.710364481801, 5661.716914154275, 5842.094475356284, 5846.853828654318, 5857.970710146422, 5858.384869588717, 5287.550558202557, 5416.756245968941, 5459.372172200208, 5478.91781623276, 5507.421720719347, 6050.818475445743, 6077.696115194222, 6079.604825686405, 6083.756649733961, 6086.087086936906, 5479.397266300703, 5784.219130417424, 5791.88416231673, 5796.275465566804, 5799.0, 5562.443755624405, 5784.4504772046585, 5815.185873977646, 5868.501177399883, 5968.791092311835, 5898.970505147533, 5909.527237820964, 5923.703814809242, 5932.555058370639, 5937.0203916354185, 5886.793962211302, 5949.881002380015, 5953.940460595425, 5954.55340849438, 5982.790602328984, 4400.4939431965095, 4426.336049592587, 4448.332750087513, 4467.01725620363, 4478.081993191388, 3967.2263908537557, 3989.92020159601, 4021.617946295097, 4021.9798901005306, 4033.092554175287, 5555.194496797942, 5560.304961879765, 5560.388357280696, 5562.777488900399, 5591.0, 5687.488126068673, 5734.827955161351, 5831.096180092092, 5854.2974843018255, 5871.4715675589405, 5649.915251271234, 5650.039493286069, 5657.4908258256955, 5672.347680016768, 5685.886282274331, 5152.098382782998, 5155.819546315861, 5156.845294641167, 5161.483851614884, 5182.352205974254, 5756.165356023437, 5779.306483221956, 6072.392760723892, 6157.599756015934, 6169.907451388232, 5897.439743224387, 5982.820515384546, 5983.431574000463, 6003.729832157476, 6014.669193194416, 6119.4798442132915, 6123.326434092335, 6131.570790044654, 6189.319174890848, 6192.225971753531, 5638.069718496402, 5684.459976302244, 5709.429057094258, 5756.30924289088, 5763.538916886555, 5786.479216870502, 5809.099589563624, 5828.504577110993, 5917.437843404869, 5930.229070220844, 5748.338941021669, 5800.100984347433, 5805.042168042226, 5812.447817457504, 5821.563382746226, 5512.889742205213, 5784.50831679312, 5797.420257974169, 5800.361960184461, 5806.65160090396, 5502.0371434997915, 5612.663240205601, 5650.152477128299, 5653.802116925888, 5752.424757524214, 5743.253377061123, 5785.27684039495, 5786.161006654096, 5791.218185545066, 5798.0, 5524.558035357242, 5573.080441727069, 5612.8877422450505, 5614.6631202128, 5617.045102332531], "value": 5393.958546869769, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.1472372421853, 624.3818619566629, 647.304148040856, 653.9346065393459, 678.084585809158, 35788.82105589472, 46693.299600505976, 47907.281390779164, 47909.281360779576, 48056.51943480564, 49746.50253497473, 50013.0, 50101.74949125259, 50137.74931125349, 50188.247176292294, 8445.535495547743, 9260.861087083682, 9400.62397504099, 9411.200047995928, 9412.435253884754, 8450.323974082059, 10993.120550355992, 11224.887751122507, 11239.438028098602, 11688.707782305426, 11322.830157547643, 11386.146039047104, 11397.601083962063, 11402.543898244061, 11405.429728513582, 11159.888401116006, 11184.217104802667, 11222.102231821435, 11269.54921803131, 11271.380074095921, 11025.83461248074, 11092.500837462252, 11116.610918617891, 11122.0, 11130.944345278222, 45970.31044534335, 46095.84760380984, 46164.845878853295, 46253.07493850104, 46254.53745462569, 3738.8317525711136, 3815.9427608585893, 4424.446944131983, 4437.667174961858, 4530.8867278317975, 35406.46890296647, 35532.11169720752, 35535.466967995504, 35548.28903421943, 36050.0, 4369.890752731207, 4451.532589078132, 4624.791884365211, 4777.06847164803, 4787.425508938914, 364705.0, 366492.0, 368127.0, 368501.0, 372337.0, 35671.64328356683, 36526.634733652325, 60347.39652603505, 60662.69668651628, 60746.392536074076, 41602.37596436056, 42008.15983680309, 42018.0, 42027.57972420298, 42052.78973605174, 17026.31894724222, 17132.229049692793, 17146.82853171477, 17148.571285717706, 17164.141792910305, 13851.722965540834, 14107.435702571987, 14785.704285914222, 15196.088234705952, 15301.846981530265, 9457.196138328181, 9523.666671666459, 9608.807823843485, 9663.226941844636, 9715.659951901649, 3624.637536246354, 3661.9816900915857, 3671.57776855665, 3674.2651469706166, 3754.755940863835, 1064.8136576099153, 1129.4352823588206, 1144.1590431033185, 1169.1465230381868, 1207.9335636540075, 34557.334759807105, 34920.65079349225, 35219.82390088033, 35470.82264588712, 35626.0, 1125.6285425809463, 1130.0564029035847, 1161.3960740414936, 1213.3751118174148, 1227.5028613411582, 18180.727289090675, 18406.63186736258, 18445.815541844677, 18491.907540462213, 18683.532911677044, 560665.0, 567798.0, 568227.0, 569655.0, 571416.0, 65161.348386516474, 65333.673331633036, 65801.0, 65912.0, 65944.0, 1330.8070329802124, 1389.7151084027953, 1432.1192466633154, 1437.2454461407845, 1450.1081834671602, 31823.840880795447, 35295.647043529236, 35561.0, 36067.81966090153, 36154.4576831353, 54524.45475545194, 54959.45040549543, 55063.44936550743, 55083.72458137684, 55096.72451637877, 413526.0, 418041.0, 418238.0, 418385.0, 419932.0, 30154.944576939703, 31647.36705265836, 31675.841620791747, 33246.66753332532, 33352.1661958453, 52747.736261318445, 52767.0, 53011.20483192832, 53023.46976530185, 53085.734571326895, 42851.0, 43892.78053609712, 44222.336664949406, 44307.77846110878, 45772.08455830931, 44676.10647786961, 45510.08979820451, 45517.08965820731, 46423.76788116038, 46752.0, 43637.12725745531, 44133.11733765371, 44888.77555612201, 44895.55104449044, 44976.77511612421, 36960.26079478342, 39051.80474097611, 39481.605183947795, 39611.60388396079, 39765.80117099396, 29795.510224489124, 30286.84856575703, 30289.0, 30447.238819029702, 30526.0], "value": 20823.02825697738, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.35047005130076, 429.1138798381342, 433.3131985802503, 434.3084174034978, 449.58638052991245, 16790.664186716273, 19328.613427731438, 20820.063097160626, 21701.45746356342, 21852.0, 20586.279480218196, 20758.377248682526, 21108.0, 21289.467763305925, 21412.25057123001, 5855.79504717335, 5929.318128415222, 6077.270727512682, 6077.4530292273685, 6123.969380153093, 6007.579469437142, 6178.3512731163255, 6186.536009799283, 6187.4431301182885, 6189.907151392733, 6131.325554189036, 6174.475169610589, 6186.597871138384, 6700.128983232173, 6711.094002309675, 6604.6130312634405, 6731.798046058626, 6757.763478278286, 6757.932420675779, 6760.1549806274215, 5964.910526342108, 5994.820155395345, 6022.608530445551, 6024.0, 6024.518038556948, 14942.25288735559, 15045.473408430653, 15102.093874367572, 17920.0, 24135.7586424137, 2335.521218150275, 2372.8220383471134, 2462.8645424501647, 2562.4362640219124, 2583.6124581312765, 10898.0, 10937.343759374464, 10994.505247263893, 11438.313701177956, 11476.655700329004, 2035.460602940222, 2061.2167376397024, 2062.7834077421953, 2064.1846470644014, 2069.5240094778287, 85927.14072859316, 87402.56298718466, 87584.56207718984, 89218.55390723005, 90192.0, 10529.84205236922, 11620.941895290469, 11805.586804461802, 11900.821487677691, 12120.27278363301, 15528.84471155274, 15659.138747369005, 16383.918080409521, 16412.09733464671, 17171.914140429468, 8638.0, 8767.693130740521, 8849.0, 8901.065388134282, 8929.776755581162, 8422.957885210657, 8444.915550844413, 8452.78868028292, 8658.0, 8661.91338086611, 5033.798648054037, 5085.821996230115, 5110.872228194325, 5192.532672059458, 5247.5569218465225, 2068.5449201175716, 2081.0011194626663, 2082.7188329575615, 2098.6747054206317, 2128.542363391878, 974.2108891797667, 991.9752006199902, 992.538469611634, 1015.7765291635828, 1032.912202462784, 19763.901180494006, 20943.266985655428, 20979.895100524704, 21068.473288167614, 22326.665100023514, 1012.2610494339174, 1042.2443728297076, 1043.2905624175482, 1071.4321409652896, 1098.1050443888205, 10867.603133523799, 12144.757104857854, 12172.878271217352, 12398.008159347231, 12488.625341239778, 530896.0, 542366.0, 551224.0, 573249.0, 573291.0, 95858.52070739742, 103056.48471757594, 103700.48149759203, 104112.0, 106501.0, 459.6231090505753, 483.09902032708743, 485.2405984634107, 1103.9889601104046, 1112.3770688414352, 11705.356205408443, 11937.46281417321, 12192.146549741434, 12491.87508124907, 12605.369731513205, 29999.850000749855, 39345.21309573735, 39993.40009899912, 41428.96427589334, 43710.12579748323, 58690.4130958685, 58698.70650646719, 61073.69463152656, 61862.0, 62481.0, 9450.574724137568, 9488.288378371788, 9514.619415223591, 9600.375975561567, 9617.134457898823, 13427.395767190696, 13749.793753093512, 13975.371108299947, 14122.78815817784, 14211.928940355232, 6063.484603808725, 8600.698975535826, 11016.512770775842, 12147.210431321935, 12624.873751262738, 11574.247673901169, 11686.474108664961, 11936.820947685965, 11997.340146292037, 12123.454444550192, 12107.152499324962, 12589.055820813292, 12624.818502128099, 12836.294003829506, 12839.743205135657, 9327.766805829908, 9429.905700942903, 9440.10319019692, 9748.317617766688, 9783.559739811582, 9658.3722058066, 12012.399380031138, 12192.878071219175, 12306.569270075504, 12755.107142499935], "value": 9508.055433342837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.063969379444, 1528.3275358842109, 1688.8733344999127, 1830.4417152768401, 1870.4575673054806, 9101.27189824814, 9859.75350616235, 9872.654457094002, 9873.851892221604, 9877.753056173598, 8328.125546817588, 8622.568871556428, 8907.688230911903, 9122.543872806365, 9137.771555711111, 2454.1410506322777, 2481.10680155144, 2492.339530024536, 2497.12600589793, 2508.7240403555593, 2150.204424362988, 2460.3480077779313, 2535.062027049985, 2539.606361014046, 2542.0975553678454, 2251.3696165073725, 2441.353041444019, 2529.304441278654, 2533.556627590174, 2555.5783295756178, 2439.5364880672614, 2483.8261321707487, 2484.5900426429616, 2487.950240995188, 2490.2155820916446, 2496.8002559795355, 2501.6747822782922, 2504.0, 2504.261242933339, 2504.8497090174465, 9488.857667134998, 9659.32384733069, 9673.129418352382, 9673.613055477843, 9682.0, 5552.5280351170195, 5704.857378565527, 5706.229658996065, 5711.857203569903, 5715.685637289947, 3472.49648800925, 3532.646735326447, 3570.393033184365, 3585.15748799032, 3593.7484376093685, 1256.0893352319586, 1258.0564576567574, 1261.9495220191181, 1275.7065874848745, 1291.3930452687223, 13387.062905596613, 13469.797953030713, 13486.460541578423, 13500.122492037986, 13519.391627376792, 4514.571115743999, 4738.3840100787365, 4818.518148185223, 4824.614030877521, 4838.95161048392, 10313.896861031444, 10322.48387580618, 10323.690289291333, 10327.380357178594, 10334.328268662513, 5593.0, 5606.271184745957, 5606.97196514023, 5611.5230205433, 5616.045272303636, 5076.213186956028, 5164.56101231392, 5244.737763111905, 5245.396779370345, 5323.334583177103, 3915.138669492707, 3931.68546516278, 3975.4434379186937, 4013.3578627420056, 4018.1561872006623, 1152.6023521884936, 1210.9212901161573, 1212.078820096733, 1224.9693757655953, 1232.2544860359533, 899.4063917814342, 1034.2036631793476, 1036.2176556699644, 1049.3284298049255, 1057.2704833664748, 6346.30190679025, 6346.746130154835, 6526.510511711641, 6588.242352129567, 6597.554174633502, 962.7737481691797, 987.7086259553449, 988.7874107067015, 1009.9697009089739, 1046.1421634259834, 4466.888327791831, 4557.817687292538, 4561.429821272341, 4567.977160114178, 4594.287885377772, 11279.379634120038, 11773.17587768848, 11918.0, 12098.153129281041, 12121.69695757613, 11413.429328533706, 11823.0, 11851.46668399924, 11875.584354547549, 11883.287002779862, 8728.25809806161, 8788.91211087881, 8887.289016878634, 8935.865962010575, 8938.329625278131, 6462.256840463407, 6571.671416429063, 6579.0, 6602.656584249617, 6604.108445360007, 10882.891171088188, 10906.61826836057, 10972.177086718688, 11058.612948546763, 11117.888821112008, 10420.478976051016, 10952.0, 11214.831777523506, 11331.263467874562, 11339.603113890975, 7867.0, 8254.587270636324, 8256.422050456651, 8258.793530161554, 8266.669333226844, 9040.773980650536, 9074.909250907407, 9141.497217653094, 9211.217046551119, 9227.0, 8103.1491693371345, 8175.79560510992, 8195.67217311301, 8353.582320884054, 8395.202455766692, 7618.8095297618, 7789.532628042336, 7889.842203156019, 7908.604569771604, 7923.564203968837, 7069.752558660422, 7122.358987691133, 7122.501424900207, 7122.821929451805, 7189.496735228484, 5331.653442526223, 5517.36550296721, 5525.0, 5528.612997090165, 5750.137479378127, 5233.110371236823, 5280.313559237428, 5286.709230992333, 5465.863353416196, 5494.09347457665], "value": 4935.67763993555, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.1927600275314, 326.0869565217391, 329.6094128457776, 335.6492465373684, 338.7865644643874, 5565.193047008182, 5566.328162482011, 5582.325302409276, 5610.859728506789, 5653.180288858113, 5224.817131400402, 5331.920021199676, 5349.839504814852, 5486.506214440699, 5582.330120385549, 4281.507626622947, 4295.742255464682, 4308.97845510772, 4316.52518222996, 4349.304111342186, 4294.97852510737, 4319.50325712544, 4326.891827704301, 4338.240807858628, 4341.0, 3949.782761948092, 4066.369712694537, 4202.516710578277, 4225.8520951766695, 4247.469066366704, 4233.899186211607, 4244.448221731155, 4247.341662042389, 4251.277282861905, 4269.466316710411, 3796.335641262768, 3809.561900381464, 3813.389857622767, 3819.7899115548635, 3820.87941192638, 5239.266502689627, 5385.165299378603, 5394.406615272317, 5397.460253974571, 5419.187121931742, 4912.214045752698, 4929.433115191763, 4931.80272789088, 4932.260160975882, 4954.083494553519, 5752.223449834302, 5760.107183386582, 5774.047282198432, 5784.2769653793275, 5821.767129314865, 4058.3100872851687, 4174.1443004184375, 4179.913222562156, 4180.331147016463, 4184.790760461965, 6003.759849606011, 6004.729787159588, 6006.309274433453, 6010.338862725097, 6012.264419689031, 4253.128108737701, 4264.978675106605, 4279.144171165779, 4283.828646854154, 4284.121755040209, 4198.139381426861, 4244.511881133647, 4269.167512335065, 4285.035866929916, 4318.179545886272, 4880.926786098212, 4882.780274887638, 4892.0, 4893.584045356113, 4910.8772280693265, 5824.359320474744, 5825.650460972355, 5844.240248767633, 5869.325027621793, 5899.056151015817, 1379.7723375642906, 1382.654336415896, 1439.6107756015494, 1453.404104317225, 1468.4933697874217, 924.690228773355, 971.6842026341467, 1001.9749506262256, 1004.8794144702537, 1004.9648262310931, 5667.0, 5672.716364181774, 5674.467893668686, 5695.914561281584, 5745.109508026179, 5826.417358264224, 6008.128821320884, 6022.457978781843, 6023.45788879003, 6039.214902062704, 5617.21359009739, 5643.576731745054, 5647.745851436695, 5649.350324712628, 5657.236273103161, 3143.6953664229545, 3174.333389988128, 3176.7935084219453, 3189.7607679424136, 3191.6170059592996, 5884.6469211847425, 6043.456088952015, 6101.8474538136015, 6116.938830611726, 6121.469632591852, 5997.790077347272, 6033.5173186145, 6033.668148251888, 6049.0, 6102.938970610237, 5925.73334199962, 6113.296970848321, 6144.815655530341, 6159.3224745277985, 6173.629582225081, 5870.911936321044, 5886.440788125293, 5905.051333059994, 5917.526597872074, 5951.137085122719, 5828.883422331614, 5875.588708790344, 5908.527317814651, 5927.436893495111, 5957.463828255304, 5907.755053079803, 6042.848928776816, 6084.148219249397, 6091.7258723358445, 6094.268687757321, 4107.958920410758, 4144.445832812695, 4151.0, 4152.294110001368, 4175.937360939528, 5691.7438715257085, 5972.223610930551, 5983.850403739767, 5986.88026239464, 5990.3710110439515, 5699.401562835843, 5732.828015159552, 5734.971325143348, 5788.7395067222915, 5828.105865593614, 5694.857628559319, 5714.3714191438075, 5731.942680573141, 5755.165501002415, 5772.278465191851, 5410.323709536308, 5525.254090697705, 5698.0, 5760.193572899713, 5769.0, 5862.736176871966, 5884.823455296348, 5952.589201373451, 5954.136650185612, 5966.49284810796, 5564.0, 5710.600257981901, 5790.363060063474, 5810.593258471867, 5822.525426693723], "value": 4407.635214111211, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [841.8021764885251, 853.5945425922691, 888.6401007591925, 902.0, 922.2391526990233, 15805.367785288588, 16778.58053548662, 16791.83208167918, 16836.494905152835, 16845.49463516095, 15470.303836327364, 15593.92203038986, 15608.687826243467, 15611.687766244668, 15617.84382156178, 710.5168485429906, 718.2458418660411, 839.5634270179503, 847.3644766425181, 865.6321063547967, 859.9656013759474, 871.2507243770352, 871.6426265231256, 872.2934423117264, 872.3544577013029, 925.763930197802, 929.488781170357, 930.2557953637097, 930.3441074042784, 930.3673502018625, 753.6872198037835, 771.5370777533491, 878.7363790862779, 932.4312169576586, 932.9440233585973, 658.1378394303478, 661.4609093588709, 662.2715013485176, 662.6620423583959, 662.7382184037326, 15366.769498457532, 15414.537563873102, 15418.22908854564, 15447.382104715798, 15539.45611903578, 15672.92163539175, 15828.28772705231, 15871.841281587152, 15978.440754573534, 16280.511584652479, 14232.288385580783, 14404.423823047066, 14439.566812995627, 14537.636559086108, 14585.70828583433, 4460.732356058615, 4469.7541635210055, 4539.931901021488, 4577.084583083363, 4644.0, 70807.0, 84862.0, 85857.14142858554, 86674.0, 87695.0, 10409.0, 11298.717532061764, 11361.091112711129, 11412.144089193178, 11605.419729013516, 23177.420564485685, 23198.0, 23202.3039308821, 23208.18771342995, 23385.88307058454, 9278.582463789146, 9321.533923303808, 9321.813563728687, 9444.574994125282, 9652.034796520291, 5086.389633243961, 5112.335396398445, 5136.255242989746, 5249.580033597303, 5371.194320851825, 3569.214772749991, 3747.793871337048, 3774.867879624255, 3782.8108594570162, 3784.148566572499, 604.6825416656203, 612.935641757609, 615.7290792051483, 622.9470495007885, 630.7098734582052, 640.69887153038, 645.9386358296049, 719.3561762222781, 733.8972543843867, 751.1662055118813, 9526.666566670134, 9928.411454167299, 10025.982268589176, 10183.490825458697, 10530.684079477627, 724.5435375713267, 734.346431675809, 735.3528894572742, 735.7682330066012, 749.568997826252, 4818.277258411196, 4919.877003074952, 4970.229614409773, 4975.527324904201, 4985.800567977313, 78349.60825195952, 108009.4599527013, 126290.36854815666, 141845.0, 146659.0, 67923.32076679269, 98896.50551747339, 107256.46371768248, 107854.46072769586, 108501.0, 86092.56953715191, 87246.56376718076, 96887.51556242033, 98185.0, 101911.0, 10178.643747468803, 10210.333265037974, 10408.271421000458, 10990.835137472784, 11149.721256968316, 39262.607373927036, 40773.0, 55848.441515585946, 56018.719906400205, 56833.71583142058, 85868.0, 86084.1391586076, 86726.1327386718, 89839.55080224818, 90220.5488972551, 14289.64275893111, 14354.138751674933, 14365.64085897861, 14472.782908256175, 14674.706505870035, 15115.848841511444, 15575.92212038978, 15649.374025039326, 15813.920930395734, 16382.197958224348, 14663.266836657911, 14718.264086795403, 15618.0, 15801.13093779853, 15807.762883556987, 14141.787873182117, 14298.642533936734, 14641.633959151106, 14677.55967320982, 14747.852521474648, 13842.377093031038, 13930.164190148624, 14240.0, 14274.429022838971, 14600.12399256048, 11806.645800625995, 12212.328321942023, 12239.53125624895, 12971.35143242853, 13014.544490942772, 12031.879681203425, 12522.812157817822, 12546.811797823222, 12678.492860285854, 13287.66780830487], "value": 8087.967422689411, "unit": "score"}], "name": "dromaeo_css", "value": 7568.264986786434, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 112.0, 112.0, 111.0, 112.0, 112.0, 113.0, 113.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 126.0, 164.0, 118.0, 122.0, 117.0, 116.0, 155.0, 120.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 159.0, 161.0, 159.0, 159.0, 160.0, 160.0, 161.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 103.0, 79.0, 76.0, 76.0, 75.0, 102.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 122.0, 83.0, 82.0, 81.0, 101.0, 82.0, 83.0, 114.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 50.0, 51.0, 51.0, 51.0, 52.0, 50.0, 50.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 73.0, 73.0, 74.0, 73.0, 80.0, 73.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 117.0, 117.0, 116.0, 117.0, 115.0, 117.0, 162.0, 127.0], "value": 122.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 150.0, 155.0, 161.0, 156.0, 151.0, 151.0, 152.0, 152.0, 152.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 62.0, 62.0, 64.0, 63.0, 66.0, 62.0, 62.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1453.2000000000003, "alertThreshold": 2.0}]} 23:17:40 INFO - Return code: 0 23:17:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:17:40 INFO - # TBPL SUCCESS # 23:17:40 INFO - Running post-action listener: _resource_record_post_action 23:17:40 INFO - [mozharness: 2016-09-20 06:17:40.429007Z] Finished run-tests step (success) 23:17:40 INFO - Running post-run listener: _resource_record_post_run 23:17:40 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 7802880; Write bytes: 384102400; Read time: 1388; Write time: 302324 23:17:40 INFO - TinderboxPrint: CPU usage
13.0% 23:17:40 INFO - TinderboxPrint: I/O read bytes / time
7,802,880 / 1,388 23:17:40 INFO - TinderboxPrint: I/O write bytes / time
384,102,400 / 302,324 23:17:40 INFO - TinderboxPrint: CPU idle
8,283.8 (87.0%) 23:17:40 INFO - TinderboxPrint: CPU user
1,186.0 (12.5%) 23:17:40 INFO - TinderboxPrint: Swap in / out
0 / 0 23:17:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 4718592; Read time: 0; Write time: 412 23:17:40 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 5562368; Write bytes: 323457024; Read time: 940; Write time: 275596 23:17:41 INFO - Running post-run listener: _upload_blobber_files 23:17:41 INFO - Blob upload gear active. 23:17:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:17:41 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 23:17:41 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'] 23:17:41 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 23:17:41 INFO - (blobuploader) - INFO - Open directory for files ... 23:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:17:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:42 INFO - (blobuploader) - INFO - Done attempting. 23:17:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:17:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:17:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:47 INFO - (blobuploader) - INFO - Done attempting. 23:17:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:17:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:17:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:48 INFO - (blobuploader) - INFO - Done attempting. 23:17:48 INFO - (blobuploader) - INFO - Iteration through files over. 23:17:48 INFO - Return code: 0 23:17:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:17:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:17:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc368ac56245512b9dbeb7f2fc1a96ef1e01e61b6641971a84f1e85d7730366d3367a15328c3bab5e35e5a04246f92abcb6c19cd3a91ba7ac8b13b9fa69b0edf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4992dd331c4ca2a5a9a7fbadce3bcda5f50194798a334b65a7a3f1ed7a5cf4c68cc75de5e495b514fea2bc9d73d3809f4898b1d14d039ea9f4ee55432108ad10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/610fca4cdb2e8e2b7383dbedde570afd5cb3b6845ef58e4098e6354d2722671031f6915025d30316628ebb8fb1e5802ba7b4bbce082dfe8dbd3cd893941c0f24"} 23:17:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:17:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:17:48 INFO - Contents: 23:17:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc368ac56245512b9dbeb7f2fc1a96ef1e01e61b6641971a84f1e85d7730366d3367a15328c3bab5e35e5a04246f92abcb6c19cd3a91ba7ac8b13b9fa69b0edf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4992dd331c4ca2a5a9a7fbadce3bcda5f50194798a334b65a7a3f1ed7a5cf4c68cc75de5e495b514fea2bc9d73d3809f4898b1d14d039ea9f4ee55432108ad10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/610fca4cdb2e8e2b7383dbedde570afd5cb3b6845ef58e4098e6354d2722671031f6915025d30316628ebb8fb1e5802ba7b4bbce082dfe8dbd3cd893941c0f24"} 23:17:48 INFO - Running post-run listener: copy_logs_to_upload_dir 23:17:48 INFO - Copying logs to upload dir... 23:17:48 INFO - mkdir: /builds/slave/test/build/upload/logs 23:17:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.979555 ========= master_lag: 0.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-19 23:17:48.542185) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 23:17:48.546197) ========= 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=cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367 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/dc368ac56245512b9dbeb7f2fc1a96ef1e01e61b6641971a84f1e85d7730366d3367a15328c3bab5e35e5a04246f92abcb6c19cd3a91ba7ac8b13b9fa69b0edf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4992dd331c4ca2a5a9a7fbadce3bcda5f50194798a334b65a7a3f1ed7a5cf4c68cc75de5e495b514fea2bc9d73d3809f4898b1d14d039ea9f4ee55432108ad10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/610fca4cdb2e8e2b7383dbedde570afd5cb3b6845ef58e4098e6354d2722671031f6915025d30316628ebb8fb1e5802ba7b4bbce082dfe8dbd3cd893941c0f24"} build_url:https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011106 build_url: 'https://queue.taskcluster.net/v1/task/ee5u3-JmTLWmKExcW5SB0w/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/dc368ac56245512b9dbeb7f2fc1a96ef1e01e61b6641971a84f1e85d7730366d3367a15328c3bab5e35e5a04246f92abcb6c19cd3a91ba7ac8b13b9fa69b0edf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4992dd331c4ca2a5a9a7fbadce3bcda5f50194798a334b65a7a3f1ed7a5cf4c68cc75de5e495b514fea2bc9d73d3809f4898b1d14d039ea9f4ee55432108ad10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/610fca4cdb2e8e2b7383dbedde570afd5cb3b6845ef58e4098e6354d2722671031f6915025d30316628ebb8fb1e5802ba7b4bbce082dfe8dbd3cd893941c0f24"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 23:17:48.572279) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 23:17:48.572681) ========= 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=cbe409c6de289db6ac707de3000001d4-1474351024.760955-956092367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 23:17:48.656798) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 23:17:48.657203) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 23:17:48.657553) ========= ========= Total master_lag: 0.46 =========