builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1474842538.82 results: success (0) buildid: 20160925123005 builduid: e838f4e5d5b647899c5091b3a343e152 revision: 17247ecdaf69a1412ffb327b165d190b1447d2e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:58.824152) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:58.824542) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:58.824804) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:58.889762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:58.890026) ========= 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=c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-25 15:28:59-- 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% 46.7M=0s 2016-09-25 15:28:59 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134258 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.038394) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.038662) ========= 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=c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021071 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.090929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.091247) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 17247ecdaf69a1412ffb327b165d190b1447d2e6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 17247ecdaf69a1412ffb327b165d190b1447d2e6 --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=c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-25 15:28:59,730 truncating revision to first 12 chars 2016-09-25 15:28:59,730 Setting DEBUG logging. 2016-09-25 15:28:59,730 attempt 1/10 2016-09-25 15:28:59,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/17247ecdaf69?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-25 15:29:00,178 unpacking tar archive at: mozilla-inbound-17247ecdaf69/testing/mozharness/ program finished with exit code 0 elapsedTime=0.798198 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.905310) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.905621) ========= script_repo_revision: 17247ecdaf69a1412ffb327b165d190b1447d2e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.906070) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.906439) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-25 15:28:59.941215) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-25 15:28:59.941511) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:29:00 INFO - MultiFileLogger online at 20160925 15:29:00 in /builds/slave/test 15:29:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 15:29:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:00 INFO - {'append_to_log': False, 15:29:00 INFO - 'base_work_dir': '/builds/slave/test', 15:29:00 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 15:29:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:00 INFO - 'branch': 'Mozilla-Inbound', 15:29:00 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:00 INFO - 'config_files': ('talos/linux_config.py',), 15:29:00 INFO - 'default_actions': ('clobber', 15:29:00 INFO - 'read-buildbot-config', 15:29:00 INFO - 'download-and-extract', 15:29:00 INFO - 'populate-webroot', 15:29:00 INFO - 'create-virtualenv', 15:29:00 INFO - 'install', 15:29:00 INFO - 'run-tests'), 15:29:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:00 INFO - 'download_minidump_stackwalk': True, 15:29:00 INFO - 'download_symbols': 'ondemand', 15:29:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:29:00 INFO - 'tooltool.py': '/tools/tooltool.py', 15:29:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:29:00 INFO - '/tools/misc-python/virtualenv.py')}, 15:29:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:00 INFO - 'installer_path': 'installer.exe', 15:29:00 INFO - 'log_level': 'info', 15:29:00 INFO - 'log_name': 'talos', 15:29:00 INFO - 'log_to_console': True, 15:29:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:29:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:29:00 INFO - 'opt_config_files': (), 15:29:00 INFO - 'pip_index': False, 15:29:00 INFO - 'sps_profile': False, 15:29:00 INFO - 'sps_profile_interval': 0, 15:29:00 INFO - 'suite': 'dromaeojs', 15:29:00 INFO - 'system_bits': '32', 15:29:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:00 INFO - 'title': 'talos-linux64-ix-004', 15:29:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:00 INFO - 'use_talos_json': True, 15:29:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:00 INFO - 'work_dir': 'build'} 15:29:00 INFO - [mozharness: 2016-09-25 22:29:00.684694Z] Running clobber step. 15:29:00 INFO - Running pre-action listener: _resource_record_pre_action 15:29:00 INFO - Running main action method: clobber 15:29:00 INFO - rmtree: /builds/slave/test/build 15:29:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:01 INFO - Running post-action listener: _resource_record_post_action 15:29:01 INFO - [mozharness: 2016-09-25 22:29:01.664652Z] Finished clobber step (success) 15:29:01 INFO - [mozharness: 2016-09-25 22:29:01.664751Z] Running read-buildbot-config step. 15:29:01 INFO - Running pre-action listener: _resource_record_pre_action 15:29:01 INFO - Running main action method: read_buildbot_config 15:29:01 INFO - Using buildbot properties: 15:29:01 INFO - { 15:29:01 INFO - "project": "", 15:29:01 INFO - "product": "firefox", 15:29:01 INFO - "script_repo_revision": "production", 15:29:01 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 15:29:01 INFO - "repository": "", 15:29:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 15:29:01 INFO - "buildid": "20160925123005", 15:29:01 INFO - "pgo_build": "True", 15:29:01 INFO - "basedir": "/builds/slave/test", 15:29:01 INFO - "buildnumber": 1599, 15:29:01 INFO - "slavename": "talos-linux64-ix-004", 15:29:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:29:01 INFO - "platform": "ubuntu64_hw", 15:29:01 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 15:29:01 INFO - "branch": "mozilla-inbound", 15:29:01 INFO - "repo_path": "integration/mozilla-inbound", 15:29:01 INFO - "revision": "17247ecdaf69a1412ffb327b165d190b1447d2e6", 15:29:01 INFO - "stage_platform": "linux64-pgo", 15:29:01 INFO - "builduid": "e838f4e5d5b647899c5091b3a343e152", 15:29:01 INFO - "slavebuilddir": "test" 15:29:01 INFO - } 15:29:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 15:29:01 INFO - Running post-action listener: _resource_record_post_action 15:29:01 INFO - [mozharness: 2016-09-25 22:29:01.667206Z] Finished read-buildbot-config step (success) 15:29:01 INFO - [mozharness: 2016-09-25 22:29:01.667295Z] Running download-and-extract step. 15:29:01 INFO - Running pre-action listener: _resource_record_pre_action 15:29:01 INFO - Running main action method: download_and_extract 15:29:01 INFO - mkdir: /builds/slave/test/build/tests 15:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:01 INFO - trying https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:29:01 INFO - Downloading https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:29:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:29:02 INFO - Downloaded 1361 bytes. 15:29:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:29:02 INFO - Using the following test package requirements: 15:29:02 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:29:02 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:29:02 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:29:02 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:02 INFO - u'jsshell-linux-x86_64.zip'], 15:29:02 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:29:02 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:29:02 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:29:02 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:29:02 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:29:02 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:29:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:29:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:29:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:29:02 INFO - Fetch https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:29:02 INFO - Expected file size: 21981792 15:29:07 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:29:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:29:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:29:07 INFO - Fetch https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:29:07 INFO - Expected file size: 21981792 15:29:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 15:29:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:29:12 INFO - Fetch https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:29:12 INFO - Expected file size: 14414057 15:29:13 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')]} 15:29:13 INFO - trying https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:29:13 INFO - Downloading https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:29:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:29:15 INFO - Downloaded 59707703 bytes. 15:29:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:29:15 INFO - mkdir: /builds/slave/test/properties 15:29:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:15 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:15 INFO - Contents: 15:29:15 INFO - build_url:https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:29:15 INFO - Running post-action listener: _resource_record_post_action 15:29:15 INFO - Running post-action listener: set_extra_try_arguments 15:29:15 INFO - [mozharness: 2016-09-25 22:29:15.649132Z] Finished download-and-extract step (success) 15:29:15 INFO - [mozharness: 2016-09-25 22:29:15.649360Z] Running populate-webroot step. 15:29:15 INFO - Running pre-action listener: _resource_record_pre_action 15:29:15 INFO - Running main action method: populate_webroot 15:29:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:29:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:29:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:29:15 INFO - '--tppagecycles', 15:29:15 INFO - '7'], 15:29:15 INFO - 'tests': ['tsvgm']}}, 15:29:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:29:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:29:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:15 INFO - 'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:15 INFO - 'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['damp', 'tps']}, 15:29:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:15 INFO - 'tests': ['damp', 'tps']}, 15:29:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['dromaeo_dom']}, 15:29:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['basic_compositor_video']}, 15:29:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:29:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['a11yr', 15:29:15 INFO - 'ts_paint', 15:29:15 INFO - 'tpaint', 15:29:15 INFO - 'sessionrestore', 15:29:15 INFO - 'sessionrestore_no_auto_restore', 15:29:15 INFO - 'tabpaint']}, 15:29:15 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:15 INFO - 'ts_paint', 15:29:15 INFO - 'tpaint', 15:29:15 INFO - 'sessionrestore', 15:29:15 INFO - 'sessionrestore_no_auto_restore', 15:29:15 INFO - 'tabpaint']}, 15:29:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:29:15 INFO - 'ts_paint', 15:29:15 INFO - 'tpaint', 15:29:15 INFO - 'sessionrestore', 15:29:15 INFO - 'sessionrestore_no_auto_restore', 15:29:15 INFO - 'tabpaint']}, 15:29:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:29:15 INFO - 'ts_paint', 15:29:15 INFO - 'tpaint', 15:29:15 INFO - 'sessionrestore', 15:29:15 INFO - 'sessionrestore_no_auto_restore', 15:29:15 INFO - 'tabpaint']}, 15:29:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['a11yr', 15:29:15 INFO - 'ts_paint', 15:29:15 INFO - 'tpaint', 15:29:15 INFO - 'sessionrestore', 15:29:15 INFO - 'sessionrestore_no_auto_restore', 15:29:15 INFO - 'tabpaint']}, 15:29:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['a11yr', 15:29:15 INFO - 'ts_paint', 15:29:15 INFO - 'tpaint', 15:29:15 INFO - 'sessionrestore', 15:29:15 INFO - 'sessionrestore_no_auto_restore', 15:29:15 INFO - 'tabpaint']}, 15:29:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['tsvgx', 15:29:15 INFO - 'tsvgr_opacity', 15:29:15 INFO - 'tart', 15:29:15 INFO - 'tscrollx', 15:29:15 INFO - 'cart']}, 15:29:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:15 INFO - 'tsvgr_opacity', 15:29:15 INFO - 'tart', 15:29:15 INFO - 'tscrollx', 15:29:15 INFO - 'cart']}, 15:29:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:15 INFO - 'talos_options': ['--disable-e10s'], 15:29:15 INFO - 'tests': ['tp5o']}, 15:29:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:15 INFO - 'tests': ['tp5o']}, 15:29:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:15 INFO - 'talos_options': ['--disable-e10s', 15:29:15 INFO - '--xperf_path', 15:29:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:15 INFO - 'tests': ['tp5n']}, 15:29:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:15 INFO - 'talos_options': ['--xperf_path', 15:29:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:15 INFO - 'tests': ['tp5n']}}, 15:29:15 INFO - 'talos.zip': {'path': '', 15:29:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:29:15 INFO - Running post-action listener: _resource_record_post_action 15:29:15 INFO - [mozharness: 2016-09-25 22:29:15.665588Z] Finished populate-webroot step (success) 15:29:15 INFO - [mozharness: 2016-09-25 22:29:15.665677Z] Running create-virtualenv step. 15:29:15 INFO - Running pre-action listener: _resource_record_pre_action 15:29:15 INFO - Running main action method: create_virtualenv 15:29:15 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:15 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 15:29:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:29:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:15 INFO - Using real prefix '/usr' 15:29:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:29:16 INFO - Installing distribute.............................................................................................................................................................................................done. 15:29:18 INFO - Installing pip.................done. 15:29:18 INFO - Return code: 0 15:29:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:29:18 INFO - Using env: {'DISPLAY': ':0', 15:29:18 INFO - 'HOME': '/home/cltbld', 15:29:18 INFO - 'LANG': 'en_US.UTF-8', 15:29:18 INFO - 'LANGUAGE': 'en_US:en', 15:29:18 INFO - 'LOGNAME': 'cltbld', 15:29:18 INFO - 'MAIL': '/var/mail/cltbld', 15:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:18 INFO - 'MOZ_NO_REMOTE': '1', 15:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:18 INFO - 'NO_EM_RESTART': '1', 15:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:18 INFO - 'PWD': '/builds/slave/test', 15:29:18 INFO - 'SHELL': '/bin/bash', 15:29:18 INFO - 'SHLVL': '1', 15:29:18 INFO - 'TERM': 'linux', 15:29:18 INFO - 'TMOUT': '86400', 15:29:18 INFO - 'USER': 'cltbld', 15:29:18 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:18 INFO - '_': '/tools/buildbot/bin/python'} 15:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:18 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 15:29:18 INFO - Cleaning up... 15:29:18 INFO - Return code: 0 15:29:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:29:18 INFO - Using env: {'DISPLAY': ':0', 15:29:18 INFO - 'HOME': '/home/cltbld', 15:29:18 INFO - 'LANG': 'en_US.UTF-8', 15:29:18 INFO - 'LANGUAGE': 'en_US:en', 15:29:18 INFO - 'LOGNAME': 'cltbld', 15:29:18 INFO - 'MAIL': '/var/mail/cltbld', 15:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:18 INFO - 'MOZ_NO_REMOTE': '1', 15:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:18 INFO - 'NO_EM_RESTART': '1', 15:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:18 INFO - 'PWD': '/builds/slave/test', 15:29:18 INFO - 'SHELL': '/bin/bash', 15:29:18 INFO - 'SHLVL': '1', 15:29:18 INFO - 'TERM': 'linux', 15:29:18 INFO - 'TMOUT': '86400', 15:29:18 INFO - 'USER': 'cltbld', 15:29:18 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:18 INFO - '_': '/tools/buildbot/bin/python'} 15:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:18 INFO - Downloading/unpacking psutil>=3.1.1 15:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:29:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:20 INFO - Installing collected packages: psutil 15:29:20 INFO - Running setup.py install for psutil 15:29:20 INFO - building 'psutil._psutil_linux' extension 15:29:20 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 15:29:20 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 15:29:20 INFO - building 'psutil._psutil_posix' extension 15:29:20 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 15:29:21 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 15:29:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:21 INFO - Successfully installed psutil 15:29:21 INFO - Cleaning up... 15:29:21 INFO - Return code: 0 15:29:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:29:21 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')]} 15:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:21 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')]} 15:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:21 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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:21 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 15:29:21 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 15:29:21 INFO - Using env: {'DISPLAY': ':0', 15:29:21 INFO - 'HOME': '/home/cltbld', 15:29:21 INFO - 'LANG': 'en_US.UTF-8', 15:29:21 INFO - 'LANGUAGE': 'en_US:en', 15:29:21 INFO - 'LOGNAME': 'cltbld', 15:29:21 INFO - 'MAIL': '/var/mail/cltbld', 15:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:21 INFO - 'MOZ_NO_REMOTE': '1', 15:29:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:21 INFO - 'NO_EM_RESTART': '1', 15:29:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:21 INFO - 'PWD': '/builds/slave/test', 15:29:21 INFO - 'SHELL': '/bin/bash', 15:29:21 INFO - 'SHLVL': '1', 15:29:21 INFO - 'TERM': 'linux', 15:29:21 INFO - 'TMOUT': '86400', 15:29:21 INFO - 'USER': 'cltbld', 15:29:21 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:21 INFO - '_': '/tools/buildbot/bin/python'} 15:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:29:21 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 15:29:21 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 15:29:21 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 15:29:21 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 15:29:21 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 15:29:21 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 15:29:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:29:23 INFO - Installing collected packages: mozsystemmonitor 15:29:23 INFO - Running setup.py install for mozsystemmonitor 15:29:23 INFO - Successfully installed mozsystemmonitor 15:29:23 INFO - Cleaning up... 15:29:23 INFO - Return code: 0 15:29:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:29:23 INFO - Using env: {'DISPLAY': ':0', 15:29:23 INFO - 'HOME': '/home/cltbld', 15:29:23 INFO - 'LANG': 'en_US.UTF-8', 15:29:23 INFO - 'LANGUAGE': 'en_US:en', 15:29:23 INFO - 'LOGNAME': 'cltbld', 15:29:23 INFO - 'MAIL': '/var/mail/cltbld', 15:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:23 INFO - 'MOZ_NO_REMOTE': '1', 15:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:23 INFO - 'NO_EM_RESTART': '1', 15:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:23 INFO - 'PWD': '/builds/slave/test', 15:29:23 INFO - 'SHELL': '/bin/bash', 15:29:23 INFO - 'SHLVL': '1', 15:29:23 INFO - 'TERM': 'linux', 15:29:23 INFO - 'TMOUT': '86400', 15:29:23 INFO - 'USER': 'cltbld', 15:29:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:23 INFO - '_': '/tools/buildbot/bin/python'} 15:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:23 INFO - Downloading/unpacking jsonschema==2.5.1 15:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:29:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:26 INFO - Downloading functools32-3.2.3-2.tar.gz 15:29:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:29:26 INFO - warning: no files found matching '*.txt' 15:29:26 INFO - no previously-included directories found matching 'build' 15:29:26 INFO - no previously-included directories found matching 'dist' 15:29:26 INFO - no previously-included directories found matching '.git*' 15:29:26 INFO - Installing collected packages: jsonschema, functools32 15:29:26 INFO - Running setup.py install for functools32 15:29:26 INFO - warning: no files found matching '*.txt' 15:29:26 INFO - no previously-included directories found matching 'build' 15:29:26 INFO - no previously-included directories found matching 'dist' 15:29:26 INFO - no previously-included directories found matching '.git*' 15:29:26 INFO - Successfully installed jsonschema functools32 15:29:26 INFO - Cleaning up... 15:29:26 INFO - Return code: 0 15:29:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:29:26 INFO - Using env: {'DISPLAY': ':0', 15:29:26 INFO - 'HOME': '/home/cltbld', 15:29:26 INFO - 'LANG': 'en_US.UTF-8', 15:29:26 INFO - 'LANGUAGE': 'en_US:en', 15:29:26 INFO - 'LOGNAME': 'cltbld', 15:29:26 INFO - 'MAIL': '/var/mail/cltbld', 15:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:26 INFO - 'MOZ_NO_REMOTE': '1', 15:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:26 INFO - 'NO_EM_RESTART': '1', 15:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:26 INFO - 'PWD': '/builds/slave/test', 15:29:26 INFO - 'SHELL': '/bin/bash', 15:29:26 INFO - 'SHLVL': '1', 15:29:26 INFO - 'TERM': 'linux', 15:29:26 INFO - 'TMOUT': '86400', 15:29:26 INFO - 'USER': 'cltbld', 15:29:26 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:26 INFO - '_': '/tools/buildbot/bin/python'} 15:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:29:26 INFO - Cleaning up... 15:29:26 INFO - Return code: 0 15:29:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:29:26 INFO - Using env: {'DISPLAY': ':0', 15:29:26 INFO - 'HOME': '/home/cltbld', 15:29:26 INFO - 'LANG': 'en_US.UTF-8', 15:29:26 INFO - 'LANGUAGE': 'en_US:en', 15:29:26 INFO - 'LOGNAME': 'cltbld', 15:29:26 INFO - 'MAIL': '/var/mail/cltbld', 15:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:26 INFO - 'MOZ_NO_REMOTE': '1', 15:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:26 INFO - 'NO_EM_RESTART': '1', 15:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:26 INFO - 'PWD': '/builds/slave/test', 15:29:26 INFO - 'SHELL': '/bin/bash', 15:29:26 INFO - 'SHLVL': '1', 15:29:26 INFO - 'TERM': 'linux', 15:29:26 INFO - 'TMOUT': '86400', 15:29:26 INFO - 'USER': 'cltbld', 15:29:26 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:26 INFO - '_': '/tools/buildbot/bin/python'} 15:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:26 INFO - Downloading/unpacking blobuploader==1.2.4 15:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:28 INFO - Downloading blobuploader-1.2.4.tar.gz 15:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:29:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:29:28 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 15:29:28 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 15:29:28 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 15:29:28 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 15:29:28 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 15:29:28 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 15:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:29:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:29:29 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 15:29:29 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 15:29:29 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 15:29:29 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 15:29:29 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 15:29:29 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 15:29:29 INFO - Downloading docopt-0.6.1.tar.gz 15:29:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:29:29 INFO - Installing collected packages: blobuploader, requests, docopt 15:29:29 INFO - Running setup.py install for blobuploader 15:29:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:29:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:29:29 INFO - Running setup.py install for requests 15:29:29 INFO - Running setup.py install for docopt 15:29:29 INFO - Successfully installed blobuploader requests docopt 15:29:29 INFO - Cleaning up... 15:29:29 INFO - Return code: 0 15:29:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:29 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')]} 15:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:30 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 15:29:30 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 15:29:30 INFO - Using env: {'DISPLAY': ':0', 15:29:30 INFO - 'HOME': '/home/cltbld', 15:29:30 INFO - 'LANG': 'en_US.UTF-8', 15:29:30 INFO - 'LANGUAGE': 'en_US:en', 15:29:30 INFO - 'LOGNAME': 'cltbld', 15:29:30 INFO - 'MAIL': '/var/mail/cltbld', 15:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:30 INFO - 'MOZ_NO_REMOTE': '1', 15:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:30 INFO - 'NO_EM_RESTART': '1', 15:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:30 INFO - 'PWD': '/builds/slave/test', 15:29:30 INFO - 'SHELL': '/bin/bash', 15:29:30 INFO - 'SHLVL': '1', 15:29:30 INFO - 'TERM': 'linux', 15:29:30 INFO - 'TMOUT': '86400', 15:29:30 INFO - 'USER': 'cltbld', 15:29:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:30 INFO - '_': '/tools/buildbot/bin/python'} 15:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:30 INFO - Running setup.py (path:/tmp/pip-HiLLb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:30 INFO - Running setup.py (path:/tmp/pip-0dGD3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:30 INFO - Running setup.py (path:/tmp/pip-2PWR25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:30 INFO - Running setup.py (path:/tmp/pip-WESOv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:30 INFO - Running setup.py (path:/tmp/pip-TnfrmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:30 INFO - Running setup.py (path:/tmp/pip-HJBtFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:30 INFO - Running setup.py (path:/tmp/pip-Qj1dx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:30 INFO - Running setup.py (path:/tmp/pip-DBOhO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:30 INFO - Running setup.py (path:/tmp/pip-wrScJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:30 INFO - Running setup.py (path:/tmp/pip-nt6aas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:30 INFO - Running setup.py (path:/tmp/pip-my8pgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:31 INFO - Running setup.py (path:/tmp/pip-tghgdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:31 INFO - Running setup.py (path:/tmp/pip-rxr1xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:31 INFO - Running setup.py (path:/tmp/pip-cYVSnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:31 INFO - Running setup.py (path:/tmp/pip-EjXQMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:31 INFO - Running setup.py (path:/tmp/pip-cLPvLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:31 INFO - Running setup.py (path:/tmp/pip-1JX8S_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:29:31 INFO - Running setup.py install for manifestparser 15:29:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:29:31 INFO - Running setup.py install for mozcrash 15:29:31 INFO - Running setup.py install for mozdebug 15:29:31 INFO - Running setup.py install for mozdevice 15:29:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:29:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:29:31 INFO - Running setup.py install for mozfile 15:29:31 INFO - Running setup.py install for mozhttpd 15:29:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Running setup.py install for mozinfo 15:29:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Running setup.py install for mozInstall 15:29:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Running setup.py install for mozleak 15:29:32 INFO - Running setup.py install for mozlog 15:29:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Running setup.py install for moznetwork 15:29:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Running setup.py install for mozprocess 15:29:32 INFO - Running setup.py install for mozprofile 15:29:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Running setup.py install for mozrunner 15:29:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:29:32 INFO - Running setup.py install for mozscreenshot 15:29:33 INFO - Running setup.py install for moztest 15:29:33 INFO - Running setup.py install for mozversion 15:29:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:29:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:29:33 INFO - Cleaning up... 15:29:33 INFO - Return code: 0 15:29:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:33 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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:33 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 15:29:33 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 15:29:33 INFO - Using env: {'DISPLAY': ':0', 15:29:33 INFO - 'HOME': '/home/cltbld', 15:29:33 INFO - 'LANG': 'en_US.UTF-8', 15:29:33 INFO - 'LANGUAGE': 'en_US:en', 15:29:33 INFO - 'LOGNAME': 'cltbld', 15:29:33 INFO - 'MAIL': '/var/mail/cltbld', 15:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:33 INFO - 'MOZ_NO_REMOTE': '1', 15:29:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:33 INFO - 'NO_EM_RESTART': '1', 15:29:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:33 INFO - 'PWD': '/builds/slave/test', 15:29:33 INFO - 'SHELL': '/bin/bash', 15:29:33 INFO - 'SHLVL': '1', 15:29:33 INFO - 'TERM': 'linux', 15:29:33 INFO - 'TMOUT': '86400', 15:29:33 INFO - 'USER': 'cltbld', 15:29:33 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:33 INFO - '_': '/tools/buildbot/bin/python'} 15:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:33 INFO - Running setup.py (path:/tmp/pip-UGnfbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:33 INFO - Running setup.py (path:/tmp/pip-l3ssJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:33 INFO - Running setup.py (path:/tmp/pip-Q2apbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:33 INFO - Running setup.py (path:/tmp/pip-o42Az5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:33 INFO - Running setup.py (path:/tmp/pip-8ge2EA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:33 INFO - Running setup.py (path:/tmp/pip-Mk7Cux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:33 INFO - Running setup.py (path:/tmp/pip-O0gp5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:33 INFO - Running setup.py (path:/tmp/pip-raz9Ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:34 INFO - Running setup.py (path:/tmp/pip-gFDlX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:34 INFO - Running setup.py (path:/tmp/pip-WAi7ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:34 INFO - Running setup.py (path:/tmp/pip-h8sxCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:34 INFO - Running setup.py (path:/tmp/pip-QfsZcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:34 INFO - Running setup.py (path:/tmp/pip-Fe7_m6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:34 INFO - Running setup.py (path:/tmp/pip-JXTN5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:34 INFO - Running setup.py (path:/tmp/pip-53bKBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:34 INFO - Running setup.py (path:/tmp/pip-pSaBn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:34 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)) 15:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:34 INFO - Running setup.py (path:/tmp/pip-vHSLaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:34 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)) 15:29:34 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)) 15:29:34 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)) 15:29:34 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)) 15:29:34 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)) 15:29:34 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)) 15:29:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:29:34 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 15:29:34 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 15:29:34 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 15:29:34 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 15:29:34 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 15:29:34 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 15:29:36 INFO - Downloading blessings-1.6.tar.gz 15:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:29:36 INFO - Installing collected packages: blessings 15:29:36 INFO - Running setup.py install for blessings 15:29:36 INFO - Successfully installed blessings 15:29:36 INFO - Cleaning up... 15:29:36 INFO - Return code: 0 15:29:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:29:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:37 INFO - Reading from file tmpfile_stdout 15:29:37 INFO - Current package versions: 15:29:37 INFO - argparse == 1.2.1 15:29:37 INFO - blessings == 1.6 15:29:37 INFO - blobuploader == 1.2.4 15:29:37 INFO - docopt == 0.6.1 15:29:37 INFO - functools32 == 3.2.3-2 15:29:37 INFO - jsonschema == 2.5.1 15:29:37 INFO - manifestparser == 1.1 15:29:37 INFO - mozInstall == 1.12 15:29:37 INFO - mozcrash == 0.17 15:29:37 INFO - mozdebug == 0.1 15:29:37 INFO - mozdevice == 0.48 15:29:37 INFO - mozfile == 1.2 15:29:37 INFO - mozhttpd == 0.7 15:29:37 INFO - mozinfo == 0.9 15:29:37 INFO - mozleak == 0.1 15:29:37 INFO - mozlog == 3.4 15:29:37 INFO - moznetwork == 0.27 15:29:37 INFO - mozprocess == 0.23 15:29:37 INFO - mozprofile == 0.28 15:29:37 INFO - mozrunner == 6.12 15:29:37 INFO - mozscreenshot == 0.1 15:29:37 INFO - mozsystemmonitor == 0.3 15:29:37 INFO - moztest == 0.7 15:29:37 INFO - mozversion == 1.4 15:29:37 INFO - psutil == 3.1.1 15:29:37 INFO - requests == 1.2.3 15:29:37 INFO - wsgiref == 0.1.2 15:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:37 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 15:29:37 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 15:29:37 INFO - Using env: {'DISPLAY': ':0', 15:29:37 INFO - 'HOME': '/home/cltbld', 15:29:37 INFO - 'LANG': 'en_US.UTF-8', 15:29:37 INFO - 'LANGUAGE': 'en_US:en', 15:29:37 INFO - 'LOGNAME': 'cltbld', 15:29:37 INFO - 'MAIL': '/var/mail/cltbld', 15:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:37 INFO - 'MOZ_NO_REMOTE': '1', 15:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:37 INFO - 'NO_EM_RESTART': '1', 15:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:37 INFO - 'PWD': '/builds/slave/test', 15:29:37 INFO - 'SHELL': '/bin/bash', 15:29:37 INFO - 'SHLVL': '1', 15:29:37 INFO - 'TERM': 'linux', 15:29:37 INFO - 'TMOUT': '86400', 15:29:37 INFO - 'USER': 'cltbld', 15:29:37 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:37 INFO - '_': '/tools/buildbot/bin/python'} 15:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 INFO - Cleaning up... 15:29:37 INFO - Return code: 0 15:29:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 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 0x7f36e72dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a84ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', '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 15:29:37 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 15:29:37 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 15:29:37 INFO - Using env: {'DISPLAY': ':0', 15:29:37 INFO - 'HOME': '/home/cltbld', 15:29:37 INFO - 'LANG': 'en_US.UTF-8', 15:29:37 INFO - 'LANGUAGE': 'en_US:en', 15:29:37 INFO - 'LOGNAME': 'cltbld', 15:29:37 INFO - 'MAIL': '/var/mail/cltbld', 15:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:37 INFO - 'MOZ_NO_REMOTE': '1', 15:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:37 INFO - 'NO_EM_RESTART': '1', 15:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:37 INFO - 'PWD': '/builds/slave/test', 15:29:37 INFO - 'SHELL': '/bin/bash', 15:29:37 INFO - 'SHLVL': '1', 15:29:37 INFO - 'TERM': 'linux', 15:29:37 INFO - 'TMOUT': '86400', 15:29:37 INFO - 'USER': 'cltbld', 15:29:37 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:37 INFO - '_': '/tools/buildbot/bin/python'} 15:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:29:37 INFO - Cleaning up... 15:29:37 INFO - Return code: 0 15:29:37 INFO - Running post-action listener: _resource_record_post_action 15:29:37 INFO - Running post-action listener: _start_resource_monitoring 15:29:37 INFO - Starting resource monitoring. 15:29:37 INFO - [mozharness: 2016-09-25 22:29:37.547843Z] Finished create-virtualenv step (success) 15:29:37 INFO - [mozharness: 2016-09-25 22:29:37.548295Z] Running install step. 15:29:37 INFO - Running pre-action listener: _resource_record_pre_action 15:29:37 INFO - Running main action method: install 15:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:37 INFO - Reading from file tmpfile_stdout 15:29:37 INFO - Detecting whether we're running mozinstall >=1.0... 15:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:29:37 INFO - Reading from file tmpfile_stdout 15:29:37 INFO - Output received: 15:29:37 INFO - Usage: mozinstall [options] installer 15:29:37 INFO - Options: 15:29:37 INFO - -h, --help show this help message and exit 15:29:37 INFO - -d DEST, --destination=DEST 15:29:37 INFO - Directory to install application into. [default: 15:29:37 INFO - "/builds/slave/test"] 15:29:37 INFO - --app=APP Application being installed. [default: firefox] 15:29:37 INFO - mkdir: /builds/slave/test/build/application 15:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:29:54 INFO - Reading from file tmpfile_stdout 15:29:54 INFO - Output received: 15:29:54 INFO - /builds/slave/test/build/application/firefox/firefox 15:29:54 INFO - Running post-action listener: _resource_record_post_action 15:29:54 INFO - [mozharness: 2016-09-25 22:29:54.858335Z] Finished install step (success) 15:29:54 INFO - [mozharness: 2016-09-25 22:29:54.858578Z] Running run-tests step. 15:29:54 INFO - Running pre-action listener: _resource_record_pre_action 15:29:54 INFO - Running main action method: run_tests 15:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:29:54 INFO - Python 2.7.3 15:29:54 INFO - Return code: 0 15:29:54 INFO - grabbing minidump binary from tooltool 15:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:54 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 0x1a81f60>, '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 0x1a82d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a831d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:29:54 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 15:29:54 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 15:29:54 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 15:29:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:29:54 INFO - Return code: 0 15:29:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:29:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:29:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:29:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:29:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 15:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 15:29:54 INFO - Using env: {'DISPLAY': ':0', 15:29:54 INFO - 'HOME': '/home/cltbld', 15:29:54 INFO - 'LANG': 'en_US.UTF-8', 15:29:54 INFO - 'LANGUAGE': 'en_US:en', 15:29:54 INFO - 'LOGNAME': 'cltbld', 15:29:54 INFO - 'MAIL': '/var/mail/cltbld', 15:29:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:29:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:54 INFO - 'MOZ_NO_REMOTE': '1', 15:29:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:54 INFO - 'NO_EM_RESTART': '1', 15:29:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:54 INFO - 'PWD': '/builds/slave/test', 15:29:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:29:54 INFO - 'SHELL': '/bin/bash', 15:29:54 INFO - 'SHLVL': '1', 15:29:54 INFO - 'TERM': 'linux', 15:29:54 INFO - 'TMOUT': '86400', 15:29:54 INFO - 'USER': 'cltbld', 15:29:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510', 15:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:54 INFO - '_': '/tools/buildbot/bin/python'} 15:29:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 15:29:55 INFO - mozversion application_buildid: 20160925123005 15:29:55 INFO - mozversion application_changeset: 17247ecdaf69a1412ffb327b165d190b1447d2e6 15:29:55 INFO - mozversion application_display_name: Nightly 15:29:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:29:55 INFO - mozversion application_name: Firefox 15:29:55 INFO - mozversion application_remotingname: firefox 15:29:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:29:55 INFO - mozversion application_vendor: Mozilla 15:29:55 INFO - mozversion application_version: 52.0a1 15:29:55 INFO - mozversion platform_buildid: 20160925123005 15:29:55 INFO - mozversion platform_changeset: 17247ecdaf69a1412ffb327b165d190b1447d2e6 15:29:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:29:55 INFO - mozversion platform_version: 52.0a1 15:29:55 INFO - using testdate: 1474842595 15:29:55 INFO - actual date: 1474842595 15:29:55 INFO - starting webserver on 'localhost:35610' 15:29:55 INFO - SUITE-START | Running 2 tests 15:29:55 INFO - TEST-START | dromaeo_css 15:29:55 INFO - operating with platform_type : linux_ 15:29:55 INFO - Initialising browser for dromaeo_css test... 15:29:55 INFO - TEST-INFO | started process 6228 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvvO2vJ/profile http://localhost:35610/getInfo.html) 15:29:59 INFO - PROCESS | 6228 | __metrics Screen width/height:1600/1200 15:29:59 INFO - PROCESS | 6228 | colorDepth:24 15:29:59 INFO - PROCESS | 6228 | Browser inner width/height: 1024/697 15:29:59 INFO - PROCESS | 6228 | __metrics 15:30:00 INFO - PROCESS | 6228 | 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" 15:30:00 INFO - PROCESS | 6228 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:30:00 INFO - PROCESS | 6228 | console.error: PushService: 15:30:00 INFO - PROCESS | 6228 | stateChangeProcessEnqueue: Error transitioning state 15:30:00 INFO - PROCESS | 6228 | UnknownError 15:30:01 INFO - TEST-INFO | 6228: exit 0 15:30:01 INFO - Browser initialized. 15:30:01 INFO - Running cycle 1/1 for dromaeo_css test... 15:30:01 INFO - TEST-INFO | started process 6310 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvvO2vJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:13 INFO - PROCESS | 6310 | RSS: Main: 173793280 15:30:13 INFO - PROCESS | 6310 | 15:31:04 INFO - PROCESS | 6310 | 1474842664770 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] 15:33:14 INFO - PROCESS | 6310 | Cycle 1(1): loaded http://localhost:35610/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35610/tests/dromaeo/cssquery-ext.html) 15:33:15 INFO - PROCESS | 6310 | RSS: Main: 310870016 15:33:15 INFO - PROCESS | 6310 | 15:36:17 INFO - PROCESS | 6310 | Cycle 1(1): loaded http://localhost:35610/tests/dromaeo/cssquery-ext.html (next: http://localhost:35610/tests/dromaeo/cssquery-jquery.html) 15:36:18 INFO - PROCESS | 6310 | RSS: Main: 280002560 15:36:18 INFO - PROCESS | 6310 | 15:39:20 INFO - PROCESS | 6310 | Cycle 1(1): loaded http://localhost:35610/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35610/tests/dromaeo/cssquery-mootools.html) 15:39:20 INFO - PROCESS | 6310 | RSS: Main: 257482752 15:39:20 INFO - PROCESS | 6310 | 15:42:22 INFO - PROCESS | 6310 | Cycle 1(1): loaded http://localhost:35610/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35610/tests/dromaeo/cssquery-prototype.html) 15:42:23 INFO - PROCESS | 6310 | RSS: Main: 215588864 15:42:23 INFO - PROCESS | 6310 | 15:45:26 INFO - PROCESS | 6310 | Cycle 1(1): loaded http://localhost:35610/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35610/tests/dromaeo/cssquery-yui.html) 15:45:26 INFO - PROCESS | 6310 | RSS: Main: 216064000 15:45:26 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | Cycle 1(1): loaded http://localhost:35610/tests/dromaeo/cssquery-yui.html (next: http://localhost:35610/tests/dromaeo/cssquery-dojo.html) 15:48:29 INFO - PROCESS | 6310 | RSS: Main: 309977088 15:48:29 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | __start_tp_report 15:48:29 INFO - PROCESS | 6310 | _x_x_mozilla_page_load 15:48:29 INFO - PROCESS | 6310 | _x_x_mozilla_page_load_details 15:48:29 INFO - PROCESS | 6310 | |i|pagename|runs| 15:48:29 INFO - PROCESS | 6310 | |0;dojo.html;2998.9999999999995;3037.9088627341175;3040.2095455181648;3070.2324418895278;3088.2588178837086;5139.383274007115;5222.3472065991755;5243;5330.706811125391;5377.059014672432;5175.171972484403;5253.343332083489;5261.158214685651;5315.14957606783;5397.54120899724;4819.566239038486;4847.272909063633;4854.854354369375;4884.804607815683;4975.850724478271;4897.191963326047;4915.5821755150855;4941.851744447672;4950.975245123769;5000.52495012975;4735.289706544011;4765.761711914425;4776.235802271654;4825.227963525819;4928.43323017854;4824.396950381202;4841.394825646794;4912.828051018233;4931.80272789088;4932.013597280522;4772.188727916263;4805.038992201574;4812.278158276286;4829.106615276185;4870.366852309213;5116.667416617908;5311.681299122065;5320.76056577455;5323.760430780624;5376.838694839161;5463.644863083927;5485.177223416479;5501.9174712379345;5546;5591.6365436246515;5637.774489020434;5701.258836351248;5714.057180565202;5718.142278658193;5718.170865224561;5280.524752772192;5281.973590132025;5282.022825777205;5282.2076688496645;5291.3385826771655;5647.576431767634;5705.172749951317;5709.486146246858;5712.685802280831;5717.714114294352;5434.945650543444;5484.314460692413;5507.586930980113;5541.612087153861;5636.830895073154;5640.8871822563315;5641.802536911188;5644.8871022579315;5649.350324712628;5650.74571644277;5542.307211598551;5612.915806262909;5619.690916999522;5624.91562626561;5666.093425051972;5448.101063324507;5464.23500709901;5559.138333558305;5602.0196465619165;5640.32316122068;4412.271975124133;4417.271150260235;4418.050119224382;4459.7993090310365;4466.642668586505;3907.980460097738;3919.980400097981;3920.1571662092215;3961.465202197724;3962.425448309979;5234.502722241381;5270.051390749702;5311.601629877775;5343.7862485500145;5389.595780316492;5705.22979397776;5709.571782116358;5712.143178523172;5756.3380211276235;5767.423257674283;5313.973430132824;5353.089974704231;5362.785488580413;5363.436839131913;5369.892602147936;4815.855524334275;4851.539103785135;4856.854294371175;4924.4090709115135;4970.2793095001025;5867.237259156283;5915.64506129625;5918.556108291809;5984.8803023940145;5985.042393217064;5660.603757736919;5683;5705.828825135252;5721.971390143022;5723.141528770718;5728.283964504437;5768.567357448124;5904.202932604216;5916;5918.408159184047;5517.889642207214;5523.558115350682;5532.917006244829;5583.776648934159;5634.887302254014;5580.274564306615;5606.467385598362;5611.5230205433;5649.5762817789655;5666.263385759825;5398.865028374322;5471.917921231265;5491.697956612425;5493.7802487900035;5544.63959842609;5431.34823821144;5466.2620546227345;5492.368377636623;5547.5007249347755;5623.0721930880945;5473.698946557977;5488.615796894339;5491.066518691912;5491.972540137273;5495.093309603871;5542.196381524737;5597.384287728428;5599.1041433370465;5619.578531610227;5644.435556444323;5346.679199248058;5357.062514060101;5386.35363756336;5447.346318441654;5476.945230547643 15:48:29 INFO - PROCESS | 6310 | |1;ext.html;552.6962217803253;583.2825624481887;601.3762840331104;606.4966078155131;615.5844804756789;32037.839810800942;41949.79025104874;43129.35305970413;43203.78398108013;46975;45528.317075243816;45948.540514594846;45971.54028459715;46074.76962615182;46079.308810367875;8975.775605609846;9135.406198597104;9151.633934642607;9152.542372881362;9191.080891910822;9098.272138228958;9166.129217724305;9190.21883139934;9194.540272986324;9198.678046268382;9176.632934682604;9217.631294748235;9229.6308147674;9234.307426942973;9242.121998410174;9127.406718563272;9168.266538676957;9176.082391760838;9185.770355741126;9191.35660503765;8938.4190027648;8978.685745998924;8993.415427997224;9002.324825638038;9028.8645670315;42146;42545.149097017886;42827.35758963587;42960.570394295966;43224.56775432237;3486.459598762196;3589.174489867345;3594.0475773919934;3859.14326620404;4059.147579008412;31587.321317588565;32153.35693286121;32368.514472282935;32382.190445238823;32723.6727632723;4303.33298338758;4343.891402714926;4386.649068074546;4472.84345047925;4620.468646105707;360015;360393.9606039401;362506;362537.18731406436;363301;58698;58806.7059664699;59002;59133.40866591365;59405.40594594085;62634.373656262855;62640;63053.684731576046;63071.684641576496;63086.36913630897;14142.151470911778;14212.005159638933;14265.144091354552;14267.85732142686;14281.78577321341;11243.100551955824;11244.100471962223;11278.097752179807;11280.887191128148;11283.54865805375;15625.42180540221;17562.385316514115;17617.735733964;17789.822101779075;17847.910760446375;5234.319538459976;5828.708564571754;5954.255718035246;5973.641581505037;5983.282006159288;941.3598752848129;986.5018165826211;991.8512223166439;1019.3374306700704;1024.574801457387;31548.842255789034;32848;32858.3428331432;32888.50667239993;33081.50377744336;1056.9101626361846;1090.4166271044953;1107.6953837694537;1146.380954284677;1212.1938910624513;16160.434384796476;16953.491395258163;17125.48623541296;17211.139443027798;17227.655446891244;555870;563618;564267;564376;566387;58236.70881645649;58565.41434585685;58602.70698646479;58858;58873.41126588765;1193.8089905615059;1233.8149277608431;1296.9740605187844;1328.1964411530885;1360.6666366740212;29372;34049;34233.486497702055;34311;34432.58971871945;49999;50768;50789.23816142687;50816;50820.23769643384;397500;401014;401485;402278;402757;28980.27549311284;29702.55446168266;29834.254143646584;30245.546316805707;40875;47722.28416573685;47743.76128119337;47809.76095119502;48248.79378015578;48254;39948.60051399449;40946;40948;41374.37938430866;42546.80898932654;41234.79382603188;41264.1747165061;41467.37798933079;42733;42849.14301714011;38703.80648096742;39142.60857391504;42137.36793948032;42212.1557568853;42480.57519424766;33430.331393372486;35600.39082067349;35688.28623427465;35984;36445.635543644225;27862.721372786822;28012;28112.156635300973;28203;28232.29419264535 15:48:29 INFO - PROCESS | 6310 | |2;jquery.html;374.7058559031161;391.7355784845229;402.67584594401507;409.2408582080241;416.0908415112978;13500.257485838274;15276.923615381922;18918.90540547297;19653.901730491365;19713.507162320948;17736;18258.908705456455;18644.60165487587;19098.904505477454;19109.713354299696;5557.527610153142;5564.777408903649;5790.797322093727;5791.739371728261;5793.159991801186;4134.400511925769;4368.847090351823;5692.345380281258;5744.885102297952;6152.876942461148;6486;6501.577397469173;6507.121538592301;6508.576942498746;6521.771737989147;6383.521235907326;6431.3913386337645;6502.349765023483;6528.281888992207;6554.41010309072;5105.106606343912;5753.223314852483;5761.769529218826;5766.135079738031;5766.509846663039;21061.789382106137;21913.233036843794;22517.549649006927;22539.887300563558;22622.773772262397;1977.4957385866655;2018.7072874433125;2133.2426988419197;2143.2605751015876;2150.473134082144;9738.561764720605;10287.588496460132;10525.157987360994;11804.70488237801;14533.200673962929;1724.741288806689;1796.6676164909534;1800.1089460717028;1820.3719716697717;1860.8790428622096;79870;80680.59659701664;80735;81975;97352.51323743336;10766.461676916122;10834.133269338434;10931.945340273407;11157.386343751172;11370.431478426046;15432.922835385976;15476.380944762084;15509.224538773016;15525;15526.689466210839;7191.352778249982;7265.927340726631;7479.738209162762;7714.537127772352;7781.5331080135375;6923.896141557881;7120.928790712027;7148.46386521013;7386.96306518464;7406.259374062551;4674.182018146811;4690.202665546865;4713.7643117844655;4726.172919739032;4797.592204662643;1813.8312014415878;1905.9714104288446;1960.6568850451115;2045.4250227324876;2058.4030631117107;934.5374039850318;958.5878072428923;1007.6876168387823;1055.9155267578576;1066.642674703983;18051.54871128206;19286;19319.227230910605;19337.4198774037;19343.322983695503;950.7337945375306;972.9416235025922;977.130353984954;991.6578780320777;1009.7929924365487;10522.526486308134;10668.253222274521;10672.839907401396;10749.140068794477;10761.24671273019;470060;472553;527617.1156767121;531175;535772;91651.57253416779;92216.53891730633;92609.53695231481;94704;94713.96529687544;904.6291020681357;959.8704174936433;1017.2777328097134;1022.5705203814267;1032.5373983050329;13716;16355.918220408821;17344.653106938043;17601.119944003003;17723.11384430799;39571.60428395679;40190.598094018686;40347.394789078775;40644.39033414442;41332.38001430041;53508.73245633747;53802.73098634482;54398.45601544092;56329;58042.12936805867;8224;8594;8670.913290867264;8755.562221889008;8845.51349675774;9589.472579008221;12895.548655797378;13082.934585327013;13586.184828910296;13613;10351.482425878827;11507.367094809866;11716.472758725708;11971.521139154336;12035.097367697283;10847.40339281347;11162.44187790591;11335.149863760416;11555.71110722205;11658.242214256046;12055;12108.273503589813;12329.013678905845;12385.442655080362;12521.123521353418;8480.448770829877;8795.868061979203;8811.118888111137;8922.375433719577;8999.955000225218;9010.414323068979;10972.780544389227;11852.52589896429;11916.702082448008;11998.58004969822 15:48:29 INFO - PROCESS | 6310 | |3;mootools.html;1450.7171101635179;1496.379002713874;1579.5024567261312;1740.0951505217279;1741.8606511479074;8158.224968627986;8410.158984101587;8795.076516965722;9435.339526233167;9436.103570160833;8716.869246961285;8967.461952282867;8996.280297576195;9211.861822072675;9226.354155209137;2210.8120809731154;2238.6194346961056;2243.697100891375;2244.1808739810017;2246.5619204255177;1892.4133518609276;1938.6316599845984;2153.6446486329737;2160.7407111146686;2172.0660116150048;2224.877631730254;2252.493189032463;2258.2773512476015;2261.518705248057;2263.298377502971;2189.277538412328;2193.1008286602496;2221.1892659179443;2221.6556433752635;2225.065472501537;2090.1639344262267;2116.216999710102;2117.4494631395796;2135.8611690240086;2183.661532462471;9220.90779092214;9336.953315233448;9350.345475816695;9350.766230844283;9367.90632093684;5262.947370526323;5370.838874833761;5423.810166644188;5429.348478182604;5435.619506634537;3293.4730443129224;3436.0894362993577;3510.8946731598094;3513.859445622172;3518.437050071976;1117.7485065860199;1156.2484385149635;1156.7281688803125;1174.7415568574986;1196.9162158648899;12939.02957278208;12951.935240323832;12957.09300348976;12970.156939798977;12970.80543791844;4476.865694029184;4646.279826626811;4764.928526072112;4805.975970120127;4842.443119041355;10800.351978881292;10856;10880.020798128206;10884.564617415374;10895.564177432972;5489;5493.642913210629;5502.889942201134;5504.7522861470525;5505.669659820343;4825.445073816556;4952.8761780955765;5024.271480635288;5046.016026874798;5047.091523525727;3709.4992176056426;3717.386631205875;3739.308227977778;3815.4627172930245;3826.330392181357;1023.3194925374543;1025.6820385680462;1096.276457538021;1116.5366372955295;1128.5316593613559;941;954.9283803714749;956.1824639932896;973.4791886340773;974.7855471796194;5918.615290006136;6177.7837775677635;6185.690715464299;6205.937940620626;6283.748650054038;962.8074385123003;969.4086607169621;986.807572523351;992.717075633441;1006.4615430744517;4051.959480405217;4189.120284740238;4229.534751177368;4274.401583778274;4333.285007973712;11221.719457013476;11645.825312620318;11648.534058637559;11654;11661;11239.55041798319;11506.424678766029;11548.422578871023;11574.537018519164;11582.536698532133;8321.750347489586;8522.74431767048;8524.8721269181;8531.914680853237;8541.786455338666;6138.938610613836;6231;6238.979256235878;6261.499080073674;6277.780277690259;10432;10495.16038716916;10552.683419497427;10574.524146413276;10686.091682206788;10660.413677247825;10751.892481075089;10774.946125269324;10933.343999360064;10938.56245750161;8335.166483351617;8403.873941890753;8458.534780587126;8628.266597339296;8635;8549.230569248797;8551.1448855114;8655.610497527501;8854.601542930712;8865.290776737716;7791.259830316111;7920.366370690215;7970.043594768665;7974;8116.715914942949;7692.692292308245;7777.922220777946;7787.454878158701;7801.570913599807;7876.173001834954;6645.169353830771;6773.390394864486;6801.319868013357;6989.545679530815;7049.612271325126;5214.601283409674;5377.112776392008;5414.918776218281;5420.864478388072;5442.37412697529;5267.104592219409;5317.361916570037;5355.410904800547;5390.757415916371;5393.649412788156 15:48:29 INFO - PROCESS | 6310 | |4;prototype.html;330.3821853134639;334.86103267144136;338.7933360650002;339.2062573577828;343.2551363541115;5147.897042059161;5273.91884663644;5292.523672869445;5307.310049693534;5307.761150748216;5175.767090480928;5231.555317797982;5235.738213089349;5242.475752424755;5242.9213561796605;3926.7643941363467;4039.838406463738;4056.71602987791;4075.8369665213354;4133.152703695735;4122.175564887019;4129.483814523184;4131.256373852705;4152.813123409439;4169.478815148106;4108.178364327132;4109.21924834282;4109.260333140033;4118.055590252969;4125.752454852718;3896.3571010783185;3898.3957486589484;3959.6436320731273;4093.488313960755;4119.567445418218;3663.358912190356;3664.8350824212976;3672.549343009523;3679.5768486624115;3710.1652128271194;5072.873178170538;5085;5085.084684756743;5085.542301192873;5119.744012799382;4544.613707834838;4672.883177920579;4710.929336059962;4711.835085772016;4716.127516409441;5403.594730395236;5411.431799661058;5412.323459567554;5420.9129485148505;5443.918341224845;4010.235496381583;4037.394390841368;4037.959620403817;4045.1302969861326;4056.1482088761395;5465.289512363367;5573.164025396182;5580.972095139498;5591.496765291144;5592.440755924456;4101.323281658493;4111.671066314748;4124.938125928114;4127.8968025798995;4132.462779838602;4151.052752545666;4156.189543039139;4174.519930208065;4184.121334519724;4191.895202619959;4590.183577387576;4613.238815595456;4637.7449240291435;4722.645801564828;4739.1232621964355;5395.163749618815;5404.972975135099;5413.675179489164;5428.538574221236;5445.455454454602;1424.893133015028;1437.3244575049919;1440.488626537585;1444.6605047813755;1447.782832575101;913.4645849682925;948.5439849830477;957.899420560845;962.6341990043878;968.6028728221395;5036.521530454601;5139.203423469368;5140.357455318085;5159.7678104485385;5173.793048278027;5421.728913554307;5565.777368905198;5574.052411090124;5603.7478313475995;5624.971875140598;5156.1750119980625;5212.687238765686;5273.945210957824;5284.260203571503;5284.339457567804;2911.563265510148;2952.468555660002;2957.098085084027;2969.9554506682416;2970.3465237647683;5727.656340619576;5756.683382413924;5762.135679648002;5772.6536407815665;5782.277215348082;5422.2951961589015;5631.352394474688;5642.379338272786;5647.322321321468;5649.858753531194;5734.569907256889;5747.166660834156;5756.481916627524;5758.955570772316;5771.826845194651;5358.249845021622;5571.777128914798;5579.637323573955;5587.966226248157;5630.943690563042;5468.125099983983;5496.532794712334;5496.642718223303;5497.147942068986;5499.615026948075;5717.514011309086;5743.655380677173;5763.740631671669;5785.247917770663;5800.564957628279;3829.5596006459614;4015.4579131818;4148.128892932518;4151.958480415157;4153.771542565067;5531.529819965348;5555.50000499957;5564.443555644403;5582.302212223472;5583.804566840141;5428.918566221589;5432.375276843213;5479.178123281539;5482.259894914136;5487.451254874481;5322.254884316121;5329.734862683266;5408.486193811582;5410.296661434146;5487.835364939058;5343.7060961647485;5352.5985551084605;5355.839324820262;5366.6779993200535;5371.194320851747;5180.684362008393;5310.893782124259;5366.731663416892;5429.592780541554;5468.863278418072;5349.277847490539;5360.3299587551555;5379.784808607768;5380.569554435714;5387 15:48:29 INFO - PROCESS | 6310 | |5;yui.html;702.5889854435155;705.3792662457038;727.3163226567024;731.3490993016213;768.1166658342903;14764.557063288092;16066.357345706158;16068;16070.758938615902;16109.91945040276;14015.229162396065;14112.082714623542;14129.78805317921;14134.293285335743;14141;603.9033754599265;616.8827922694674;767.2021098058013;769.9807504812383;790.0598288037218;771.9189485104067;787.3071696906715;788.113372455987;788.8619491588978;789.5026133535864;831.4221615976899;832.6169961817571;832.9958350208241;833.9249467547919;834.015861283685;679.1612358736955;694.9200841903196;803.4656953126164;827.7433995461396;828.3456069704926;607.641491520001;611.8072807065804;612.5558969746943;612.7824622259124;613.0650757594659;14872.925635371863;15002;15015.924920375437;15022.924885375613;15042.097474151586;13953.232572208524;14108.858911410856;14391.712165756626;14489.637759056002;14781.778273325908;10598.735031624272;10673.466326683634;10840.13278937683;11440.99130608569;11618.825717614243;4355.281378572532;4394.384786129945;4441.224234853216;4546.40896683429;4585.105904348653;81114.59442702807;81256.09653444526;86977;87154.56422717846;87351;10225.233107516966;10331.32846364984;10406.58373665045;10411;10603.734906627242;21834.344969650934;21960.560788784136;21977.890110549346;22201.333959981228;22740;7197.050308084474;7333.4866559340335;7520;7566.394688424913;7744.4578879477895;4216.093539888939;4282.678799090081;4286.657067434598;4341.261985462479;4415.072834704683;3380.4253276942486;3487.5989261234777;3490.2495963367996;3494.8776792812127;3499.1427100360575;592.8310431526994;598.2133494454778;604.6221111805122;605.276694350256;615.7013848283578;552.6822077305566;585.4145854145854;594.9196858424058;663.0882536512296;692.6917521702894;8325.542095184703;8837.867431988525;9162.725118246462;9367.969523352425;10547.894521054845;653.5163978655761;693.3447891742356;723.8588475247276;749.9950066910367;782.5187509681505;4539.228331183706;4870.634702397334;4924.113659541317;4933.629977751683;4949.307097006422;110346;116204.41897790457;119082;119417;120085;103489.48255258826;104731.4763426178;105200;105327;107115;73821.6308918452;84966.57516712377;85971.57014214889;90630.54684726665;90745;8227;8318.83362332762;8458.873116903374;8460.238578527962;8550.957245213984;48949;49051.528454146435;49115;50232.49767502278;50488.495115048376;72208;74180.25819741734;78515;79625.60187199026;80276.59861700654;12755.61733148007;12958;13179.802302965272;13372.331383430985;13642;15086.54740357791;15112.546623601309;15220.695586088437;15261.465848695687;15449.5365139046;13126.803097953347;13829.112818285754;13852.584422467342;14012.719745604827;14803;13044.152130111512;13134.14628049212;13190.472381104648;13272.601821945356;13795.586132416043;14050.929745351208;14265.57203283903;14348.856511434751;14420.278986050445;14478.131312121306;10612.469376531297;10747.46262686847;10825.296355737168;10828.620998265023;11003.17476189273;10479.58081676755;10516;10835;10932.890671093188;11011.064059555027 15:48:29 INFO - PROCESS | 6310 | __end_tp_report 15:48:29 INFO - PROCESS | 6310 | __start_cc_report 15:48:29 INFO - PROCESS | 6310 | _x_x_mozilla_cycle_collect,1018 15:48:29 INFO - PROCESS | 6310 | __end_cc_report 15:48:29 INFO - PROCESS | 6310 | __startTimestamp1474843709703__endTimestamp 15:48:29 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | ------- Summary: start ------- 15:48:29 INFO - PROCESS | 6310 | Number of tests: 6 15:48:29 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | [#0] dojo.html Cycles:165 Average:5258.23 Median:5448.10 stddev:578.15 (10.6%) stddev-sans-first:552.09 15:48:29 INFO - PROCESS | 6310 | Values: 2999.0 3037.9 3040.2 3070.2 3088.3 5139.4 5222.3 5243.0 5330.7 5377.1 5175.2 5253.3 5261.2 5315.1 5397.5 4819.6 4847.3 4854.9 4884.8 4975.9 4897.2 4915.6 4941.9 4951.0 5000.5 4735.3 4765.8 4776.2 4825.2 4928.4 4824.4 4841.4 4912.8 4931.8 4932.0 4772.2 4805.0 4812.3 4829.1 4870.4 5116.7 5311.7 5320.8 5323.8 5376.8 5463.6 5485.2 5501.9 5546.0 5591.6 5637.8 5701.3 5714.1 5718.1 5718.2 5280.5 5282.0 5282.0 5282.2 5291.3 5647.6 5705.2 5709.5 5712.7 5717.7 5434.9 5484.3 5507.6 5541.6 5636.8 5640.9 5641.8 5644.9 5649.4 5650.7 5542.3 5612.9 5619.7 5624.9 5666.1 5448.1 5464.2 5559.1 5602.0 5640.3 4412.3 4417.3 4418.1 4459.8 4466.6 3908.0 3920.0 3920.2 3961.5 3962.4 5234.5 5270.1 5311.6 5343.8 5389.6 5705.2 5709.6 5712.1 5756.3 5767.4 5314.0 5353.1 5362.8 5363.4 5369.9 4815.9 4851.5 4856.9 4924.4 4970.3 5867.2 5915.6 5918.6 5984.9 5985.0 5660.6 5683.0 5705.8 5722.0 5723.1 5728.3 5768.6 5904.2 5916.0 5918.4 5517.9 5523.6 5532.9 5583.8 5634.9 5580.3 5606.5 5611.5 5649.6 5666.3 5398.9 5471.9 5491.7 5493.8 5544.6 5431.3 5466.3 5492.4 5547.5 5623.1 5473.7 5488.6 5491.1 5492.0 5495.1 5542.2 5597.4 5599.1 5619.6 5644.4 5346.7 5357.1 5386.4 5447.3 5476.9 15:48:29 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | [#1] ext.html Cycles:180 Average:60439.92 Median:42346.37 stddev:119507.44 (282.2%) stddev-sans-first:119758.10 15:48:29 INFO - PROCESS | 6310 | Values: 552.7 583.3 601.4 606.5 615.6 32037.8 41949.8 43129.4 43203.8 46975.0 45528.3 45948.5 45971.5 46074.8 46079.3 8975.8 9135.4 9151.6 9152.5 9191.1 9098.3 9166.1 9190.2 9194.5 9198.7 9176.6 9217.6 9229.6 9234.3 9242.1 9127.4 9168.3 9176.1 9185.8 9191.4 8938.4 8978.7 8993.4 9002.3 9028.9 42146.0 42545.1 42827.4 42960.6 43224.6 3486.5 3589.2 3594.0 3859.1 4059.1 31587.3 32153.4 32368.5 32382.2 32723.7 4303.3 4343.9 4386.6 4472.8 4620.5 360015.0 360394.0 362506.0 362537.2 363301.0 58698.0 58806.7 59002.0 59133.4 59405.4 62634.4 62640.0 63053.7 63071.7 63086.4 14142.2 14212.0 14265.1 14267.9 14281.8 11243.1 11244.1 11278.1 11280.9 11283.5 15625.4 17562.4 17617.7 17789.8 17847.9 5234.3 5828.7 5954.3 5973.6 5983.3 941.4 986.5 991.9 1019.3 1024.6 31548.8 32848.0 32858.3 32888.5 33081.5 1056.9 1090.4 1107.7 1146.4 1212.2 16160.4 16953.5 17125.5 17211.1 17227.7 555870.0 563618.0 564267.0 564376.0 566387.0 58236.7 58565.4 58602.7 58858.0 58873.4 1193.8 1233.8 1297.0 1328.2 1360.7 29372.0 34049.0 34233.5 34311.0 34432.6 49999.0 50768.0 50789.2 50816.0 50820.2 397500.0 401014.0 401485.0 402278.0 402757.0 28980.3 29702.6 29834.3 30245.5 40875.0 47722.3 47743.8 47809.8 48248.8 48254.0 39948.6 40946.0 40948.0 41374.4 42546.8 41234.8 41264.2 41467.4 42733.0 42849.1 38703.8 39142.6 42137.4 42212.2 42480.6 33430.3 35600.4 35688.3 35984.0 36445.6 27862.7 28012.0 28112.2 28203.0 28232.3 15:48:29 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | [#2] jquery.html Cycles:180 Average:29362.26 Median:19981.67 stddev:83796.87 (419.4%) stddev-sans-first:84003.67 15:48:29 INFO - PROCESS | 6310 | Values: 374.7 391.7 402.7 409.2 416.1 13500.3 15276.9 18918.9 19653.9 19713.5 17736.0 18258.9 18644.6 19098.9 19109.7 5557.5 5564.8 5790.8 5791.7 5793.2 4134.4 4368.8 5692.3 5744.9 6152.9 6486.0 6501.6 6507.1 6508.6 6521.8 6383.5 6431.4 6502.3 6528.3 6554.4 5105.1 5753.2 5761.8 5766.1 5766.5 21061.8 21913.2 22517.5 22539.9 22622.8 1977.5 2018.7 2133.2 2143.3 2150.5 9738.6 10287.6 10525.2 11804.7 14533.2 1724.7 1796.7 1800.1 1820.4 1860.9 79870.0 80680.6 80735.0 81975.0 97352.5 10766.5 10834.1 10931.9 11157.4 11370.4 15432.9 15476.4 15509.2 15525.0 15526.7 7191.4 7265.9 7479.7 7714.5 7781.5 6923.9 7120.9 7148.5 7387.0 7406.3 4674.2 4690.2 4713.8 4726.2 4797.6 1813.8 1906.0 1960.7 2045.4 2058.4 934.5 958.6 1007.7 1055.9 1066.6 18051.5 19286.0 19319.2 19337.4 19343.3 950.7 972.9 977.1 991.7 1009.8 10522.5 10668.3 10672.8 10749.1 10761.2 470060.0 472553.0 527617.1 531175.0 535772.0 91651.6 92216.5 92609.5 94704.0 94714.0 904.6 959.9 1017.3 1022.6 1032.5 13716.0 16355.9 17344.7 17601.1 17723.1 39571.6 40190.6 40347.4 40644.4 41332.4 53508.7 53802.7 54398.5 56329.0 58042.1 8224.0 8594.0 8670.9 8755.6 8845.5 9589.5 12895.5 13082.9 13586.2 13613.0 10351.5 11507.4 11716.5 11971.5 12035.1 10847.4 11162.4 11335.1 11555.7 11658.2 12055.0 12108.3 12329.0 12385.4 12521.1 8480.4 8795.9 8811.1 8922.4 9000.0 9010.4 10972.8 11852.5 11916.7 11998.6 15:48:29 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | [#3] mootools.html Cycles:180 Average:5985.64 Median:4833.94 stddev:3517.01 (72.8%) stddev-sans-first:3510.36 15:48:29 INFO - PROCESS | 6310 | Values: 1450.7 1496.4 1579.5 1740.1 1741.9 8158.2 8410.2 8795.1 9435.3 9436.1 8716.9 8967.5 8996.3 9211.9 9226.4 2210.8 2238.6 2243.7 2244.2 2246.6 1892.4 1938.6 2153.6 2160.7 2172.1 2224.9 2252.5 2258.3 2261.5 2263.3 2189.3 2193.1 2221.2 2221.7 2225.1 2090.2 2116.2 2117.4 2135.9 2183.7 9220.9 9337.0 9350.3 9350.8 9367.9 5262.9 5370.8 5423.8 5429.3 5435.6 3293.5 3436.1 3510.9 3513.9 3518.4 1117.7 1156.2 1156.7 1174.7 1196.9 12939.0 12951.9 12957.1 12970.2 12970.8 4476.9 4646.3 4764.9 4806.0 4842.4 10800.4 10856.0 10880.0 10884.6 10895.6 5489.0 5493.6 5502.9 5504.8 5505.7 4825.4 4952.9 5024.3 5046.0 5047.1 3709.5 3717.4 3739.3 3815.5 3826.3 1023.3 1025.7 1096.3 1116.5 1128.5 941.0 954.9 956.2 973.5 974.8 5918.6 6177.8 6185.7 6205.9 6283.7 962.8 969.4 986.8 992.7 1006.5 4052.0 4189.1 4229.5 4274.4 4333.3 11221.7 11645.8 11648.5 11654.0 11661.0 11239.6 11506.4 11548.4 11574.5 11582.5 8321.8 8522.7 8524.9 8531.9 8541.8 6138.9 6231.0 6239.0 6261.5 6277.8 10432.0 10495.2 10552.7 10574.5 10686.1 10660.4 10751.9 10774.9 10933.3 10938.6 8335.2 8403.9 8458.5 8628.3 8635.0 8549.2 8551.1 8655.6 8854.6 8865.3 7791.3 7920.4 7970.0 7974.0 8116.7 7692.7 7777.9 7787.5 7801.6 7876.2 6645.2 6773.4 6801.3 6989.5 7049.6 5214.6 5377.1 5414.9 5420.9 5442.4 5267.1 5317.4 5355.4 5390.8 5393.6 15:48:29 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | [#4] prototype.html Cycles:180 Average:4602.52 Median:5279.10 stddev:1326.41 (25.1%) stddev-sans-first:1290.79 15:48:29 INFO - PROCESS | 6310 | Values: 330.4 334.9 338.8 339.2 343.3 5147.9 5273.9 5292.5 5307.3 5307.8 5175.8 5231.6 5235.7 5242.5 5242.9 3926.8 4039.8 4056.7 4075.8 4133.2 4122.2 4129.5 4131.3 4152.8 4169.5 4108.2 4109.2 4109.3 4118.1 4125.8 3896.4 3898.4 3959.6 4093.5 4119.6 3663.4 3664.8 3672.5 3679.6 3710.2 5072.9 5085.0 5085.1 5085.5 5119.7 4544.6 4672.9 4710.9 4711.8 4716.1 5403.6 5411.4 5412.3 5420.9 5443.9 4010.2 4037.4 4038.0 4045.1 4056.1 5465.3 5573.2 5581.0 5591.5 5592.4 4101.3 4111.7 4124.9 4127.9 4132.5 4151.1 4156.2 4174.5 4184.1 4191.9 4590.2 4613.2 4637.7 4722.6 4739.1 5395.2 5405.0 5413.7 5428.5 5445.5 1424.9 1437.3 1440.5 1444.7 1447.8 913.5 948.5 957.9 962.6 968.6 5036.5 5139.2 5140.4 5159.8 5173.8 5421.7 5565.8 5574.1 5603.7 5625.0 5156.2 5212.7 5273.9 5284.3 5284.3 2911.6 2952.5 2957.1 2970.0 2970.3 5727.7 5756.7 5762.1 5772.7 5782.3 5422.3 5631.4 5642.4 5647.3 5649.9 5734.6 5747.2 5756.5 5759.0 5771.8 5358.2 5571.8 5579.6 5588.0 5630.9 5468.1 5496.5 5496.6 5497.1 5499.6 5717.5 5743.7 5763.7 5785.2 5800.6 3829.6 4015.5 4148.1 4152.0 4153.8 5531.5 5555.5 5564.4 5582.3 5583.8 5428.9 5432.4 5479.2 5482.3 5487.5 5322.3 5329.7 5408.5 5410.3 5487.8 5343.7 5352.6 5355.8 5366.7 5371.2 5180.7 5310.9 5366.7 5429.6 5468.9 5349.3 5360.3 5379.8 5380.6 5387.0 15:48:29 INFO - PROCESS | 6310 | 15:48:29 INFO - PROCESS | 6310 | [#5] yui.html Cycles:180 Average:21156.48 Median:4374.83 stddev:31110.61 (711.1%) stddev-sans-first:31159.97 15:48:29 INFO - PROCESS | 6310 | Values: 702.6 705.4 727.3 731.3 768.1 14764.6 16066.4 16068.0 16070.8 16109.9 14015.2 14112.1 14129.8 14134.3 14141.0 603.9 616.9 767.2 770.0 790.1 771.9 787.3 788.1 788.9 789.5 831.4 832.6 833.0 833.9 834.0 679.2 694.9 803.5 827.7 828.3 607.6 611.8 612.6 612.8 613.1 14872.9 15002.0 15015.9 15022.9 15042.1 13953.2 14108.9 14391.7 14489.6 14781.8 10598.7 10673.5 10840.1 11441.0 11618.8 4355.3 4394.4 4441.2 4546.4 4585.1 81114.6 81256.1 86977.0 87154.6 87351.0 10225.2 10331.3 10406.6 10411.0 10603.7 21834.3 21960.6 21977.9 22201.3 22740.0 7197.1 7333.5 7520.0 7566.4 7744.5 4216.1 4282.7 4286.7 4341.3 4415.1 3380.4 3487.6 3490.2 3494.9 3499.1 592.8 598.2 604.6 605.3 615.7 552.7 585.4 594.9 663.1 692.7 8325.5 8837.9 9162.7 9368.0 10547.9 653.5 693.3 723.9 750.0 782.5 4539.2 4870.6 4924.1 4933.6 4949.3 110346.0 116204.4 119082.0 119417.0 120085.0 103489.5 104731.5 105200.0 105327.0 107115.0 73821.6 84966.6 85971.6 90630.5 90745.0 8227.0 8318.8 8458.9 8460.2 8551.0 48949.0 49051.5 49115.0 50232.5 50488.5 72208.0 74180.3 78515.0 79625.6 80276.6 12755.6 12958.0 13179.8 13372.3 13642.0 15086.5 15112.5 15220.7 15261.5 15449.5 13126.8 13829.1 13852.6 14012.7 14803.0 13044.2 13134.1 13190.5 13272.6 13795.6 14050.9 14265.6 14348.9 14420.3 14478.1 10612.5 10747.5 10825.3 10828.6 11003.2 10479.6 10516.0 10835.0 10932.9 11011.1 15:48:29 INFO - PROCESS | 6310 | -------- Summary: end -------- 15:48:29 INFO - PROCESS | 6310 | 15:48:30 INFO - TEST-INFO | 6310: exit 0 15:48:30 INFO - TEST-OK | dromaeo_css | took 1115471ms 15:48:30 INFO - TEST-START | kraken 15:48:30 INFO - operating with platform_type : linux_ 15:48:30 INFO - Initialising browser for kraken test... 15:48:30 INFO - TEST-INFO | started process 6614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDAJXUu/profile http://localhost:35610/getInfo.html) 15:48:34 INFO - PROCESS | 6614 | __metrics Screen width/height:1600/1200 15:48:34 INFO - PROCESS | 6614 | colorDepth:24 15:48:34 INFO - PROCESS | 6614 | Browser inner width/height: 1024/697 15:48:34 INFO - PROCESS | 6614 | __metrics 15:48:35 INFO - PROCESS | 6614 | 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" 15:48:35 INFO - PROCESS | 6614 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:48:36 INFO - PROCESS | 6614 | console.error: PushService: 15:48:36 INFO - PROCESS | 6614 | stateChangeProcessEnqueue: Error transitioning state 15:48:36 INFO - PROCESS | 6614 | UnknownError 15:48:36 INFO - TEST-INFO | 6614: exit 0 15:48:36 INFO - Browser initialized. 15:48:36 INFO - Running cycle 1/1 for kraken test... 15:48:36 INFO - TEST-INFO | started process 6697 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDAJXUu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:48 INFO - PROCESS | 6697 | RSS: Main: 173993984 15:48:48 INFO - PROCESS | 6697 | 15:48:51 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35610/tests/kraken/driver.html?testName=audio-beat-detection) 15:48:52 INFO - PROCESS | 6697 | RSS: Main: 233713664 15:48:52 INFO - PROCESS | 6697 | 15:48:54 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35610/tests/kraken/driver.html?testName=audio-dft) 15:48:55 INFO - PROCESS | 6697 | RSS: Main: 216924160 15:48:55 INFO - PROCESS | 6697 | 15:48:58 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35610/tests/kraken/driver.html?testName=audio-fft) 15:48:59 INFO - PROCESS | 6697 | RSS: Main: 298401792 15:48:59 INFO - PROCESS | 6697 | 15:49:01 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35610/tests/kraken/driver.html?testName=audio-oscillator) 15:49:01 INFO - PROCESS | 6697 | RSS: Main: 208203776 15:49:01 INFO - PROCESS | 6697 | 15:49:04 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35610/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:49:04 INFO - PROCESS | 6697 | RSS: Main: 212865024 15:49:04 INFO - PROCESS | 6697 | 15:49:07 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35610/tests/kraken/driver.html?testName=imaging-darkroom) 15:49:08 INFO - PROCESS | 6697 | RSS: Main: 249372672 15:49:08 INFO - PROCESS | 6697 | 15:49:11 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35610/tests/kraken/driver.html?testName=imaging-desaturate) 15:49:12 INFO - PROCESS | 6697 | RSS: Main: 248004608 15:49:12 INFO - PROCESS | 6697 | 15:49:15 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35610/tests/kraken/driver.html?testName=json-parse-financial) 15:49:15 INFO - PROCESS | 6697 | RSS: Main: 247472128 15:49:15 INFO - PROCESS | 6697 | 15:49:17 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35610/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:49:17 INFO - PROCESS | 6697 | RSS: Main: 210145280 15:49:17 INFO - PROCESS | 6697 | 15:49:19 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35610/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:49:20 INFO - PROCESS | 6697 | RSS: Main: 218759168 15:49:20 INFO - PROCESS | 6697 | 15:49:22 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35610/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:49:22 INFO - PROCESS | 6697 | RSS: Main: 223244288 15:49:22 INFO - PROCESS | 6697 | 15:49:25 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35610/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:49:25 INFO - PROCESS | 6697 | RSS: Main: 223293440 15:49:25 INFO - PROCESS | 6697 | 15:49:28 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35610/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:49:29 INFO - PROCESS | 6697 | RSS: Main: 221921280 15:49:29 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | Cycle 1(1): loaded http://localhost:35610/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35610/tests/kraken/driver.html?testName=ai-astar) 15:49:31 INFO - PROCESS | 6697 | RSS: Main: 272646144 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | __start_tp_report 15:49:31 INFO - PROCESS | 6697 | _x_x_mozilla_page_load 15:49:31 INFO - PROCESS | 6697 | _x_x_mozilla_page_load_details 15:49:31 INFO - PROCESS | 6697 | |i|pagename|runs| 15:49:31 INFO - PROCESS | 6697 | |0;ai-astar;109;111;111;109;112;112;111;112;110;112 15:49:31 INFO - PROCESS | 6697 | |1;audio-beat-detection;143;119;119;169;120;119;120;119;151;122 15:49:31 INFO - PROCESS | 6697 | |2;audio-dft;163;165;163;162;169;162;162;161;160;162 15:49:31 INFO - PROCESS | 6697 | |3;audio-fft;75;76;76;113;77;76;76;76;112;76 15:49:31 INFO - PROCESS | 6697 | |4;audio-oscillator;85;85;113;84;83;83;106;84;84;111 15:49:31 INFO - PROCESS | 6697 | |5;imaging-gaussian-blur;117;117;116;117;116;116;117;116;116;117 15:49:31 INFO - PROCESS | 6697 | |6;imaging-darkroom;146;146;145;145;145;145;146;146;146;146 15:49:31 INFO - PROCESS | 6697 | |7;imaging-desaturate;95;95;96;95;94;95;95;95;96;95 15:49:31 INFO - PROCESS | 6697 | |8;json-parse-financial;51;50;51;51;52;51;51;53;51;52 15:49:31 INFO - PROCESS | 6697 | |9;json-stringify-tinderbox;60;59;59;60;61;59;60;60;60;60 15:49:31 INFO - PROCESS | 6697 | |10;stanford-crypto-aes;80;79;78;78;79;87;78;78;79;78 15:49:31 INFO - PROCESS | 6697 | |11;stanford-crypto-ccm;129;132;122;121;122;122;123;121;169;122 15:49:31 INFO - PROCESS | 6697 | |12;stanford-crypto-pbkdf2;164;163;161;166;162;160;163;165;174;166 15:49:31 INFO - PROCESS | 6697 | |13;stanford-crypto-sha256-iterative;66;65;64;68;64;64;65;65;64;64 15:49:31 INFO - PROCESS | 6697 | __end_tp_report 15:49:31 INFO - PROCESS | 6697 | __start_cc_report 15:49:31 INFO - PROCESS | 6697 | _x_x_mozilla_cycle_collect,1613 15:49:31 INFO - PROCESS | 6697 | __end_cc_report 15:49:31 INFO - PROCESS | 6697 | __startTimestamp1474843771259__endTimestamp 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | ------- Summary: start ------- 15:49:31 INFO - PROCESS | 6697 | Number of tests: 14 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.50 stddev:1.20 (1.1%) stddev-sans-first:1.05 15:49:31 INFO - PROCESS | 6697 | Values: 109.0 111.0 111.0 109.0 112.0 112.0 111.0 112.0 110.0 112.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:121.00 stddev:17.89 (14.8%) stddev-sans-first:18.35 15:49:31 INFO - PROCESS | 6697 | Values: 143.0 119.0 119.0 169.0 120.0 119.0 120.0 119.0 151.0 122.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#2] audio-dft Cycles:10 Average:162.90 Median:162.50 stddev:2.51 (1.5%) stddev-sans-first:2.67 15:49:31 INFO - PROCESS | 6697 | Values: 163.0 165.0 163.0 162.0 169.0 162.0 162.0 161.0 160.0 162.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#3] audio-fft Cycles:10 Average:83.30 Median:76.00 stddev:15.40 (20.3%) stddev-sans-first:16.04 15:49:31 INFO - PROCESS | 6697 | Values: 75.0 76.0 76.0 113.0 77.0 76.0 76.0 76.0 112.0 76.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:84.00 stddev:12.69 (15.1%) stddev-sans-first:13.22 15:49:31 INFO - PROCESS | 6697 | Values: 85.0 85.0 113.0 84.0 83.0 83.0 106.0 84.0 84.0 111.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 15:49:31 INFO - PROCESS | 6697 | Values: 117.0 117.0 116.0 117.0 116.0 116.0 117.0 116.0 116.0 117.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 15:49:31 INFO - PROCESS | 6697 | Values: 146.0 146.0 145.0 145.0 145.0 145.0 146.0 146.0 146.0 146.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 15:49:31 INFO - PROCESS | 6697 | Values: 95.0 95.0 96.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 15:49:31 INFO - PROCESS | 6697 | Values: 51.0 50.0 51.0 51.0 52.0 51.0 51.0 53.0 51.0 52.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 15:49:31 INFO - PROCESS | 6697 | Values: 60.0 59.0 59.0 60.0 61.0 59.0 60.0 60.0 60.0 60.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.00 stddev:2.76 (3.5%) stddev-sans-first:2.92 15:49:31 INFO - PROCESS | 6697 | Values: 80.0 79.0 78.0 78.0 79.0 87.0 78.0 78.0 79.0 78.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:122.50 stddev:14.77 (12.1%) stddev-sans-first:15.67 15:49:31 INFO - PROCESS | 6697 | Values: 129.0 132.0 122.0 121.0 122.0 122.0 123.0 121.0 169.0 122.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:164.50 stddev:3.92 (2.4%) stddev-sans-first:4.16 15:49:31 INFO - PROCESS | 6697 | Values: 164.0 163.0 161.0 166.0 162.0 160.0 163.0 165.0 174.0 166.0 15:49:31 INFO - PROCESS | 6697 | 15:49:31 INFO - PROCESS | 6697 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:1.29 (2.0%) stddev-sans-first:1.30 15:49:31 INFO - PROCESS | 6697 | Values: 66.0 65.0 64.0 68.0 64.0 64.0 65.0 65.0 64.0 64.0 15:49:31 INFO - PROCESS | 6697 | -------- Summary: end -------- 15:49:31 INFO - PROCESS | 6697 | 15:49:32 INFO - TEST-INFO | 6697: exit 0 15:49:32 INFO - TEST-OK | kraken | took 61931ms 15:49:32 INFO - SUITE-END | took 1177s 15:49:32 INFO - Completed test suite (00:19:37) 15:49:32 INFO - javascript benchmark 15:49:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2998.9999999999995, 3037.9088627341175, 3040.2095455181648, 3070.2324418895278, 3088.2588178837086, 5139.383274007115, 5222.3472065991755, 5243.0, 5330.706811125391, 5377.059014672432, 5175.171972484403, 5253.343332083489, 5261.158214685651, 5315.14957606783, 5397.54120899724, 4819.566239038486, 4847.272909063633, 4854.854354369375, 4884.804607815683, 4975.850724478271, 4897.191963326047, 4915.5821755150855, 4941.851744447672, 4950.975245123769, 5000.52495012975, 4735.289706544011, 4765.761711914425, 4776.235802271654, 4825.227963525819, 4928.43323017854, 4824.396950381202, 4841.394825646794, 4912.828051018233, 4931.80272789088, 4932.013597280522, 4772.188727916263, 4805.038992201574, 4812.278158276286, 4829.106615276185, 4870.366852309213, 5116.667416617908, 5311.681299122065, 5320.76056577455, 5323.760430780624, 5376.838694839161, 5463.644863083927, 5485.177223416479, 5501.9174712379345, 5546.0, 5591.6365436246515, 5637.774489020434, 5701.258836351248, 5714.057180565202, 5718.142278658193, 5718.170865224561, 5280.524752772192, 5281.973590132025, 5282.022825777205, 5282.2076688496645, 5291.3385826771655, 5647.576431767634, 5705.172749951317, 5709.486146246858, 5712.685802280831, 5717.714114294352, 5434.945650543444, 5484.314460692413, 5507.586930980113, 5541.612087153861, 5636.830895073154, 5640.8871822563315, 5641.802536911188, 5644.8871022579315, 5649.350324712628, 5650.74571644277, 5542.307211598551, 5612.915806262909, 5619.690916999522, 5624.91562626561, 5666.093425051972, 5448.101063324507, 5464.23500709901, 5559.138333558305, 5602.0196465619165, 5640.32316122068, 4412.271975124133, 4417.271150260235, 4418.050119224382, 4459.7993090310365, 4466.642668586505, 3907.980460097738, 3919.980400097981, 3920.1571662092215, 3961.465202197724, 3962.425448309979, 5234.502722241381, 5270.051390749702, 5311.601629877775, 5343.7862485500145, 5389.595780316492, 5705.22979397776, 5709.571782116358, 5712.143178523172, 5756.3380211276235, 5767.423257674283, 5313.973430132824, 5353.089974704231, 5362.785488580413, 5363.436839131913, 5369.892602147936, 4815.855524334275, 4851.539103785135, 4856.854294371175, 4924.4090709115135, 4970.2793095001025, 5867.237259156283, 5915.64506129625, 5918.556108291809, 5984.8803023940145, 5985.042393217064, 5660.603757736919, 5683.0, 5705.828825135252, 5721.971390143022, 5723.141528770718, 5728.283964504437, 5768.567357448124, 5904.202932604216, 5916.0, 5918.408159184047, 5517.889642207214, 5523.558115350682, 5532.917006244829, 5583.776648934159, 5634.887302254014, 5580.274564306615, 5606.467385598362, 5611.5230205433, 5649.5762817789655, 5666.263385759825, 5398.865028374322, 5471.917921231265, 5491.697956612425, 5493.7802487900035, 5544.63959842609, 5431.34823821144, 5466.2620546227345, 5492.368377636623, 5547.5007249347755, 5623.0721930880945, 5473.698946557977, 5488.615796894339, 5491.066518691912, 5491.972540137273, 5495.093309603871, 5542.196381524737, 5597.384287728428, 5599.1041433370465, 5619.578531610227, 5644.435556444323, 5346.679199248058, 5357.062514060101, 5386.35363756336, 5447.346318441654, 5476.945230547643], "value": 5220.1575154370685, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.6962217803253, 583.2825624481887, 601.3762840331104, 606.4966078155131, 615.5844804756789, 32037.839810800942, 41949.79025104874, 43129.35305970413, 43203.78398108013, 46975.0, 45528.317075243816, 45948.540514594846, 45971.54028459715, 46074.76962615182, 46079.308810367875, 8975.775605609846, 9135.406198597104, 9151.633934642607, 9152.542372881362, 9191.080891910822, 9098.272138228958, 9166.129217724305, 9190.21883139934, 9194.540272986324, 9198.678046268382, 9176.632934682604, 9217.631294748235, 9229.6308147674, 9234.307426942973, 9242.121998410174, 9127.406718563272, 9168.266538676957, 9176.082391760838, 9185.770355741126, 9191.35660503765, 8938.4190027648, 8978.685745998924, 8993.415427997224, 9002.324825638038, 9028.8645670315, 42146.0, 42545.149097017886, 42827.35758963587, 42960.570394295966, 43224.56775432237, 3486.459598762196, 3589.174489867345, 3594.0475773919934, 3859.14326620404, 4059.147579008412, 31587.321317588565, 32153.35693286121, 32368.514472282935, 32382.190445238823, 32723.6727632723, 4303.33298338758, 4343.891402714926, 4386.649068074546, 4472.84345047925, 4620.468646105707, 360015.0, 360393.9606039401, 362506.0, 362537.18731406436, 363301.0, 58698.0, 58806.7059664699, 59002.0, 59133.40866591365, 59405.40594594085, 62634.373656262855, 62640.0, 63053.684731576046, 63071.684641576496, 63086.36913630897, 14142.151470911778, 14212.005159638933, 14265.144091354552, 14267.85732142686, 14281.78577321341, 11243.100551955824, 11244.100471962223, 11278.097752179807, 11280.887191128148, 11283.54865805375, 15625.42180540221, 17562.385316514115, 17617.735733964, 17789.822101779075, 17847.910760446375, 5234.319538459976, 5828.708564571754, 5954.255718035246, 5973.641581505037, 5983.282006159288, 941.3598752848129, 986.5018165826211, 991.8512223166439, 1019.3374306700704, 1024.574801457387, 31548.842255789034, 32848.0, 32858.3428331432, 32888.50667239993, 33081.50377744336, 1056.9101626361846, 1090.4166271044953, 1107.6953837694537, 1146.380954284677, 1212.1938910624513, 16160.434384796476, 16953.491395258163, 17125.48623541296, 17211.139443027798, 17227.655446891244, 555870.0, 563618.0, 564267.0, 564376.0, 566387.0, 58236.70881645649, 58565.41434585685, 58602.70698646479, 58858.0, 58873.41126588765, 1193.8089905615059, 1233.8149277608431, 1296.9740605187844, 1328.1964411530885, 1360.6666366740212, 29372.0, 34049.0, 34233.486497702055, 34311.0, 34432.58971871945, 49999.0, 50768.0, 50789.23816142687, 50816.0, 50820.23769643384, 397500.0, 401014.0, 401485.0, 402278.0, 402757.0, 28980.27549311284, 29702.55446168266, 29834.254143646584, 30245.546316805707, 40875.0, 47722.28416573685, 47743.76128119337, 47809.76095119502, 48248.79378015578, 48254.0, 39948.60051399449, 40946.0, 40948.0, 41374.37938430866, 42546.80898932654, 41234.79382603188, 41264.1747165061, 41467.37798933079, 42733.0, 42849.14301714011, 38703.80648096742, 39142.60857391504, 42137.36793948032, 42212.1557568853, 42480.57519424766, 33430.331393372486, 35600.39082067349, 35688.28623427465, 35984.0, 36445.635543644225, 27862.721372786822, 28012.0, 28112.156635300973, 28203.0, 28232.29419264535], "value": 19983.49492672337, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.7058559031161, 391.7355784845229, 402.67584594401507, 409.2408582080241, 416.0908415112978, 13500.257485838274, 15276.923615381922, 18918.90540547297, 19653.901730491365, 19713.507162320948, 17736.0, 18258.908705456455, 18644.60165487587, 19098.904505477454, 19109.713354299696, 5557.527610153142, 5564.777408903649, 5790.797322093727, 5791.739371728261, 5793.159991801186, 4134.400511925769, 4368.847090351823, 5692.345380281258, 5744.885102297952, 6152.876942461148, 6486.0, 6501.577397469173, 6507.121538592301, 6508.576942498746, 6521.771737989147, 6383.521235907326, 6431.3913386337645, 6502.349765023483, 6528.281888992207, 6554.41010309072, 5105.106606343912, 5753.223314852483, 5761.769529218826, 5766.135079738031, 5766.509846663039, 21061.789382106137, 21913.233036843794, 22517.549649006927, 22539.887300563558, 22622.773772262397, 1977.4957385866655, 2018.7072874433125, 2133.2426988419197, 2143.2605751015876, 2150.473134082144, 9738.561764720605, 10287.588496460132, 10525.157987360994, 11804.70488237801, 14533.200673962929, 1724.741288806689, 1796.6676164909534, 1800.1089460717028, 1820.3719716697717, 1860.8790428622096, 79870.0, 80680.59659701664, 80735.0, 81975.0, 97352.51323743336, 10766.461676916122, 10834.133269338434, 10931.945340273407, 11157.386343751172, 11370.431478426046, 15432.922835385976, 15476.380944762084, 15509.224538773016, 15525.0, 15526.689466210839, 7191.352778249982, 7265.927340726631, 7479.738209162762, 7714.537127772352, 7781.5331080135375, 6923.896141557881, 7120.928790712027, 7148.46386521013, 7386.96306518464, 7406.259374062551, 4674.182018146811, 4690.202665546865, 4713.7643117844655, 4726.172919739032, 4797.592204662643, 1813.8312014415878, 1905.9714104288446, 1960.6568850451115, 2045.4250227324876, 2058.4030631117107, 934.5374039850318, 958.5878072428923, 1007.6876168387823, 1055.9155267578576, 1066.642674703983, 18051.54871128206, 19286.0, 19319.227230910605, 19337.4198774037, 19343.322983695503, 950.7337945375306, 972.9416235025922, 977.130353984954, 991.6578780320777, 1009.7929924365487, 10522.526486308134, 10668.253222274521, 10672.839907401396, 10749.140068794477, 10761.24671273019, 470060.0, 472553.0, 527617.1156767121, 531175.0, 535772.0, 91651.57253416779, 92216.53891730633, 92609.53695231481, 94704.0, 94713.96529687544, 904.6291020681357, 959.8704174936433, 1017.2777328097134, 1022.5705203814267, 1032.5373983050329, 13716.0, 16355.918220408821, 17344.653106938043, 17601.119944003003, 17723.11384430799, 39571.60428395679, 40190.598094018686, 40347.394789078775, 40644.39033414442, 41332.38001430041, 53508.73245633747, 53802.73098634482, 54398.45601544092, 56329.0, 58042.12936805867, 8224.0, 8594.0, 8670.913290867264, 8755.562221889008, 8845.51349675774, 9589.472579008221, 12895.548655797378, 13082.934585327013, 13586.184828910296, 13613.0, 10351.482425878827, 11507.367094809866, 11716.472758725708, 11971.521139154336, 12035.097367697283, 10847.40339281347, 11162.44187790591, 11335.149863760416, 11555.71110722205, 11658.242214256046, 12055.0, 12108.273503589813, 12329.013678905845, 12385.442655080362, 12521.123521353418, 8480.448770829877, 8795.868061979203, 8811.118888111137, 8922.375433719577, 8999.955000225218, 9010.414323068979, 10972.780544389227, 11852.52589896429, 11916.702082448008, 11998.58004969822], "value": 9120.103746652925, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.7171101635179, 1496.379002713874, 1579.5024567261312, 1740.0951505217279, 1741.8606511479074, 8158.224968627986, 8410.158984101587, 8795.076516965722, 9435.339526233167, 9436.103570160833, 8716.869246961285, 8967.461952282867, 8996.280297576195, 9211.861822072675, 9226.354155209137, 2210.8120809731154, 2238.6194346961056, 2243.697100891375, 2244.1808739810017, 2246.5619204255177, 1892.4133518609276, 1938.6316599845984, 2153.6446486329737, 2160.7407111146686, 2172.0660116150048, 2224.877631730254, 2252.493189032463, 2258.2773512476015, 2261.518705248057, 2263.298377502971, 2189.277538412328, 2193.1008286602496, 2221.1892659179443, 2221.6556433752635, 2225.065472501537, 2090.1639344262267, 2116.216999710102, 2117.4494631395796, 2135.8611690240086, 2183.661532462471, 9220.90779092214, 9336.953315233448, 9350.345475816695, 9350.766230844283, 9367.90632093684, 5262.947370526323, 5370.838874833761, 5423.810166644188, 5429.348478182604, 5435.619506634537, 3293.4730443129224, 3436.0894362993577, 3510.8946731598094, 3513.859445622172, 3518.437050071976, 1117.7485065860199, 1156.2484385149635, 1156.7281688803125, 1174.7415568574986, 1196.9162158648899, 12939.02957278208, 12951.935240323832, 12957.09300348976, 12970.156939798977, 12970.80543791844, 4476.865694029184, 4646.279826626811, 4764.928526072112, 4805.975970120127, 4842.443119041355, 10800.351978881292, 10856.0, 10880.020798128206, 10884.564617415374, 10895.564177432972, 5489.0, 5493.642913210629, 5502.889942201134, 5504.7522861470525, 5505.669659820343, 4825.445073816556, 4952.8761780955765, 5024.271480635288, 5046.016026874798, 5047.091523525727, 3709.4992176056426, 3717.386631205875, 3739.308227977778, 3815.4627172930245, 3826.330392181357, 1023.3194925374543, 1025.6820385680462, 1096.276457538021, 1116.5366372955295, 1128.5316593613559, 941.0, 954.9283803714749, 956.1824639932896, 973.4791886340773, 974.7855471796194, 5918.615290006136, 6177.7837775677635, 6185.690715464299, 6205.937940620626, 6283.748650054038, 962.8074385123003, 969.4086607169621, 986.807572523351, 992.717075633441, 1006.4615430744517, 4051.959480405217, 4189.120284740238, 4229.534751177368, 4274.401583778274, 4333.285007973712, 11221.719457013476, 11645.825312620318, 11648.534058637559, 11654.0, 11661.0, 11239.55041798319, 11506.424678766029, 11548.422578871023, 11574.537018519164, 11582.536698532133, 8321.750347489586, 8522.74431767048, 8524.8721269181, 8531.914680853237, 8541.786455338666, 6138.938610613836, 6231.0, 6238.979256235878, 6261.499080073674, 6277.780277690259, 10432.0, 10495.16038716916, 10552.683419497427, 10574.524146413276, 10686.091682206788, 10660.413677247825, 10751.892481075089, 10774.946125269324, 10933.343999360064, 10938.56245750161, 8335.166483351617, 8403.873941890753, 8458.534780587126, 8628.266597339296, 8635.0, 8549.230569248797, 8551.1448855114, 8655.610497527501, 8854.601542930712, 8865.290776737716, 7791.259830316111, 7920.366370690215, 7970.043594768665, 7974.0, 8116.715914942949, 7692.692292308245, 7777.922220777946, 7787.454878158701, 7801.570913599807, 7876.173001834954, 6645.169353830771, 6773.390394864486, 6801.319868013357, 6989.545679530815, 7049.612271325126, 5214.601283409674, 5377.112776392008, 5414.918776218281, 5420.864478388072, 5442.37412697529, 5267.104592219409, 5317.361916570037, 5355.410904800547, 5390.757415916371, 5393.649412788156], "value": 4705.973723607043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.3821853134639, 334.86103267144136, 338.7933360650002, 339.2062573577828, 343.2551363541115, 5147.897042059161, 5273.91884663644, 5292.523672869445, 5307.310049693534, 5307.761150748216, 5175.767090480928, 5231.555317797982, 5235.738213089349, 5242.475752424755, 5242.9213561796605, 3926.7643941363467, 4039.838406463738, 4056.71602987791, 4075.8369665213354, 4133.152703695735, 4122.175564887019, 4129.483814523184, 4131.256373852705, 4152.813123409439, 4169.478815148106, 4108.178364327132, 4109.21924834282, 4109.260333140033, 4118.055590252969, 4125.752454852718, 3896.3571010783185, 3898.3957486589484, 3959.6436320731273, 4093.488313960755, 4119.567445418218, 3663.358912190356, 3664.8350824212976, 3672.549343009523, 3679.5768486624115, 3710.1652128271194, 5072.873178170538, 5085.0, 5085.084684756743, 5085.542301192873, 5119.744012799382, 4544.613707834838, 4672.883177920579, 4710.929336059962, 4711.835085772016, 4716.127516409441, 5403.594730395236, 5411.431799661058, 5412.323459567554, 5420.9129485148505, 5443.918341224845, 4010.235496381583, 4037.394390841368, 4037.959620403817, 4045.1302969861326, 4056.1482088761395, 5465.289512363367, 5573.164025396182, 5580.972095139498, 5591.496765291144, 5592.440755924456, 4101.323281658493, 4111.671066314748, 4124.938125928114, 4127.8968025798995, 4132.462779838602, 4151.052752545666, 4156.189543039139, 4174.519930208065, 4184.121334519724, 4191.895202619959, 4590.183577387576, 4613.238815595456, 4637.7449240291435, 4722.645801564828, 4739.1232621964355, 5395.163749618815, 5404.972975135099, 5413.675179489164, 5428.538574221236, 5445.455454454602, 1424.893133015028, 1437.3244575049919, 1440.488626537585, 1444.6605047813755, 1447.782832575101, 913.4645849682925, 948.5439849830477, 957.899420560845, 962.6341990043878, 968.6028728221395, 5036.521530454601, 5139.203423469368, 5140.357455318085, 5159.7678104485385, 5173.793048278027, 5421.728913554307, 5565.777368905198, 5574.052411090124, 5603.7478313475995, 5624.971875140598, 5156.1750119980625, 5212.687238765686, 5273.945210957824, 5284.260203571503, 5284.339457567804, 2911.563265510148, 2952.468555660002, 2957.098085084027, 2969.9554506682416, 2970.3465237647683, 5727.656340619576, 5756.683382413924, 5762.135679648002, 5772.6536407815665, 5782.277215348082, 5422.2951961589015, 5631.352394474688, 5642.379338272786, 5647.322321321468, 5649.858753531194, 5734.569907256889, 5747.166660834156, 5756.481916627524, 5758.955570772316, 5771.826845194651, 5358.249845021622, 5571.777128914798, 5579.637323573955, 5587.966226248157, 5630.943690563042, 5468.125099983983, 5496.532794712334, 5496.642718223303, 5497.147942068986, 5499.615026948075, 5717.514011309086, 5743.655380677173, 5763.740631671669, 5785.247917770663, 5800.564957628279, 3829.5596006459614, 4015.4579131818, 4148.128892932518, 4151.958480415157, 4153.771542565067, 5531.529819965348, 5555.50000499957, 5564.443555644403, 5582.302212223472, 5583.804566840141, 5428.918566221589, 5432.375276843213, 5479.178123281539, 5482.259894914136, 5487.451254874481, 5322.254884316121, 5329.734862683266, 5408.486193811582, 5410.296661434146, 5487.835364939058, 5343.7060961647485, 5352.5985551084605, 5355.839324820262, 5366.6779993200535, 5371.194320851747, 5180.684362008393, 5310.893782124259, 5366.731663416892, 5429.592780541554, 5468.863278418072, 5349.277847490539, 5360.3299587551555, 5379.784808607768, 5380.569554435714, 5387.0], "value": 4183.866269367192, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.5889854435155, 705.3792662457038, 727.3163226567024, 731.3490993016213, 768.1166658342903, 14764.557063288092, 16066.357345706158, 16068.0, 16070.758938615902, 16109.91945040276, 14015.229162396065, 14112.082714623542, 14129.78805317921, 14134.293285335743, 14141.0, 603.9033754599265, 616.8827922694674, 767.2021098058013, 769.9807504812383, 790.0598288037218, 771.9189485104067, 787.3071696906715, 788.113372455987, 788.8619491588978, 789.5026133535864, 831.4221615976899, 832.6169961817571, 832.9958350208241, 833.9249467547919, 834.015861283685, 679.1612358736955, 694.9200841903196, 803.4656953126164, 827.7433995461396, 828.3456069704926, 607.641491520001, 611.8072807065804, 612.5558969746943, 612.7824622259124, 613.0650757594659, 14872.925635371863, 15002.0, 15015.924920375437, 15022.924885375613, 15042.097474151586, 13953.232572208524, 14108.858911410856, 14391.712165756626, 14489.637759056002, 14781.778273325908, 10598.735031624272, 10673.466326683634, 10840.13278937683, 11440.99130608569, 11618.825717614243, 4355.281378572532, 4394.384786129945, 4441.224234853216, 4546.40896683429, 4585.105904348653, 81114.59442702807, 81256.09653444526, 86977.0, 87154.56422717846, 87351.0, 10225.233107516966, 10331.32846364984, 10406.58373665045, 10411.0, 10603.734906627242, 21834.344969650934, 21960.560788784136, 21977.890110549346, 22201.333959981228, 22740.0, 7197.050308084474, 7333.4866559340335, 7520.0, 7566.394688424913, 7744.4578879477895, 4216.093539888939, 4282.678799090081, 4286.657067434598, 4341.261985462479, 4415.072834704683, 3380.4253276942486, 3487.5989261234777, 3490.2495963367996, 3494.8776792812127, 3499.1427100360575, 592.8310431526994, 598.2133494454778, 604.6221111805122, 605.276694350256, 615.7013848283578, 552.6822077305566, 585.4145854145854, 594.9196858424058, 663.0882536512296, 692.6917521702894, 8325.542095184703, 8837.867431988525, 9162.725118246462, 9367.969523352425, 10547.894521054845, 653.5163978655761, 693.3447891742356, 723.8588475247276, 749.9950066910367, 782.5187509681505, 4539.228331183706, 4870.634702397334, 4924.113659541317, 4933.629977751683, 4949.307097006422, 110346.0, 116204.41897790457, 119082.0, 119417.0, 120085.0, 103489.48255258826, 104731.4763426178, 105200.0, 105327.0, 107115.0, 73821.6308918452, 84966.57516712377, 85971.57014214889, 90630.54684726665, 90745.0, 8227.0, 8318.83362332762, 8458.873116903374, 8460.238578527962, 8550.957245213984, 48949.0, 49051.528454146435, 49115.0, 50232.49767502278, 50488.495115048376, 72208.0, 74180.25819741734, 78515.0, 79625.60187199026, 80276.59861700654, 12755.61733148007, 12958.0, 13179.802302965272, 13372.331383430985, 13642.0, 15086.54740357791, 15112.546623601309, 15220.695586088437, 15261.465848695687, 15449.5365139046, 13126.803097953347, 13829.112818285754, 13852.584422467342, 14012.719745604827, 14803.0, 13044.152130111512, 13134.14628049212, 13190.472381104648, 13272.601821945356, 13795.586132416043, 14050.929745351208, 14265.57203283903, 14348.856511434751, 14420.278986050445, 14478.131312121306, 10612.469376531297, 10747.46262686847, 10825.296355737168, 10828.620998265023, 11003.17476189273, 10479.58081676755, 10516.0, 10835.0, 10932.890671093188, 11011.064059555027], "value": 7409.993720660407, "unit": "score"}], "name": "dromaeo_css", "value": 7195.71871506614, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 111.0, 109.0, 112.0, 112.0, 111.0, 112.0, 110.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 119.0, 119.0, 169.0, 120.0, 119.0, 120.0, 119.0, 151.0, 122.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 163.0, 162.0, 169.0, 162.0, 162.0, 161.0, 160.0, 162.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 113.0, 77.0, 76.0, 76.0, 76.0, 112.0, 76.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 113.0, 84.0, 83.0, 83.0, 106.0, 84.0, 84.0, 111.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 51.0, 52.0, 51.0, 51.0, 53.0, 51.0, 52.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 60.0, 61.0, 59.0, 60.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 78.0, 78.0, 79.0, 87.0, 78.0, 78.0, 79.0, 78.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 132.0, 122.0, 121.0, 122.0, 122.0, 123.0, 121.0, 169.0, 122.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 161.0, 166.0, 162.0, 160.0, 163.0, 165.0, 174.0, 166.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 68.0, 64.0, 64.0, 65.0, 65.0, 64.0, 64.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1484.3000000000002, "alertThreshold": 2.0}]} 15:49:32 INFO - Return code: 0 15:49:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:49:33 INFO - # TBPL SUCCESS # 15:49:33 INFO - Running post-action listener: _resource_record_post_action 15:49:33 INFO - [mozharness: 2016-09-25 22:49:33.060882Z] Finished run-tests step (success) 15:49:33 INFO - Running post-run listener: _resource_record_post_run 15:49:33 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 410152960; Read time: 12; Write time: 336632 15:49:33 INFO - TinderboxPrint: CPU usage
13.0% 15:49:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 15:49:33 INFO - TinderboxPrint: I/O write bytes / time
410,152,960 / 336,632 15:49:33 INFO - TinderboxPrint: CPU idle
8,295.5 (87.0%) 15:49:33 INFO - TinderboxPrint: CPU user
1,188.4 (12.5%) 15:49:33 INFO - TinderboxPrint: Swap in / out
0 / 0 15:49:33 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 121106432; Read time: 0; Write time: 104016 15:49:33 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 289046528; Read time: 0; Write time: 232616 15:49:33 INFO - Running post-run listener: _upload_blobber_files 15:49:33 INFO - Blob upload gear active. 15:49:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:49:33 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 15:49:33 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:49:33 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:49:34 INFO - (blobuploader) - INFO - Open directory for files ... 15:49:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:49:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:49:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:35 INFO - (blobuploader) - INFO - Done attempting. 15:49:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:49:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:49:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:36 INFO - (blobuploader) - INFO - Done attempting. 15:49:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:49:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:40 INFO - (blobuploader) - INFO - Done attempting. 15:49:40 INFO - (blobuploader) - INFO - Iteration through files over. 15:49:40 INFO - Return code: 0 15:49:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:49:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:49:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/016ca6284db7191b6e40e2e1c046abdc342b96fef8a2a1326b6b32e69c0893870e9c1e761c662c76d898db1ccecb2942091993ddcf135a3e5431e9a32d76cd88", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d9e33379de7c6414ca3b9aa781f8d5d0549046d4b1a1ba664c478d75be99fe3a7a165ea8cd8cba039e45c90348cbbda1e5d18643f44c3bb7359530d8a1122bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3d14d33192d58a62716e97a2052671089989b83cf88599d7323ac88a29d8b4fe9e4066fd6a7f413ab0c68e0cf5edcc166181754493abc216b5f9c98d5a0b3d57"} 15:49:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:49:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:49:40 INFO - Contents: 15:49:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/016ca6284db7191b6e40e2e1c046abdc342b96fef8a2a1326b6b32e69c0893870e9c1e761c662c76d898db1ccecb2942091993ddcf135a3e5431e9a32d76cd88", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d9e33379de7c6414ca3b9aa781f8d5d0549046d4b1a1ba664c478d75be99fe3a7a165ea8cd8cba039e45c90348cbbda1e5d18643f44c3bb7359530d8a1122bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3d14d33192d58a62716e97a2052671089989b83cf88599d7323ac88a29d8b4fe9e4066fd6a7f413ab0c68e0cf5edcc166181754493abc216b5f9c98d5a0b3d57"} 15:49:40 INFO - Running post-run listener: copy_logs_to_upload_dir 15:49:40 INFO - Copying logs to upload dir... 15:49:40 INFO - mkdir: /builds/slave/test/build/upload/logs 15:49:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.651797 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-25 15:49:39.617026) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-25 15:49:39.621585) ========= 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=c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510 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-Inbound/sha512/016ca6284db7191b6e40e2e1c046abdc342b96fef8a2a1326b6b32e69c0893870e9c1e761c662c76d898db1ccecb2942091993ddcf135a3e5431e9a32d76cd88", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d9e33379de7c6414ca3b9aa781f8d5d0549046d4b1a1ba664c478d75be99fe3a7a165ea8cd8cba039e45c90348cbbda1e5d18643f44c3bb7359530d8a1122bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3d14d33192d58a62716e97a2052671089989b83cf88599d7323ac88a29d8b4fe9e4066fd6a7f413ab0c68e0cf5edcc166181754493abc216b5f9c98d5a0b3d57"} build_url:https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011463 build_url: 'https://queue.taskcluster.net/v1/task/PeIAHku0ROWDkN1-VxSxkw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/016ca6284db7191b6e40e2e1c046abdc342b96fef8a2a1326b6b32e69c0893870e9c1e761c662c76d898db1ccecb2942091993ddcf135a3e5431e9a32d76cd88", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d9e33379de7c6414ca3b9aa781f8d5d0549046d4b1a1ba664c478d75be99fe3a7a165ea8cd8cba039e45c90348cbbda1e5d18643f44c3bb7359530d8a1122bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3d14d33192d58a62716e97a2052671089989b83cf88599d7323ac88a29d8b4fe9e4066fd6a7f413ab0c68e0cf5edcc166181754493abc216b5f9c98d5a0b3d57"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-25 15:49:39.649989) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 15:49:39.650351) ========= 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=c6927cf182745ad25b1d38b9000001fe-1474832783.102513-222994510 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 15:49:39.737422) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-25 15:49:39.738454) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-25 15:49:39.739252) ========= ========= Total master_lag: 0.18 =========