builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1474304186.11 results: success (0) buildid: 20160919065229 builduid: 545acaaf810d4e38b5646a38601b9d03 revision: 5dddbefdf759f09b1411f33fa0920835b919fc81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.113017) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.113491) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.113821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.128775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.129085) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 09:56:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 65.8M=0s 2016-09-19 09:56:26 (65.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106958 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.253501) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.254300) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015587 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.306016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.306392) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 09:56:26,541 truncating revision to first 12 chars 2016-09-19 09:56:26,541 Setting DEBUG logging. 2016-09-19 09:56:26,541 attempt 1/10 2016-09-19 09:56:26,541 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 09:56:26,842 unpacking tar archive at: mozilla-aurora-5dddbefdf759/testing/mozharness/ program finished with exit code 0 elapsedTime=0.621290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.944753) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.945124) ========= script_repo_revision: 5dddbefdf759f09b1411f33fa0920835b919fc81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.945554) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.945872) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 09:56:26.955925) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-19 09:56:26.956227) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:56:27 INFO - MultiFileLogger online at 20160919 09:56:27 in /builds/slave/test 09:56:27 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 09:56:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:56:27 INFO - {'append_to_log': False, 09:56:27 INFO - 'base_work_dir': '/builds/slave/test', 09:56:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:56:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:56:27 INFO - 'branch': 'Mozilla-Aurora', 09:56:27 INFO - 'buildbot_json_path': 'buildprops.json', 09:56:27 INFO - 'config_files': ('talos/linux_config.py',), 09:56:27 INFO - 'default_actions': ('clobber', 09:56:27 INFO - 'read-buildbot-config', 09:56:27 INFO - 'download-and-extract', 09:56:27 INFO - 'populate-webroot', 09:56:27 INFO - 'create-virtualenv', 09:56:27 INFO - 'install', 09:56:27 INFO - 'run-tests'), 09:56:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:56:27 INFO - 'download_minidump_stackwalk': True, 09:56:27 INFO - 'download_symbols': 'ondemand', 09:56:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:56:27 INFO - 'tooltool.py': '/tools/tooltool.py', 09:56:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:56:27 INFO - '/tools/misc-python/virtualenv.py')}, 09:56:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:56:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:56:27 INFO - 'installer_path': 'installer.exe', 09:56:27 INFO - 'log_level': 'info', 09:56:27 INFO - 'log_name': 'talos', 09:56:27 INFO - 'log_to_console': True, 09:56:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:56:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:56:27 INFO - 'opt_config_files': (), 09:56:27 INFO - 'pip_index': False, 09:56:27 INFO - 'sps_profile': False, 09:56:27 INFO - 'sps_profile_interval': 0, 09:56:27 INFO - 'suite': 'dromaeojs', 09:56:27 INFO - 'system_bits': '32', 09:56:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:56:27 INFO - 'title': 'talos-linux64-ix-057', 09:56:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:56:27 INFO - 'use_talos_json': True, 09:56:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:56:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:56:27 INFO - 'work_dir': 'build'} 09:56:27 INFO - [mozharness: 2016-09-19 16:56:27.289140Z] Running clobber step. 09:56:27 INFO - Running pre-action listener: _resource_record_pre_action 09:56:27 INFO - Running main action method: clobber 09:56:27 INFO - rmtree: /builds/slave/test/build 09:56:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:56:27 INFO - Running post-action listener: _resource_record_post_action 09:56:27 INFO - [mozharness: 2016-09-19 16:56:27.806607Z] Finished clobber step (success) 09:56:27 INFO - [mozharness: 2016-09-19 16:56:27.806709Z] Running read-buildbot-config step. 09:56:27 INFO - Running pre-action listener: _resource_record_pre_action 09:56:27 INFO - Running main action method: read_buildbot_config 09:56:27 INFO - Using buildbot properties: 09:56:27 INFO - { 09:56:27 INFO - "project": "", 09:56:27 INFO - "product": "firefox", 09:56:27 INFO - "script_repo_revision": "production", 09:56:27 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:56:27 INFO - "repository": "", 09:56:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:56:27 INFO - "buildid": "20160919065229", 09:56:27 INFO - "pgo_build": "True", 09:56:27 INFO - "basedir": "/builds/slave/test", 09:56:27 INFO - "buildnumber": 758, 09:56:27 INFO - "slavename": "talos-linux64-ix-057", 09:56:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:56:27 INFO - "platform": "ubuntu64_hw", 09:56:27 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:56:27 INFO - "branch": "mozilla-aurora", 09:56:27 INFO - "repo_path": "releases/mozilla-aurora", 09:56:27 INFO - "revision": "5dddbefdf759f09b1411f33fa0920835b919fc81", 09:56:27 INFO - "stage_platform": "linux64-pgo", 09:56:27 INFO - "builduid": "545acaaf810d4e38b5646a38601b9d03", 09:56:27 INFO - "slavebuilddir": "test" 09:56:27 INFO - } 09:56:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:56:27 INFO - Running post-action listener: _resource_record_post_action 09:56:27 INFO - [mozharness: 2016-09-19 16:56:27.809173Z] Finished read-buildbot-config step (success) 09:56:27 INFO - [mozharness: 2016-09-19 16:56:27.809264Z] Running download-and-extract step. 09:56:27 INFO - Running pre-action listener: _resource_record_pre_action 09:56:27 INFO - Running main action method: download_and_extract 09:56:27 INFO - mkdir: /builds/slave/test/build/tests 09:56:27 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')]} 09:56:27 INFO - trying https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:56:27 INFO - Downloading https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/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 09:56:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/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 09:56:28 INFO - Downloaded 1361 bytes. 09:56:28 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:56:28 INFO - Using the following test package requirements: 09:56:28 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:56:28 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:56:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:56:28 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:56:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:56:28 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:56:28 INFO - u'jsshell-linux-x86_64.zip'], 09:56:28 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:56:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:56:28 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:56:28 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:56:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:56:28 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:56:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:56:28 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:56:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:56:28 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:56:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:56:28 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:56:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:56:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:56:28 INFO - Fetch https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:56:28 INFO - Expected file size: 21892455 09:56:33 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 09:56:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:56:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:56:33 INFO - Fetch https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:56:33 INFO - Expected file size: 21892455 09:56:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:56:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:56:38 INFO - Fetch https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:56:39 INFO - Expected file size: 14414059 09:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:39 INFO - trying https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:56:39 INFO - Downloading https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/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 09:56:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/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 09:56:40 INFO - Downloaded 63968570 bytes. 09:56:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:56:40 INFO - mkdir: /builds/slave/test/properties 09:56:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:56:40 INFO - Writing to file /builds/slave/test/properties/build_url 09:56:40 INFO - Contents: 09:56:40 INFO - build_url:https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:56:41 INFO - Running post-action listener: _resource_record_post_action 09:56:41 INFO - Running post-action listener: set_extra_try_arguments 09:56:41 INFO - [mozharness: 2016-09-19 16:56:41.331251Z] Finished download-and-extract step (success) 09:56:41 INFO - [mozharness: 2016-09-19 16:56:41.331481Z] Running populate-webroot step. 09:56:41 INFO - Running pre-action listener: _resource_record_pre_action 09:56:41 INFO - Running main action method: populate_webroot 09:56:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:56:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:56:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:56:41 INFO - '--tppagecycles', 09:56:41 INFO - '7'], 09:56:41 INFO - 'tests': ['tsvgm']}}, 09:56:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:56:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:56:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:56:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:56:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:41 INFO - 'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:41 INFO - 'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['damp', 'tps']}, 09:56:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:41 INFO - 'tests': ['damp', 'tps']}, 09:56:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['dromaeo_dom']}, 09:56:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:56:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['basic_compositor_video']}, 09:56:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:56:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['a11yr', 09:56:41 INFO - 'ts_paint', 09:56:41 INFO - 'tpaint', 09:56:41 INFO - 'sessionrestore', 09:56:41 INFO - 'sessionrestore_no_auto_restore', 09:56:41 INFO - 'tabpaint']}, 09:56:41 INFO - 'other-e10s': {'tests': ['a11yr', 09:56:41 INFO - 'ts_paint', 09:56:41 INFO - 'tpaint', 09:56:41 INFO - 'sessionrestore', 09:56:41 INFO - 'sessionrestore_no_auto_restore', 09:56:41 INFO - 'tabpaint']}, 09:56:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:56:41 INFO - 'ts_paint', 09:56:41 INFO - 'tpaint', 09:56:41 INFO - 'sessionrestore', 09:56:41 INFO - 'sessionrestore_no_auto_restore', 09:56:41 INFO - 'tabpaint']}, 09:56:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:56:41 INFO - 'ts_paint', 09:56:41 INFO - 'tpaint', 09:56:41 INFO - 'sessionrestore', 09:56:41 INFO - 'sessionrestore_no_auto_restore', 09:56:41 INFO - 'tabpaint']}, 09:56:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['a11yr', 09:56:41 INFO - 'ts_paint', 09:56:41 INFO - 'tpaint', 09:56:41 INFO - 'sessionrestore', 09:56:41 INFO - 'sessionrestore_no_auto_restore', 09:56:41 INFO - 'tabpaint']}, 09:56:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['a11yr', 09:56:41 INFO - 'ts_paint', 09:56:41 INFO - 'tpaint', 09:56:41 INFO - 'sessionrestore', 09:56:41 INFO - 'sessionrestore_no_auto_restore', 09:56:41 INFO - 'tabpaint']}, 09:56:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['tsvgx', 09:56:41 INFO - 'tsvgr_opacity', 09:56:41 INFO - 'tart', 09:56:41 INFO - 'tscrollx', 09:56:41 INFO - 'cart']}, 09:56:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:56:41 INFO - 'tsvgr_opacity', 09:56:41 INFO - 'tart', 09:56:41 INFO - 'tscrollx', 09:56:41 INFO - 'cart']}, 09:56:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:41 INFO - 'talos_options': ['--disable-e10s'], 09:56:41 INFO - 'tests': ['tp5o']}, 09:56:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:41 INFO - 'tests': ['tp5o']}, 09:56:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:41 INFO - 'talos_options': ['--disable-e10s', 09:56:41 INFO - '--xperf_path', 09:56:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:41 INFO - 'tests': ['tp5n']}, 09:56:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:41 INFO - 'talos_options': ['--xperf_path', 09:56:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:41 INFO - 'tests': ['tp5n']}}, 09:56:41 INFO - 'talos.zip': {'path': '', 09:56:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:56:41 INFO - Running post-action listener: _resource_record_post_action 09:56:41 INFO - [mozharness: 2016-09-19 16:56:41.350209Z] Finished populate-webroot step (success) 09:56:41 INFO - [mozharness: 2016-09-19 16:56:41.350298Z] Running create-virtualenv step. 09:56:41 INFO - Running pre-action listener: _resource_record_pre_action 09:56:41 INFO - Running main action method: create_virtualenv 09:56:41 INFO - Creating virtualenv /builds/slave/test/build/venv 09:56:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:56:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:56:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:56:41 INFO - Using real prefix '/usr' 09:56:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:56:41 INFO - Installing distribute.............................................................................................................................................................................................done. 09:56:43 INFO - Installing pip.................done. 09:56:43 INFO - Return code: 0 09:56:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:56:43 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')]} 09:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:43 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')]} 09:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:43 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 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:56:43 INFO - Using env: {'DISPLAY': ':0', 09:56:43 INFO - 'HOME': '/home/cltbld', 09:56:43 INFO - 'LANG': 'en_US.UTF-8', 09:56:43 INFO - 'LANGUAGE': 'en_US:en', 09:56:43 INFO - 'LOGNAME': 'cltbld', 09:56:43 INFO - 'MAIL': '/var/mail/cltbld', 09:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:43 INFO - 'MOZ_NO_REMOTE': '1', 09:56:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:43 INFO - 'NO_EM_RESTART': '1', 09:56:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:43 INFO - 'PWD': '/builds/slave/test', 09:56:43 INFO - 'SHELL': '/bin/bash', 09:56:43 INFO - 'SHLVL': '1', 09:56:43 INFO - 'TERM': 'linux', 09:56:43 INFO - 'TMOUT': '86400', 09:56:43 INFO - 'USER': 'cltbld', 09:56:43 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:43 INFO - '_': '/tools/buildbot/bin/python'} 09:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:56:44 INFO - Cleaning up... 09:56:44 INFO - Return code: 0 09:56:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:56:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:56:44 INFO - Using env: {'DISPLAY': ':0', 09:56:44 INFO - 'HOME': '/home/cltbld', 09:56:44 INFO - 'LANG': 'en_US.UTF-8', 09:56:44 INFO - 'LANGUAGE': 'en_US:en', 09:56:44 INFO - 'LOGNAME': 'cltbld', 09:56:44 INFO - 'MAIL': '/var/mail/cltbld', 09:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:44 INFO - 'MOZ_NO_REMOTE': '1', 09:56:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:44 INFO - 'NO_EM_RESTART': '1', 09:56:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:44 INFO - 'PWD': '/builds/slave/test', 09:56:44 INFO - 'SHELL': '/bin/bash', 09:56:44 INFO - 'SHLVL': '1', 09:56:44 INFO - 'TERM': 'linux', 09:56:44 INFO - 'TMOUT': '86400', 09:56:44 INFO - 'USER': 'cltbld', 09:56:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:44 INFO - '_': '/tools/buildbot/bin/python'} 09:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:44 INFO - Downloading/unpacking psutil>=3.1.1 09:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:56:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:46 INFO - Installing collected packages: psutil 09:56:46 INFO - Running setup.py install for psutil 09:56:46 INFO - building 'psutil._psutil_linux' extension 09:56:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:56:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:56:46 INFO - building 'psutil._psutil_posix' extension 09:56:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:56:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:56:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:46 INFO - Successfully installed psutil 09:56:46 INFO - Cleaning up... 09:56:46 INFO - Return code: 0 09:56:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:56:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:56:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:56:46 INFO - Using env: {'DISPLAY': ':0', 09:56:46 INFO - 'HOME': '/home/cltbld', 09:56:46 INFO - 'LANG': 'en_US.UTF-8', 09:56:46 INFO - 'LANGUAGE': 'en_US:en', 09:56:46 INFO - 'LOGNAME': 'cltbld', 09:56:46 INFO - 'MAIL': '/var/mail/cltbld', 09:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:46 INFO - 'MOZ_NO_REMOTE': '1', 09:56:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:46 INFO - 'NO_EM_RESTART': '1', 09:56:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:46 INFO - 'PWD': '/builds/slave/test', 09:56:46 INFO - 'SHELL': '/bin/bash', 09:56:46 INFO - 'SHLVL': '1', 09:56:46 INFO - 'TERM': 'linux', 09:56:46 INFO - 'TMOUT': '86400', 09:56:46 INFO - 'USER': 'cltbld', 09:56:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:46 INFO - '_': '/tools/buildbot/bin/python'} 09:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:56:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:56:49 INFO - Installing collected packages: mozsystemmonitor 09:56:49 INFO - Running setup.py install for mozsystemmonitor 09:56:49 INFO - Successfully installed mozsystemmonitor 09:56:49 INFO - Cleaning up... 09:56:49 INFO - Return code: 0 09:56:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:56:49 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')]} 09:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:49 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')]} 09:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:49 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 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:56:49 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 09:56:49 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 09:56:49 INFO - Using env: {'DISPLAY': ':0', 09:56:49 INFO - 'HOME': '/home/cltbld', 09:56:49 INFO - 'LANG': 'en_US.UTF-8', 09:56:49 INFO - 'LANGUAGE': 'en_US:en', 09:56:49 INFO - 'LOGNAME': 'cltbld', 09:56:49 INFO - 'MAIL': '/var/mail/cltbld', 09:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:49 INFO - 'MOZ_NO_REMOTE': '1', 09:56:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:49 INFO - 'NO_EM_RESTART': '1', 09:56:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:49 INFO - 'PWD': '/builds/slave/test', 09:56:49 INFO - 'SHELL': '/bin/bash', 09:56:49 INFO - 'SHLVL': '1', 09:56:49 INFO - 'TERM': 'linux', 09:56:49 INFO - 'TMOUT': '86400', 09:56:49 INFO - 'USER': 'cltbld', 09:56:49 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:49 INFO - '_': '/tools/buildbot/bin/python'} 09:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:49 INFO - Downloading/unpacking jsonschema==2.5.1 09:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:56:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:56:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:56:51 INFO - warning: no files found matching '*.txt' 09:56:51 INFO - no previously-included directories found matching 'build' 09:56:51 INFO - no previously-included directories found matching 'dist' 09:56:51 INFO - no previously-included directories found matching '.git*' 09:56:51 INFO - Installing collected packages: jsonschema, functools32 09:56:51 INFO - Running setup.py install for functools32 09:56:51 INFO - warning: no files found matching '*.txt' 09:56:51 INFO - no previously-included directories found matching 'build' 09:56:51 INFO - no previously-included directories found matching 'dist' 09:56:51 INFO - no previously-included directories found matching '.git*' 09:56:51 INFO - Successfully installed jsonschema functools32 09:56:51 INFO - Cleaning up... 09:56:51 INFO - Return code: 0 09:56:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:51 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 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:56:51 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 09:56:51 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 09:56:51 INFO - Using env: {'DISPLAY': ':0', 09:56:51 INFO - 'HOME': '/home/cltbld', 09:56:51 INFO - 'LANG': 'en_US.UTF-8', 09:56:51 INFO - 'LANGUAGE': 'en_US:en', 09:56:51 INFO - 'LOGNAME': 'cltbld', 09:56:51 INFO - 'MAIL': '/var/mail/cltbld', 09:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:51 INFO - 'MOZ_NO_REMOTE': '1', 09:56:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:51 INFO - 'NO_EM_RESTART': '1', 09:56:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:51 INFO - 'PWD': '/builds/slave/test', 09:56:51 INFO - 'SHELL': '/bin/bash', 09:56:51 INFO - 'SHLVL': '1', 09:56:51 INFO - 'TERM': 'linux', 09:56:51 INFO - 'TMOUT': '86400', 09:56:51 INFO - 'USER': 'cltbld', 09:56:51 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:51 INFO - '_': '/tools/buildbot/bin/python'} 09:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:56:51 INFO - Cleaning up... 09:56:52 INFO - Return code: 0 09:56:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:56:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:56:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:56:52 INFO - Using env: {'DISPLAY': ':0', 09:56:52 INFO - 'HOME': '/home/cltbld', 09:56:52 INFO - 'LANG': 'en_US.UTF-8', 09:56:52 INFO - 'LANGUAGE': 'en_US:en', 09:56:52 INFO - 'LOGNAME': 'cltbld', 09:56:52 INFO - 'MAIL': '/var/mail/cltbld', 09:56:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:52 INFO - 'MOZ_NO_REMOTE': '1', 09:56:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:52 INFO - 'NO_EM_RESTART': '1', 09:56:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:52 INFO - 'PWD': '/builds/slave/test', 09:56:52 INFO - 'SHELL': '/bin/bash', 09:56:52 INFO - 'SHLVL': '1', 09:56:52 INFO - 'TERM': 'linux', 09:56:52 INFO - 'TMOUT': '86400', 09:56:52 INFO - 'USER': 'cltbld', 09:56:52 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:52 INFO - '_': '/tools/buildbot/bin/python'} 09:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:56:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:56:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:56:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:54 INFO - Downloading docopt-0.6.1.tar.gz 09:56:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:56:54 INFO - Installing collected packages: blobuploader, requests, docopt 09:56:54 INFO - Running setup.py install for blobuploader 09:56:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:56:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:56:54 INFO - Running setup.py install for requests 09:56:55 INFO - Running setup.py install for docopt 09:56:55 INFO - Successfully installed blobuploader requests docopt 09:56:55 INFO - Cleaning up... 09:56:55 INFO - Return code: 0 09:56:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:55 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 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:56:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:56:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:56:55 INFO - Using env: {'DISPLAY': ':0', 09:56:55 INFO - 'HOME': '/home/cltbld', 09:56:55 INFO - 'LANG': 'en_US.UTF-8', 09:56:55 INFO - 'LANGUAGE': 'en_US:en', 09:56:55 INFO - 'LOGNAME': 'cltbld', 09:56:55 INFO - 'MAIL': '/var/mail/cltbld', 09:56:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:55 INFO - 'MOZ_NO_REMOTE': '1', 09:56:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:55 INFO - 'NO_EM_RESTART': '1', 09:56:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:55 INFO - 'PWD': '/builds/slave/test', 09:56:55 INFO - 'SHELL': '/bin/bash', 09:56:55 INFO - 'SHLVL': '1', 09:56:55 INFO - 'TERM': 'linux', 09:56:55 INFO - 'TMOUT': '86400', 09:56:55 INFO - 'USER': 'cltbld', 09:56:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:56:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:55 INFO - '_': '/tools/buildbot/bin/python'} 09:56:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:55 INFO - Running setup.py (path:/tmp/pip-JWIAA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:55 INFO - Running setup.py (path:/tmp/pip-QLkbLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:55 INFO - Running setup.py (path:/tmp/pip-Kf11cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:55 INFO - Running setup.py (path:/tmp/pip-ZPpdSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:55 INFO - Running setup.py (path:/tmp/pip-iMxu0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:56 INFO - Running setup.py (path:/tmp/pip-7ZOmVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:56 INFO - Running setup.py (path:/tmp/pip-aSME4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:56 INFO - Running setup.py (path:/tmp/pip-v5P_O9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:56 INFO - Running setup.py (path:/tmp/pip-r29cNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:56 INFO - Running setup.py (path:/tmp/pip-amGwZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:56 INFO - Running setup.py (path:/tmp/pip-KXdjLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:56 INFO - Running setup.py (path:/tmp/pip-YCcRyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:56 INFO - Running setup.py (path:/tmp/pip-lsafUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:56 INFO - Running setup.py (path:/tmp/pip-Kb0SGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:56 INFO - Running setup.py (path:/tmp/pip-BbBfFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:56 INFO - Running setup.py (path:/tmp/pip-Qxxf3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:56 INFO - Running setup.py (path:/tmp/pip-51yeoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:56 INFO - Running setup.py install for manifestparser 09:56:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:56:56 INFO - Running setup.py install for mozcrash 09:56:57 INFO - Running setup.py install for mozdebug 09:56:57 INFO - Running setup.py install for mozdevice 09:56:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Running setup.py install for mozfile 09:56:57 INFO - Running setup.py install for mozhttpd 09:56:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Running setup.py install for mozinfo 09:56:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Running setup.py install for mozInstall 09:56:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Running setup.py install for mozleak 09:56:57 INFO - Running setup.py install for mozlog 09:56:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:56:57 INFO - Running setup.py install for moznetwork 09:56:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:56:58 INFO - Running setup.py install for mozprocess 09:56:58 INFO - Running setup.py install for mozprofile 09:56:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:56:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:56:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:56:58 INFO - Running setup.py install for mozrunner 09:56:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:56:58 INFO - Running setup.py install for mozscreenshot 09:56:58 INFO - Running setup.py install for moztest 09:56:58 INFO - Running setup.py install for mozversion 09:56:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:56:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:58 INFO - Cleaning up... 09:56:58 INFO - Return code: 0 09:56:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:58 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 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:56:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:56:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:56:58 INFO - Using env: {'DISPLAY': ':0', 09:56:58 INFO - 'HOME': '/home/cltbld', 09:56:58 INFO - 'LANG': 'en_US.UTF-8', 09:56:58 INFO - 'LANGUAGE': 'en_US:en', 09:56:58 INFO - 'LOGNAME': 'cltbld', 09:56:58 INFO - 'MAIL': '/var/mail/cltbld', 09:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:58 INFO - 'MOZ_NO_REMOTE': '1', 09:56:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:58 INFO - 'NO_EM_RESTART': '1', 09:56:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:58 INFO - 'PWD': '/builds/slave/test', 09:56:58 INFO - 'SHELL': '/bin/bash', 09:56:58 INFO - 'SHLVL': '1', 09:56:58 INFO - 'TERM': 'linux', 09:56:58 INFO - 'TMOUT': '86400', 09:56:58 INFO - 'USER': 'cltbld', 09:56:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:58 INFO - '_': '/tools/buildbot/bin/python'} 09:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:58 INFO - Running setup.py (path:/tmp/pip-xDRDww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:58 INFO - Running setup.py (path:/tmp/pip-IJpr07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:59 INFO - Running setup.py (path:/tmp/pip-AAMuaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:59 INFO - Running setup.py (path:/tmp/pip-3FbG_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:59 INFO - Running setup.py (path:/tmp/pip-llDTie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:59 INFO - Running setup.py (path:/tmp/pip-ZIvEEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:59 INFO - Running setup.py (path:/tmp/pip-Qe0zH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:59 INFO - Running setup.py (path:/tmp/pip-TR3iTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:59 INFO - Running setup.py (path:/tmp/pip-gDYf3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:59 INFO - Running setup.py (path:/tmp/pip-uEaREI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:59 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)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:59 INFO - Running setup.py (path:/tmp/pip-7GQnX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:59 INFO - Running setup.py (path:/tmp/pip-eJD6cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:59 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)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:59 INFO - Running setup.py (path:/tmp/pip-Wdd7no-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:59 INFO - Running setup.py (path:/tmp/pip-mcGVXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:59 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)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:59 INFO - Running setup.py (path:/tmp/pip-hbEY5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:59 INFO - Running setup.py (path:/tmp/pip-O6EGDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:57:00 INFO - Running setup.py (path:/tmp/pip-K93en_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:57:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:02 INFO - Downloading blessings-1.6.tar.gz 09:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:57:02 INFO - Installing collected packages: blessings 09:57:02 INFO - Running setup.py install for blessings 09:57:02 INFO - Successfully installed blessings 09:57:02 INFO - Cleaning up... 09:57:02 INFO - Return code: 0 09:57:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:57:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:57:02 INFO - Reading from file tmpfile_stdout 09:57:02 INFO - Current package versions: 09:57:02 INFO - argparse == 1.2.1 09:57:02 INFO - blessings == 1.6 09:57:02 INFO - blobuploader == 1.2.4 09:57:02 INFO - docopt == 0.6.1 09:57:02 INFO - functools32 == 3.2.3-2 09:57:02 INFO - jsonschema == 2.5.1 09:57:02 INFO - manifestparser == 1.1 09:57:02 INFO - mozInstall == 1.12 09:57:02 INFO - mozcrash == 0.17 09:57:02 INFO - mozdebug == 0.1 09:57:02 INFO - mozdevice == 0.48 09:57:02 INFO - mozfile == 1.2 09:57:02 INFO - mozhttpd == 0.7 09:57:02 INFO - mozinfo == 0.9 09:57:02 INFO - mozleak == 0.1 09:57:02 INFO - mozlog == 3.4 09:57:02 INFO - moznetwork == 0.27 09:57:02 INFO - mozprocess == 0.23 09:57:02 INFO - mozprofile == 0.28 09:57:02 INFO - mozrunner == 6.12 09:57:02 INFO - mozscreenshot == 0.1 09:57:02 INFO - mozsystemmonitor == 0.3 09:57:02 INFO - moztest == 0.7 09:57:02 INFO - mozversion == 1.4 09:57:02 INFO - psutil == 3.1.1 09:57:02 INFO - requests == 1.2.3 09:57:02 INFO - wsgiref == 0.1.2 09:57:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:02 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 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:57:02 INFO - Using env: {'DISPLAY': ':0', 09:57:02 INFO - 'HOME': '/home/cltbld', 09:57:02 INFO - 'LANG': 'en_US.UTF-8', 09:57:02 INFO - 'LANGUAGE': 'en_US:en', 09:57:02 INFO - 'LOGNAME': 'cltbld', 09:57:02 INFO - 'MAIL': '/var/mail/cltbld', 09:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:02 INFO - 'MOZ_NO_REMOTE': '1', 09:57:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:02 INFO - 'NO_EM_RESTART': '1', 09:57:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:02 INFO - 'PWD': '/builds/slave/test', 09:57:02 INFO - 'SHELL': '/bin/bash', 09:57:02 INFO - 'SHLVL': '1', 09:57:02 INFO - 'TERM': 'linux', 09:57:02 INFO - 'TMOUT': '86400', 09:57:02 INFO - 'USER': 'cltbld', 09:57:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:02 INFO - '_': '/tools/buildbot/bin/python'} 09:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:57:02 INFO - Cleaning up... 09:57:02 INFO - Return code: 0 09:57:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3fb2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2699ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:57:02 INFO - Using env: {'DISPLAY': ':0', 09:57:02 INFO - 'HOME': '/home/cltbld', 09:57:02 INFO - 'LANG': 'en_US.UTF-8', 09:57:02 INFO - 'LANGUAGE': 'en_US:en', 09:57:02 INFO - 'LOGNAME': 'cltbld', 09:57:02 INFO - 'MAIL': '/var/mail/cltbld', 09:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:02 INFO - 'MOZ_NO_REMOTE': '1', 09:57:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:02 INFO - 'NO_EM_RESTART': '1', 09:57:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:02 INFO - 'PWD': '/builds/slave/test', 09:57:02 INFO - 'SHELL': '/bin/bash', 09:57:02 INFO - 'SHLVL': '1', 09:57:02 INFO - 'TERM': 'linux', 09:57:02 INFO - 'TMOUT': '86400', 09:57:02 INFO - 'USER': 'cltbld', 09:57:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:02 INFO - '_': '/tools/buildbot/bin/python'} 09:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:57:02 INFO - Cleaning up... 09:57:02 INFO - Return code: 0 09:57:02 INFO - Running post-action listener: _resource_record_post_action 09:57:02 INFO - Running post-action listener: _start_resource_monitoring 09:57:02 INFO - Starting resource monitoring. 09:57:02 INFO - [mozharness: 2016-09-19 16:57:02.981621Z] Finished create-virtualenv step (success) 09:57:02 INFO - [mozharness: 2016-09-19 16:57:02.982079Z] Running install step. 09:57:02 INFO - Running pre-action listener: _resource_record_pre_action 09:57:02 INFO - Running main action method: install 09:57:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:57:03 INFO - Reading from file tmpfile_stdout 09:57:03 INFO - Detecting whether we're running mozinstall >=1.0... 09:57:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:57:03 INFO - Reading from file tmpfile_stdout 09:57:03 INFO - Output received: 09:57:03 INFO - Usage: mozinstall [options] installer 09:57:03 INFO - Options: 09:57:03 INFO - -h, --help show this help message and exit 09:57:03 INFO - -d DEST, --destination=DEST 09:57:03 INFO - Directory to install application into. [default: 09:57:03 INFO - "/builds/slave/test"] 09:57:03 INFO - --app=APP Application being installed. [default: firefox] 09:57:03 INFO - mkdir: /builds/slave/test/build/application 09:57:03 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'] 09:57:03 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 09:57:19 INFO - Reading from file tmpfile_stdout 09:57:19 INFO - Output received: 09:57:19 INFO - /builds/slave/test/build/application/firefox/firefox 09:57:19 INFO - Running post-action listener: _resource_record_post_action 09:57:19 INFO - [mozharness: 2016-09-19 16:57:19.279484Z] Finished install step (success) 09:57:19 INFO - [mozharness: 2016-09-19 16:57:19.279788Z] Running run-tests step. 09:57:19 INFO - Running pre-action listener: _resource_record_pre_action 09:57:19 INFO - Running main action method: run_tests 09:57:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:57:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:57:19 INFO - Python 2.7.3 09:57:19 INFO - Return code: 0 09:57:19 INFO - grabbing minidump binary from tooltool 09:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:19 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 0x2696f60>, '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 0x2697d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26981d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:57:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:57:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:57:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:57:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:57:19 INFO - Return code: 0 09:57:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:57:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:57:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:57:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:57:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:57:19 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/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:57:19 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/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:57:19 INFO - Using env: {'DISPLAY': ':0', 09:57:19 INFO - 'HOME': '/home/cltbld', 09:57:19 INFO - 'LANG': 'en_US.UTF-8', 09:57:19 INFO - 'LANGUAGE': 'en_US:en', 09:57:19 INFO - 'LOGNAME': 'cltbld', 09:57:19 INFO - 'MAIL': '/var/mail/cltbld', 09:57:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:57:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:19 INFO - 'MOZ_NO_REMOTE': '1', 09:57:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:57:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:19 INFO - 'NO_EM_RESTART': '1', 09:57:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:19 INFO - 'PWD': '/builds/slave/test', 09:57:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:57:19 INFO - 'SHELL': '/bin/bash', 09:57:19 INFO - 'SHLVL': '1', 09:57:19 INFO - 'TERM': 'linux', 09:57:19 INFO - 'TMOUT': '86400', 09:57:19 INFO - 'USER': 'cltbld', 09:57:19 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725', 09:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:19 INFO - '_': '/tools/buildbot/bin/python'} 09:57:19 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/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:57:19 INFO - mozversion application_buildid: 20160919065229 09:57:19 INFO - mozversion application_changeset: 5dddbefdf759f09b1411f33fa0920835b919fc81 09:57:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:57:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:57:19 INFO - mozversion application_name: Firefox 09:57:19 INFO - mozversion application_remotingname: firefox-dev 09:57:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:57:19 INFO - mozversion application_vendor: Mozilla 09:57:19 INFO - mozversion application_version: 51.0a2 09:57:19 INFO - mozversion platform_buildid: 20160919065229 09:57:19 INFO - mozversion platform_changeset: 5dddbefdf759f09b1411f33fa0920835b919fc81 09:57:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:57:19 INFO - mozversion platform_version: 51.0a2 09:57:19 INFO - using testdate: 1474304239 09:57:19 INFO - actual date: 1474304239 09:57:19 INFO - starting webserver on 'localhost:51585' 09:57:19 INFO - SUITE-START | Running 2 tests 09:57:19 INFO - TEST-START | dromaeo_css 09:57:19 INFO - operating with platform_type : linux_ 09:57:19 INFO - Initialising browser for dromaeo_css test... 09:57:19 INFO - TEST-INFO | started process 15267 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiLSNhp/profile http://localhost:51585/getInfo.html) 09:57:23 INFO - PROCESS | 15267 | __metrics Screen width/height:1600/1200 09:57:23 INFO - PROCESS | 15267 | colorDepth:24 09:57:23 INFO - PROCESS | 15267 | Browser inner width/height: 1024/697 09:57:23 INFO - PROCESS | 15267 | __metrics 09:57:24 INFO - PROCESS | 15267 | 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" 09:57:24 INFO - PROCESS | 15267 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:24 INFO - PROCESS | 15267 | console.error: PushService: 09:57:24 INFO - PROCESS | 15267 | stateChangeProcessEnqueue: Error transitioning state 09:57:24 INFO - PROCESS | 15267 | UnknownError 09:57:25 INFO - TEST-INFO | 15267: exit 0 09:57:25 INFO - Browser initialized. 09:57:25 INFO - Running cycle 1/1 for dromaeo_css test... 09:57:25 INFO - TEST-INFO | started process 15358 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiLSNhp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:37 INFO - PROCESS | 15358 | RSS: Main: 144285696 09:57:37 INFO - PROCESS | 15358 | 09:58:26 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.03307 seconds. 09:58:28 INFO - PROCESS | 15358 | 1474304308271 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:58:31 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01685 seconds. 09:58:35 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.03308 seconds. 09:58:49 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 09:58:51 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 09:59:06 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01632 seconds. 09:59:10 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.16675 seconds. 09:59:32 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.0328 seconds. 09:59:36 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 09:59:38 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01646 seconds. 10:00:06 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 10:00:23 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01689 seconds. 10:00:27 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.78328 seconds. 10:00:30 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01696 seconds. 10:00:33 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 10:00:37 INFO - PROCESS | 15358 | Cycle 1(1): loaded http://localhost:51585/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51585/tests/dromaeo/cssquery-ext.html) 10:00:38 INFO - PROCESS | 15358 | RSS: Main: 242454528 10:00:38 INFO - PROCESS | 15358 | 10:00:41 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 10:01:03 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 10:02:22 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 10:02:34 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01599 seconds. 10:03:03 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01689 seconds. 10:03:05 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01601 seconds. 10:03:10 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00082 seconds. 10:03:20 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 10:03:36 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 10:03:40 INFO - PROCESS | 15358 | Cycle 1(1): loaded http://localhost:51585/tests/dromaeo/cssquery-ext.html (next: http://localhost:51585/tests/dromaeo/cssquery-jquery.html) 10:03:41 INFO - PROCESS | 15358 | RSS: Main: 225517568 10:03:41 INFO - PROCESS | 15358 | 10:04:18 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00067 seconds. 10:04:30 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00074 seconds. 10:04:42 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00028 seconds. 10:04:46 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01718 seconds. 10:05:02 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00057 seconds. 10:05:20 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01692 seconds. 10:05:22 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.0165 seconds. 10:05:24 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00058 seconds. 10:05:37 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00059 seconds. 10:06:03 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 10:06:12 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:06:29 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:06:43 INFO - PROCESS | 15358 | Cycle 1(1): loaded http://localhost:51585/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51585/tests/dromaeo/cssquery-mootools.html) 10:06:44 INFO - PROCESS | 15358 | RSS: Main: 173309952 10:06:44 INFO - PROCESS | 15358 | 10:06:47 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.03277 seconds. 10:06:55 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01585 seconds. 10:07:04 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 10:07:31 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01597 seconds. 10:07:33 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01643 seconds. 10:08:15 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00042 seconds. 10:08:25 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 10:08:35 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 10:09:13 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 10:09:30 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00026 seconds. 10:09:34 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 10:09:46 INFO - PROCESS | 15358 | Cycle 1(1): loaded http://localhost:51585/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51585/tests/dromaeo/cssquery-prototype.html) 10:09:46 INFO - PROCESS | 15358 | RSS: Main: 176496640 10:09:46 INFO - PROCESS | 15358 | 10:09:50 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.03328 seconds. 10:09:53 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.03267 seconds. 10:10:04 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.03332 seconds. 10:10:09 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 10:10:11 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 10:10:13 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00057 seconds. 10:10:17 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 10:10:31 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 10:10:39 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 10:11:22 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01728 seconds. 10:11:26 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 10:11:32 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01651 seconds. 10:11:36 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00058 seconds. 10:11:44 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 10:11:46 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01651 seconds. 10:11:48 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01685 seconds. 10:12:00 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00059 seconds. 10:12:26 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.0173 seconds. 10:12:29 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01728 seconds. 10:12:35 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00058 seconds. 10:12:49 INFO - PROCESS | 15358 | Cycle 1(1): loaded http://localhost:51585/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51585/tests/dromaeo/cssquery-yui.html) 10:12:49 INFO - PROCESS | 15358 | RSS: Main: 173953024 10:12:49 INFO - PROCESS | 15358 | 10:12:56 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 10:13:00 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00058 seconds. 10:13:07 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.0336 seconds. 10:13:20 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:13:24 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 10:13:28 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00071 seconds. 10:13:38 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00059 seconds. 10:13:55 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 10:14:01 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 10:14:13 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:14:15 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 10:14:25 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 10:14:28 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01685 seconds. 10:14:37 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 10:14:43 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 10:15:21 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 10:15:34 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.03325 seconds. 10:15:38 INFO - PROCESS | 15358 | [GFX1-]: Refresh driver waiting for the compositor for 1.01712 seconds. 10:15:52 INFO - PROCESS | 15358 | Cycle 1(1): loaded http://localhost:51585/tests/dromaeo/cssquery-yui.html (next: http://localhost:51585/tests/dromaeo/cssquery-dojo.html) 10:15:52 INFO - PROCESS | 15358 | RSS: Main: 258924544 10:15:52 INFO - PROCESS | 15358 | 10:15:52 INFO - PROCESS | 15358 | __start_tp_report 10:15:52 INFO - PROCESS | 15358 | _x_x_mozilla_page_load 10:15:52 INFO - PROCESS | 15358 | _x_x_mozilla_page_load_details 10:15:52 INFO - PROCESS | 15358 | |i|pagename|runs| 10:15:52 INFO - PROCESS | 15358 | |0;dojo.html;3061.8315992620405;3093.4122516721823;3097.039917625535;3114.704103110204;3140.1207661854673;5484.628842789302;5552.500274975251;5623.606347555673;5627.380988091307;5658.519025882805;5672.602917795745;5730.598858079936;5739.3399759027725;5739.885202295962;5768.971155144219;5144.022635699224;5174.223866420049;5190.455002224769;5217.86955326118;5247.711375874325;5039.118154322997;5247.763850626731;5255.369355677324;5264.6051546134;5265.183896496049;5025.899482010358;5112.718800465973;5114.667546609458;5140.203268493389;5162.277281180638;5127.564157046656;5163.173892177234;5163.612729045337;5224.973875130638;5232.92150617741;5101.346823076173;5128.538431541142;5195.9480405195845;5208.973955130238;5238.13570760824;5411.891762164774;5449.155380915925;5481.424450432687;5567.053600887859;5590.8043218487155;5879.500242479395;5924.763009479616;5926.674032928187;5943.821685349447;6006.8498287542725;6035.275766907956;6049.516038716892;6054.939450605481;6064.211652485193;6064.302605200414;5500.22996780451;5529.806456774034;5536.833894983117;5576.358718747396;5580.051391263495;6129;6166.7224974876235;6168.907466388007;6170.382961703884;6171.907421388682;5915.852103697442;5917.112433134978;5920.467157955719;5924;5928.881422371528;5892.116182572563;5927.644341339534;5939.881202375928;5963.79126730571;5964.403559644001;5998.760049598054;6002.729877155539;6011.729472173763;6016.759329626766;6034.2155519782145;5934.643921364732;5989.071693887455;6007.789727359609;6022.277326720821;6035;4529.479109902338;4562.133194693064;4576.931346029813;4604.355390245369;4625.121226966866;4078.2455245779624;4114.362273847559;4128.690348223895;4135.81388837497;4152.43942067817;5663.6035477517;5667.603267771299;5671.801486947937;5672.177534257509;5674.801381951612;5928.170056192136;5985.102234664835;6014.639121652627;6131.325554189036;6139.907901381483;5674.687892165971;5721.141828725725;5732.484076433141;5770.567207459458;5773.24947756789;5111.565516931102;5121.231815227746;5121.334226550524;5128.948710512847;5137.152369858932;5965.045592705146;6095.481884039729;6237.407446292595;6297.244330680348;6323.747050118036;6006;6012.1582978382185;6061.848453788691;6112.358202388685;6142.170806941006;6267.655278959701;6305.873882522415;6306.0225665022;6310.968445157745;6318.399752023551;5770.826875193751;5991.460768530853;6010.459058684739;6010.519158467399;6013.699315034143;5877.529797616266;5943.019401798655;5978.641281523122;6013.939860601338;6099.329073801967;5845.561582881386;5895.587308888336;5898.4101589840675;5901.232839730807;5903.291605007426;5884.529237660891;5887.293524777054;5891.7643294269465;5892.175095486549;5896.056630939029;5586.86032849182;5708.172315014217;5836.182934389104;5876.911846322394;5899.17411562373;6018.398160183947;6018.8796224074395;6030.668313242813;6033.607815491979;6050.304215015153;5645.4072322405555;5863.648181109147;5896.70516474166;5898.498627616529;5900.970495147497 10:15:52 INFO - PROCESS | 15358 | |1;ext.html;590.2268028882165;605.7183409714484;637.3085202555227;658.9769358072468;667.3560014585923;34095.31809363815;46201.30698039528;46514.30228546575;46697.29954050688;46713.532864671346;46645;47136;47221.05557888849;47336.289955650696;47341;8616.569171541429;9190.172884440415;9332.300077494216;9339.906600933971;9342.813143737121;8590.355723320745;10893.945530272338;11119.110471162288;11159.442027898573;11615.245009074426;11285.379304138269;11357.829632555477;11396.772064558703;11397.145214108934;11413.48639311233;8453.323734101257;8686.522241276727;11275.210735248536;11345;11349.20555561111;8627.266682332009;8676.696315628922;10755.139588832875;11089;11120.61077862279;43880;44283.55716442827;44323;44323.33514997278;44346;3643.7995910224927;3681.668649821502;3703.3519134151684;3703.759255648401;4106.815193316278;33780.49329260063;34634.65365346339;34840.65159348399;35029.949101526996;35064.82467587671;4537.38745269391;4635.142498637762;4660.464046634646;4663.253879379317;4682.438107427097;369222;370014;370519;370566;370852;64332.35667643357;64505.67747161234;64541;64550.35449645537;64663.67668161629;64690;64749.67625161844;64803;64875;64883;15976.600584985468;16370.017798932102;16377.83622163787;16379.099149546651;16389.590260243353;12404.62786116418;12623.936880315723;13028.283444410648;13443.86556134426;13490.258035807929;17481.912590436965;17637.118144092743;17779.555511112067;17859.107044647975;17924.193411296525;6520.47836173105;6534.281229064894;6583.967080164569;6618;6640.634765087966;986.2208855004466;1059.819830628795;1109.0794640448407;1133.2237417368963;1157.1957489544855;33980.66019339775;34268.31463370719;34568.48147277793;34581.308373832384;34602.307953840784;1161.73280145567;1161.8257261410688;1171.1358229489022;1204.512172570093;1219.8353222314875;17845.023753443398;17970;18018.279268829363;18222.544436389195;18960.52598685044;497591;500539;502118;502579;503002;64234.678826606505;64432.35567644357;64461;64464;64473;1326.2838067443665;1335.732853429318;1386.3345594115083;1420.155007770375;1458.4603696632314;32562.348753025282;34476.31047378988;34492.482612761334;34530.136746581535;34818.82590587031;53713.731431342596;54193;54272.18591721048;54287;54295;413230;415358;416527;417143;418073;29919.252018699706;30511;30802.229944251572;31039.534406984367;32362.676373235965;50449.747751261006;50548.49451505585;50734;51223.48776512187;52217.21674174815;41386;41960;45528.54471455243;45599.31601026054;47489.28766068443;41199.58800411958;41572.58427415687;41864.3720344189;42253.57746422497;43963.78018110017;43771.365448057724;44065.559344406145;44517;44626.77686611546;44869;35974.46038309375;38502.22995540129;38774.2245155101;39513.60486395099;39739.2052158961;29484;29782.70217297886;29856.25359366033;29946.401071978875;30379.696203037685 10:15:52 INFO - PROCESS | 15358 | |2;jquery.html;395.17605791923836;397.15406184826315;419.50288907644443;426.92528807458694;440.4428398076434;14405;16343;20430.489237769063;20687.586248275027;21228.681569776465;18197;18716.43850684478;19067.14197861096;21220.681689774665;21437.464063398384;5832.358440571534;5857.765689372431;6035.879282414371;6076.118962750421;6084.574079814415;4065.735727177724;6059.303180134275;6098.603590766608;6099;6102.694865256742;4117.743759371521;4301.161273551643;4439.600435960763;6513.93486065138;6567.967160164217;5905.734241959122;6592.472602191813;6601.537892347537;6614.636195009272;6625.93374066258;5947.762089516415;6071.271447426293;6071.605345652562;6071.908921366183;6072.423119803611;14726.926365368105;14922.552323430315;14977.775333370008;15059.548213553611;15139.772903406458;2401.3996500874605;2563.0772921748157;2593.351662084479;2856.557233628791;2890.638670166229;10771.676849694522;10931.234813563055;11047.226694131414;11310.208285420023;11673.299602023906;1905.3426567834076;1973.1022384814876;2009.6583580791155;2039.1333683184735;2061.597988392249;72425.03324634339;72570.63714681445;75736;75918.24081759166;86166.13833861644;10372.896271037192;11546.82462322868;11935.164538482222;12043.458044387848;12069.396530173457;16854.41009564684;16863.831361686473;16895.915520422564;16900.57748556296;17031.82968170327;8560.14398560139;8729.817545613596;8772.956135219283;8797.604107815036;8833;8272.0487143978;8310.70912518059;8315.168483151636;8464.957675211708;8516.446430981967;5120.897582048338;5223.3209682741;5224.164133738584;5233.528982391603;5314.468553144655;2000.6798912174274;2119.6184686756224;2135.145941623332;2164.8326970581566;2166.837487188467;918.545320066558;1008.369768894441;1025.2993356935463;1051.0645525482328;1094.7099018760034;20055.398338049883;20259.392218233475;20623.69064464034;20806.687899681518;20949.68575471369;977.6969139566758;1003.6386900715737;1005.6228914157191;1010.1817527802503;1087.1846115413439;10354.948225258826;11490.253133546294;11567.537298507965;11615.941920290345;11771.352575608424;546713;548954;549468;551786;555320;94827.52586237024;96282;96817.5159124214;97252.51373743085;106632;534.8716308086032;553.7452771725052;554.5951455437553;558.2770312445393;995.4773743784426;11393.829092563452;11797.410129493663;12213.511459541518;12416.627501174979;12468.314242716737;40488.59511404848;41771.37342939797;42215;42240.36639450349;44501.777491112334;54732.452675472734;54746;55180.724096379265;63330.36669633245;63899.3610063912;8080.636371363185;8131.146229646039;8452.02801677815;8631.39580229403;9036.14156655143;12384.442700078338;12506.499740010298;12560.560380386643;12616.306103164414;12670;10784.514696838818;10854.403007834644;10899.182561308093;11124.221304508606;11446.885531144582;11003.779924401308;11066.557337706401;11221.214514983873;11251.831222531504;11681.474333654836;11842.585509507126;11846.407679615812;11900.226485278776;11939.403029848647;12194.390280485763;8608.526531040852;8727.65089396417;8997.7750556234;9001.549922503718;9080.863787043067;10582.4708764563;11308.49111789955;11454.198206125824;11726;11860 10:15:52 INFO - PROCESS | 15358 | |3;mootools.html;1491.3587157522265;1523.0100434717433;1669.874759393045;1791.1492041280385;1808.511701840504;8960.999999999993;9682.12860842524;9692.079252471021;9701.07839755223;9720.61117555297;8116.269535741782;8374.706885259016;8795.692150774725;9027.368084234105;9474.384165029269;2570.6529618501495;2585.521678489476;2599.428125812328;2601.4536947241104;2602.947941041178;2484.1057219401;2531.164715643833;2549.54108260513;2551.2983929419465;2557.322309587951;2511.6985961684622;2524.5582023145967;2535.378832186117;2537.251510804308;2540.110961163587;2451.9387015324583;2453.141400509825;2464.7904928080957;2464.8521088734733;2469.2839076667747;2471.9876400618064;2480.900763969439;2486.689163854518;2488.5271798358253;2498;9303.813923721487;9355.204807591363;9358;9410.95294523523;9437.669681561112;5408.702521361323;5571.9721401393135;5572.498475137257;5595.4964053235;5597;3643.234920666663;3751.8874433767032;3752.605976372469;3756.6619004289473;3818.618138186187;1281.2248589603248;1330.6074707961175;1345.0719003887384;1358.4701966233176;1397.4410235905618;12480.81278780819;12583.433745481476;12592.748145037049;12607.810882836764;12638.936805316007;4887.839315402952;5036.597072234213;5126.5898728101665;5144.6913185209005;5198.16829307309;11049.116070714485;11070.557177712963;11088.833667494993;11095.667129986114;11116.444177791078;5733.913991290134;5734.569907256972;5736.6844823534275;5752.108423194411;5753.194552762617;4555.453345598483;4558.9772051139535;5148.382194136728;5214.113600687816;5218.452062533455;4277.272863613193;4323.589259020388;4394.055278115221;4441.911161776811;4447.599716025573;1348.676317683749;1354.417600431804;1365.528892532075;1376.4150236149596;1377.22875189894;887.6893087419352;1018.8318927376899;1021.3463383434607;1025.6974192613197;1025.9025392587691;6119;6170.969145154335;6198.2871969723155;6289.622622642656;6346.079818426395;503.08689728143304;506.8656805946427;518.1191973644819;524.6773234460779;532.2202972645093;4141.254574176677;4302.161078589707;4385.386045953569;4385.990925535996;4483.260262056724;11637.185397022296;11722.238054526428;11789.469473873696;11795.41022948849;11828.822567661491;11574.6527604172;11651.650450486499;11707.707307317214;11896.702582435508;12043.096767742454;8849.60176792046;8866.468011919176;8925.152110549487;8951.104889511127;8953.597088130919;6214.658193799384;6223.439890409885;6235.095911092957;6271.090691849748;6340.1123842661145;11044.944775276394;11108;11155.16336274799;11172;11195.776084478102;10989.835152472559;11056.11551075928;11068.225224234226;11132.944335278597;11258.493367798304;7174.748883789042;7391.667374968247;7392.075990501187;8278.337732981467;8348.123447037973;7782.182870798485;8457.365697572584;8713.390062695804;8817.823643527221;9004.594793234452;7579.431542634215;7628.694852205849;7826.960865195638;8145.51126932386;8152.062512811102;7535.886961695469;7611.162772094955;7619.695212191672;7674.8081297968;7806.297433231037;7236.131664200119;7344;7349.265073492608;7358.11702595692;7358.705651773869;5458.208559758892;5479.972600136974;5551.2228288040515;5592.27300450955;5631.324241091095;5367.758450869642;5481.150421684645;5523.751431185526;5533.7786488541615;5603.859903502445 10:15:52 INFO - PROCESS | 15358 | |4;prototype.html;352.90118766745314;358.2512548773064;358.30667658081603;360.12310025088897;364.99817500912474;5622;5674.319081710195;5680.204771332017;5686.260786097807;5690.374058853523;5509.586780991422;5565.805196818113;5639.802606908759;5641.802536911209;5645.0121228785;4228.978855105728;4324.891877703051;4363.716358436692;4372.2785740352965;4396.296592545194;4342.804573794187;4360.193364227628;4372.3878656988045;4373.978130109361;4378.124375124972;4325.589069038437;4340.131973605276;4341.50072741634;4345.826166953318;4356.390105385249;4279.972910312365;4284.357346398018;4313.4176886120285;4313.654907607384;4336.934945975813;3811.7331786774935;3822.273767984102;3825.1775868188474;3826.1199924017633;3831.4061320495366;5361.142217245222;5388.299521062276;5394.6493477924205;5411.21537377082;5413.810516631938;4856.8300109495995;4902.166631672624;4932.136876046713;4942.555170034714;4976.303317535548;5745.741441635137;5767.509761670263;5782.686925229899;5784.962894480389;5785.132230165466;4203.600657937491;4219.396629280854;4222.894427639303;4228.112096459717;4228.936565951513;5707.3721890592005;5765.769369225226;5877.9412205877825;5972.9701351493395;5981.401859814027;4179.540250572434;4294.26997410441;4302.462192225972;4306.784660766949;4317.589828966243;4368.213721530155;4379.277419225856;4391.714538554984;4400.801963911632;4412.955870441255;4743.31221972812;4746.840187714582;4803.087413391444;5015.6489045767175;5042.873928151752;5763.769449221984;5781.335146458127;5795.275590551181;5808.215890854765;5845.707714614337;1486.8884833637303;1510.358097808427;1512.3345727879698;1522.62695639569;1533.5246073717183;975.531744762518;994.8259054665549;998.1316254858175;1041.234692501011;1059.560282482761;5509.862253443615;5525.778968841201;5531.363893152258;5552.361478429952;5590.413006634326;5938.198343223696;5941.435563621449;5950.9830864166515;5956.672383018976;5979.402059793986;5355.732213389393;5582.8325150245555;5617.269754931833;5653;5656;3137.215696075981;3167.619885613695;3170.524421336818;3174.507951267557;3200.9359812803614;5903.468687818117;6137.84655383619;6220.502359811204;6227.190465239581;6249.468795152463;6128.479079278222;6150.753969841246;6159.876802463926;6161.876762464815;6166.537509686791;6170.876582468415;6194.4115309045565;6202.72087756052;6204.937950620436;6206.968965155145;5732.742026608729;5930.940690593211;5977.252843394575;5979.4917432018765;5992.041273396236;5933.970330148321;5951.702414879325;5953.226080609493;5959.344472107978;6020.458158765732;5967.552433567413;6204.286507051746;6215.720292586754;6231.2522497300615;6328.335524769833;4456.420665313489;4471.217536931089;4493;4515.503294637521;4541.818327266872;6072.423119803611;6091.543134265036;6093.908591371044;6105.5420843437805;6105.938940610537;5684.3747187808485;5873.530117590668;5885.14665373527;5891.528677705687;5906.464319277105;5837.474627283395;5854.121881717606;5860.736266868086;5875.794347197828;5939.732712027882;5600.379686758359;5762.164486149569;5768.894353561004;5839.941600583939;5895.734691938787;5832.950068987623;5858.560607954335;5866.2373891393845;5878.353381128158;5903.793367232126;5739.569532285179;5753.165790960204;5783.855403614775;5834.766609335579;5863.7099838035565 10:15:52 INFO - PROCESS | 15358 | |5;yui.html;770.2259229474378;804.9839003219936;810.7324582887647;829.3033851564686;835.0522157351404;15111.697766044672;15918;15948.920255398736;15953.44162954297;15989.280482378292;14473.710525789478;14726.116433014;14729.705405891877;14735.042222255546;14740.262986850641;700.8388070743732;719.2843121094518;818.582522913314;830.0744669693279;862.8144948835999;866.2160744526183;868.0971789339111;875.5053394831931;875.7022612311813;877.5743764274322;905.7418635688797;906.3338446242007;906.8231694819513;907.314977192219;907.4328544659588;746.1792028768366;759.2066290726204;867.1068799136867;882.8631562107884;891.8617614269799;637.3180696654581;642.4154019841968;642.495640921874;642.5855323316434;644.5520363347475;14036.92981535096;14054.92972535141;14101.78847317291;14117.78823317651;14129.929350353284;13582.728345433135;14048.648783780385;14134.36395362211;14155.929220353935;14162.504312349121;14226.940546133903;14258.28708564578;14447.494337698236;14795.40818367264;15272.38910443581;4588.954110458886;4591.885202869921;4616.492185859553;4625.271090056849;4819.975900120512;85417.57291213566;85766;85868.57065714631;86014;86033;10928.792414920314;11376.829347559793;11552.59565915189;11706.531738730355;11903;21820.672689909665;21847.235346763104;21857.125714971542;21910.45223869416;22022.33932982013;8427.95786021066;8428.536430496259;8440;8448.281896038783;8528.829423411496;4768.833090841804;4918.3360246367;4918.385201849769;5032.72320022395;5037.924431133536;3646.799426031541;3739.8130093495215;3746.0822098586023;3775.981120094382;3777.1690228149764;605.33110912442;641.6310621392718;657.0800878769761;670.6546128743701;677.3598948993252;713.5362014690493;759.1232126893447;777.6694904665608;797.9666332099945;802.6949759091625;9645.71062868115;9655.179309758609;9901.801963960681;10361.948190259;10446.477676116316;659.7690808217181;713.6110819603329;720.1861896057421;748.0948052855996;751.9511231769917;4708.505606911225;4783.402074740658;4802.495737947535;4825.9034819303415;4899.926501102486;113298;132141.3392933029;134893.3255333717;134912;136797.3160134193;67618.32381676219;95422.52288738512;113421;113840.43079784547;114374;97670;98370;99256;100655;102070.48964755278;9005.279577633904;9272.072792720945;9328.224877913382;9374.765630859283;9476.57355418994;47802;49575;50870.23694644509;52207;52798.2080268804;77017;78240.60879695565;82521.17478825134;84446.15553844383;87462.56268718616;13272.203667779964;13310.600681979557;13363.465461381436;13568.864311357154;14032.438702452197;14684.632884177554;14745.11529308245;14924.403023879358;15211.011284266488;15462.458813941457;14450.783238251224;14559.636009099857;14623.415063397344;14987.400503980154;15050.096994179947;13277.269750163834;13626.591202263948;13949.302534873013;14018.78971815444;14760.852391475948;13705.314734263047;14108.78836817428;14142.50501232452;14754.336054877342;14929.85070149328;11093.389863557259;11134.721631959266;11334.94332528332;11347.829782553434;12302.077344199042;10163.288569800043;10179.541920613443;10242.82472433548;10376.552310036988;10464.790704186025 10:15:52 INFO - PROCESS | 15358 | __end_tp_report 10:15:52 INFO - PROCESS | 15358 | __start_cc_report 10:15:52 INFO - PROCESS | 15358 | _x_x_mozilla_cycle_collect,883 10:15:52 INFO - PROCESS | 15358 | __end_cc_report 10:15:52 INFO - PROCESS | 15358 | __startTimestamp1474305352754__endTimestamp 10:15:52 INFO - PROCESS | 15358 | 10:15:52 INFO - PROCESS | 15358 | ------- Summary: start ------- 10:15:52 INFO - PROCESS | 15358 | Number of tests: 6 10:15:52 INFO - PROCESS | 15358 | 10:15:52 INFO - PROCESS | 15358 | [#0] dojo.html Cycles:165 Average:5592.73 Median:5876.91 stddev:650.96 (11.1%) stddev-sans-first:621.94 10:15:52 INFO - PROCESS | 15358 | Values: 3061.8 3093.4 3097.0 3114.7 3140.1 5484.6 5552.5 5623.6 5627.4 5658.5 5672.6 5730.6 5739.3 5739.9 5769.0 5144.0 5174.2 5190.5 5217.9 5247.7 5039.1 5247.8 5255.4 5264.6 5265.2 5025.9 5112.7 5114.7 5140.2 5162.3 5127.6 5163.2 5163.6 5225.0 5232.9 5101.3 5128.5 5195.9 5209.0 5238.1 5411.9 5449.2 5481.4 5567.1 5590.8 5879.5 5924.8 5926.7 5943.8 6006.8 6035.3 6049.5 6054.9 6064.2 6064.3 5500.2 5529.8 5536.8 5576.4 5580.1 6129.0 6166.7 6168.9 6170.4 6171.9 5915.9 5917.1 5920.5 5924.0 5928.9 5892.1 5927.6 5939.9 5963.8 5964.4 5998.8 6002.7 6011.7 6016.8 6034.2 5934.6 5989.1 6007.8 6022.3 6035.0 4529.5 4562.1 4576.9 4604.4 4625.1 4078.2 4114.4 4128.7 4135.8 4152.4 5663.6 5667.6 5671.8 5672.2 5674.8 5928.2 5985.1 6014.6 6131.3 6139.9 5674.7 5721.1 5732.5 5770.6 5773.2 5111.6 5121.2 5121.3 5128.9 5137.2 5965.0 6095.5 6237.4 6297.2 6323.7 6006.0 6012.2 6061.8 6112.4 6142.2 6267.7 6305.9 6306.0 6311.0 6318.4 5770.8 5991.5 6010.5 6010.5 6013.7 5877.5 5943.0 5978.6 6013.9 6099.3 5845.6 5895.6 5898.4 5901.2 5903.3 5884.5 5887.3 5891.8 5892.2 5896.1 5586.9 5708.2 5836.2 5876.9 5899.2 6018.4 6018.9 6030.7 6033.6 6050.3 5645.4 5863.6 5896.7 5898.5 5901.0 10:15:52 INFO - PROCESS | 15358 | 10:15:52 INFO - PROCESS | 15358 | [#1] ext.html Cycles:180 Average:60869.14 Median:227214.79 stddev:114150.20 (50.2%) stddev-sans-first:114380.70 10:15:52 INFO - PROCESS | 15358 | Values: 590.2 605.7 637.3 659.0 667.4 34095.3 46201.3 46514.3 46697.3 46713.5 46645.0 47136.0 47221.1 47336.3 47341.0 8616.6 9190.2 9332.3 9339.9 9342.8 8590.4 10893.9 11119.1 11159.4 11615.2 11285.4 11357.8 11396.8 11397.1 11413.5 8453.3 8686.5 11275.2 11345.0 11349.2 8627.3 8676.7 10755.1 11089.0 11120.6 43880.0 44283.6 44323.0 44323.3 44346.0 3643.8 3681.7 3703.4 3703.8 4106.8 33780.5 34634.7 34840.7 35029.9 35064.8 4537.4 4635.1 4660.5 4663.3 4682.4 369222.0 370014.0 370519.0 370566.0 370852.0 64332.4 64505.7 64541.0 64550.4 64663.7 64690.0 64749.7 64803.0 64875.0 64883.0 15976.6 16370.0 16377.8 16379.1 16389.6 12404.6 12623.9 13028.3 13443.9 13490.3 17481.9 17637.1 17779.6 17859.1 17924.2 6520.5 6534.3 6584.0 6618.0 6640.6 986.2 1059.8 1109.1 1133.2 1157.2 33980.7 34268.3 34568.5 34581.3 34602.3 1161.7 1161.8 1171.1 1204.5 1219.8 17845.0 17970.0 18018.3 18222.5 18960.5 497591.0 500539.0 502118.0 502579.0 503002.0 64234.7 64432.4 64461.0 64464.0 64473.0 1326.3 1335.7 1386.3 1420.2 1458.5 32562.3 34476.3 34492.5 34530.1 34818.8 53713.7 54193.0 54272.2 54287.0 54295.0 413230.0 415358.0 416527.0 417143.0 418073.0 29919.3 30511.0 30802.2 31039.5 32362.7 50449.7 50548.5 50734.0 51223.5 52217.2 41386.0 41960.0 45528.5 45599.3 47489.3 41199.6 41572.6 41864.4 42253.6 43963.8 43771.4 44065.6 44517.0 44626.8 44869.0 35974.5 38502.2 38774.2 39513.6 39739.2 29484.0 29782.7 29856.3 29946.4 30379.7 10:15:52 INFO - PROCESS | 15358 | 10:15:52 INFO - PROCESS | 15358 | [#2] jquery.html Cycles:180 Average:30554.99 Median:2578.21 stddev:90558.75 (3512.5%) stddev-sans-first:90784.47 10:15:52 INFO - PROCESS | 15358 | Values: 395.2 397.2 419.5 426.9 440.4 14405.0 16343.0 20430.5 20687.6 21228.7 18197.0 18716.4 19067.1 21220.7 21437.5 5832.4 5857.8 6035.9 6076.1 6084.6 4065.7 6059.3 6098.6 6099.0 6102.7 4117.7 4301.2 4439.6 6513.9 6568.0 5905.7 6592.5 6601.5 6614.6 6625.9 5947.8 6071.3 6071.6 6071.9 6072.4 14726.9 14922.6 14977.8 15059.5 15139.8 2401.4 2563.1 2593.4 2856.6 2890.6 10771.7 10931.2 11047.2 11310.2 11673.3 1905.3 1973.1 2009.7 2039.1 2061.6 72425.0 72570.6 75736.0 75918.2 86166.1 10372.9 11546.8 11935.2 12043.5 12069.4 16854.4 16863.8 16895.9 16900.6 17031.8 8560.1 8729.8 8773.0 8797.6 8833.0 8272.0 8310.7 8315.2 8465.0 8516.4 5120.9 5223.3 5224.2 5233.5 5314.5 2000.7 2119.6 2135.1 2164.8 2166.8 918.5 1008.4 1025.3 1051.1 1094.7 20055.4 20259.4 20623.7 20806.7 20949.7 977.7 1003.6 1005.6 1010.2 1087.2 10354.9 11490.3 11567.5 11615.9 11771.4 546713.0 548954.0 549468.0 551786.0 555320.0 94827.5 96282.0 96817.5 97252.5 106632.0 534.9 553.7 554.6 558.3 995.5 11393.8 11797.4 12213.5 12416.6 12468.3 40488.6 41771.4 42215.0 42240.4 44501.8 54732.5 54746.0 55180.7 63330.4 63899.4 8080.6 8131.1 8452.0 8631.4 9036.1 12384.4 12506.5 12560.6 12616.3 12670.0 10784.5 10854.4 10899.2 11124.2 11446.9 11003.8 11066.6 11221.2 11251.8 11681.5 11842.6 11846.4 11900.2 11939.4 12194.4 8608.5 8727.7 8997.8 9001.5 9080.9 10582.5 11308.5 11454.2 11726.0 11860.0 10:15:52 INFO - PROCESS | 15358 | 10:15:52 INFO - PROCESS | 15358 | [#3] mootools.html Cycles:180 Average:6136.53 Median:4723.41 stddev:3493.31 (74.0%) stddev-sans-first:3485.67 10:15:52 INFO - PROCESS | 15358 | Values: 1491.4 1523.0 1669.9 1791.1 1808.5 8961.0 9682.1 9692.1 9701.1 9720.6 8116.3 8374.7 8795.7 9027.4 9474.4 2570.7 2585.5 2599.4 2601.5 2602.9 2484.1 2531.2 2549.5 2551.3 2557.3 2511.7 2524.6 2535.4 2537.3 2540.1 2451.9 2453.1 2464.8 2464.9 2469.3 2472.0 2480.9 2486.7 2488.5 2498.0 9303.8 9355.2 9358.0 9411.0 9437.7 5408.7 5572.0 5572.5 5595.5 5597.0 3643.2 3751.9 3752.6 3756.7 3818.6 1281.2 1330.6 1345.1 1358.5 1397.4 12480.8 12583.4 12592.7 12607.8 12638.9 4887.8 5036.6 5126.6 5144.7 5198.2 11049.1 11070.6 11088.8 11095.7 11116.4 5733.9 5734.6 5736.7 5752.1 5753.2 4555.5 4559.0 5148.4 5214.1 5218.5 4277.3 4323.6 4394.1 4441.9 4447.6 1348.7 1354.4 1365.5 1376.4 1377.2 887.7 1018.8 1021.3 1025.7 1025.9 6119.0 6171.0 6198.3 6289.6 6346.1 503.1 506.9 518.1 524.7 532.2 4141.3 4302.2 4385.4 4386.0 4483.3 11637.2 11722.2 11789.5 11795.4 11828.8 11574.7 11651.7 11707.7 11896.7 12043.1 8849.6 8866.5 8925.2 8951.1 8953.6 6214.7 6223.4 6235.1 6271.1 6340.1 11044.9 11108.0 11155.2 11172.0 11195.8 10989.8 11056.1 11068.2 11132.9 11258.5 7174.7 7391.7 7392.1 8278.3 8348.1 7782.2 8457.4 8713.4 8817.8 9004.6 7579.4 7628.7 7827.0 8145.5 8152.1 7535.9 7611.2 7619.7 7674.8 7806.3 7236.1 7344.0 7349.3 7358.1 7358.7 5458.2 5480.0 5551.2 5592.3 5631.3 5367.8 5481.2 5523.8 5533.8 5603.9 10:15:52 INFO - PROCESS | 15358 | 10:15:52 INFO - PROCESS | 15358 | [#4] prototype.html Cycles:180 Average:4927.78 Median:5643.41 stddev:1439.60 (25.5%) stddev-sans-first:1402.09 10:15:52 INFO - PROCESS | 15358 | Values: 352.9 358.3 358.3 360.1 365.0 5622.0 5674.3 5680.2 5686.3 5690.4 5509.6 5565.8 5639.8 5641.8 5645.0 4229.0 4324.9 4363.7 4372.3 4396.3 4342.8 4360.2 4372.4 4374.0 4378.1 4325.6 4340.1 4341.5 4345.8 4356.4 4280.0 4284.4 4313.4 4313.7 4336.9 3811.7 3822.3 3825.2 3826.1 3831.4 5361.1 5388.3 5394.6 5411.2 5413.8 4856.8 4902.2 4932.1 4942.6 4976.3 5745.7 5767.5 5782.7 5785.0 5785.1 4203.6 4219.4 4222.9 4228.1 4228.9 5707.4 5765.8 5877.9 5973.0 5981.4 4179.5 4294.3 4302.5 4306.8 4317.6 4368.2 4379.3 4391.7 4400.8 4413.0 4743.3 4746.8 4803.1 5015.6 5042.9 5763.8 5781.3 5795.3 5808.2 5845.7 1486.9 1510.4 1512.3 1522.6 1533.5 975.5 994.8 998.1 1041.2 1059.6 5509.9 5525.8 5531.4 5552.4 5590.4 5938.2 5941.4 5951.0 5956.7 5979.4 5355.7 5582.8 5617.3 5653.0 5656.0 3137.2 3167.6 3170.5 3174.5 3200.9 5903.5 6137.8 6220.5 6227.2 6249.5 6128.5 6150.8 6159.9 6161.9 6166.5 6170.9 6194.4 6202.7 6204.9 6207.0 5732.7 5930.9 5977.3 5979.5 5992.0 5934.0 5951.7 5953.2 5959.3 6020.5 5967.6 6204.3 6215.7 6231.3 6328.3 4456.4 4471.2 4493.0 4515.5 4541.8 6072.4 6091.5 6093.9 6105.5 6105.9 5684.4 5873.5 5885.1 5891.5 5906.5 5837.5 5854.1 5860.7 5875.8 5939.7 5600.4 5762.2 5768.9 5839.9 5895.7 5833.0 5858.6 5866.2 5878.4 5903.8 5739.6 5753.2 5783.9 5834.8 5863.7 10:15:52 INFO - PROCESS | 15358 | 10:15:52 INFO - PROCESS | 15358 | [#5] yui.html Cycles:180 Average:22277.86 Median:4666.89 stddev:33263.09 (712.7%) stddev-sans-first:33317.20 10:15:52 INFO - PROCESS | 15358 | Values: 770.2 805.0 810.7 829.3 835.1 15111.7 15918.0 15948.9 15953.4 15989.3 14473.7 14726.1 14729.7 14735.0 14740.3 700.8 719.3 818.6 830.1 862.8 866.2 868.1 875.5 875.7 877.6 905.7 906.3 906.8 907.3 907.4 746.2 759.2 867.1 882.9 891.9 637.3 642.4 642.5 642.6 644.6 14036.9 14054.9 14101.8 14117.8 14129.9 13582.7 14048.6 14134.4 14155.9 14162.5 14226.9 14258.3 14447.5 14795.4 15272.4 4589.0 4591.9 4616.5 4625.3 4820.0 85417.6 85766.0 85868.6 86014.0 86033.0 10928.8 11376.8 11552.6 11706.5 11903.0 21820.7 21847.2 21857.1 21910.5 22022.3 8428.0 8428.5 8440.0 8448.3 8528.8 4768.8 4918.3 4918.4 5032.7 5037.9 3646.8 3739.8 3746.1 3776.0 3777.2 605.3 641.6 657.1 670.7 677.4 713.5 759.1 777.7 798.0 802.7 9645.7 9655.2 9901.8 10361.9 10446.5 659.8 713.6 720.2 748.1 752.0 4708.5 4783.4 4802.5 4825.9 4899.9 113298.0 132141.3 134893.3 134912.0 136797.3 67618.3 95422.5 113421.0 113840.4 114374.0 97670.0 98370.0 99256.0 100655.0 102070.5 9005.3 9272.1 9328.2 9374.8 9476.6 47802.0 49575.0 50870.2 52207.0 52798.2 77017.0 78240.6 82521.2 84446.2 87462.6 13272.2 13310.6 13363.5 13568.9 14032.4 14684.6 14745.1 14924.4 15211.0 15462.5 14450.8 14559.6 14623.4 14987.4 15050.1 13277.3 13626.6 13949.3 14018.8 14760.9 13705.3 14108.8 14142.5 14754.3 14929.9 11093.4 11134.7 11334.9 11347.8 12302.1 10163.3 10179.5 10242.8 10376.6 10464.8 10:15:52 INFO - PROCESS | 15358 | -------- Summary: end -------- 10:15:52 INFO - PROCESS | 15358 | 10:15:53 INFO - TEST-INFO | 15358: exit 0 10:15:53 INFO - TEST-OK | dromaeo_css | took 1113915ms 10:15:53 INFO - TEST-START | kraken 10:15:53 INFO - operating with platform_type : linux_ 10:15:53 INFO - Initialising browser for kraken test... 10:15:53 INFO - TEST-INFO | started process 15679 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYwqhSA/profile http://localhost:51585/getInfo.html) 10:15:57 INFO - PROCESS | 15679 | __metrics Screen width/height:1600/1200 10:15:57 INFO - PROCESS | 15679 | colorDepth:24 10:15:57 INFO - PROCESS | 15679 | Browser inner width/height: 1024/697 10:15:57 INFO - PROCESS | 15679 | __metrics 10:15:58 INFO - PROCESS | 15679 | 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" 10:15:58 INFO - PROCESS | 15679 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:15:58 INFO - PROCESS | 15679 | console.error: PushService: 10:15:58 INFO - PROCESS | 15679 | stateChangeProcessEnqueue: Error transitioning state 10:15:58 INFO - PROCESS | 15679 | UnknownError 10:15:59 INFO - TEST-INFO | 15679: exit 0 10:15:59 INFO - Browser initialized. 10:15:59 INFO - Running cycle 1/1 for kraken test... 10:15:59 INFO - TEST-INFO | started process 15770 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYwqhSA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:16:11 INFO - PROCESS | 15770 | RSS: Main: 144543744 10:16:11 INFO - PROCESS | 15770 | 10:16:13 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51585/tests/kraken/driver.html?testName=audio-beat-detection) 10:16:14 INFO - PROCESS | 15770 | RSS: Main: 185532416 10:16:14 INFO - PROCESS | 15770 | 10:16:16 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51585/tests/kraken/driver.html?testName=audio-dft) 10:16:17 INFO - PROCESS | 15770 | RSS: Main: 174821376 10:16:17 INFO - PROCESS | 15770 | 10:16:20 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51585/tests/kraken/driver.html?testName=audio-fft) 10:16:20 INFO - PROCESS | 15770 | RSS: Main: 267014144 10:16:20 INFO - PROCESS | 15770 | 10:16:22 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51585/tests/kraken/driver.html?testName=audio-oscillator) 10:16:23 INFO - PROCESS | 15770 | RSS: Main: 172904448 10:16:23 INFO - PROCESS | 15770 | 10:16:25 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51585/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:16:26 INFO - PROCESS | 15770 | RSS: Main: 174665728 10:16:26 INFO - PROCESS | 15770 | 10:16:29 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51585/tests/kraken/driver.html?testName=imaging-darkroom) 10:16:29 INFO - PROCESS | 15770 | RSS: Main: 210718720 10:16:29 INFO - PROCESS | 15770 | 10:16:33 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51585/tests/kraken/driver.html?testName=imaging-desaturate) 10:16:33 INFO - PROCESS | 15770 | RSS: Main: 209358848 10:16:33 INFO - PROCESS | 15770 | 10:16:36 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51585/tests/kraken/driver.html?testName=json-parse-financial) 10:16:37 INFO - PROCESS | 15770 | RSS: Main: 208990208 10:16:37 INFO - PROCESS | 15770 | 10:16:38 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51585/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:16:39 INFO - PROCESS | 15770 | RSS: Main: 171134976 10:16:39 INFO - PROCESS | 15770 | 10:16:40 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51585/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:16:41 INFO - PROCESS | 15770 | RSS: Main: 178659328 10:16:41 INFO - PROCESS | 15770 | 10:16:43 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51585/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:16:44 INFO - PROCESS | 15770 | RSS: Main: 182996992 10:16:44 INFO - PROCESS | 15770 | 10:16:46 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51585/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:16:47 INFO - PROCESS | 15770 | RSS: Main: 184205312 10:16:47 INFO - PROCESS | 15770 | 10:16:49 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51585/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:16:50 INFO - PROCESS | 15770 | RSS: Main: 182652928 10:16:50 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | Cycle 1(1): loaded http://localhost:51585/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51585/tests/kraken/driver.html?testName=ai-astar) 10:16:52 INFO - PROCESS | 15770 | RSS: Main: 232964096 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | __start_tp_report 10:16:52 INFO - PROCESS | 15770 | _x_x_mozilla_page_load 10:16:52 INFO - PROCESS | 15770 | _x_x_mozilla_page_load_details 10:16:52 INFO - PROCESS | 15770 | |i|pagename|runs| 10:16:52 INFO - PROCESS | 15770 | |0;ai-astar;110;110;110;110;112;113;110;109;112;110 10:16:52 INFO - PROCESS | 15770 | |1;audio-beat-detection;147;121;115;159;116;124;118;118;158;117 10:16:52 INFO - PROCESS | 15770 | |2;audio-dft;160;159;160;161;161;162;162;160;161;160 10:16:52 INFO - PROCESS | 15770 | |3;audio-fft;75;76;75;108;75;76;77;76;96;76 10:16:52 INFO - PROCESS | 15770 | |4;audio-oscillator;81;81;114;81;81;83;99;82;81;109 10:16:52 INFO - PROCESS | 15770 | |5;imaging-gaussian-blur;117;117;118;117;117;117;117;117;117;118 10:16:52 INFO - PROCESS | 15770 | |6;imaging-darkroom;146;146;146;146;146;145;146;146;146;146 10:16:52 INFO - PROCESS | 15770 | |7;imaging-desaturate;95;95;96;95;95;95;95;94;95;95 10:16:52 INFO - PROCESS | 15770 | |8;json-parse-financial;49;48;51;49;48;49;49;49;49;48 10:16:52 INFO - PROCESS | 15770 | |9;json-stringify-tinderbox;59;60;59;59;59;59;60;59;59;60 10:16:52 INFO - PROCESS | 15770 | |10;stanford-crypto-aes;72;74;72;72;78;72;73;77;74;72 10:16:52 INFO - PROCESS | 15770 | |11;stanford-crypto-ccm;123;128;125;129;119;127;118;123;176;120 10:16:52 INFO - PROCESS | 15770 | |12;stanford-crypto-pbkdf2;152;153;149;155;151;152;149;151;149;151 10:16:52 INFO - PROCESS | 15770 | |13;stanford-crypto-sha256-iterative;64;62;62;62;62;62;60;62;60;61 10:16:52 INFO - PROCESS | 15770 | __end_tp_report 10:16:52 INFO - PROCESS | 15770 | __start_cc_report 10:16:52 INFO - PROCESS | 15770 | _x_x_mozilla_cycle_collect,1611 10:16:52 INFO - PROCESS | 15770 | __end_cc_report 10:16:52 INFO - PROCESS | 15770 | __startTimestamp1474305412276__endTimestamp 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | ------- Summary: start ------- 10:16:52 INFO - PROCESS | 15770 | Number of tests: 14 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#0] ai-astar Cycles:10 Average:110.60 Median:110.00 stddev:1.26 (1.1%) stddev-sans-first:1.32 10:16:52 INFO - PROCESS | 15770 | Values: 110.0 110.0 110.0 110.0 112.0 113.0 110.0 109.0 112.0 110.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:122.50 stddev:17.96 (14.7%) stddev-sans-first:17.87 10:16:52 INFO - PROCESS | 15770 | Values: 147.0 121.0 115.0 159.0 116.0 124.0 118.0 118.0 158.0 117.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 10:16:52 INFO - PROCESS | 15770 | Values: 160.0 159.0 160.0 161.0 161.0 162.0 162.0 160.0 161.0 160.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:11.44 (15.1%) stddev-sans-first:11.93 10:16:52 INFO - PROCESS | 15770 | Values: 75.0 76.0 75.0 108.0 75.0 76.0 77.0 76.0 96.0 76.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:81.00 stddev:13.04 (16.1%) stddev-sans-first:13.49 10:16:52 INFO - PROCESS | 15770 | Values: 81.0 81.0 114.0 81.0 81.0 83.0 99.0 82.0 81.0 109.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:16:52 INFO - PROCESS | 15770 | Values: 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 10:16:52 INFO - PROCESS | 15770 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 10:16:52 INFO - PROCESS | 15770 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#8] json-parse-financial Cycles:10 Average:48.90 Median:49.00 stddev:0.88 (1.8%) stddev-sans-first:0.93 10:16:52 INFO - PROCESS | 15770 | Values: 49.0 48.0 51.0 49.0 48.0 49.0 49.0 49.0 49.0 48.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 10:16:52 INFO - PROCESS | 15770 | Values: 59.0 60.0 59.0 59.0 59.0 59.0 60.0 59.0 59.0 60.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:73.50 stddev:2.22 (3.0%) stddev-sans-first:2.28 10:16:52 INFO - PROCESS | 15770 | Values: 72.0 74.0 72.0 72.0 78.0 72.0 73.0 77.0 74.0 72.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:126.00 stddev:17.01 (13.5%) stddev-sans-first:17.91 10:16:52 INFO - PROCESS | 15770 | Values: 123.0 128.0 125.0 129.0 119.0 127.0 118.0 123.0 176.0 120.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.20 Median:151.50 stddev:1.93 (1.3%) stddev-sans-first:2.03 10:16:52 INFO - PROCESS | 15770 | Values: 152.0 153.0 149.0 155.0 151.0 152.0 149.0 151.0 149.0 151.0 10:16:52 INFO - PROCESS | 15770 | 10:16:52 INFO - PROCESS | 15770 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.16 (1.9%) stddev-sans-first:0.88 10:16:52 INFO - PROCESS | 15770 | Values: 64.0 62.0 62.0 62.0 62.0 62.0 60.0 62.0 60.0 61.0 10:16:52 INFO - PROCESS | 15770 | -------- Summary: end -------- 10:16:52 INFO - PROCESS | 15770 | 10:16:53 INFO - TEST-INFO | 15770: exit 0 10:16:53 INFO - TEST-OK | kraken | took 59822ms 10:16:53 INFO - SUITE-END | took 1173s 10:16:53 INFO - Completed test suite (00:19:33) 10:16:53 INFO - javascript benchmark 10:16:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3061.8315992620405, 3093.4122516721823, 3097.039917625535, 3114.704103110204, 3140.1207661854673, 5484.628842789302, 5552.500274975251, 5623.606347555673, 5627.380988091307, 5658.519025882805, 5672.602917795745, 5730.598858079936, 5739.3399759027725, 5739.885202295962, 5768.971155144219, 5144.022635699224, 5174.223866420049, 5190.455002224769, 5217.86955326118, 5247.711375874325, 5039.118154322997, 5247.763850626731, 5255.369355677324, 5264.6051546134, 5265.183896496049, 5025.899482010358, 5112.718800465973, 5114.667546609458, 5140.203268493389, 5162.277281180638, 5127.564157046656, 5163.173892177234, 5163.612729045337, 5224.973875130638, 5232.92150617741, 5101.346823076173, 5128.538431541142, 5195.9480405195845, 5208.973955130238, 5238.13570760824, 5411.891762164774, 5449.155380915925, 5481.424450432687, 5567.053600887859, 5590.8043218487155, 5879.500242479395, 5924.763009479616, 5926.674032928187, 5943.821685349447, 6006.8498287542725, 6035.275766907956, 6049.516038716892, 6054.939450605481, 6064.211652485193, 6064.302605200414, 5500.22996780451, 5529.806456774034, 5536.833894983117, 5576.358718747396, 5580.051391263495, 6129.0, 6166.7224974876235, 6168.907466388007, 6170.382961703884, 6171.907421388682, 5915.852103697442, 5917.112433134978, 5920.467157955719, 5924.0, 5928.881422371528, 5892.116182572563, 5927.644341339534, 5939.881202375928, 5963.79126730571, 5964.403559644001, 5998.760049598054, 6002.729877155539, 6011.729472173763, 6016.759329626766, 6034.2155519782145, 5934.643921364732, 5989.071693887455, 6007.789727359609, 6022.277326720821, 6035.0, 4529.479109902338, 4562.133194693064, 4576.931346029813, 4604.355390245369, 4625.121226966866, 4078.2455245779624, 4114.362273847559, 4128.690348223895, 4135.81388837497, 4152.43942067817, 5663.6035477517, 5667.603267771299, 5671.801486947937, 5672.177534257509, 5674.801381951612, 5928.170056192136, 5985.102234664835, 6014.639121652627, 6131.325554189036, 6139.907901381483, 5674.687892165971, 5721.141828725725, 5732.484076433141, 5770.567207459458, 5773.24947756789, 5111.565516931102, 5121.231815227746, 5121.334226550524, 5128.948710512847, 5137.152369858932, 5965.045592705146, 6095.481884039729, 6237.407446292595, 6297.244330680348, 6323.747050118036, 6006.0, 6012.1582978382185, 6061.848453788691, 6112.358202388685, 6142.170806941006, 6267.655278959701, 6305.873882522415, 6306.0225665022, 6310.968445157745, 6318.399752023551, 5770.826875193751, 5991.460768530853, 6010.459058684739, 6010.519158467399, 6013.699315034143, 5877.529797616266, 5943.019401798655, 5978.641281523122, 6013.939860601338, 6099.329073801967, 5845.561582881386, 5895.587308888336, 5898.4101589840675, 5901.232839730807, 5903.291605007426, 5884.529237660891, 5887.293524777054, 5891.7643294269465, 5892.175095486549, 5896.056630939029, 5586.86032849182, 5708.172315014217, 5836.182934389104, 5876.911846322394, 5899.17411562373, 6018.398160183947, 6018.8796224074395, 6030.668313242813, 6033.607815491979, 6050.304215015153, 5645.4072322405555, 5863.648181109147, 5896.70516474166, 5898.498627616529, 5900.970495147497], "value": 5546.577345473603, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.2268028882165, 605.7183409714484, 637.3085202555227, 658.9769358072468, 667.3560014585923, 34095.31809363815, 46201.30698039528, 46514.30228546575, 46697.29954050688, 46713.532864671346, 46645.0, 47136.0, 47221.05557888849, 47336.289955650696, 47341.0, 8616.569171541429, 9190.172884440415, 9332.300077494216, 9339.906600933971, 9342.813143737121, 8590.355723320745, 10893.945530272338, 11119.110471162288, 11159.442027898573, 11615.245009074426, 11285.379304138269, 11357.829632555477, 11396.772064558703, 11397.145214108934, 11413.48639311233, 8453.323734101257, 8686.522241276727, 11275.210735248536, 11345.0, 11349.20555561111, 8627.266682332009, 8676.696315628922, 10755.139588832875, 11089.0, 11120.61077862279, 43880.0, 44283.55716442827, 44323.0, 44323.33514997278, 44346.0, 3643.7995910224927, 3681.668649821502, 3703.3519134151684, 3703.759255648401, 4106.815193316278, 33780.49329260063, 34634.65365346339, 34840.65159348399, 35029.949101526996, 35064.82467587671, 4537.38745269391, 4635.142498637762, 4660.464046634646, 4663.253879379317, 4682.438107427097, 369222.0, 370014.0, 370519.0, 370566.0, 370852.0, 64332.35667643357, 64505.67747161234, 64541.0, 64550.35449645537, 64663.67668161629, 64690.0, 64749.67625161844, 64803.0, 64875.0, 64883.0, 15976.600584985468, 16370.017798932102, 16377.83622163787, 16379.099149546651, 16389.590260243353, 12404.62786116418, 12623.936880315723, 13028.283444410648, 13443.86556134426, 13490.258035807929, 17481.912590436965, 17637.118144092743, 17779.555511112067, 17859.107044647975, 17924.193411296525, 6520.47836173105, 6534.281229064894, 6583.967080164569, 6618.0, 6640.634765087966, 986.2208855004466, 1059.819830628795, 1109.0794640448407, 1133.2237417368963, 1157.1957489544855, 33980.66019339775, 34268.31463370719, 34568.48147277793, 34581.308373832384, 34602.307953840784, 1161.73280145567, 1161.8257261410688, 1171.1358229489022, 1204.512172570093, 1219.8353222314875, 17845.023753443398, 17970.0, 18018.279268829363, 18222.544436389195, 18960.52598685044, 497591.0, 500539.0, 502118.0, 502579.0, 503002.0, 64234.678826606505, 64432.35567644357, 64461.0, 64464.0, 64473.0, 1326.2838067443665, 1335.732853429318, 1386.3345594115083, 1420.155007770375, 1458.4603696632314, 32562.348753025282, 34476.31047378988, 34492.482612761334, 34530.136746581535, 34818.82590587031, 53713.731431342596, 54193.0, 54272.18591721048, 54287.0, 54295.0, 413230.0, 415358.0, 416527.0, 417143.0, 418073.0, 29919.252018699706, 30511.0, 30802.229944251572, 31039.534406984367, 32362.676373235965, 50449.747751261006, 50548.49451505585, 50734.0, 51223.48776512187, 52217.21674174815, 41386.0, 41960.0, 45528.54471455243, 45599.31601026054, 47489.28766068443, 41199.58800411958, 41572.58427415687, 41864.3720344189, 42253.57746422497, 43963.78018110017, 43771.365448057724, 44065.559344406145, 44517.0, 44626.77686611546, 44869.0, 35974.46038309375, 38502.22995540129, 38774.2245155101, 39513.60486395099, 39739.2052158961, 29484.0, 29782.70217297886, 29856.25359366033, 29946.401071978875, 30379.696203037685], "value": 21285.808677912642, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.17605791923836, 397.15406184826315, 419.50288907644443, 426.92528807458694, 440.4428398076434, 14405.0, 16343.0, 20430.489237769063, 20687.586248275027, 21228.681569776465, 18197.0, 18716.43850684478, 19067.14197861096, 21220.681689774665, 21437.464063398384, 5832.358440571534, 5857.765689372431, 6035.879282414371, 6076.118962750421, 6084.574079814415, 4065.735727177724, 6059.303180134275, 6098.603590766608, 6099.0, 6102.694865256742, 4117.743759371521, 4301.161273551643, 4439.600435960763, 6513.93486065138, 6567.967160164217, 5905.734241959122, 6592.472602191813, 6601.537892347537, 6614.636195009272, 6625.93374066258, 5947.762089516415, 6071.271447426293, 6071.605345652562, 6071.908921366183, 6072.423119803611, 14726.926365368105, 14922.552323430315, 14977.775333370008, 15059.548213553611, 15139.772903406458, 2401.3996500874605, 2563.0772921748157, 2593.351662084479, 2856.557233628791, 2890.638670166229, 10771.676849694522, 10931.234813563055, 11047.226694131414, 11310.208285420023, 11673.299602023906, 1905.3426567834076, 1973.1022384814876, 2009.6583580791155, 2039.1333683184735, 2061.597988392249, 72425.03324634339, 72570.63714681445, 75736.0, 75918.24081759166, 86166.13833861644, 10372.896271037192, 11546.82462322868, 11935.164538482222, 12043.458044387848, 12069.396530173457, 16854.41009564684, 16863.831361686473, 16895.915520422564, 16900.57748556296, 17031.82968170327, 8560.14398560139, 8729.817545613596, 8772.956135219283, 8797.604107815036, 8833.0, 8272.0487143978, 8310.70912518059, 8315.168483151636, 8464.957675211708, 8516.446430981967, 5120.897582048338, 5223.3209682741, 5224.164133738584, 5233.528982391603, 5314.468553144655, 2000.6798912174274, 2119.6184686756224, 2135.145941623332, 2164.8326970581566, 2166.837487188467, 918.545320066558, 1008.369768894441, 1025.2993356935463, 1051.0645525482328, 1094.7099018760034, 20055.398338049883, 20259.392218233475, 20623.69064464034, 20806.687899681518, 20949.68575471369, 977.6969139566758, 1003.6386900715737, 1005.6228914157191, 1010.1817527802503, 1087.1846115413439, 10354.948225258826, 11490.253133546294, 11567.537298507965, 11615.941920290345, 11771.352575608424, 546713.0, 548954.0, 549468.0, 551786.0, 555320.0, 94827.52586237024, 96282.0, 96817.5159124214, 97252.51373743085, 106632.0, 534.8716308086032, 553.7452771725052, 554.5951455437553, 558.2770312445393, 995.4773743784426, 11393.829092563452, 11797.410129493663, 12213.511459541518, 12416.627501174979, 12468.314242716737, 40488.59511404848, 41771.37342939797, 42215.0, 42240.36639450349, 44501.777491112334, 54732.452675472734, 54746.0, 55180.724096379265, 63330.36669633245, 63899.3610063912, 8080.636371363185, 8131.146229646039, 8452.02801677815, 8631.39580229403, 9036.14156655143, 12384.442700078338, 12506.499740010298, 12560.560380386643, 12616.306103164414, 12670.0, 10784.514696838818, 10854.403007834644, 10899.182561308093, 11124.221304508606, 11446.885531144582, 11003.779924401308, 11066.557337706401, 11221.214514983873, 11251.831222531504, 11681.474333654836, 11842.585509507126, 11846.407679615812, 11900.226485278776, 11939.403029848647, 12194.390280485763, 8608.526531040852, 8727.65089396417, 8997.7750556234, 9001.549922503718, 9080.863787043067, 10582.4708764563, 11308.49111789955, 11454.198206125824, 11726.0, 11860.0], "value": 9175.90951007516, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.3587157522265, 1523.0100434717433, 1669.874759393045, 1791.1492041280385, 1808.511701840504, 8960.999999999993, 9682.12860842524, 9692.079252471021, 9701.07839755223, 9720.61117555297, 8116.269535741782, 8374.706885259016, 8795.692150774725, 9027.368084234105, 9474.384165029269, 2570.6529618501495, 2585.521678489476, 2599.428125812328, 2601.4536947241104, 2602.947941041178, 2484.1057219401, 2531.164715643833, 2549.54108260513, 2551.2983929419465, 2557.322309587951, 2511.6985961684622, 2524.5582023145967, 2535.378832186117, 2537.251510804308, 2540.110961163587, 2451.9387015324583, 2453.141400509825, 2464.7904928080957, 2464.8521088734733, 2469.2839076667747, 2471.9876400618064, 2480.900763969439, 2486.689163854518, 2488.5271798358253, 2498.0, 9303.813923721487, 9355.204807591363, 9358.0, 9410.95294523523, 9437.669681561112, 5408.702521361323, 5571.9721401393135, 5572.498475137257, 5595.4964053235, 5597.0, 3643.234920666663, 3751.8874433767032, 3752.605976372469, 3756.6619004289473, 3818.618138186187, 1281.2248589603248, 1330.6074707961175, 1345.0719003887384, 1358.4701966233176, 1397.4410235905618, 12480.81278780819, 12583.433745481476, 12592.748145037049, 12607.810882836764, 12638.936805316007, 4887.839315402952, 5036.597072234213, 5126.5898728101665, 5144.6913185209005, 5198.16829307309, 11049.116070714485, 11070.557177712963, 11088.833667494993, 11095.667129986114, 11116.444177791078, 5733.913991290134, 5734.569907256972, 5736.6844823534275, 5752.108423194411, 5753.194552762617, 4555.453345598483, 4558.9772051139535, 5148.382194136728, 5214.113600687816, 5218.452062533455, 4277.272863613193, 4323.589259020388, 4394.055278115221, 4441.911161776811, 4447.599716025573, 1348.676317683749, 1354.417600431804, 1365.528892532075, 1376.4150236149596, 1377.22875189894, 887.6893087419352, 1018.8318927376899, 1021.3463383434607, 1025.6974192613197, 1025.9025392587691, 6119.0, 6170.969145154335, 6198.2871969723155, 6289.622622642656, 6346.079818426395, 503.08689728143304, 506.8656805946427, 518.1191973644819, 524.6773234460779, 532.2202972645093, 4141.254574176677, 4302.161078589707, 4385.386045953569, 4385.990925535996, 4483.260262056724, 11637.185397022296, 11722.238054526428, 11789.469473873696, 11795.41022948849, 11828.822567661491, 11574.6527604172, 11651.650450486499, 11707.707307317214, 11896.702582435508, 12043.096767742454, 8849.60176792046, 8866.468011919176, 8925.152110549487, 8951.104889511127, 8953.597088130919, 6214.658193799384, 6223.439890409885, 6235.095911092957, 6271.090691849748, 6340.1123842661145, 11044.944775276394, 11108.0, 11155.16336274799, 11172.0, 11195.776084478102, 10989.835152472559, 11056.11551075928, 11068.225224234226, 11132.944335278597, 11258.493367798304, 7174.748883789042, 7391.667374968247, 7392.075990501187, 8278.337732981467, 8348.123447037973, 7782.182870798485, 8457.365697572584, 8713.390062695804, 8817.823643527221, 9004.594793234452, 7579.431542634215, 7628.694852205849, 7826.960865195638, 8145.51126932386, 8152.062512811102, 7535.886961695469, 7611.162772094955, 7619.695212191672, 7674.8081297968, 7806.297433231037, 7236.131664200119, 7344.0, 7349.265073492608, 7358.11702595692, 7358.705651773869, 5458.208559758892, 5479.972600136974, 5551.2228288040515, 5592.27300450955, 5631.324241091095, 5367.758450869642, 5481.150421684645, 5523.751431185526, 5533.7786488541615, 5603.859903502445], "value": 4859.658027888567, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.90118766745314, 358.2512548773064, 358.30667658081603, 360.12310025088897, 364.99817500912474, 5622.0, 5674.319081710195, 5680.204771332017, 5686.260786097807, 5690.374058853523, 5509.586780991422, 5565.805196818113, 5639.802606908759, 5641.802536911209, 5645.0121228785, 4228.978855105728, 4324.891877703051, 4363.716358436692, 4372.2785740352965, 4396.296592545194, 4342.804573794187, 4360.193364227628, 4372.3878656988045, 4373.978130109361, 4378.124375124972, 4325.589069038437, 4340.131973605276, 4341.50072741634, 4345.826166953318, 4356.390105385249, 4279.972910312365, 4284.357346398018, 4313.4176886120285, 4313.654907607384, 4336.934945975813, 3811.7331786774935, 3822.273767984102, 3825.1775868188474, 3826.1199924017633, 3831.4061320495366, 5361.142217245222, 5388.299521062276, 5394.6493477924205, 5411.21537377082, 5413.810516631938, 4856.8300109495995, 4902.166631672624, 4932.136876046713, 4942.555170034714, 4976.303317535548, 5745.741441635137, 5767.509761670263, 5782.686925229899, 5784.962894480389, 5785.132230165466, 4203.600657937491, 4219.396629280854, 4222.894427639303, 4228.112096459717, 4228.936565951513, 5707.3721890592005, 5765.769369225226, 5877.9412205877825, 5972.9701351493395, 5981.401859814027, 4179.540250572434, 4294.26997410441, 4302.462192225972, 4306.784660766949, 4317.589828966243, 4368.213721530155, 4379.277419225856, 4391.714538554984, 4400.801963911632, 4412.955870441255, 4743.31221972812, 4746.840187714582, 4803.087413391444, 5015.6489045767175, 5042.873928151752, 5763.769449221984, 5781.335146458127, 5795.275590551181, 5808.215890854765, 5845.707714614337, 1486.8884833637303, 1510.358097808427, 1512.3345727879698, 1522.62695639569, 1533.5246073717183, 975.531744762518, 994.8259054665549, 998.1316254858175, 1041.234692501011, 1059.560282482761, 5509.862253443615, 5525.778968841201, 5531.363893152258, 5552.361478429952, 5590.413006634326, 5938.198343223696, 5941.435563621449, 5950.9830864166515, 5956.672383018976, 5979.402059793986, 5355.732213389393, 5582.8325150245555, 5617.269754931833, 5653.0, 5656.0, 3137.215696075981, 3167.619885613695, 3170.524421336818, 3174.507951267557, 3200.9359812803614, 5903.468687818117, 6137.84655383619, 6220.502359811204, 6227.190465239581, 6249.468795152463, 6128.479079278222, 6150.753969841246, 6159.876802463926, 6161.876762464815, 6166.537509686791, 6170.876582468415, 6194.4115309045565, 6202.72087756052, 6204.937950620436, 6206.968965155145, 5732.742026608729, 5930.940690593211, 5977.252843394575, 5979.4917432018765, 5992.041273396236, 5933.970330148321, 5951.702414879325, 5953.226080609493, 5959.344472107978, 6020.458158765732, 5967.552433567413, 6204.286507051746, 6215.720292586754, 6231.2522497300615, 6328.335524769833, 4456.420665313489, 4471.217536931089, 4493.0, 4515.503294637521, 4541.818327266872, 6072.423119803611, 6091.543134265036, 6093.908591371044, 6105.5420843437805, 6105.938940610537, 5684.3747187808485, 5873.530117590668, 5885.14665373527, 5891.528677705687, 5906.464319277105, 5837.474627283395, 5854.121881717606, 5860.736266868086, 5875.794347197828, 5939.732712027882, 5600.379686758359, 5762.164486149569, 5768.894353561004, 5839.941600583939, 5895.734691938787, 5832.950068987623, 5858.560607954335, 5866.2373891393845, 5878.353381128158, 5903.793367232126, 5739.569532285179, 5753.165790960204, 5783.855403614775, 5834.766609335579, 5863.7099838035565], "value": 4472.784665116193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.2259229474378, 804.9839003219936, 810.7324582887647, 829.3033851564686, 835.0522157351404, 15111.697766044672, 15918.0, 15948.920255398736, 15953.44162954297, 15989.280482378292, 14473.710525789478, 14726.116433014, 14729.705405891877, 14735.042222255546, 14740.262986850641, 700.8388070743732, 719.2843121094518, 818.582522913314, 830.0744669693279, 862.8144948835999, 866.2160744526183, 868.0971789339111, 875.5053394831931, 875.7022612311813, 877.5743764274322, 905.7418635688797, 906.3338446242007, 906.8231694819513, 907.314977192219, 907.4328544659588, 746.1792028768366, 759.2066290726204, 867.1068799136867, 882.8631562107884, 891.8617614269799, 637.3180696654581, 642.4154019841968, 642.495640921874, 642.5855323316434, 644.5520363347475, 14036.92981535096, 14054.92972535141, 14101.78847317291, 14117.78823317651, 14129.929350353284, 13582.728345433135, 14048.648783780385, 14134.36395362211, 14155.929220353935, 14162.504312349121, 14226.940546133903, 14258.28708564578, 14447.494337698236, 14795.40818367264, 15272.38910443581, 4588.954110458886, 4591.885202869921, 4616.492185859553, 4625.271090056849, 4819.975900120512, 85417.57291213566, 85766.0, 85868.57065714631, 86014.0, 86033.0, 10928.792414920314, 11376.829347559793, 11552.59565915189, 11706.531738730355, 11903.0, 21820.672689909665, 21847.235346763104, 21857.125714971542, 21910.45223869416, 22022.33932982013, 8427.95786021066, 8428.536430496259, 8440.0, 8448.281896038783, 8528.829423411496, 4768.833090841804, 4918.3360246367, 4918.385201849769, 5032.72320022395, 5037.924431133536, 3646.799426031541, 3739.8130093495215, 3746.0822098586023, 3775.981120094382, 3777.1690228149764, 605.33110912442, 641.6310621392718, 657.0800878769761, 670.6546128743701, 677.3598948993252, 713.5362014690493, 759.1232126893447, 777.6694904665608, 797.9666332099945, 802.6949759091625, 9645.71062868115, 9655.179309758609, 9901.801963960681, 10361.948190259, 10446.477676116316, 659.7690808217181, 713.6110819603329, 720.1861896057421, 748.0948052855996, 751.9511231769917, 4708.505606911225, 4783.402074740658, 4802.495737947535, 4825.9034819303415, 4899.926501102486, 113298.0, 132141.3392933029, 134893.3255333717, 134912.0, 136797.3160134193, 67618.32381676219, 95422.52288738512, 113421.0, 113840.43079784547, 114374.0, 97670.0, 98370.0, 99256.0, 100655.0, 102070.48964755278, 9005.279577633904, 9272.072792720945, 9328.224877913382, 9374.765630859283, 9476.57355418994, 47802.0, 49575.0, 50870.23694644509, 52207.0, 52798.2080268804, 77017.0, 78240.60879695565, 82521.17478825134, 84446.15553844383, 87462.56268718616, 13272.203667779964, 13310.600681979557, 13363.465461381436, 13568.864311357154, 14032.438702452197, 14684.632884177554, 14745.11529308245, 14924.403023879358, 15211.011284266488, 15462.458813941457, 14450.783238251224, 14559.636009099857, 14623.415063397344, 14987.400503980154, 15050.096994179947, 13277.269750163834, 13626.591202263948, 13949.302534873013, 14018.78971815444, 14760.852391475948, 13705.314734263047, 14108.78836817428, 14142.50501232452, 14754.336054877342, 14929.85070149328, 11093.389863557259, 11134.721631959266, 11334.94332528332, 11347.829782553434, 12302.077344199042, 10163.288569800043, 10179.541920613443, 10242.82472433548, 10376.552310036988, 10464.790704186025], "value": 7862.416016585395, "unit": "score"}], "name": "dromaeo_css", "value": 7549.6086534373035, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 110.0, 110.0, 112.0, 113.0, 110.0, 109.0, 112.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 115.0, 159.0, 116.0, 124.0, 118.0, 118.0, 158.0, 117.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 161.0, 161.0, 162.0, 162.0, 160.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 108.0, 75.0, 76.0, 77.0, 76.0, 96.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 114.0, 81.0, 81.0, 83.0, 99.0, 82.0, 81.0, 109.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 51.0, 49.0, 48.0, 49.0, 49.0, 49.0, 49.0, 48.0], "value": 48.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 60.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 72.0, 72.0, 78.0, 72.0, 73.0, 77.0, 74.0, 72.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 128.0, 125.0, 129.0, 119.0, 127.0, 118.0, 123.0, 176.0, 120.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 149.0, 155.0, 151.0, 152.0, 149.0, 151.0, 149.0, 151.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 62.0, 62.0, 62.0, 60.0, 62.0, 60.0, 61.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1452.3, "alertThreshold": 2.0}]} 10:16:53 INFO - Return code: 0 10:16:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:16:53 INFO - # TBPL SUCCESS # 10:16:53 INFO - Running post-action listener: _resource_record_post_action 10:16:53 INFO - [mozharness: 2016-09-19 17:16:53.583918Z] Finished run-tests step (success) 10:16:53 INFO - Running post-run listener: _resource_record_post_run 10:16:53 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 357380096; Read time: 28; Write time: 216660 10:16:53 INFO - TinderboxPrint: CPU usage
12.9% 10:16:53 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 10:16:53 INFO - TinderboxPrint: I/O write bytes / time
357,380,096 / 216,660 10:16:53 INFO - TinderboxPrint: CPU idle
8,267.8 (87.0%) 10:16:53 INFO - TinderboxPrint: CPU user
1,184.1 (12.5%) 10:16:53 INFO - TinderboxPrint: Swap in / out
0 / 0 10:16:53 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107544576; Read time: 0; Write time: 70460 10:16:53 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249835520; Read time: 16; Write time: 146200 10:16:54 INFO - Running post-run listener: _upload_blobber_files 10:16:54 INFO - Blob upload gear active. 10:16:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:16:54 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 10:16:54 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'] 10:16:54 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 10:16:54 INFO - (blobuploader) - INFO - Open directory for files ... 10:16:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:16:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:16:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:55 INFO - (blobuploader) - INFO - Done attempting. 10:16:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:16:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:16:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:59 INFO - (blobuploader) - INFO - Done attempting. 10:16:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:16:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:17:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:17:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:17:00 INFO - (blobuploader) - INFO - Done attempting. 10:17:00 INFO - (blobuploader) - INFO - Iteration through files over. 10:17:00 INFO - Return code: 0 10:17:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:17:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:17:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e1a22098af561b6883c5675c7ad64363923cbda93c051ecb94dbc8c7861f1e511178da8260349f1dfe2cb3ef7ef32a4d2c772b37ca6166ef05fac9aaeceb3b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a968542063c82d066e4bbb8e347cb8bdaa4bacf8203ef94e582d472f859ed49a42280f9a33cdb277d4e3d5740d94c7806c5a1a86f7de0e34ad96aa43d484981a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f042cfebf03ccfa9f00c8a233586c546d6427af6257043dac2c1de865109ca00b366e3e44420308c25d3ecf011b11b22d570dd2e90d9d78be9a44fdf0fe057f2"} 10:17:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:17:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:17:00 INFO - Contents: 10:17:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e1a22098af561b6883c5675c7ad64363923cbda93c051ecb94dbc8c7861f1e511178da8260349f1dfe2cb3ef7ef32a4d2c772b37ca6166ef05fac9aaeceb3b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a968542063c82d066e4bbb8e347cb8bdaa4bacf8203ef94e582d472f859ed49a42280f9a33cdb277d4e3d5740d94c7806c5a1a86f7de0e34ad96aa43d484981a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f042cfebf03ccfa9f00c8a233586c546d6427af6257043dac2c1de865109ca00b366e3e44420308c25d3ecf011b11b22d570dd2e90d9d78be9a44fdf0fe057f2"} 10:17:00 INFO - Running post-run listener: copy_logs_to_upload_dir 10:17:00 INFO - Copying logs to upload dir... 10:17:00 INFO - mkdir: /builds/slave/test/build/upload/logs 10:17:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.119078 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-19 10:17:00.086941) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 10:17:00.090433) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725 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/5e1a22098af561b6883c5675c7ad64363923cbda93c051ecb94dbc8c7861f1e511178da8260349f1dfe2cb3ef7ef32a4d2c772b37ca6166ef05fac9aaeceb3b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a968542063c82d066e4bbb8e347cb8bdaa4bacf8203ef94e582d472f859ed49a42280f9a33cdb277d4e3d5740d94c7806c5a1a86f7de0e34ad96aa43d484981a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f042cfebf03ccfa9f00c8a233586c546d6427af6257043dac2c1de865109ca00b366e3e44420308c25d3ecf011b11b22d570dd2e90d9d78be9a44fdf0fe057f2"} build_url:https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011292 build_url: 'https://queue.taskcluster.net/v1/task/CcHs2RXkSRWcsV0-JZjd9w/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/5e1a22098af561b6883c5675c7ad64363923cbda93c051ecb94dbc8c7861f1e511178da8260349f1dfe2cb3ef7ef32a4d2c772b37ca6166ef05fac9aaeceb3b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a968542063c82d066e4bbb8e347cb8bdaa4bacf8203ef94e582d472f859ed49a42280f9a33cdb277d4e3d5740d94c7806c5a1a86f7de0e34ad96aa43d484981a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f042cfebf03ccfa9f00c8a233586c546d6427af6257043dac2c1de865109ca00b366e3e44420308c25d3ecf011b11b22d570dd2e90d9d78be9a44fdf0fe057f2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 10:17:00.116165) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:17:00.116511) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474304185.584162-2127219725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:17:00.202143) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 10:17:00.202464) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 10:17:00.202859) ========= ========= Total master_lag: 0.18 =========