builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1474326537.13 results: success (0) buildid: 20160919065232 builduid: f89185a30a7343359f9c5b9acabffd00 revision: 5dddbefdf759f09b1411f33fa0920835b919fc81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.133462) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.133997) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.134355) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.149407) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.149745) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 16:08:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2016-09-19 16:08:57 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081034 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.245342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.245738) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019567 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.299039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.299980) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5dddbefdf759f09b1411f33fa0920835b919fc81 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5dddbefdf759f09b1411f33fa0920835b919fc81 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 16:08:57,673 truncating revision to first 12 chars 2016-09-19 16:08:57,673 Setting DEBUG logging. 2016-09-19 16:08:57,673 attempt 1/10 2016-09-19 16:08:57,673 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5dddbefdf759?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 16:08:57,987 unpacking tar archive at: mozilla-aurora-5dddbefdf759/testing/mozharness/ program finished with exit code 0 elapsedTime=0.635816 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.951844) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.952222) ========= script_repo_revision: 5dddbefdf759f09b1411f33fa0920835b919fc81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.952682) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.952993) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 16:08:57.964596) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-09-19 16:08:57.965315) ========= /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=a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:08:58 INFO - MultiFileLogger online at 20160919 16:08:58 in /builds/slave/test 16:08:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:08:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:08:58 INFO - {'append_to_log': False, 16:08:58 INFO - 'base_work_dir': '/builds/slave/test', 16:08:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:08:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:08:58 INFO - 'branch': 'Mozilla-Aurora', 16:08:58 INFO - 'buildbot_json_path': 'buildprops.json', 16:08:58 INFO - 'config_files': ('talos/linux_config.py',), 16:08:58 INFO - 'default_actions': ('clobber', 16:08:58 INFO - 'read-buildbot-config', 16:08:58 INFO - 'download-and-extract', 16:08:58 INFO - 'populate-webroot', 16:08:58 INFO - 'create-virtualenv', 16:08:58 INFO - 'install', 16:08:58 INFO - 'run-tests'), 16:08:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:08:58 INFO - 'download_minidump_stackwalk': True, 16:08:58 INFO - 'download_symbols': 'ondemand', 16:08:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:08:58 INFO - 'tooltool.py': '/tools/tooltool.py', 16:08:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:08:58 INFO - '/tools/misc-python/virtualenv.py')}, 16:08:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:08:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:08:58 INFO - 'installer_path': 'installer.exe', 16:08:58 INFO - 'log_level': 'info', 16:08:58 INFO - 'log_name': 'talos', 16:08:58 INFO - 'log_to_console': True, 16:08:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:08:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:08:58 INFO - 'opt_config_files': (), 16:08:58 INFO - 'pip_index': False, 16:08:58 INFO - 'sps_profile': False, 16:08:58 INFO - 'sps_profile_interval': 0, 16:08:58 INFO - 'suite': 'dromaeojs', 16:08:58 INFO - 'system_bits': '32', 16:08:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:08:58 INFO - 'title': 'talos-linux64-ix-002', 16:08:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:08:58 INFO - 'use_talos_json': True, 16:08:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:08:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:08:58 INFO - 'work_dir': 'build'} 16:08:58 INFO - [mozharness: 2016-09-19 23:08:58.439370Z] Running clobber step. 16:08:58 INFO - Running pre-action listener: _resource_record_pre_action 16:08:58 INFO - Running main action method: clobber 16:08:58 INFO - rmtree: /builds/slave/test/build 16:08:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:08:58 INFO - Running post-action listener: _resource_record_post_action 16:08:58 INFO - [mozharness: 2016-09-19 23:08:58.934298Z] Finished clobber step (success) 16:08:58 INFO - [mozharness: 2016-09-19 23:08:58.934411Z] Running read-buildbot-config step. 16:08:58 INFO - Running pre-action listener: _resource_record_pre_action 16:08:58 INFO - Running main action method: read_buildbot_config 16:08:58 INFO - Using buildbot properties: 16:08:58 INFO - { 16:08:58 INFO - "project": "", 16:08:58 INFO - "product": "firefox", 16:08:58 INFO - "script_repo_revision": "production", 16:08:58 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:08:58 INFO - "repository": "", 16:08:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:08:58 INFO - "buildid": "20160919065232", 16:08:58 INFO - "stage_platform": "linux64-pgo", 16:08:58 INFO - "basedir": "/builds/slave/test", 16:08:58 INFO - "nightly_build": "True", 16:08:58 INFO - "buildnumber": 760, 16:08:58 INFO - "slavename": "talos-linux64-ix-002", 16:08:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:08:58 INFO - "platform": "ubuntu64_hw", 16:08:58 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:08:58 INFO - "branch": "mozilla-aurora", 16:08:58 INFO - "repo_path": "releases/mozilla-aurora", 16:08:58 INFO - "revision": "5dddbefdf759f09b1411f33fa0920835b919fc81", 16:08:58 INFO - "pgo_build": "True", 16:08:58 INFO - "builduid": "f89185a30a7343359f9c5b9acabffd00", 16:08:58 INFO - "slavebuilddir": "test" 16:08:58 INFO - } 16:08:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 16:08:58 INFO - Running post-action listener: _resource_record_post_action 16:08:58 INFO - [mozharness: 2016-09-19 23:08:58.936911Z] Finished read-buildbot-config step (success) 16:08:58 INFO - [mozharness: 2016-09-19 23:08:58.937001Z] Running download-and-extract step. 16:08:58 INFO - Running pre-action listener: _resource_record_pre_action 16:08:58 INFO - Running main action method: download_and_extract 16:08:58 INFO - mkdir: /builds/slave/test/build/tests 16:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:08:58 INFO - trying https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:08:58 INFO - Downloading https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:08:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:08:59 INFO - Downloaded 1361 bytes. 16:08:59 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:08:59 INFO - Using the following test package requirements: 16:08:59 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 16:08:59 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:08:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:08:59 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:08:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:08:59 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:08:59 INFO - u'jsshell-linux-x86_64.zip'], 16:08:59 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:08:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:08:59 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 16:08:59 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:08:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:08:59 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:08:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:08:59 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:08:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:08:59 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:08:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:08:59 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:08:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 16:08:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:08:59 INFO - Fetch https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 16:09:00 INFO - Expected file size: 21893796 16:09:04 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:09:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 16:09:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:09:04 INFO - Fetch https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 16:09:04 INFO - Expected file size: 21893796 16:09:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 16:09:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:09:09 INFO - Fetch https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 16:09:10 INFO - Expected file size: 14414059 16:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:10 INFO - trying https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:09:10 INFO - Downloading https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:09:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:09:11 INFO - Downloaded 63958077 bytes. 16:09:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:09:11 INFO - mkdir: /builds/slave/test/properties 16:09:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:09:11 INFO - Writing to file /builds/slave/test/properties/build_url 16:09:11 INFO - Contents: 16:09:11 INFO - build_url:https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:09:12 INFO - Running post-action listener: _resource_record_post_action 16:09:12 INFO - Running post-action listener: set_extra_try_arguments 16:09:12 INFO - [mozharness: 2016-09-19 23:09:12.654835Z] Finished download-and-extract step (success) 16:09:12 INFO - [mozharness: 2016-09-19 23:09:12.655063Z] Running populate-webroot step. 16:09:12 INFO - Running pre-action listener: _resource_record_pre_action 16:09:12 INFO - Running main action method: populate_webroot 16:09:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:09:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:09:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:09:12 INFO - '--tppagecycles', 16:09:12 INFO - '7'], 16:09:12 INFO - 'tests': ['tsvgm']}}, 16:09:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:09:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:09:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:09:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:09:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:12 INFO - 'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:12 INFO - 'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['damp', 'tps']}, 16:09:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:12 INFO - 'tests': ['damp', 'tps']}, 16:09:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['dromaeo_dom']}, 16:09:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:09:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['basic_compositor_video']}, 16:09:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:09:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['a11yr', 16:09:12 INFO - 'ts_paint', 16:09:12 INFO - 'tpaint', 16:09:12 INFO - 'sessionrestore', 16:09:12 INFO - 'sessionrestore_no_auto_restore', 16:09:12 INFO - 'tabpaint']}, 16:09:12 INFO - 'other-e10s': {'tests': ['a11yr', 16:09:12 INFO - 'ts_paint', 16:09:12 INFO - 'tpaint', 16:09:12 INFO - 'sessionrestore', 16:09:12 INFO - 'sessionrestore_no_auto_restore', 16:09:12 INFO - 'tabpaint']}, 16:09:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:09:12 INFO - 'ts_paint', 16:09:12 INFO - 'tpaint', 16:09:12 INFO - 'sessionrestore', 16:09:12 INFO - 'sessionrestore_no_auto_restore', 16:09:12 INFO - 'tabpaint']}, 16:09:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:09:12 INFO - 'ts_paint', 16:09:12 INFO - 'tpaint', 16:09:12 INFO - 'sessionrestore', 16:09:12 INFO - 'sessionrestore_no_auto_restore', 16:09:12 INFO - 'tabpaint']}, 16:09:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['a11yr', 16:09:12 INFO - 'ts_paint', 16:09:12 INFO - 'tpaint', 16:09:12 INFO - 'sessionrestore', 16:09:12 INFO - 'sessionrestore_no_auto_restore', 16:09:12 INFO - 'tabpaint']}, 16:09:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['a11yr', 16:09:12 INFO - 'ts_paint', 16:09:12 INFO - 'tpaint', 16:09:12 INFO - 'sessionrestore', 16:09:12 INFO - 'sessionrestore_no_auto_restore', 16:09:12 INFO - 'tabpaint']}, 16:09:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['tsvgx', 16:09:12 INFO - 'tsvgr_opacity', 16:09:12 INFO - 'tart', 16:09:12 INFO - 'tscrollx', 16:09:12 INFO - 'cart']}, 16:09:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:09:12 INFO - 'tsvgr_opacity', 16:09:12 INFO - 'tart', 16:09:12 INFO - 'tscrollx', 16:09:12 INFO - 'cart']}, 16:09:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:12 INFO - 'talos_options': ['--disable-e10s'], 16:09:12 INFO - 'tests': ['tp5o']}, 16:09:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:12 INFO - 'tests': ['tp5o']}, 16:09:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:12 INFO - 'talos_options': ['--disable-e10s', 16:09:12 INFO - '--xperf_path', 16:09:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:12 INFO - 'tests': ['tp5n']}, 16:09:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:12 INFO - 'talos_options': ['--xperf_path', 16:09:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:12 INFO - 'tests': ['tp5n']}}, 16:09:12 INFO - 'talos.zip': {'path': '', 16:09:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:09:12 INFO - Running post-action listener: _resource_record_post_action 16:09:12 INFO - [mozharness: 2016-09-19 23:09:12.670799Z] Finished populate-webroot step (success) 16:09:12 INFO - [mozharness: 2016-09-19 23:09:12.670888Z] Running create-virtualenv step. 16:09:12 INFO - Running pre-action listener: _resource_record_pre_action 16:09:12 INFO - Running main action method: create_virtualenv 16:09:12 INFO - Creating virtualenv /builds/slave/test/build/venv 16:09:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:09:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:09:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:09:12 INFO - Using real prefix '/usr' 16:09:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:09:13 INFO - Installing distribute.............................................................................................................................................................................................done. 16:09:15 INFO - Installing pip.................done. 16:09:15 INFO - Return code: 0 16:09:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:09:15 INFO - Using env: {'DISPLAY': ':0', 16:09:15 INFO - 'HOME': '/home/cltbld', 16:09:15 INFO - 'LANG': 'en_US.UTF-8', 16:09:15 INFO - 'LANGUAGE': 'en_US:en', 16:09:15 INFO - 'LOGNAME': 'cltbld', 16:09:15 INFO - 'MAIL': '/var/mail/cltbld', 16:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:15 INFO - 'MOZ_NO_REMOTE': '1', 16:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:15 INFO - 'NO_EM_RESTART': '1', 16:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:15 INFO - 'PWD': '/builds/slave/test', 16:09:15 INFO - 'SHELL': '/bin/bash', 16:09:15 INFO - 'SHLVL': '1', 16:09:15 INFO - 'TERM': 'linux', 16:09:15 INFO - 'TMOUT': '86400', 16:09:15 INFO - 'USER': 'cltbld', 16:09:15 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:15 INFO - '_': '/tools/buildbot/bin/python'} 16:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:09:15 INFO - Cleaning up... 16:09:15 INFO - Return code: 0 16:09:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:09:15 INFO - Using env: {'DISPLAY': ':0', 16:09:15 INFO - 'HOME': '/home/cltbld', 16:09:15 INFO - 'LANG': 'en_US.UTF-8', 16:09:15 INFO - 'LANGUAGE': 'en_US:en', 16:09:15 INFO - 'LOGNAME': 'cltbld', 16:09:15 INFO - 'MAIL': '/var/mail/cltbld', 16:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:15 INFO - 'MOZ_NO_REMOTE': '1', 16:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:15 INFO - 'NO_EM_RESTART': '1', 16:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:15 INFO - 'PWD': '/builds/slave/test', 16:09:15 INFO - 'SHELL': '/bin/bash', 16:09:15 INFO - 'SHLVL': '1', 16:09:15 INFO - 'TERM': 'linux', 16:09:15 INFO - 'TMOUT': '86400', 16:09:15 INFO - 'USER': 'cltbld', 16:09:15 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:15 INFO - '_': '/tools/buildbot/bin/python'} 16:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:15 INFO - Downloading/unpacking psutil>=3.1.1 16:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:09:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:17 INFO - Installing collected packages: psutil 16:09:17 INFO - Running setup.py install for psutil 16:09:17 INFO - building 'psutil._psutil_linux' extension 16:09:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:09:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:09:17 INFO - building 'psutil._psutil_posix' extension 16:09:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:09:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:09:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:18 INFO - Successfully installed psutil 16:09:18 INFO - Cleaning up... 16:09:18 INFO - Return code: 0 16:09:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:09:18 INFO - Using env: {'DISPLAY': ':0', 16:09:18 INFO - 'HOME': '/home/cltbld', 16:09:18 INFO - 'LANG': 'en_US.UTF-8', 16:09:18 INFO - 'LANGUAGE': 'en_US:en', 16:09:18 INFO - 'LOGNAME': 'cltbld', 16:09:18 INFO - 'MAIL': '/var/mail/cltbld', 16:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:18 INFO - 'MOZ_NO_REMOTE': '1', 16:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:18 INFO - 'NO_EM_RESTART': '1', 16:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:18 INFO - 'PWD': '/builds/slave/test', 16:09:18 INFO - 'SHELL': '/bin/bash', 16:09:18 INFO - 'SHLVL': '1', 16:09:18 INFO - 'TERM': 'linux', 16:09:18 INFO - 'TMOUT': '86400', 16:09:18 INFO - 'USER': 'cltbld', 16:09:18 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:18 INFO - '_': '/tools/buildbot/bin/python'} 16:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:09:20 INFO - Installing collected packages: mozsystemmonitor 16:09:20 INFO - Running setup.py install for mozsystemmonitor 16:09:20 INFO - Successfully installed mozsystemmonitor 16:09:20 INFO - Cleaning up... 16:09:20 INFO - Return code: 0 16:09:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:20 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 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:09:20 INFO - Using env: {'DISPLAY': ':0', 16:09:20 INFO - 'HOME': '/home/cltbld', 16:09:20 INFO - 'LANG': 'en_US.UTF-8', 16:09:20 INFO - 'LANGUAGE': 'en_US:en', 16:09:20 INFO - 'LOGNAME': 'cltbld', 16:09:20 INFO - 'MAIL': '/var/mail/cltbld', 16:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:20 INFO - 'MOZ_NO_REMOTE': '1', 16:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:20 INFO - 'NO_EM_RESTART': '1', 16:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:20 INFO - 'PWD': '/builds/slave/test', 16:09:20 INFO - 'SHELL': '/bin/bash', 16:09:20 INFO - 'SHLVL': '1', 16:09:20 INFO - 'TERM': 'linux', 16:09:20 INFO - 'TMOUT': '86400', 16:09:20 INFO - 'USER': 'cltbld', 16:09:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:20 INFO - '_': '/tools/buildbot/bin/python'} 16:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:20 INFO - Downloading/unpacking jsonschema==2.5.1 16:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:09:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:22 INFO - Downloading functools32-3.2.3-2.tar.gz 16:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:09:22 INFO - warning: no files found matching '*.txt' 16:09:22 INFO - no previously-included directories found matching 'build' 16:09:22 INFO - no previously-included directories found matching 'dist' 16:09:22 INFO - no previously-included directories found matching '.git*' 16:09:22 INFO - Installing collected packages: jsonschema, functools32 16:09:23 INFO - Running setup.py install for functools32 16:09:23 INFO - warning: no files found matching '*.txt' 16:09:23 INFO - no previously-included directories found matching 'build' 16:09:23 INFO - no previously-included directories found matching 'dist' 16:09:23 INFO - no previously-included directories found matching '.git*' 16:09:23 INFO - Successfully installed jsonschema functools32 16:09:23 INFO - Cleaning up... 16:09:23 INFO - Return code: 0 16:09:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:23 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 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:09:23 INFO - Using env: {'DISPLAY': ':0', 16:09:23 INFO - 'HOME': '/home/cltbld', 16:09:23 INFO - 'LANG': 'en_US.UTF-8', 16:09:23 INFO - 'LANGUAGE': 'en_US:en', 16:09:23 INFO - 'LOGNAME': 'cltbld', 16:09:23 INFO - 'MAIL': '/var/mail/cltbld', 16:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:23 INFO - 'MOZ_NO_REMOTE': '1', 16:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:23 INFO - 'NO_EM_RESTART': '1', 16:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:23 INFO - 'PWD': '/builds/slave/test', 16:09:23 INFO - 'SHELL': '/bin/bash', 16:09:23 INFO - 'SHLVL': '1', 16:09:23 INFO - 'TERM': 'linux', 16:09:23 INFO - 'TMOUT': '86400', 16:09:23 INFO - 'USER': 'cltbld', 16:09:23 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:23 INFO - '_': '/tools/buildbot/bin/python'} 16:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:09:23 INFO - Cleaning up... 16:09:23 INFO - Return code: 0 16:09:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:23 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 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:09:23 INFO - Using env: {'DISPLAY': ':0', 16:09:23 INFO - 'HOME': '/home/cltbld', 16:09:23 INFO - 'LANG': 'en_US.UTF-8', 16:09:23 INFO - 'LANGUAGE': 'en_US:en', 16:09:23 INFO - 'LOGNAME': 'cltbld', 16:09:23 INFO - 'MAIL': '/var/mail/cltbld', 16:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:23 INFO - 'MOZ_NO_REMOTE': '1', 16:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:23 INFO - 'NO_EM_RESTART': '1', 16:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:23 INFO - 'PWD': '/builds/slave/test', 16:09:23 INFO - 'SHELL': '/bin/bash', 16:09:23 INFO - 'SHLVL': '1', 16:09:23 INFO - 'TERM': 'linux', 16:09:23 INFO - 'TMOUT': '86400', 16:09:23 INFO - 'USER': 'cltbld', 16:09:23 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:23 INFO - '_': '/tools/buildbot/bin/python'} 16:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:23 INFO - Downloading/unpacking blobuploader==1.2.4 16:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:25 INFO - Downloading blobuploader-1.2.4.tar.gz 16:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:09:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:09:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:26 INFO - Downloading docopt-0.6.1.tar.gz 16:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:09:26 INFO - Installing collected packages: blobuploader, requests, docopt 16:09:26 INFO - Running setup.py install for blobuploader 16:09:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:09:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:09:26 INFO - Running setup.py install for requests 16:09:26 INFO - Running setup.py install for docopt 16:09:26 INFO - Successfully installed blobuploader requests docopt 16:09:26 INFO - Cleaning up... 16:09:26 INFO - Return code: 0 16:09:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:26 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 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:09:26 INFO - Using env: {'DISPLAY': ':0', 16:09:26 INFO - 'HOME': '/home/cltbld', 16:09:26 INFO - 'LANG': 'en_US.UTF-8', 16:09:26 INFO - 'LANGUAGE': 'en_US:en', 16:09:26 INFO - 'LOGNAME': 'cltbld', 16:09:26 INFO - 'MAIL': '/var/mail/cltbld', 16:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:26 INFO - 'MOZ_NO_REMOTE': '1', 16:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:26 INFO - 'NO_EM_RESTART': '1', 16:09:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:26 INFO - 'PWD': '/builds/slave/test', 16:09:26 INFO - 'SHELL': '/bin/bash', 16:09:26 INFO - 'SHLVL': '1', 16:09:26 INFO - 'TERM': 'linux', 16:09:26 INFO - 'TMOUT': '86400', 16:09:26 INFO - 'USER': 'cltbld', 16:09:26 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:26 INFO - '_': '/tools/buildbot/bin/python'} 16:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:09:27 INFO - Running setup.py (path:/tmp/pip-rsN2AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:09:27 INFO - Running setup.py (path:/tmp/pip-zE9icZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:09:27 INFO - Running setup.py (path:/tmp/pip-ulRqCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:09:27 INFO - Running setup.py (path:/tmp/pip-9nob5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:09:27 INFO - Running setup.py (path:/tmp/pip-MuNebz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:09:27 INFO - Running setup.py (path:/tmp/pip-X2ZSpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:09:27 INFO - Running setup.py (path:/tmp/pip-1U7iAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:09:27 INFO - Running setup.py (path:/tmp/pip-ytgHs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:09:27 INFO - Running setup.py (path:/tmp/pip-f24B2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:09:27 INFO - Running setup.py (path:/tmp/pip-oDmRNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:09:27 INFO - Running setup.py (path:/tmp/pip-dmoQQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:09:27 INFO - Running setup.py (path:/tmp/pip-2I3xVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:09:27 INFO - Running setup.py (path:/tmp/pip-Oj7yPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:09:28 INFO - Running setup.py (path:/tmp/pip-t8gHN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:28 INFO - Running setup.py (path:/tmp/pip-QehqXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:09:28 INFO - Running setup.py (path:/tmp/pip-yiNCol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:09:28 INFO - Running setup.py (path:/tmp/pip-zmCmmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:09:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:09:28 INFO - Running setup.py install for manifestparser 16:09:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:09:28 INFO - Running setup.py install for mozcrash 16:09:28 INFO - Running setup.py install for mozdebug 16:09:28 INFO - Running setup.py install for mozdevice 16:09:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:09:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:09:28 INFO - Running setup.py install for mozfile 16:09:28 INFO - Running setup.py install for mozhttpd 16:09:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:09:28 INFO - Running setup.py install for mozinfo 16:09:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:09:28 INFO - Running setup.py install for mozInstall 16:09:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Running setup.py install for mozleak 16:09:29 INFO - Running setup.py install for mozlog 16:09:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Running setup.py install for moznetwork 16:09:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Running setup.py install for mozprocess 16:09:29 INFO - Running setup.py install for mozprofile 16:09:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Running setup.py install for mozrunner 16:09:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:09:29 INFO - Running setup.py install for mozscreenshot 16:09:29 INFO - Running setup.py install for moztest 16:09:29 INFO - Running setup.py install for mozversion 16:09:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:09:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:09:30 INFO - Cleaning up... 16:09:30 INFO - Return code: 0 16:09:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:30 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 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:09:30 INFO - Using env: {'DISPLAY': ':0', 16:09:30 INFO - 'HOME': '/home/cltbld', 16:09:30 INFO - 'LANG': 'en_US.UTF-8', 16:09:30 INFO - 'LANGUAGE': 'en_US:en', 16:09:30 INFO - 'LOGNAME': 'cltbld', 16:09:30 INFO - 'MAIL': '/var/mail/cltbld', 16:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:30 INFO - 'MOZ_NO_REMOTE': '1', 16:09:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:30 INFO - 'NO_EM_RESTART': '1', 16:09:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:30 INFO - 'PWD': '/builds/slave/test', 16:09:30 INFO - 'SHELL': '/bin/bash', 16:09:30 INFO - 'SHLVL': '1', 16:09:30 INFO - 'TERM': 'linux', 16:09:30 INFO - 'TMOUT': '86400', 16:09:30 INFO - 'USER': 'cltbld', 16:09:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:30 INFO - '_': '/tools/buildbot/bin/python'} 16:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:09:30 INFO - Running setup.py (path:/tmp/pip-2RPP9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:09:30 INFO - Running setup.py (path:/tmp/pip-CQhdjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:09:30 INFO - Running setup.py (path:/tmp/pip-pqYF7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:09:30 INFO - Running setup.py (path:/tmp/pip-MZB5pB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:09:30 INFO - Running setup.py (path:/tmp/pip-ho3TrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:09:30 INFO - Running setup.py (path:/tmp/pip-n1gXLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:09:30 INFO - Running setup.py (path:/tmp/pip-tYa5Ni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:09:30 INFO - Running setup.py (path:/tmp/pip-odrkXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:09:30 INFO - Running setup.py (path:/tmp/pip-O_v08L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:09:30 INFO - Running setup.py (path:/tmp/pip-B81era-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:09:31 INFO - Running setup.py (path:/tmp/pip-qSmvXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:09:31 INFO - Running setup.py (path:/tmp/pip-aMpGyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:09:31 INFO - Running setup.py (path:/tmp/pip-jHcOnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:09:31 INFO - Running setup.py (path:/tmp/pip-DQCUQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:31 INFO - Running setup.py (path:/tmp/pip-fmu3mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:09:31 INFO - Running setup.py (path:/tmp/pip-pE5kds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:09:31 INFO - Running setup.py (path:/tmp/pip-OscU7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:09:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09:33 INFO - Downloading blessings-1.6.tar.gz 16:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:09:33 INFO - Installing collected packages: blessings 16:09:33 INFO - Running setup.py install for blessings 16:09:33 INFO - Successfully installed blessings 16:09:33 INFO - Cleaning up... 16:09:33 INFO - Return code: 0 16:09:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:09:33 INFO - Reading from file tmpfile_stdout 16:09:33 INFO - Current package versions: 16:09:33 INFO - argparse == 1.2.1 16:09:33 INFO - blessings == 1.6 16:09:33 INFO - blobuploader == 1.2.4 16:09:33 INFO - docopt == 0.6.1 16:09:33 INFO - functools32 == 3.2.3-2 16:09:33 INFO - jsonschema == 2.5.1 16:09:33 INFO - manifestparser == 1.1 16:09:33 INFO - mozInstall == 1.12 16:09:33 INFO - mozcrash == 0.17 16:09:33 INFO - mozdebug == 0.1 16:09:33 INFO - mozdevice == 0.48 16:09:33 INFO - mozfile == 1.2 16:09:33 INFO - mozhttpd == 0.7 16:09:33 INFO - mozinfo == 0.9 16:09:33 INFO - mozleak == 0.1 16:09:33 INFO - mozlog == 3.4 16:09:33 INFO - moznetwork == 0.27 16:09:33 INFO - mozprocess == 0.23 16:09:33 INFO - mozprofile == 0.28 16:09:33 INFO - mozrunner == 6.12 16:09:33 INFO - mozscreenshot == 0.1 16:09:33 INFO - mozsystemmonitor == 0.3 16:09:33 INFO - moztest == 0.7 16:09:33 INFO - mozversion == 1.4 16:09:33 INFO - psutil == 3.1.1 16:09:33 INFO - requests == 1.2.3 16:09:33 INFO - wsgiref == 0.1.2 16:09:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:33 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 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:09:33 INFO - Using env: {'DISPLAY': ':0', 16:09:33 INFO - 'HOME': '/home/cltbld', 16:09:33 INFO - 'LANG': 'en_US.UTF-8', 16:09:33 INFO - 'LANGUAGE': 'en_US:en', 16:09:33 INFO - 'LOGNAME': 'cltbld', 16:09:33 INFO - 'MAIL': '/var/mail/cltbld', 16:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:33 INFO - 'MOZ_NO_REMOTE': '1', 16:09:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:33 INFO - 'NO_EM_RESTART': '1', 16:09:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:33 INFO - 'PWD': '/builds/slave/test', 16:09:33 INFO - 'SHELL': '/bin/bash', 16:09:33 INFO - 'SHLVL': '1', 16:09:33 INFO - 'TERM': 'linux', 16:09:33 INFO - 'TMOUT': '86400', 16:09:33 INFO - 'USER': 'cltbld', 16:09:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:33 INFO - '_': '/tools/buildbot/bin/python'} 16:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:09:34 INFO - Cleaning up... 16:09:34 INFO - Return code: 0 16:09:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:34 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 0x7fc180e59f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f14ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:09:34 INFO - Using env: {'DISPLAY': ':0', 16:09:34 INFO - 'HOME': '/home/cltbld', 16:09:34 INFO - 'LANG': 'en_US.UTF-8', 16:09:34 INFO - 'LANGUAGE': 'en_US:en', 16:09:34 INFO - 'LOGNAME': 'cltbld', 16:09:34 INFO - 'MAIL': '/var/mail/cltbld', 16:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:34 INFO - 'MOZ_NO_REMOTE': '1', 16:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:34 INFO - 'NO_EM_RESTART': '1', 16:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:34 INFO - 'PWD': '/builds/slave/test', 16:09:34 INFO - 'SHELL': '/bin/bash', 16:09:34 INFO - 'SHLVL': '1', 16:09:34 INFO - 'TERM': 'linux', 16:09:34 INFO - 'TMOUT': '86400', 16:09:34 INFO - 'USER': 'cltbld', 16:09:34 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:34 INFO - '_': '/tools/buildbot/bin/python'} 16:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:09:34 INFO - Cleaning up... 16:09:34 INFO - Return code: 0 16:09:34 INFO - Running post-action listener: _resource_record_post_action 16:09:34 INFO - Running post-action listener: _start_resource_monitoring 16:09:34 INFO - Starting resource monitoring. 16:09:34 INFO - [mozharness: 2016-09-19 23:09:34.348344Z] Finished create-virtualenv step (success) 16:09:34 INFO - [mozharness: 2016-09-19 23:09:34.348839Z] Running install step. 16:09:34 INFO - Running pre-action listener: _resource_record_pre_action 16:09:34 INFO - Running main action method: install 16:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:09:34 INFO - Reading from file tmpfile_stdout 16:09:34 INFO - Detecting whether we're running mozinstall >=1.0... 16:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:09:34 INFO - Reading from file tmpfile_stdout 16:09:34 INFO - Output received: 16:09:34 INFO - Usage: mozinstall [options] installer 16:09:34 INFO - Options: 16:09:34 INFO - -h, --help show this help message and exit 16:09:34 INFO - -d DEST, --destination=DEST 16:09:34 INFO - Directory to install application into. [default: 16:09:34 INFO - "/builds/slave/test"] 16:09:34 INFO - --app=APP Application being installed. [default: firefox] 16:09:34 INFO - mkdir: /builds/slave/test/build/application 16:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:09:50 INFO - Reading from file tmpfile_stdout 16:09:50 INFO - Output received: 16:09:50 INFO - /builds/slave/test/build/application/firefox/firefox 16:09:50 INFO - Running post-action listener: _resource_record_post_action 16:09:50 INFO - [mozharness: 2016-09-19 23:09:50.581714Z] Finished install step (success) 16:09:50 INFO - [mozharness: 2016-09-19 23:09:50.581960Z] Running run-tests step. 16:09:50 INFO - Running pre-action listener: _resource_record_pre_action 16:09:50 INFO - Running main action method: run_tests 16:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:09:50 INFO - Python 2.7.3 16:09:50 INFO - Return code: 0 16:09:50 INFO - grabbing minidump binary from tooltool 16:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:50 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 0x1f11f60>, '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 0x1f12d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f131d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:09:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:09:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:09:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:09:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:09:50 INFO - Return code: 0 16:09:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:09:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:09:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:09:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:09:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:09:50 INFO - Using env: {'DISPLAY': ':0', 16:09:50 INFO - 'HOME': '/home/cltbld', 16:09:50 INFO - 'LANG': 'en_US.UTF-8', 16:09:50 INFO - 'LANGUAGE': 'en_US:en', 16:09:50 INFO - 'LOGNAME': 'cltbld', 16:09:50 INFO - 'MAIL': '/var/mail/cltbld', 16:09:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:09:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:50 INFO - 'MOZ_NO_REMOTE': '1', 16:09:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:50 INFO - 'NO_EM_RESTART': '1', 16:09:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:50 INFO - 'PWD': '/builds/slave/test', 16:09:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:09:50 INFO - 'SHELL': '/bin/bash', 16:09:50 INFO - 'SHLVL': '1', 16:09:50 INFO - 'TERM': 'linux', 16:09:50 INFO - 'TMOUT': '86400', 16:09:50 INFO - 'USER': 'cltbld', 16:09:50 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260', 16:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:50 INFO - '_': '/tools/buildbot/bin/python'} 16:09:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:09:50 INFO - mozversion application_buildid: 20160919065232 16:09:50 INFO - mozversion application_changeset: 5dddbefdf759f09b1411f33fa0920835b919fc81 16:09:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:09:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:09:50 INFO - mozversion application_name: Firefox 16:09:50 INFO - mozversion application_remotingname: firefox-dev 16:09:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:09:50 INFO - mozversion application_vendor: Mozilla 16:09:50 INFO - mozversion application_version: 51.0a2 16:09:50 INFO - mozversion platform_buildid: 20160919065232 16:09:50 INFO - mozversion platform_changeset: 5dddbefdf759f09b1411f33fa0920835b919fc81 16:09:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:09:50 INFO - mozversion platform_version: 51.0a2 16:09:50 INFO - using testdate: 1474326590 16:09:50 INFO - actual date: 1474326590 16:09:50 INFO - starting webserver on 'localhost:36769' 16:09:50 INFO - SUITE-START | Running 2 tests 16:09:50 INFO - TEST-START | dromaeo_css 16:09:50 INFO - operating with platform_type : linux_ 16:09:50 INFO - Initialising browser for dromaeo_css test... 16:09:50 INFO - TEST-INFO | started process 13378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvBjoAd/profile http://localhost:36769/getInfo.html) 16:09:54 INFO - PROCESS | 13378 | __metrics Screen width/height:1600/1200 16:09:54 INFO - PROCESS | 13378 | colorDepth:24 16:09:54 INFO - PROCESS | 13378 | Browser inner width/height: 1024/697 16:09:54 INFO - PROCESS | 13378 | __metrics 16:09:55 INFO - PROCESS | 13378 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:09:55 INFO - PROCESS | 13378 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:09:56 INFO - PROCESS | 13378 | console.error: PushService: 16:09:56 INFO - PROCESS | 13378 | stateChangeProcessEnqueue: Error transitioning state 16:09:56 INFO - PROCESS | 13378 | UnknownError 16:09:56 INFO - TEST-INFO | 13378: exit 0 16:09:56 INFO - Browser initialized. 16:09:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:09:56 INFO - TEST-INFO | started process 13470 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvBjoAd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:08 INFO - PROCESS | 13470 | RSS: Main: 144953344 16:10:08 INFO - PROCESS | 13470 | 16:10:12 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.13356 seconds. 16:10:21 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 16:10:35 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01687 seconds. 16:10:37 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.03385 seconds. 16:10:39 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.10036 seconds. 16:10:59 INFO - PROCESS | 13470 | 1474326659467 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:11:00 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 16:11:05 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 16:11:09 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:11:16 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00054 seconds. 16:11:17 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 2.03351 seconds. 16:11:24 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00306 seconds. 16:11:29 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.16658 seconds. 16:11:39 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01588 seconds. 16:11:42 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01612 seconds. 16:11:44 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.06679 seconds. 16:11:58 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01691 seconds. 16:12:29 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01617 seconds. 16:12:45 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01708 seconds. 16:12:47 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.0173 seconds. 16:12:59 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.03283 seconds. 16:13:01 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 16:13:08 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01701 seconds. 16:13:08 INFO - PROCESS | 13470 | Cycle 1(1): loaded http://localhost:36769/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36769/tests/dromaeo/cssquery-ext.html) 16:13:09 INFO - PROCESS | 13470 | RSS: Main: 280825856 16:13:09 INFO - PROCESS | 13470 | 16:13:56 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00074 seconds. 16:14:02 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00067 seconds. 16:14:31 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 16:15:05 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00076 seconds. 16:15:17 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01627 seconds. 16:15:34 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:15:43 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01695 seconds. 16:16:12 INFO - PROCESS | 13470 | Cycle 1(1): loaded http://localhost:36769/tests/dromaeo/cssquery-ext.html (next: http://localhost:36769/tests/dromaeo/cssquery-jquery.html) 16:16:12 INFO - PROCESS | 13470 | RSS: Main: 231428096 16:16:12 INFO - PROCESS | 13470 | 16:16:16 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.0171 seconds. 16:16:19 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.05026 seconds. 16:16:33 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00065 seconds. 16:16:43 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00074 seconds. 16:17:13 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 16:17:35 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 16:17:43 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01627 seconds. 16:17:45 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 16:18:22 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 16:18:40 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 16:18:51 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01699 seconds. 16:18:54 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00042 seconds. 16:18:56 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00039 seconds. 16:19:04 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00077 seconds. 16:19:06 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 16:19:12 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 16:19:14 INFO - PROCESS | 13470 | Cycle 1(1): loaded http://localhost:36769/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36769/tests/dromaeo/cssquery-mootools.html) 16:19:15 INFO - PROCESS | 13470 | RSS: Main: 228937728 16:19:15 INFO - PROCESS | 13470 | 16:19:18 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01646 seconds. 16:19:53 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01692 seconds. 16:19:56 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.03297 seconds. 16:20:00 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01711 seconds. 16:20:08 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00081 seconds. 16:20:16 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01702 seconds. 16:20:45 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 2 seconds. 16:20:58 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 16:21:28 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00066 seconds. 16:21:36 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00063 seconds. 16:21:44 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01695 seconds. 16:21:55 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 16:22:05 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 16:22:17 INFO - PROCESS | 13470 | Cycle 1(1): loaded http://localhost:36769/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36769/tests/dromaeo/cssquery-prototype.html) 16:22:17 INFO - PROCESS | 13470 | RSS: Main: 173662208 16:22:17 INFO - PROCESS | 13470 | 16:22:21 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.0339 seconds. 16:22:34 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.03304 seconds. 16:22:44 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00064 seconds. 16:22:46 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00021 seconds. 16:23:00 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 16:23:23 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 16:23:35 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 16:23:45 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 16:23:52 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.06622 seconds. 16:23:54 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01642 seconds. 16:24:07 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01611 seconds. 16:24:09 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.017 seconds. 16:24:30 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 16:24:42 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.0169 seconds. 16:25:09 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00065 seconds. 16:25:20 INFO - PROCESS | 13470 | Cycle 1(1): loaded http://localhost:36769/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36769/tests/dromaeo/cssquery-yui.html) 16:25:21 INFO - PROCESS | 13470 | RSS: Main: 175726592 16:25:21 INFO - PROCESS | 13470 | 16:25:28 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 16:25:41 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01594 seconds. 16:25:44 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 16:26:47 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01638 seconds. 16:27:06 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 16:27:24 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00068 seconds. 16:27:30 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 16:27:44 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00067 seconds. 16:28:20 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.01689 seconds. 16:28:22 INFO - PROCESS | 13470 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:28:23 INFO - PROCESS | 13470 | Cycle 1(1): loaded http://localhost:36769/tests/dromaeo/cssquery-yui.html (next: http://localhost:36769/tests/dromaeo/cssquery-dojo.html) 16:28:23 INFO - PROCESS | 13470 | RSS: Main: 255385600 16:28:23 INFO - PROCESS | 13470 | 16:28:23 INFO - PROCESS | 13470 | __start_tp_report 16:28:23 INFO - PROCESS | 13470 | _x_x_mozilla_page_load 16:28:23 INFO - PROCESS | 13470 | _x_x_mozilla_page_load_details 16:28:23 INFO - PROCESS | 13470 | |i|pagename|runs| 16:28:23 INFO - PROCESS | 13470 | |0;dojo.html;3107.1455349778807;3157.5421563873238;3159.3049529103596;3184.3631273745254;3206.2946151846586;5599.552035837133;5669.433056694328;5691.516221121199;5715.628484148528;5723.771049158039;5559.11054231322;5613.214150019001;5623.044082505973;5624.240727501786;5648.6893220872835;5054.823081192149;5078.720670363128;5104.795808167669;5107.106256405133;5116.462771409004;4896.363472748538;4913.631477639174;5077.416097148838;5142.768575414097;5147.974260128713;4952.579030782388;5040.697558146505;5048.974755126219;5049.772760225798;5087.516685914832;5093.032323858473;5128.615353848476;5148.124818780816;5154.046501397253;5204.843854684365;5090.618203634742;5210.713410762407;5212.192110222922;5212.713300768456;5241.895162096736;5266.210068489757;5305.522502974751;5658.490735833795;5698.943010569883;5761.740721667535;5855.238818953551;5869.677167755772;5888.793892213752;5927.2887253529425;5944.613600115978;5936.257967754031;6027.578069535134;6032.819015429544;6047.909281360783;6048.304444988838;5330.200469929541;5597.832065038056;5612.943870561324;5614;5620.241267428926;5828.300603927556;6038.698065096728;6165.229346331708;6192.442680158717;6204.193454850841;5942.762289508458;5942.821715348547;5948.791792287249;5949.583529152917;5967.254093238345;5605.1872478491205;6032.426919442646;6039.063945088518;6040.969795150996;6045.063015232646;5615.578831587565;5933.02105152645;5984.431479009487;5986.820395388146;6067.817965461043;6038.064100064497;6041.305249896231;6054.152418661391;6057.909131362945;6078.696065196723;4618.907621847544;4653.325267836145;4666.463356714022;4672.2057250267535;4707.458642256116;3926.1166237596312;3979.8209080590864;4040.777757223322;4071.450354202204;4082.3060079786505;5660.283313229647;5678.432156784371;5680.034394153004;5693.402192769782;5707.34365547959;6006.729697163638;6011.248593925759;6012.30858451275;6018.097285407136;6018.6990650467305;5491.807786727445;5506.669599824023;5507.228987941691;5661.405552416937;5675.31896172462;5046.520580544916;5105.463926287761;5107.310513080686;5137.306463627438;5139.203423469368;6310.9368906311265;6328.936710632835;6332.430081292614;6332.8416789579705;6334.873302534015;5637.633553818989;6012.398760123952;6151.32335443092;6152.200213972155;6156.722947467464;5841.532677385884;6179.783707570394;6234.096056071753;6240.157578726996;6343.270523889812;6010.038393857137;6042.546808989431;6050.213472248755;6055.878882422415;6091.604045737013;6084.148219249397;6124.5712800103565;6142.109394137736;6143.72353244114;6147.108669242845;5568.749406276808;5663.122216056518;5807.477327040587;5815.360310365941;5840.269966254218;5917.881642367214;5923.49650279731;5926.436988486087;5926.881462370642;5948.315943666534;5819.912701309399;5846.298444186725;5847.035239185487;5847.590668653153;5854.385289544536;5513.21855301531;5646.378898321099;5773.134029895381;5824.650520968756;5920.200772895776;5751.309842818889;5754.8849023018465;5756.481916627524;5795.014847475855;5797.014507533814 16:28:23 INFO - PROCESS | 13470 | |1;ext.html;567.3333832746522;602.5270162922106;634.0900807341466;656.878477481666;663.9004149377596;35186.296274074506;46974;48407.757961210234;48478.999999999956;48621.51378486214;50236.24645630318;50585.74707126468;50709;50783.23825142616;50794.49205507953;8265.83468330633;9151.222146117589;9269.860952085724;9285.767855803591;9288.814223715523;8319.292860106898;11161.72095697606;11505.194636375458;11511.827322590168;11933.403329833516;11629.185956983014;11638.359890206078;11680.474378652938;11690.181687281894;11714.648560543197;11424.942875285653;11572.826407603892;11586.246893951866;11592.304461732281;11592.594259200887;11408.885911140866;11467.426628668532;11480.942595287053;11483.081353491702;11486.310821350662;47425.28862067072;47767.76116119432;47854.521454785354;47920.28119578209;47943.28085078727;3744.1762812181278;3847.942280865789;3861.555921069085;3878.4182372644327;3912.2957867583823;31615.367692646018;31781.52327715086;31897.681023189703;35618.28763424743;37532.24935501275;4531.3882625845745;4629.467611224719;4634.490206077328;4676.354160946877;4699.107169637792;372750;372796;373049.99999999726;374211;374274;38937.41593876094;40242;66793.3320666787;67278;67376.66311668509;35058.64941350605;35249.295014099574;35250.823745881105;35272;35290.82354588211;18004.54988625295;18104.27582896696;18179.091045447938;18196.363127290748;18196.9090154551;12870.742585148244;13135.802962955564;14388.712225755426;15527.689446211238;15610.843891560939;9459.90540094604;9641.132298093205;9669.70990870275;9709.417434953926;9742.31803773729;3549.7515173937577;3622.3298689742483;3662.8717994870053;3679.2825399047465;3684.8710295140077;1048.1719441641171;1118.1166878166157;1122.2088427658482;1148.5520646947698;1182.0956967919549;31062.68937310598;31682.207944801565;32322.83838580792;34076.466559004904;35326.47010294848;1178.4579093617033;1186.5313201285444;1229.2255878796302;1239.151181440716;1243.483954159032;17775.200115994812;18188.90905545464;18270.45188644343;18497.53756156107;18530.629387412177;593999;597211;598460;599422;601264;66202.66898665573;66689;66697;66729;66755.66622166858;1264.1151194164122;1314.001358967192;1377.4834437086092;1438.96402589934;1448.7899254608233;27536;30887.382252355277;31320.686793131776;31543.52684709685;32126.839365803957;57087.42912570821;57905.42094579;57910.13134802897;57938.7103064482;57962.4203757957;467911;470798;472157;472238;475266;30588.388232234785;31883.681163188998;32127.518087228244;33475.330493389505;33507.49738753872;42664.57335426606;42848.57151428446;42967.355489667054;43263.18789278687;43401.56598434102;38389.80805095957;38881.2223755529;38923.02692432712;39052.414213787386;40080.599194007686;45741.340692743834;46603.30095048509;46676.53323466722;47304;47515.28727069166;44735.77632111819;44910.32634510419;45587.77206113948;45842.541574583825;46153.076938461716;31865.203369915936;33866.322673546885;34342.313153736286;34535.309293814484;34877.65122348744;26425.735742643097;26629.467410652065;29845.403091937605;29935.55096673508;30589.847050765493 16:28:23 INFO - PROCESS | 13470 | |2;jquery.html;403.4654083339572;412.30320757919094;419.7124969395964;427.41230807639505;442.80295268605465;15048.774268385969;16554;20559.897200513995;20820.791792082076;21625;20222.29221977231;20301;20330.89834550829;20487.38537843867;20632.896835515803;5810.099434587646;5862.677552734598;5965.88068238635;6016.308124565688;6018.368071352511;4403.867883963486;4610.423697037871;5933.762649494015;6014.188084608566;6017.72920218589;6334.809955701337;6581.01284807278;6608.636524991123;6724.193096828388;6724.5965242085385;6391.328910464404;6551.672416379186;6610.801675949681;6616.338366163393;6711.026901099338;5771.192033115367;5924.822255332347;5927.73325200367;5941.70291485424;5944.7027648617395;24902.252932411877;25240.873795630905;25358.619620705704;25395.492090158277;25433.364165895815;2519.8236123471365;2641.313258552771;2642.4582960493053;2655.150351887397;2805.368792021799;14802.851971480255;15618.531444056696;15718.449854255161;15778.447754328543;15791.289391977274;2028.949276268105;2055.969160462594;2069.151647824385;2082.8125468707735;2132.4668832791804;81453.18546814515;83733;83755.16244837534;85039.57480212621;86785;11393.487293071832;11564;11599.594014209462;11665.125115616192;11787.11596630239;16797;16799.41202057922;16847.57881052959;16850.662986740197;16860.662786744197;9360.204382627553;9455.621775129055;9468.14786669203;9536.618535258513;9656.420614762996;9526;9536.713898583053;9549;9729.464879431558;9757.756056098513;7137.964310178416;7209.567425954459;7270.9457128717095;7323.340899319087;7351.8162045949275;2043.6934459831143;2059.4851287178208;2104.7895210479137;2111.229401268526;2121.8939053047284;984.2224642532462;987.7234374375187;1065.3022270412737;1089.515165751249;1143.2683082826998;18856.434306970812;21274.36176914695;21482.78517214839;21510;21552.245671401426;1056.9048785609177;1085.9619913302997;1096.9341839489657;1146.7706458708292;1185.300672603151;10954.890451095547;12164.148509604243;12290.631281061582;12343.567975120828;12396.566120185751;514645;522515;527729;529662;531988;82338.58830705807;84916;85036.57481712553;86285.13714862897;87709.56145219233;1065.5082883962668;1150.930944143354;1163.5629996953755;1173.6831055614937;1178.4402408855724;18266.908665456587;18657;19083.332083377016;19201.231950721816;19284.325048623232;41811.37282940819;41894;42158.25971311855;42618.36072458977;42715.78642106894;55973.72013140071;56636.716816415654;57082;57644;58263.4173658258;8657.740267791976;8814.206721395105;9244.399114057565;9299.256059515088;9307.813843723223;7774.572398518136;12555.93722031384;13495.662608434868;13607.57120502333;13915;11088.390138542458;11826.46780894879;11850.940745296562;12610.306433145897;12740.554080607135;11502.309861408343;11660.358680272328;11836.704082397664;12124.818127727915;12277.631671049883;12180.756384872077;12351.876481235073;12623.30571818522;12642.2414655121;12798.376332851556;7692.615369231628;7980.800479988046;8261.215184557457;8290.917090829014;8353.33173346143;12025.398730063636;12289.508419663114;12323.174784012037;12368.134230604132;12564.931980781736 16:28:23 INFO - PROCESS | 13470 | |3;mootools.html;1357.979630305545;1395.787676532696;1472.5508719840448;1513.1223890143717;1724.793024837019;7771.222877712226;9036.186743193111;9419.387739796917;9421.057894210575;9673.70978870635;9307.81384372314;9403.247740180786;9406.15344618984;9409.529523523814;9410.52947352633;2547.414094758207;2583.160472846323;2585.741425857418;2587.780038696713;2587.8447293162376;2515.5220508103403;2603.7656610905015;2606.3484128967757;2607.634931109637;2609.739026097394;2569;2583.599542070973;2603.934901627465;2605.2444791010585;2606.1269474725896;2489.2905521926164;2504.023430861956;2508.636247744076;2519.1308998395707;2521.4578865543813;2454.4947624168585;2475.3192871960355;2494.787943024845;2525.936851578725;2529.607910773833;9066.274698024143;9126.315526335551;9247.21398681113;9394.107559781809;9492.382995105296;5653.802116925929;5749.942500574983;5761.337446193657;5772.855678608026;5792.304923409218;3646.21606354635;3681.7790932543867;3731.869384571527;3752.43713442983;3775.528058992626;1194.7252132009687;1200.5317926008863;1207.1489599832184;1234.666640007193;1235.518147922311;12799.552015679406;12883;12927.806082908764;12931.288779117145;12976.87023129766;4709.458412282563;4801.447833499193;4872.3909511311085;4934.4572097069295;5104.668196567211;10037.44794036335;10042.497875106215;10055.497225138713;10063.597456101821;10071.69784906454;5452.40023597404;5460.645058071213;5463.754131064112;5466.562674985991;5472.50747432725;4371.169477799208;4449.57729015743;5103.923441148386;5172.793088276427;5251.343582052244;4147.433756948455;4176.477940257468;4244.087521182899;4296.119295544406;4308.009157893639;1296.319432298051;1309.1228876652665;1318.9580231617108;1322.2397121655088;1325.721598464314;822.6956026270199;833.2792134803351;890.2877697841701;913.0869130869131;950.5437390052675;6613.669316534154;6626.635535045522;6663.600183988976;6753.561018533779;6874.072000279898;1018.2159737002466;1018.7351288664852;1047.8061558611528;1065.3128731967838;1072.477082542791;4316.848910288125;4365.607095361369;4415.602595766396;4432.423784907941;4607.216773148572;11403.885961140282;11858.466369013415;11860.051195904307;11880;11882.88117118835;11104.888951110386;11713.941430292794;11722.58970936013;11725.648230553097;11757.118216133824;8426.536540490331;8786.516741579275;8806.735797926072;8822.823543529223;8823.955880220687;6337.524685648687;6368.108464814837;6451.258105317948;6483.837904052436;6600.702968366339;10405.167586592903;10425.957404259816;10445.11216546602;10512.42181680015;10520.526576304237;10227.590896364061;10506.369617822955;10735.570577176826;10756.892431075903;10897.400642964712;6877.6217308048535;7057.258987806206;7064.046353742179;8321.625526851427;8534.658613655385;8068.273855353046;8870.201681848665;8994.82010359776;9065.32010099232;9240.122188392092;7696.576688282198;7871.134175240604;7957.7612671619945;7975.4018448615425;8047.155048720034;8017.879731804144;8110.148434414537;8141.715039973573;8171.305439037748;8175.264226219669;7124.928750712426;7229.313215244543;7355.1541572719825;7484.251574842472;7488.737894173678;5687.800926967537;5735.4551317624755;5805.651660900359;5871.324797648154;5943.940560594338;5555.194496798023;5701.657900525982;5758.827235182951;5760.300071445529;5881.32364778056 16:28:23 INFO - PROCESS | 13470 | |4;prototype.html;359.46080878681977;359.9784012959222;362.5667327543585;364.7756629672752;367.14638465567543;5528.308961379827;5537.197106419567;5575.606098475381;5582.023145949453;5592.636478628886;5468.535174510172;5499.780008799653;5509.504144626982;5515.834524964257;5519.282493275869;4114.773687447197;4283.186194623027;4328.761918094504;4335.132973405316;4360.149770794696;4349.608535231829;4354.738715677069;4355.607995280424;4364.7817609119575;4368.191884501377;4323.935140972888;4326.134773045388;4337.54455782143;4342.848000319974;4347.695661303695;4171.687123465737;4284.828606855722;4288.627639155471;4314.71954322968;4339.609435150835;3775.339315619756;3787.0532366908274;3791.545014598238;3825.9043523911846;3833.616638336172;5369.892602147936;5372.355317361902;5376.1398176292;5383.623146379755;5395.29861118056;4738.502457241974;4952.579030782388;5003.74981250936;5007.2489126630935;5012.248162775576;5762.884742305172;5775.480206781368;5813.563982701272;5816.82549523515;5819.883602327972;4206.684498662582;4212.368144778277;4212.7472351659;4227.900745806081;4228.281192197333;5673.773049078032;5799.376174671099;5881.176635271023;5903.704814759288;6037.154798328237;4087.1621317629815;4087.4277601135864;4091.4272001919753;4159.604837540429;4280.208161490072;4316.395704601358;4323.978380108142;4331.913361732748;4355.760433176142;4365.738055716667;4751.83368582098;4919.680220785637;4935.383077115361;4949.7030178189425;4969.180085285887;5730.426957304237;5772.365039845614;5777.226931073024;5777.740001699934;5810.157527158538;1474.7861560073943;1486.47973209378;1488.0625206120292;1489.9031562948373;1490.4038384646228;930.4045410937006;967.3808762392075;974.7611835100304;979.6913972098766;987.9280980136489;5015.974920125376;5033.823816166417;5070.594352451795;5073.822416215415;5073.847784566395;5146.073706732824;5322.866928326823;5329;5334.786608535693;5335.545649001503;5002.549260714425;5042.806379225049;5072.391313042459;5251.634575010448;5672.914906276409;3141.874325027019;3168.287135394518;3172.397244523533;3173.1432513221303;3182.586263785693;6015.969920150371;6200.56596038273;6204.193454850841;6211;6222.377762223741;6056.515478761688;6081.898231901762;6087.117367981707;6089.451949324493;6100.834203857656;6135.785247516315;6145.477634401037;6191.380861913773;6211.96830567242;6222.377762223832;5847.502962248256;5928.304012522531;5992.760289588367;6023.247094113236;6025.758969641165;5945.851353716192;5953.642781433128;5962.314333851663;5969.791057312973;5972.731227094704;5700.2304688868135;5828.038373668297;5988.610740301867;6002.159697642246;6167.629942203482;4191.245575796386;4298.39822424867;4312.353147027971;4312.93530597035;4316.89919070635;5792.797252096157;5938.376470470538;5967.582269241111;5978.4021597839865;6000.459958603572;5809.419058094326;5810.302763668386;5852.912206316823;5869.6771677558145;5875.41245875409;5784.884302314014;5814.970925145516;5837.503812176013;5844.86025225077;5856.765729370948;5533.446655334596;5790.160426738015;5800.9419905800405;5806.883862322815;5851.039901633;5887.293524777054;5891.94108058931;5895.94104058954;5898.94101058984;5947.286325640951;5532.6680399176175;5744.913826292693;5750.281214848144;5754.625949313281;5757.625754325955 16:28:23 INFO - PROCESS | 13470 | |5;yui.html;757.8976838126855;761.6534476813047;780.0678189563466;834.6703052294343;893.7184786792162;13571.999999999987;14685.92657036716;14692.632684182874;14707.558773236806;14713.48502802402;15183.240837958085;15644.139572323518;15717.13555754433;15768;15839.287232074556;724.6992498113277;859.7979474823427;874.5452364770316;877.0922095631023;877.4077497689067;721.2030706069796;847.4110493207223;870.0299166429448;877.666486735043;878.6617152396342;903.891533016039;906.7869050773096;909.1635695160435;910.3400034974624;910.3809409601469;740.8629403560359;899.0559912092342;905.8414777413959;905.8958219804741;906.4425378392274;555.6693767208504;649.9447546958467;663.171036204744;663.9634820084893;670.3631550027493;14350.138991660533;14424.350904209336;14434.422623095064;14479;14509.419623215059;13978.720425591533;14669.926650366786;15148.621284467867;15414.383424663;15429.768553471707;11890.215011396484;12209.633710988684;12353.752924941451;12355.62933112008;12500;4386.320120381346;4440.933385999213;4513.977430112861;4585.449746030497;4648.372469716579;86076.56961715214;89577.55211223967;90979;91435;92342.53828730814;11826.231294965799;11852.348120853263;11976.401179940967;12073.57742479009;12129.21160124589;22927.197548085736;22927.541449170923;22950;22956.770432295463;22971.6554251683;8774.473531587997;8785.209331160226;8961.283097352196;9111.589978450853;9306.953465232766;4920.3603531541385;4993.251012348177;5009.148444764399;5023.196288593807;5096.643234973591;3542.6811586956783;3684.5394325709285;3685.963140368562;3687.6312368763447;3688.760230585057;612.4579746924004;619.3187493756922;621.2638023941644;624.44736408279;625.4339822460589;671.4192223726442;678.633537889544;682.2018238660779;701.5580184483845;725.0827702955766;9065.728028159167;9667.984861589572;9753.268504862164;10096.899031009743;10293.176545876311;592.3040427497673;606.9059295809157;626.743035355502;665.2981104934244;682.9385355318045;4550.931736023962;4620.1914664934175;4636.397268355092;4654.697444666086;4778.187708089633;94535.52732236295;127792.36103819608;133477.3326133363;141150.29424853015;141503;63255;115946;116105.41947290378;116802;118905;102888;103392;103820;104279;104457.47771261095;8955.865662014945;9029.593668285075;9287.860682089638;9310.15143017396;9402.247820174232;54690;55968;56913.29260122203;57154.428455716574;57220;82057;83969;85443.57278213817;86571.13428865882;88586.55706721425;11877.76244475123;12089.81865272004;14119;14242.07426517273;14332.785008225093;14597;16059.759103613222;16107;16221.918890405472;16634.667306654042;15037.248137592858;15381.384744610537;15397.923010384877;15817.604559886095;15957.281922313756;14923.253837307873;15009.474668386554;15117.924410377878;15355.923220383827;15416.691666166389;13302.268375239455;13726.794098088736;14055.859441405864;14396.064255823758;14627.341769620605;11858.644240672793;12308.753824923631;12575.245485270912;13007.674808129872;13021.088523803242;12442.253464792142;12639.1784534005;12824.871751282368;12825.615231543068;12873.22760634365 16:28:23 INFO - PROCESS | 13470 | __end_tp_report 16:28:23 INFO - PROCESS | 13470 | __start_cc_report 16:28:23 INFO - PROCESS | 13470 | _x_x_mozilla_cycle_collect,1145 16:28:23 INFO - PROCESS | 13470 | __end_cc_report 16:28:23 INFO - PROCESS | 13470 | __startTimestamp1474327703794__endTimestamp 16:28:23 INFO - PROCESS | 13470 | 16:28:23 INFO - PROCESS | 13470 | ------- Summary: start ------- 16:28:23 INFO - PROCESS | 13470 | Number of tests: 6 16:28:23 INFO - PROCESS | 13470 | 16:28:23 INFO - PROCESS | 13470 | [#0] dojo.html Cycles:165 Average:5566.63 Median:5754.88 stddev:654.42 (11.4%) stddev-sans-first:627.34 16:28:23 INFO - PROCESS | 13470 | Values: 3107.1 3157.5 3159.3 3184.4 3206.3 5599.6 5669.4 5691.5 5715.6 5723.8 5559.1 5613.2 5623.0 5624.2 5648.7 5054.8 5078.7 5104.8 5107.1 5116.5 4896.4 4913.6 5077.4 5142.8 5148.0 4952.6 5040.7 5049.0 5049.8 5087.5 5093.0 5128.6 5148.1 5154.0 5204.8 5090.6 5210.7 5212.2 5212.7 5241.9 5266.2 5305.5 5658.5 5698.9 5761.7 5855.2 5869.7 5888.8 5927.3 5944.6 5936.3 6027.6 6032.8 6047.9 6048.3 5330.2 5597.8 5612.9 5614.0 5620.2 5828.3 6038.7 6165.2 6192.4 6204.2 5942.8 5942.8 5948.8 5949.6 5967.3 5605.2 6032.4 6039.1 6041.0 6045.1 5615.6 5933.0 5984.4 5986.8 6067.8 6038.1 6041.3 6054.2 6057.9 6078.7 4618.9 4653.3 4666.5 4672.2 4707.5 3926.1 3979.8 4040.8 4071.5 4082.3 5660.3 5678.4 5680.0 5693.4 5707.3 6006.7 6011.2 6012.3 6018.1 6018.7 5491.8 5506.7 5507.2 5661.4 5675.3 5046.5 5105.5 5107.3 5137.3 5139.2 6310.9 6328.9 6332.4 6332.8 6334.9 5637.6 6012.4 6151.3 6152.2 6156.7 5841.5 6179.8 6234.1 6240.2 6343.3 6010.0 6042.5 6050.2 6055.9 6091.6 6084.1 6124.6 6142.1 6143.7 6147.1 5568.7 5663.1 5807.5 5815.4 5840.3 5917.9 5923.5 5926.4 5926.9 5948.3 5819.9 5846.3 5847.0 5847.6 5854.4 5513.2 5646.4 5773.1 5824.7 5920.2 5751.3 5754.9 5756.5 5795.0 5797.0 16:28:23 INFO - PROCESS | 13470 | 16:28:23 INFO - PROCESS | 13470 | [#1] ext.html Cycles:180 Average:63908.94 Median:372923.00 stddev:130246.41 (34.9%) stddev-sans-first:130524.96 16:28:23 INFO - PROCESS | 13470 | Values: 567.3 602.5 634.1 656.9 663.9 35186.3 46974.0 48407.8 48479.0 48621.5 50236.2 50585.7 50709.0 50783.2 50794.5 8265.8 9151.2 9269.9 9285.8 9288.8 8319.3 11161.7 11505.2 11511.8 11933.4 11629.2 11638.4 11680.5 11690.2 11714.6 11424.9 11572.8 11586.2 11592.3 11592.6 11408.9 11467.4 11480.9 11483.1 11486.3 47425.3 47767.8 47854.5 47920.3 47943.3 3744.2 3847.9 3861.6 3878.4 3912.3 31615.4 31781.5 31897.7 35618.3 37532.2 4531.4 4629.5 4634.5 4676.4 4699.1 372750.0 372796.0 373050.0 374211.0 374274.0 38937.4 40242.0 66793.3 67278.0 67376.7 35058.6 35249.3 35250.8 35272.0 35290.8 18004.5 18104.3 18179.1 18196.4 18196.9 12870.7 13135.8 14388.7 15527.7 15610.8 9459.9 9641.1 9669.7 9709.4 9742.3 3549.8 3622.3 3662.9 3679.3 3684.9 1048.2 1118.1 1122.2 1148.6 1182.1 31062.7 31682.2 32322.8 34076.5 35326.5 1178.5 1186.5 1229.2 1239.2 1243.5 17775.2 18188.9 18270.5 18497.5 18530.6 593999.0 597211.0 598460.0 599422.0 601264.0 66202.7 66689.0 66697.0 66729.0 66755.7 1264.1 1314.0 1377.5 1439.0 1448.8 27536.0 30887.4 31320.7 31543.5 32126.8 57087.4 57905.4 57910.1 57938.7 57962.4 467911.0 470798.0 472157.0 472238.0 475266.0 30588.4 31883.7 32127.5 33475.3 33507.5 42664.6 42848.6 42967.4 43263.2 43401.6 38389.8 38881.2 38923.0 39052.4 40080.6 45741.3 46603.3 46676.5 47304.0 47515.3 44735.8 44910.3 45587.8 45842.5 46153.1 31865.2 33866.3 34342.3 34535.3 34877.7 26425.7 26629.5 29845.4 29935.6 30589.8 16:28:23 INFO - PROCESS | 13470 | 16:28:23 INFO - PROCESS | 13470 | [#2] jquery.html Cycles:180 Average:30555.96 Median:25377.06 stddev:86221.01 (339.8%) stddev-sans-first:86433.16 16:28:23 INFO - PROCESS | 13470 | Values: 403.5 412.3 419.7 427.4 442.8 15048.8 16554.0 20559.9 20820.8 21625.0 20222.3 20301.0 20330.9 20487.4 20632.9 5810.1 5862.7 5965.9 6016.3 6018.4 4403.9 4610.4 5933.8 6014.2 6017.7 6334.8 6581.0 6608.6 6724.2 6724.6 6391.3 6551.7 6610.8 6616.3 6711.0 5771.2 5924.8 5927.7 5941.7 5944.7 24902.3 25240.9 25358.6 25395.5 25433.4 2519.8 2641.3 2642.5 2655.2 2805.4 14802.9 15618.5 15718.4 15778.4 15791.3 2028.9 2056.0 2069.2 2082.8 2132.5 81453.2 83733.0 83755.2 85039.6 86785.0 11393.5 11564.0 11599.6 11665.1 11787.1 16797.0 16799.4 16847.6 16850.7 16860.7 9360.2 9455.6 9468.1 9536.6 9656.4 9526.0 9536.7 9549.0 9729.5 9757.8 7138.0 7209.6 7270.9 7323.3 7351.8 2043.7 2059.5 2104.8 2111.2 2121.9 984.2 987.7 1065.3 1089.5 1143.3 18856.4 21274.4 21482.8 21510.0 21552.2 1056.9 1086.0 1096.9 1146.8 1185.3 10954.9 12164.1 12290.6 12343.6 12396.6 514645.0 522515.0 527729.0 529662.0 531988.0 82338.6 84916.0 85036.6 86285.1 87709.6 1065.5 1150.9 1163.6 1173.7 1178.4 18266.9 18657.0 19083.3 19201.2 19284.3 41811.4 41894.0 42158.3 42618.4 42715.8 55973.7 56636.7 57082.0 57644.0 58263.4 8657.7 8814.2 9244.4 9299.3 9307.8 7774.6 12555.9 13495.7 13607.6 13915.0 11088.4 11826.5 11850.9 12610.3 12740.6 11502.3 11660.4 11836.7 12124.8 12277.6 12180.8 12351.9 12623.3 12642.2 12798.4 7692.6 7980.8 8261.2 8290.9 8353.3 12025.4 12289.5 12323.2 12368.1 12564.9 16:28:23 INFO - PROCESS | 13470 | 16:28:23 INFO - PROCESS | 13470 | [#3] mootools.html Cycles:180 Average:6122.67 Median:4528.40 stddev:3427.58 (75.7%) stddev-sans-first:3418.49 16:28:23 INFO - PROCESS | 13470 | Values: 1358.0 1395.8 1472.6 1513.1 1724.8 7771.2 9036.2 9419.4 9421.1 9673.7 9307.8 9403.2 9406.2 9409.5 9410.5 2547.4 2583.2 2585.7 2587.8 2587.8 2515.5 2603.8 2606.3 2607.6 2609.7 2569.0 2583.6 2603.9 2605.2 2606.1 2489.3 2504.0 2508.6 2519.1 2521.5 2454.5 2475.3 2494.8 2525.9 2529.6 9066.3 9126.3 9247.2 9394.1 9492.4 5653.8 5749.9 5761.3 5772.9 5792.3 3646.2 3681.8 3731.9 3752.4 3775.5 1194.7 1200.5 1207.1 1234.7 1235.5 12799.6 12883.0 12927.8 12931.3 12976.9 4709.5 4801.4 4872.4 4934.5 5104.7 10037.4 10042.5 10055.5 10063.6 10071.7 5452.4 5460.6 5463.8 5466.6 5472.5 4371.2 4449.6 5103.9 5172.8 5251.3 4147.4 4176.5 4244.1 4296.1 4308.0 1296.3 1309.1 1319.0 1322.2 1325.7 822.7 833.3 890.3 913.1 950.5 6613.7 6626.6 6663.6 6753.6 6874.1 1018.2 1018.7 1047.8 1065.3 1072.5 4316.8 4365.6 4415.6 4432.4 4607.2 11403.9 11858.5 11860.1 11880.0 11882.9 11104.9 11713.9 11722.6 11725.6 11757.1 8426.5 8786.5 8806.7 8822.8 8824.0 6337.5 6368.1 6451.3 6483.8 6600.7 10405.2 10426.0 10445.1 10512.4 10520.5 10227.6 10506.4 10735.6 10756.9 10897.4 6877.6 7057.3 7064.0 8321.6 8534.7 8068.3 8870.2 8994.8 9065.3 9240.1 7696.6 7871.1 7957.8 7975.4 8047.2 8017.9 8110.1 8141.7 8171.3 8175.3 7124.9 7229.3 7355.2 7484.3 7488.7 5687.8 5735.5 5805.7 5871.3 5943.9 5555.2 5701.7 5758.8 5760.3 5881.3 16:28:23 INFO - PROCESS | 13470 | 16:28:23 INFO - PROCESS | 13470 | [#4] prototype.html Cycles:180 Average:4849.49 Median:5504.64 stddev:1419.31 (25.8%) stddev-sans-first:1382.70 16:28:23 INFO - PROCESS | 13470 | Values: 359.5 360.0 362.6 364.8 367.1 5528.3 5537.2 5575.6 5582.0 5592.6 5468.5 5499.8 5509.5 5515.8 5519.3 4114.8 4283.2 4328.8 4335.1 4360.1 4349.6 4354.7 4355.6 4364.8 4368.2 4323.9 4326.1 4337.5 4342.8 4347.7 4171.7 4284.8 4288.6 4314.7 4339.6 3775.3 3787.1 3791.5 3825.9 3833.6 5369.9 5372.4 5376.1 5383.6 5395.3 4738.5 4952.6 5003.7 5007.2 5012.2 5762.9 5775.5 5813.6 5816.8 5819.9 4206.7 4212.4 4212.7 4227.9 4228.3 5673.8 5799.4 5881.2 5903.7 6037.2 4087.2 4087.4 4091.4 4159.6 4280.2 4316.4 4324.0 4331.9 4355.8 4365.7 4751.8 4919.7 4935.4 4949.7 4969.2 5730.4 5772.4 5777.2 5777.7 5810.2 1474.8 1486.5 1488.1 1489.9 1490.4 930.4 967.4 974.8 979.7 987.9 5016.0 5033.8 5070.6 5073.8 5073.8 5146.1 5322.9 5329.0 5334.8 5335.5 5002.5 5042.8 5072.4 5251.6 5672.9 3141.9 3168.3 3172.4 3173.1 3182.6 6016.0 6200.6 6204.2 6211.0 6222.4 6056.5 6081.9 6087.1 6089.5 6100.8 6135.8 6145.5 6191.4 6212.0 6222.4 5847.5 5928.3 5992.8 6023.2 6025.8 5945.9 5953.6 5962.3 5969.8 5972.7 5700.2 5828.0 5988.6 6002.2 6167.6 4191.2 4298.4 4312.4 4312.9 4316.9 5792.8 5938.4 5967.6 5978.4 6000.5 5809.4 5810.3 5852.9 5869.7 5875.4 5784.9 5815.0 5837.5 5844.9 5856.8 5533.4 5790.2 5800.9 5806.9 5851.0 5887.3 5891.9 5895.9 5898.9 5947.3 5532.7 5744.9 5750.3 5754.6 5757.6 16:28:23 INFO - PROCESS | 13470 | 16:28:23 INFO - PROCESS | 13470 | [#5] yui.html Cycles:180 Average:22999.78 Median:4413.63 stddev:34305.07 (777.3%) stddev-sans-first:34360.65 16:28:23 INFO - PROCESS | 13470 | Values: 757.9 761.7 780.1 834.7 893.7 13572.0 14685.9 14692.6 14707.6 14713.5 15183.2 15644.1 15717.1 15768.0 15839.3 724.7 859.8 874.5 877.1 877.4 721.2 847.4 870.0 877.7 878.7 903.9 906.8 909.2 910.3 910.4 740.9 899.1 905.8 905.9 906.4 555.7 649.9 663.2 664.0 670.4 14350.1 14424.4 14434.4 14479.0 14509.4 13978.7 14669.9 15148.6 15414.4 15429.8 11890.2 12209.6 12353.8 12355.6 12500.0 4386.3 4440.9 4514.0 4585.4 4648.4 86076.6 89577.6 90979.0 91435.0 92342.5 11826.2 11852.3 11976.4 12073.6 12129.2 22927.2 22927.5 22950.0 22956.8 22971.7 8774.5 8785.2 8961.3 9111.6 9307.0 4920.4 4993.3 5009.1 5023.2 5096.6 3542.7 3684.5 3686.0 3687.6 3688.8 612.5 619.3 621.3 624.4 625.4 671.4 678.6 682.2 701.6 725.1 9065.7 9668.0 9753.3 10096.9 10293.2 592.3 606.9 626.7 665.3 682.9 4550.9 4620.2 4636.4 4654.7 4778.2 94535.5 127792.4 133477.3 141150.3 141503.0 63255.0 115946.0 116105.4 116802.0 118905.0 102888.0 103392.0 103820.0 104279.0 104457.5 8955.9 9029.6 9287.9 9310.2 9402.2 54690.0 55968.0 56913.3 57154.4 57220.0 82057.0 83969.0 85443.6 86571.1 88586.6 11877.8 12089.8 14119.0 14242.1 14332.8 14597.0 16059.8 16107.0 16221.9 16634.7 15037.2 15381.4 15397.9 15817.6 15957.3 14923.3 15009.5 15117.9 15355.9 15416.7 13302.3 13726.8 14055.9 14396.1 14627.3 11858.6 12308.8 12575.2 13007.7 13021.1 12442.3 12639.2 12824.9 12825.6 12873.2 16:28:23 INFO - PROCESS | 13470 | -------- Summary: end -------- 16:28:23 INFO - PROCESS | 13470 | 16:28:24 INFO - TEST-INFO | 13470: exit 0 16:28:24 INFO - TEST-OK | dromaeo_css | took 1113688ms 16:28:24 INFO - TEST-START | kraken 16:28:24 INFO - operating with platform_type : linux_ 16:28:24 INFO - Initialising browser for kraken test... 16:28:24 INFO - TEST-INFO | started process 13798 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSrTEEO/profile http://localhost:36769/getInfo.html) 16:28:28 INFO - PROCESS | 13798 | __metrics Screen width/height:1600/1200 16:28:28 INFO - PROCESS | 13798 | colorDepth:24 16:28:28 INFO - PROCESS | 13798 | Browser inner width/height: 1024/697 16:28:28 INFO - PROCESS | 13798 | __metrics 16:28:29 INFO - PROCESS | 13798 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:28:29 INFO - PROCESS | 13798 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:29 INFO - PROCESS | 13798 | console.error: PushService: 16:28:29 INFO - PROCESS | 13798 | stateChangeProcessEnqueue: Error transitioning state 16:28:29 INFO - PROCESS | 13798 | UnknownError 16:28:30 INFO - TEST-INFO | 13798: exit 0 16:28:30 INFO - Browser initialized. 16:28:30 INFO - Running cycle 1/1 for kraken test... 16:28:30 INFO - TEST-INFO | started process 13889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSrTEEO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:41 INFO - PROCESS | 13889 | RSS: Main: 144887808 16:28:41 INFO - PROCESS | 13889 | 16:28:44 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36769/tests/kraken/driver.html?testName=audio-beat-detection) 16:28:45 INFO - PROCESS | 13889 | RSS: Main: 186556416 16:28:45 INFO - PROCESS | 13889 | 16:28:47 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36769/tests/kraken/driver.html?testName=audio-dft) 16:28:48 INFO - PROCESS | 13889 | RSS: Main: 175239168 16:28:48 INFO - PROCESS | 13889 | 16:28:51 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36769/tests/kraken/driver.html?testName=audio-fft) 16:28:51 INFO - PROCESS | 13889 | RSS: Main: 266498048 16:28:51 INFO - PROCESS | 13889 | 16:28:53 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36769/tests/kraken/driver.html?testName=audio-oscillator) 16:28:54 INFO - PROCESS | 13889 | RSS: Main: 173477888 16:28:54 INFO - PROCESS | 13889 | 16:28:56 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36769/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:28:57 INFO - PROCESS | 13889 | RSS: Main: 175054848 16:28:57 INFO - PROCESS | 13889 | 16:29:00 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36769/tests/kraken/driver.html?testName=imaging-darkroom) 16:29:00 INFO - PROCESS | 13889 | RSS: Main: 210944000 16:29:00 INFO - PROCESS | 13889 | 16:29:03 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36769/tests/kraken/driver.html?testName=imaging-desaturate) 16:29:04 INFO - PROCESS | 13889 | RSS: Main: 209866752 16:29:04 INFO - PROCESS | 13889 | 16:29:07 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36769/tests/kraken/driver.html?testName=json-parse-financial) 16:29:07 INFO - PROCESS | 13889 | RSS: Main: 209543168 16:29:07 INFO - PROCESS | 13889 | 16:29:09 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36769/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:29:09 INFO - PROCESS | 13889 | RSS: Main: 171200512 16:29:09 INFO - PROCESS | 13889 | 16:29:11 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36769/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:29:12 INFO - PROCESS | 13889 | RSS: Main: 178397184 16:29:12 INFO - PROCESS | 13889 | 16:29:14 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36769/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:29:14 INFO - PROCESS | 13889 | RSS: Main: 182927360 16:29:14 INFO - PROCESS | 13889 | 16:29:17 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36769/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:29:17 INFO - PROCESS | 13889 | RSS: Main: 184610816 16:29:17 INFO - PROCESS | 13889 | 16:29:20 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36769/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:29:21 INFO - PROCESS | 13889 | RSS: Main: 181706752 16:29:21 INFO - PROCESS | 13889 | 16:29:22 INFO - PROCESS | 13889 | Cycle 1(1): loaded http://localhost:36769/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36769/tests/kraken/driver.html?testName=ai-astar) 16:29:23 INFO - PROCESS | 13889 | RSS: Main: 233783296 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | __start_tp_report 16:29:23 INFO - PROCESS | 13889 | _x_x_mozilla_page_load 16:29:23 INFO - PROCESS | 13889 | _x_x_mozilla_page_load_details 16:29:23 INFO - PROCESS | 13889 | |i|pagename|runs| 16:29:23 INFO - PROCESS | 13889 | |0;ai-astar;108;111;111;110;111;111;111;111;110;110 16:29:23 INFO - PROCESS | 13889 | |1;audio-beat-detection;148;116;119;165;119;118;116;122;158;117 16:29:23 INFO - PROCESS | 13889 | |2;audio-dft;161;160;161;160;161;162;160;161;161;160 16:29:23 INFO - PROCESS | 13889 | |3;audio-fft;74;77;77;122;75;76;76;75;105;76 16:29:23 INFO - PROCESS | 13889 | |4;audio-oscillator;81;81;117;82;81;81;98;81;82;112 16:29:23 INFO - PROCESS | 13889 | |5;imaging-gaussian-blur;117;116;117;116;116;116;117;116;116;116 16:29:23 INFO - PROCESS | 13889 | |6;imaging-darkroom;145;145;146;146;145;146;145;146;145;145 16:29:23 INFO - PROCESS | 13889 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;95;95 16:29:23 INFO - PROCESS | 13889 | |8;json-parse-financial;50;58;50;49;48;49;49;49;50;50 16:29:23 INFO - PROCESS | 13889 | |9;json-stringify-tinderbox;58;57;58;59;58;57;57;57;59;58 16:29:23 INFO - PROCESS | 13889 | |10;stanford-crypto-aes;72;73;72;73;72;72;72;81;72;72 16:29:23 INFO - PROCESS | 13889 | |11;stanford-crypto-ccm;118;117;125;118;118;121;117;125;154;123 16:29:23 INFO - PROCESS | 13889 | |12;stanford-crypto-pbkdf2;152;148;148;149;150;150;149;152;148;149 16:29:23 INFO - PROCESS | 13889 | |13;stanford-crypto-sha256-iterative;63;61;70;66;61;62;62;61;60;59 16:29:23 INFO - PROCESS | 13889 | __end_tp_report 16:29:23 INFO - PROCESS | 13889 | __start_cc_report 16:29:23 INFO - PROCESS | 13889 | _x_x_mozilla_cycle_collect,1590 16:29:23 INFO - PROCESS | 13889 | __end_cc_report 16:29:23 INFO - PROCESS | 13889 | __startTimestamp1474327763061__endTimestamp 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | ------- Summary: start ------- 16:29:23 INFO - PROCESS | 13889 | Number of tests: 14 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:0.97 (0.9%) stddev-sans-first:0.50 16:29:23 INFO - PROCESS | 13889 | Values: 108.0 111.0 111.0 110.0 111.0 111.0 111.0 111.0 110.0 110.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:120.50 stddev:19.27 (16.0%) stddev-sans-first:19.29 16:29:23 INFO - PROCESS | 13889 | Values: 148.0 116.0 119.0 165.0 119.0 118.0 116.0 122.0 158.0 117.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 16:29:23 INFO - PROCESS | 13889 | Values: 161.0 160.0 161.0 160.0 161.0 162.0 160.0 161.0 161.0 160.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#3] audio-fft Cycles:10 Average:83.30 Median:76.00 stddev:16.44 (21.6%) stddev-sans-first:17.09 16:29:23 INFO - PROCESS | 13889 | Values: 74.0 77.0 77.0 122.0 75.0 76.0 76.0 75.0 105.0 76.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:81.00 stddev:14.18 (17.5%) stddev-sans-first:14.69 16:29:23 INFO - PROCESS | 13889 | Values: 81.0 81.0 117.0 82.0 81.0 81.0 98.0 81.0 82.0 112.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 16:29:23 INFO - PROCESS | 13889 | Values: 117.0 116.0 117.0 116.0 116.0 116.0 117.0 116.0 116.0 116.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:29:23 INFO - PROCESS | 13889 | Values: 145.0 145.0 146.0 146.0 145.0 146.0 145.0 146.0 145.0 145.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:29:23 INFO - PROCESS | 13889 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:2.82 (5.6%) stddev-sans-first:2.99 16:29:23 INFO - PROCESS | 13889 | Values: 50.0 58.0 50.0 49.0 48.0 49.0 49.0 49.0 50.0 50.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#9] json-stringify-tinderbox Cycles:10 Average:57.80 Median:58.00 stddev:0.79 (1.4%) stddev-sans-first:0.83 16:29:23 INFO - PROCESS | 13889 | Values: 58.0 57.0 58.0 59.0 58.0 57.0 57.0 57.0 59.0 58.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:72.00 stddev:2.81 (3.9%) stddev-sans-first:2.95 16:29:23 INFO - PROCESS | 13889 | Values: 72.0 73.0 72.0 73.0 72.0 72.0 72.0 81.0 72.0 72.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:122.00 stddev:11.14 (9.1%) stddev-sans-first:11.63 16:29:23 INFO - PROCESS | 13889 | Values: 118.0 117.0 125.0 118.0 118.0 121.0 117.0 125.0 154.0 123.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.50 Median:149.50 stddev:1.51 (1.0%) stddev-sans-first:1.30 16:29:23 INFO - PROCESS | 13889 | Values: 152.0 148.0 148.0 149.0 150.0 150.0 149.0 152.0 148.0 149.0 16:29:23 INFO - PROCESS | 13889 | 16:29:23 INFO - PROCESS | 13889 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.00 stddev:3.24 (5.2%) stddev-sans-first:3.43 16:29:23 INFO - PROCESS | 13889 | Values: 63.0 61.0 70.0 66.0 61.0 62.0 62.0 61.0 60.0 59.0 16:29:23 INFO - PROCESS | 13889 | -------- Summary: end -------- 16:29:23 INFO - PROCESS | 13889 | 16:29:24 INFO - TEST-INFO | 13889: exit 0 16:29:24 INFO - TEST-OK | kraken | took 59532ms 16:29:24 INFO - SUITE-END | took 1173s 16:29:24 INFO - Completed test suite (00:19:33) 16:29:24 INFO - javascript benchmark 16:29:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3107.1455349778807, 3157.5421563873238, 3159.3049529103596, 3184.3631273745254, 3206.2946151846586, 5599.552035837133, 5669.433056694328, 5691.516221121199, 5715.628484148528, 5723.771049158039, 5559.11054231322, 5613.214150019001, 5623.044082505973, 5624.240727501786, 5648.6893220872835, 5054.823081192149, 5078.720670363128, 5104.795808167669, 5107.106256405133, 5116.462771409004, 4896.363472748538, 4913.631477639174, 5077.416097148838, 5142.768575414097, 5147.974260128713, 4952.579030782388, 5040.697558146505, 5048.974755126219, 5049.772760225798, 5087.516685914832, 5093.032323858473, 5128.615353848476, 5148.124818780816, 5154.046501397253, 5204.843854684365, 5090.618203634742, 5210.713410762407, 5212.192110222922, 5212.713300768456, 5241.895162096736, 5266.210068489757, 5305.522502974751, 5658.490735833795, 5698.943010569883, 5761.740721667535, 5855.238818953551, 5869.677167755772, 5888.793892213752, 5927.2887253529425, 5944.613600115978, 5936.257967754031, 6027.578069535134, 6032.819015429544, 6047.909281360783, 6048.304444988838, 5330.200469929541, 5597.832065038056, 5612.943870561324, 5614.0, 5620.241267428926, 5828.300603927556, 6038.698065096728, 6165.229346331708, 6192.442680158717, 6204.193454850841, 5942.762289508458, 5942.821715348547, 5948.791792287249, 5949.583529152917, 5967.254093238345, 5605.1872478491205, 6032.426919442646, 6039.063945088518, 6040.969795150996, 6045.063015232646, 5615.578831587565, 5933.02105152645, 5984.431479009487, 5986.820395388146, 6067.817965461043, 6038.064100064497, 6041.305249896231, 6054.152418661391, 6057.909131362945, 6078.696065196723, 4618.907621847544, 4653.325267836145, 4666.463356714022, 4672.2057250267535, 4707.458642256116, 3926.1166237596312, 3979.8209080590864, 4040.777757223322, 4071.450354202204, 4082.3060079786505, 5660.283313229647, 5678.432156784371, 5680.034394153004, 5693.402192769782, 5707.34365547959, 6006.729697163638, 6011.248593925759, 6012.30858451275, 6018.097285407136, 6018.6990650467305, 5491.807786727445, 5506.669599824023, 5507.228987941691, 5661.405552416937, 5675.31896172462, 5046.520580544916, 5105.463926287761, 5107.310513080686, 5137.306463627438, 5139.203423469368, 6310.9368906311265, 6328.936710632835, 6332.430081292614, 6332.8416789579705, 6334.873302534015, 5637.633553818989, 6012.398760123952, 6151.32335443092, 6152.200213972155, 6156.722947467464, 5841.532677385884, 6179.783707570394, 6234.096056071753, 6240.157578726996, 6343.270523889812, 6010.038393857137, 6042.546808989431, 6050.213472248755, 6055.878882422415, 6091.604045737013, 6084.148219249397, 6124.5712800103565, 6142.109394137736, 6143.72353244114, 6147.108669242845, 5568.749406276808, 5663.122216056518, 5807.477327040587, 5815.360310365941, 5840.269966254218, 5917.881642367214, 5923.49650279731, 5926.436988486087, 5926.881462370642, 5948.315943666534, 5819.912701309399, 5846.298444186725, 5847.035239185487, 5847.590668653153, 5854.385289544536, 5513.21855301531, 5646.378898321099, 5773.134029895381, 5824.650520968756, 5920.200772895776, 5751.309842818889, 5754.8849023018465, 5756.481916627524, 5795.014847475855, 5797.014507533814], "value": 5521.06045021683, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.3333832746522, 602.5270162922106, 634.0900807341466, 656.878477481666, 663.9004149377596, 35186.296274074506, 46974.0, 48407.757961210234, 48478.999999999956, 48621.51378486214, 50236.24645630318, 50585.74707126468, 50709.0, 50783.23825142616, 50794.49205507953, 8265.83468330633, 9151.222146117589, 9269.860952085724, 9285.767855803591, 9288.814223715523, 8319.292860106898, 11161.72095697606, 11505.194636375458, 11511.827322590168, 11933.403329833516, 11629.185956983014, 11638.359890206078, 11680.474378652938, 11690.181687281894, 11714.648560543197, 11424.942875285653, 11572.826407603892, 11586.246893951866, 11592.304461732281, 11592.594259200887, 11408.885911140866, 11467.426628668532, 11480.942595287053, 11483.081353491702, 11486.310821350662, 47425.28862067072, 47767.76116119432, 47854.521454785354, 47920.28119578209, 47943.28085078727, 3744.1762812181278, 3847.942280865789, 3861.555921069085, 3878.4182372644327, 3912.2957867583823, 31615.367692646018, 31781.52327715086, 31897.681023189703, 35618.28763424743, 37532.24935501275, 4531.3882625845745, 4629.467611224719, 4634.490206077328, 4676.354160946877, 4699.107169637792, 372750.0, 372796.0, 373049.99999999726, 374211.0, 374274.0, 38937.41593876094, 40242.0, 66793.3320666787, 67278.0, 67376.66311668509, 35058.64941350605, 35249.295014099574, 35250.823745881105, 35272.0, 35290.82354588211, 18004.54988625295, 18104.27582896696, 18179.091045447938, 18196.363127290748, 18196.9090154551, 12870.742585148244, 13135.802962955564, 14388.712225755426, 15527.689446211238, 15610.843891560939, 9459.90540094604, 9641.132298093205, 9669.70990870275, 9709.417434953926, 9742.31803773729, 3549.7515173937577, 3622.3298689742483, 3662.8717994870053, 3679.2825399047465, 3684.8710295140077, 1048.1719441641171, 1118.1166878166157, 1122.2088427658482, 1148.5520646947698, 1182.0956967919549, 31062.68937310598, 31682.207944801565, 32322.83838580792, 34076.466559004904, 35326.47010294848, 1178.4579093617033, 1186.5313201285444, 1229.2255878796302, 1239.151181440716, 1243.483954159032, 17775.200115994812, 18188.90905545464, 18270.45188644343, 18497.53756156107, 18530.629387412177, 593999.0, 597211.0, 598460.0, 599422.0, 601264.0, 66202.66898665573, 66689.0, 66697.0, 66729.0, 66755.66622166858, 1264.1151194164122, 1314.001358967192, 1377.4834437086092, 1438.96402589934, 1448.7899254608233, 27536.0, 30887.382252355277, 31320.686793131776, 31543.52684709685, 32126.839365803957, 57087.42912570821, 57905.42094579, 57910.13134802897, 57938.7103064482, 57962.4203757957, 467911.0, 470798.0, 472157.0, 472238.0, 475266.0, 30588.388232234785, 31883.681163188998, 32127.518087228244, 33475.330493389505, 33507.49738753872, 42664.57335426606, 42848.57151428446, 42967.355489667054, 43263.18789278687, 43401.56598434102, 38389.80805095957, 38881.2223755529, 38923.02692432712, 39052.414213787386, 40080.599194007686, 45741.340692743834, 46603.30095048509, 46676.53323466722, 47304.0, 47515.28727069166, 44735.77632111819, 44910.32634510419, 45587.77206113948, 45842.541574583825, 46153.076938461716, 31865.203369915936, 33866.322673546885, 34342.313153736286, 34535.309293814484, 34877.65122348744, 26425.735742643097, 26629.467410652065, 29845.403091937605, 29935.55096673508, 30589.847050765493], "value": 20537.585127419534, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.4654083339572, 412.30320757919094, 419.7124969395964, 427.41230807639505, 442.80295268605465, 15048.774268385969, 16554.0, 20559.897200513995, 20820.791792082076, 21625.0, 20222.29221977231, 20301.0, 20330.89834550829, 20487.38537843867, 20632.896835515803, 5810.099434587646, 5862.677552734598, 5965.88068238635, 6016.308124565688, 6018.368071352511, 4403.867883963486, 4610.423697037871, 5933.762649494015, 6014.188084608566, 6017.72920218589, 6334.809955701337, 6581.01284807278, 6608.636524991123, 6724.193096828388, 6724.5965242085385, 6391.328910464404, 6551.672416379186, 6610.801675949681, 6616.338366163393, 6711.026901099338, 5771.192033115367, 5924.822255332347, 5927.73325200367, 5941.70291485424, 5944.7027648617395, 24902.252932411877, 25240.873795630905, 25358.619620705704, 25395.492090158277, 25433.364165895815, 2519.8236123471365, 2641.313258552771, 2642.4582960493053, 2655.150351887397, 2805.368792021799, 14802.851971480255, 15618.531444056696, 15718.449854255161, 15778.447754328543, 15791.289391977274, 2028.949276268105, 2055.969160462594, 2069.151647824385, 2082.8125468707735, 2132.4668832791804, 81453.18546814515, 83733.0, 83755.16244837534, 85039.57480212621, 86785.0, 11393.487293071832, 11564.0, 11599.594014209462, 11665.125115616192, 11787.11596630239, 16797.0, 16799.41202057922, 16847.57881052959, 16850.662986740197, 16860.662786744197, 9360.204382627553, 9455.621775129055, 9468.14786669203, 9536.618535258513, 9656.420614762996, 9526.0, 9536.713898583053, 9549.0, 9729.464879431558, 9757.756056098513, 7137.964310178416, 7209.567425954459, 7270.9457128717095, 7323.340899319087, 7351.8162045949275, 2043.6934459831143, 2059.4851287178208, 2104.7895210479137, 2111.229401268526, 2121.8939053047284, 984.2224642532462, 987.7234374375187, 1065.3022270412737, 1089.515165751249, 1143.2683082826998, 18856.434306970812, 21274.36176914695, 21482.78517214839, 21510.0, 21552.245671401426, 1056.9048785609177, 1085.9619913302997, 1096.9341839489657, 1146.7706458708292, 1185.300672603151, 10954.890451095547, 12164.148509604243, 12290.631281061582, 12343.567975120828, 12396.566120185751, 514645.0, 522515.0, 527729.0, 529662.0, 531988.0, 82338.58830705807, 84916.0, 85036.57481712553, 86285.13714862897, 87709.56145219233, 1065.5082883962668, 1150.930944143354, 1163.5629996953755, 1173.6831055614937, 1178.4402408855724, 18266.908665456587, 18657.0, 19083.332083377016, 19201.231950721816, 19284.325048623232, 41811.37282940819, 41894.0, 42158.25971311855, 42618.36072458977, 42715.78642106894, 55973.72013140071, 56636.716816415654, 57082.0, 57644.0, 58263.4173658258, 8657.740267791976, 8814.206721395105, 9244.399114057565, 9299.256059515088, 9307.813843723223, 7774.572398518136, 12555.93722031384, 13495.662608434868, 13607.57120502333, 13915.0, 11088.390138542458, 11826.46780894879, 11850.940745296562, 12610.306433145897, 12740.554080607135, 11502.309861408343, 11660.358680272328, 11836.704082397664, 12124.818127727915, 12277.631671049883, 12180.756384872077, 12351.876481235073, 12623.30571818522, 12642.2414655121, 12798.376332851556, 7692.615369231628, 7980.800479988046, 8261.215184557457, 8290.917090829014, 8353.33173346143, 12025.398730063636, 12289.508419663114, 12323.174784012037, 12368.134230604132, 12564.931980781736], "value": 9972.649173590715, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.979630305545, 1395.787676532696, 1472.5508719840448, 1513.1223890143717, 1724.793024837019, 7771.222877712226, 9036.186743193111, 9419.387739796917, 9421.057894210575, 9673.70978870635, 9307.81384372314, 9403.247740180786, 9406.15344618984, 9409.529523523814, 9410.52947352633, 2547.414094758207, 2583.160472846323, 2585.741425857418, 2587.780038696713, 2587.8447293162376, 2515.5220508103403, 2603.7656610905015, 2606.3484128967757, 2607.634931109637, 2609.739026097394, 2569.0, 2583.599542070973, 2603.934901627465, 2605.2444791010585, 2606.1269474725896, 2489.2905521926164, 2504.023430861956, 2508.636247744076, 2519.1308998395707, 2521.4578865543813, 2454.4947624168585, 2475.3192871960355, 2494.787943024845, 2525.936851578725, 2529.607910773833, 9066.274698024143, 9126.315526335551, 9247.21398681113, 9394.107559781809, 9492.382995105296, 5653.802116925929, 5749.942500574983, 5761.337446193657, 5772.855678608026, 5792.304923409218, 3646.21606354635, 3681.7790932543867, 3731.869384571527, 3752.43713442983, 3775.528058992626, 1194.7252132009687, 1200.5317926008863, 1207.1489599832184, 1234.666640007193, 1235.518147922311, 12799.552015679406, 12883.0, 12927.806082908764, 12931.288779117145, 12976.87023129766, 4709.458412282563, 4801.447833499193, 4872.3909511311085, 4934.4572097069295, 5104.668196567211, 10037.44794036335, 10042.497875106215, 10055.497225138713, 10063.597456101821, 10071.69784906454, 5452.40023597404, 5460.645058071213, 5463.754131064112, 5466.562674985991, 5472.50747432725, 4371.169477799208, 4449.57729015743, 5103.923441148386, 5172.793088276427, 5251.343582052244, 4147.433756948455, 4176.477940257468, 4244.087521182899, 4296.119295544406, 4308.009157893639, 1296.319432298051, 1309.1228876652665, 1318.9580231617108, 1322.2397121655088, 1325.721598464314, 822.6956026270199, 833.2792134803351, 890.2877697841701, 913.0869130869131, 950.5437390052675, 6613.669316534154, 6626.635535045522, 6663.600183988976, 6753.561018533779, 6874.072000279898, 1018.2159737002466, 1018.7351288664852, 1047.8061558611528, 1065.3128731967838, 1072.477082542791, 4316.848910288125, 4365.607095361369, 4415.602595766396, 4432.423784907941, 4607.216773148572, 11403.885961140282, 11858.466369013415, 11860.051195904307, 11880.0, 11882.88117118835, 11104.888951110386, 11713.941430292794, 11722.58970936013, 11725.648230553097, 11757.118216133824, 8426.536540490331, 8786.516741579275, 8806.735797926072, 8822.823543529223, 8823.955880220687, 6337.524685648687, 6368.108464814837, 6451.258105317948, 6483.837904052436, 6600.702968366339, 10405.167586592903, 10425.957404259816, 10445.11216546602, 10512.42181680015, 10520.526576304237, 10227.590896364061, 10506.369617822955, 10735.570577176826, 10756.892431075903, 10897.400642964712, 6877.6217308048535, 7057.258987806206, 7064.046353742179, 8321.625526851427, 8534.658613655385, 8068.273855353046, 8870.201681848665, 8994.82010359776, 9065.32010099232, 9240.122188392092, 7696.576688282198, 7871.134175240604, 7957.7612671619945, 7975.4018448615425, 8047.155048720034, 8017.879731804144, 8110.148434414537, 8141.715039973573, 8171.305439037748, 8175.264226219669, 7124.928750712426, 7229.313215244543, 7355.1541572719825, 7484.251574842472, 7488.737894173678, 5687.800926967537, 5735.4551317624755, 5805.651660900359, 5871.324797648154, 5943.940560594338, 5555.194496798023, 5701.657900525982, 5758.827235182951, 5760.300071445529, 5881.32364778056], "value": 4911.6815157215315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.46080878681977, 359.9784012959222, 362.5667327543585, 364.7756629672752, 367.14638465567543, 5528.308961379827, 5537.197106419567, 5575.606098475381, 5582.023145949453, 5592.636478628886, 5468.535174510172, 5499.780008799653, 5509.504144626982, 5515.834524964257, 5519.282493275869, 4114.773687447197, 4283.186194623027, 4328.761918094504, 4335.132973405316, 4360.149770794696, 4349.608535231829, 4354.738715677069, 4355.607995280424, 4364.7817609119575, 4368.191884501377, 4323.935140972888, 4326.134773045388, 4337.54455782143, 4342.848000319974, 4347.695661303695, 4171.687123465737, 4284.828606855722, 4288.627639155471, 4314.71954322968, 4339.609435150835, 3775.339315619756, 3787.0532366908274, 3791.545014598238, 3825.9043523911846, 3833.616638336172, 5369.892602147936, 5372.355317361902, 5376.1398176292, 5383.623146379755, 5395.29861118056, 4738.502457241974, 4952.579030782388, 5003.74981250936, 5007.2489126630935, 5012.248162775576, 5762.884742305172, 5775.480206781368, 5813.563982701272, 5816.82549523515, 5819.883602327972, 4206.684498662582, 4212.368144778277, 4212.7472351659, 4227.900745806081, 4228.281192197333, 5673.773049078032, 5799.376174671099, 5881.176635271023, 5903.704814759288, 6037.154798328237, 4087.1621317629815, 4087.4277601135864, 4091.4272001919753, 4159.604837540429, 4280.208161490072, 4316.395704601358, 4323.978380108142, 4331.913361732748, 4355.760433176142, 4365.738055716667, 4751.83368582098, 4919.680220785637, 4935.383077115361, 4949.7030178189425, 4969.180085285887, 5730.426957304237, 5772.365039845614, 5777.226931073024, 5777.740001699934, 5810.157527158538, 1474.7861560073943, 1486.47973209378, 1488.0625206120292, 1489.9031562948373, 1490.4038384646228, 930.4045410937006, 967.3808762392075, 974.7611835100304, 979.6913972098766, 987.9280980136489, 5015.974920125376, 5033.823816166417, 5070.594352451795, 5073.822416215415, 5073.847784566395, 5146.073706732824, 5322.866928326823, 5329.0, 5334.786608535693, 5335.545649001503, 5002.549260714425, 5042.806379225049, 5072.391313042459, 5251.634575010448, 5672.914906276409, 3141.874325027019, 3168.287135394518, 3172.397244523533, 3173.1432513221303, 3182.586263785693, 6015.969920150371, 6200.56596038273, 6204.193454850841, 6211.0, 6222.377762223741, 6056.515478761688, 6081.898231901762, 6087.117367981707, 6089.451949324493, 6100.834203857656, 6135.785247516315, 6145.477634401037, 6191.380861913773, 6211.96830567242, 6222.377762223832, 5847.502962248256, 5928.304012522531, 5992.760289588367, 6023.247094113236, 6025.758969641165, 5945.851353716192, 5953.642781433128, 5962.314333851663, 5969.791057312973, 5972.731227094704, 5700.2304688868135, 5828.038373668297, 5988.610740301867, 6002.159697642246, 6167.629942203482, 4191.245575796386, 4298.39822424867, 4312.353147027971, 4312.93530597035, 4316.89919070635, 5792.797252096157, 5938.376470470538, 5967.582269241111, 5978.4021597839865, 6000.459958603572, 5809.419058094326, 5810.302763668386, 5852.912206316823, 5869.6771677558145, 5875.41245875409, 5784.884302314014, 5814.970925145516, 5837.503812176013, 5844.86025225077, 5856.765729370948, 5533.446655334596, 5790.160426738015, 5800.9419905800405, 5806.883862322815, 5851.039901633, 5887.293524777054, 5891.94108058931, 5895.94104058954, 5898.94101058984, 5947.286325640951, 5532.6680399176175, 5744.913826292693, 5750.281214848144, 5754.625949313281, 5757.625754325955], "value": 4402.228770367908, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.8976838126855, 761.6534476813047, 780.0678189563466, 834.6703052294343, 893.7184786792162, 13571.999999999987, 14685.92657036716, 14692.632684182874, 14707.558773236806, 14713.48502802402, 15183.240837958085, 15644.139572323518, 15717.13555754433, 15768.0, 15839.287232074556, 724.6992498113277, 859.7979474823427, 874.5452364770316, 877.0922095631023, 877.4077497689067, 721.2030706069796, 847.4110493207223, 870.0299166429448, 877.666486735043, 878.6617152396342, 903.891533016039, 906.7869050773096, 909.1635695160435, 910.3400034974624, 910.3809409601469, 740.8629403560359, 899.0559912092342, 905.8414777413959, 905.8958219804741, 906.4425378392274, 555.6693767208504, 649.9447546958467, 663.171036204744, 663.9634820084893, 670.3631550027493, 14350.138991660533, 14424.350904209336, 14434.422623095064, 14479.0, 14509.419623215059, 13978.720425591533, 14669.926650366786, 15148.621284467867, 15414.383424663, 15429.768553471707, 11890.215011396484, 12209.633710988684, 12353.752924941451, 12355.62933112008, 12500.0, 4386.320120381346, 4440.933385999213, 4513.977430112861, 4585.449746030497, 4648.372469716579, 86076.56961715214, 89577.55211223967, 90979.0, 91435.0, 92342.53828730814, 11826.231294965799, 11852.348120853263, 11976.401179940967, 12073.57742479009, 12129.21160124589, 22927.197548085736, 22927.541449170923, 22950.0, 22956.770432295463, 22971.6554251683, 8774.473531587997, 8785.209331160226, 8961.283097352196, 9111.589978450853, 9306.953465232766, 4920.3603531541385, 4993.251012348177, 5009.148444764399, 5023.196288593807, 5096.643234973591, 3542.6811586956783, 3684.5394325709285, 3685.963140368562, 3687.6312368763447, 3688.760230585057, 612.4579746924004, 619.3187493756922, 621.2638023941644, 624.44736408279, 625.4339822460589, 671.4192223726442, 678.633537889544, 682.2018238660779, 701.5580184483845, 725.0827702955766, 9065.728028159167, 9667.984861589572, 9753.268504862164, 10096.899031009743, 10293.176545876311, 592.3040427497673, 606.9059295809157, 626.743035355502, 665.2981104934244, 682.9385355318045, 4550.931736023962, 4620.1914664934175, 4636.397268355092, 4654.697444666086, 4778.187708089633, 94535.52732236295, 127792.36103819608, 133477.3326133363, 141150.29424853015, 141503.0, 63255.0, 115946.0, 116105.41947290378, 116802.0, 118905.0, 102888.0, 103392.0, 103820.0, 104279.0, 104457.47771261095, 8955.865662014945, 9029.593668285075, 9287.860682089638, 9310.15143017396, 9402.247820174232, 54690.0, 55968.0, 56913.29260122203, 57154.428455716574, 57220.0, 82057.0, 83969.0, 85443.57278213817, 86571.13428865882, 88586.55706721425, 11877.76244475123, 12089.81865272004, 14119.0, 14242.07426517273, 14332.785008225093, 14597.0, 16059.759103613222, 16107.0, 16221.918890405472, 16634.667306654042, 15037.248137592858, 15381.384744610537, 15397.923010384877, 15817.604559886095, 15957.281922313756, 14923.253837307873, 15009.474668386554, 15117.924410377878, 15355.923220383827, 15416.691666166389, 13302.268375239455, 13726.794098088736, 14055.859441405864, 14396.064255823758, 14627.341769620605, 11858.644240672793, 12308.753824923631, 12575.245485270912, 13007.674808129872, 13021.088523803242, 12442.253464792142, 12639.1784534005, 12824.871751282368, 12825.615231543068, 12873.22760634365], "value": 7964.7575385282435, "unit": "score"}], "name": "dromaeo_css", "value": 7613.48353025889, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 111.0, 110.0, 111.0, 111.0, 111.0, 111.0, 110.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 119.0, 165.0, 119.0, 118.0, 116.0, 122.0, 158.0, 117.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 161.0, 162.0, 160.0, 161.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 77.0, 122.0, 75.0, 76.0, 76.0, 75.0, 105.0, 76.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 117.0, 82.0, 81.0, 81.0, 98.0, 81.0, 82.0, 112.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 58.0, 50.0, 49.0, 48.0, 49.0, 49.0, 49.0, 50.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 59.0, 58.0, 57.0, 57.0, 57.0, 59.0, 58.0], "value": 57.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 73.0, 72.0, 72.0, 72.0, 81.0, 72.0, 72.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 125.0, 118.0, 118.0, 121.0, 117.0, 125.0, 154.0, 123.0], "value": 123.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 148.0, 148.0, 149.0, 150.0, 150.0, 149.0, 152.0, 148.0, 149.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 70.0, 66.0, 61.0, 62.0, 62.0, 61.0, 60.0, 59.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1447.0999999999997, "alertThreshold": 2.0}]} 16:29:24 INFO - Return code: 0 16:29:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:29:24 INFO - # TBPL SUCCESS # 16:29:24 INFO - Running post-action listener: _resource_record_post_action 16:29:24 INFO - [mozharness: 2016-09-19 23:29:24.365459Z] Finished run-tests step (success) 16:29:24 INFO - Running post-run listener: _resource_record_post_run 16:29:24 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 354848768; Read time: 16; Write time: 313268 16:29:24 INFO - TinderboxPrint: CPU usage
13.0% 16:29:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:29:24 INFO - TinderboxPrint: I/O write bytes / time
354,848,768 / 313,268 16:29:24 INFO - TinderboxPrint: CPU idle
8,257.3 (87.0%) 16:29:24 INFO - TinderboxPrint: CPU user
1,185.3 (12.5%) 16:29:24 INFO - TinderboxPrint: Swap in / out
0 / 0 16:29:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110292992; Read time: 0; Write time: 131656 16:29:24 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 243601408; Read time: 0; Write time: 181612 16:29:25 INFO - Running post-run listener: _upload_blobber_files 16:29:25 INFO - Blob upload gear active. 16:29:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:29:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:29:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:29:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:29:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:29:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:26 INFO - (blobuploader) - INFO - Done attempting. 16:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:29:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:29 INFO - (blobuploader) - INFO - Done attempting. 16:29:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:29:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:29:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:30 INFO - (blobuploader) - INFO - Done attempting. 16:29:30 INFO - (blobuploader) - INFO - Iteration through files over. 16:29:30 INFO - Return code: 0 16:29:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:29:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:29:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7bb0703c176570d84e054059d11698009d145a7d4b84f23ba37f2468761440f0f1088403bb332ce766a8ad363d7f2624e0bebfc69d8ba2778b136e4aa9279972", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6523e238b520d87229c16544002f91248f1b3127ea16668404d8310ec7ddf087f3a3f0c8916571cfcdc4e81f48f254d173babb1718d05164cd10bd08a66afa09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a56d0e6c6b561fe21b3e842abf924dcb829db5d303908c50bdf56a519a28cc3c8e32dd56d9fb3dfdb6c3727b257f742dd8a34f17ccd2f3d6dca37d1b36be2a15"} 16:29:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:29:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:29:30 INFO - Contents: 16:29:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7bb0703c176570d84e054059d11698009d145a7d4b84f23ba37f2468761440f0f1088403bb332ce766a8ad363d7f2624e0bebfc69d8ba2778b136e4aa9279972", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6523e238b520d87229c16544002f91248f1b3127ea16668404d8310ec7ddf087f3a3f0c8916571cfcdc4e81f48f254d173babb1718d05164cd10bd08a66afa09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a56d0e6c6b561fe21b3e842abf924dcb829db5d303908c50bdf56a519a28cc3c8e32dd56d9fb3dfdb6c3727b257f742dd8a34f17ccd2f3d6dca37d1b36be2a15"} 16:29:30 INFO - Running post-run listener: copy_logs_to_upload_dir 16:29:30 INFO - Copying logs to upload dir... 16:29:30 INFO - mkdir: /builds/slave/test/build/upload/logs 16:29:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.362253 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-09-19 16:29:30.349090) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 16:29:30.353139) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260 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/7bb0703c176570d84e054059d11698009d145a7d4b84f23ba37f2468761440f0f1088403bb332ce766a8ad363d7f2624e0bebfc69d8ba2778b136e4aa9279972", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6523e238b520d87229c16544002f91248f1b3127ea16668404d8310ec7ddf087f3a3f0c8916571cfcdc4e81f48f254d173babb1718d05164cd10bd08a66afa09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a56d0e6c6b561fe21b3e842abf924dcb829db5d303908c50bdf56a519a28cc3c8e32dd56d9fb3dfdb6c3727b257f742dd8a34f17ccd2f3d6dca37d1b36be2a15"} build_url:https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011335 build_url: 'https://queue.taskcluster.net/v1/task/QYX13ktAR5uSByMpiYNKOQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7bb0703c176570d84e054059d11698009d145a7d4b84f23ba37f2468761440f0f1088403bb332ce766a8ad363d7f2624e0bebfc69d8ba2778b136e4aa9279972", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6523e238b520d87229c16544002f91248f1b3127ea16668404d8310ec7ddf087f3a3f0c8916571cfcdc4e81f48f254d173babb1718d05164cd10bd08a66afa09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a56d0e6c6b561fe21b3e842abf924dcb829db5d303908c50bdf56a519a28cc3c8e32dd56d9fb3dfdb6c3727b257f742dd8a34f17ccd2f3d6dca37d1b36be2a15"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 16:29:30.379055) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:29:30.379423) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474326536.741486-1112994260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 16:29:30.465383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 16:29:30.465762) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 16:29:30.466110) ========= ========= Total master_lag: 0.18 =========