builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1479093136.2 results: success (0) buildid: 20161113163320 builduid: 22a2007f3c0244f8af685c0e8399581c revision: ec2757bbefb2134970ee18ff67ee43086a8ef4a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.195914) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.196404) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.196689) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.216953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.217365) ========= 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=6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-13 19:12:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-11-13 19:12:16 (113 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090139 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.332930) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.333224) ========= 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=6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019072 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.383249) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:16.383604) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ec2757bbefb2134970ee18ff67ee43086a8ef4a0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ec2757bbefb2134970ee18ff67ee43086a8ef4a0 --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=6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-13 19:12:16,834 truncating revision to first 12 chars 2016-11-13 19:12:16,834 Setting DEBUG logging. 2016-11-13 19:12:16,834 attempt 1/10 2016-11-13 19:12:16,834 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ec2757bbefb2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-13 19:12:17,184 unpacking tar archive at: mozilla-aurora-ec2757bbefb2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.684598 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:17.082082) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:17.082411) ========= script_repo_revision: ec2757bbefb2134970ee18ff67ee43086a8ef4a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:17.082841) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:17.083133) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-13 19:12:17.091516) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-13 19:12:17.091930) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:12:17 INFO - MultiFileLogger online at 20161113 19:12:17 in /builds/slave/test 19:12:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:12:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:12:17 INFO - {'append_to_log': False, 19:12:17 INFO - 'base_work_dir': '/builds/slave/test', 19:12:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:12:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:12:17 INFO - 'branch': 'Mozilla-Aurora', 19:12:17 INFO - 'buildbot_json_path': 'buildprops.json', 19:12:17 INFO - 'config_files': ('talos/linux_config.py',), 19:12:17 INFO - 'default_actions': ('clobber', 19:12:17 INFO - 'read-buildbot-config', 19:12:17 INFO - 'download-and-extract', 19:12:17 INFO - 'populate-webroot', 19:12:17 INFO - 'create-virtualenv', 19:12:17 INFO - 'install', 19:12:17 INFO - 'run-tests'), 19:12:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:12:17 INFO - 'download_minidump_stackwalk': True, 19:12:17 INFO - 'download_symbols': 'ondemand', 19:12:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:12:17 INFO - 'tooltool.py': '/tools/tooltool.py', 19:12:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:12:17 INFO - '/tools/misc-python/virtualenv.py')}, 19:12:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:12:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:12:17 INFO - 'installer_path': 'installer.exe', 19:12:17 INFO - 'log_level': 'info', 19:12:17 INFO - 'log_name': 'talos', 19:12:17 INFO - 'log_to_console': True, 19:12:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:12:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:12:17 INFO - 'opt_config_files': (), 19:12:17 INFO - 'pip_index': False, 19:12:17 INFO - 'sps_profile': False, 19:12:17 INFO - 'sps_profile_interval': 0, 19:12:17 INFO - 'suite': 'dromaeojs', 19:12:17 INFO - 'system_bits': '32', 19:12:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:12:17 INFO - 'title': 'talos-linux64-ix-023', 19:12:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:12:17 INFO - 'use_talos_json': True, 19:12:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:12:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:12:17 INFO - 'work_dir': 'build'} 19:12:17 INFO - [mozharness: 2016-11-14 03:12:17.640556Z] Running clobber step. 19:12:17 INFO - Running pre-action listener: _resource_record_pre_action 19:12:17 INFO - Running main action method: clobber 19:12:17 INFO - rmtree: /builds/slave/test/build 19:12:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:12:18 INFO - Running post-action listener: _resource_record_post_action 19:12:18 INFO - [mozharness: 2016-11-14 03:12:18.090182Z] Finished clobber step (success) 19:12:18 INFO - [mozharness: 2016-11-14 03:12:18.090282Z] Running read-buildbot-config step. 19:12:18 INFO - Running pre-action listener: _resource_record_pre_action 19:12:18 INFO - Running main action method: read_buildbot_config 19:12:18 INFO - Using buildbot properties: 19:12:18 INFO - { 19:12:18 INFO - "project": "", 19:12:18 INFO - "product": "firefox", 19:12:18 INFO - "script_repo_revision": "production", 19:12:18 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:12:18 INFO - "repository": "", 19:12:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:12:18 INFO - "buildid": "20161113163320", 19:12:18 INFO - "pgo_build": "True", 19:12:18 INFO - "basedir": "/builds/slave/test", 19:12:18 INFO - "buildnumber": 579, 19:12:18 INFO - "slavename": "talos-linux64-ix-023", 19:12:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:12:18 INFO - "platform": "ubuntu64_hw", 19:12:18 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:12:18 INFO - "branch": "mozilla-aurora", 19:12:18 INFO - "repo_path": "releases/mozilla-aurora", 19:12:18 INFO - "revision": "ec2757bbefb2134970ee18ff67ee43086a8ef4a0", 19:12:18 INFO - "stage_platform": "linux64-pgo", 19:12:18 INFO - "builduid": "22a2007f3c0244f8af685c0e8399581c", 19:12:18 INFO - "slavebuilddir": "test" 19:12:18 INFO - } 19:12:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 19:12:18 INFO - Running post-action listener: _resource_record_post_action 19:12:18 INFO - [mozharness: 2016-11-14 03:12:18.092722Z] Finished read-buildbot-config step (success) 19:12:18 INFO - [mozharness: 2016-11-14 03:12:18.092811Z] Running download-and-extract step. 19:12:18 INFO - Running pre-action listener: _resource_record_pre_action 19:12:18 INFO - Running main action method: download_and_extract 19:12:18 INFO - mkdir: /builds/slave/test/build/tests 19:12: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')]} 19:12:18 INFO - trying https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:12:18 INFO - Downloading https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:12:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:12:18 INFO - Downloaded 1361 bytes. 19:12:18 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:12:18 INFO - Using the following test package requirements: 19:12:18 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:12:18 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:12:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:12:18 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:12:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:12:18 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:12:18 INFO - u'jsshell-linux-x86_64.zip'], 19:12:18 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:12:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:12:18 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:12:18 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:12:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:12:18 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:12:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:12:18 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:12:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:12:18 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:12:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:12:18 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:12:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:12:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:12:18 INFO - Fetch https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:12:19 INFO - Expected file size: 21962988 19:12:23 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:12:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:12:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:12:23 INFO - Fetch https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:12:24 INFO - Expected file size: 21962988 19:12:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 19:12:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:12:28 INFO - Fetch https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 19:12:29 INFO - Expected file size: 14414089 19:12: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')]} 19:12:29 INFO - trying https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:12:29 INFO - Downloading https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:12:31 INFO - Downloaded 64062238 bytes. 19:12:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:12:31 INFO - mkdir: /builds/slave/test/properties 19:12:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:12:31 INFO - Writing to file /builds/slave/test/properties/build_url 19:12:31 INFO - Contents: 19:12:31 INFO - build_url:https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:12:31 INFO - Running post-action listener: _resource_record_post_action 19:12:31 INFO - Running post-action listener: set_extra_try_arguments 19:12:31 INFO - [mozharness: 2016-11-14 03:12:31.741063Z] Finished download-and-extract step (success) 19:12:31 INFO - [mozharness: 2016-11-14 03:12:31.741292Z] Running populate-webroot step. 19:12:31 INFO - Running pre-action listener: _resource_record_pre_action 19:12:31 INFO - Running main action method: populate_webroot 19:12:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:12:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:12:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:12:31 INFO - '--tppagecycles', 19:12:31 INFO - '7'], 19:12:31 INFO - 'tests': ['tsvgm']}}, 19:12:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:12:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:12:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:12:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:12:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:31 INFO - 'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:31 INFO - 'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['damp', 'tps']}, 19:12:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:31 INFO - 'tests': ['damp', 'tps']}, 19:12:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['dromaeo_dom']}, 19:12:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:12:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['basic_compositor_video']}, 19:12:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:12:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['a11yr', 19:12:31 INFO - 'ts_paint', 19:12:31 INFO - 'tpaint', 19:12:31 INFO - 'sessionrestore', 19:12:31 INFO - 'sessionrestore_no_auto_restore', 19:12:31 INFO - 'tabpaint']}, 19:12:31 INFO - 'other-e10s': {'tests': ['a11yr', 19:12:31 INFO - 'ts_paint', 19:12:31 INFO - 'tpaint', 19:12:31 INFO - 'sessionrestore', 19:12:31 INFO - 'sessionrestore_no_auto_restore', 19:12:31 INFO - 'tabpaint']}, 19:12:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:12:31 INFO - 'ts_paint', 19:12:31 INFO - 'tpaint', 19:12:31 INFO - 'sessionrestore', 19:12:31 INFO - 'sessionrestore_no_auto_restore', 19:12:31 INFO - 'tabpaint']}, 19:12:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:12:31 INFO - 'ts_paint', 19:12:31 INFO - 'tpaint', 19:12:31 INFO - 'sessionrestore', 19:12:31 INFO - 'sessionrestore_no_auto_restore', 19:12:31 INFO - 'tabpaint']}, 19:12:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['a11yr', 19:12:31 INFO - 'ts_paint', 19:12:31 INFO - 'tpaint', 19:12:31 INFO - 'sessionrestore', 19:12:31 INFO - 'sessionrestore_no_auto_restore', 19:12:31 INFO - 'tabpaint']}, 19:12:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['a11yr', 19:12:31 INFO - 'ts_paint', 19:12:31 INFO - 'tpaint', 19:12:31 INFO - 'sessionrestore', 19:12:31 INFO - 'sessionrestore_no_auto_restore', 19:12:31 INFO - 'tabpaint']}, 19:12:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['tsvgx', 19:12:31 INFO - 'tsvgr_opacity', 19:12:31 INFO - 'tart', 19:12:31 INFO - 'tscrollx', 19:12:31 INFO - 'cart']}, 19:12:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:12:31 INFO - 'tsvgr_opacity', 19:12:31 INFO - 'tart', 19:12:31 INFO - 'tscrollx', 19:12:31 INFO - 'cart']}, 19:12:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:31 INFO - 'talos_options': ['--disable-e10s'], 19:12:31 INFO - 'tests': ['tp5o']}, 19:12:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:31 INFO - 'tests': ['tp5o']}, 19:12:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:31 INFO - 'talos_options': ['--disable-e10s', 19:12:31 INFO - '--xperf_path', 19:12:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:31 INFO - 'tests': ['tp5n']}, 19:12:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:31 INFO - 'talos_options': ['--xperf_path', 19:12:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:31 INFO - 'tests': ['tp5n']}}, 19:12:31 INFO - 'talos.zip': {'path': '', 19:12:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:12:31 INFO - Running post-action listener: _resource_record_post_action 19:12:31 INFO - [mozharness: 2016-11-14 03:12:31.760629Z] Finished populate-webroot step (success) 19:12:31 INFO - [mozharness: 2016-11-14 03:12:31.760718Z] Running create-virtualenv step. 19:12:31 INFO - Running pre-action listener: _resource_record_pre_action 19:12:31 INFO - Running main action method: create_virtualenv 19:12:31 INFO - Creating virtualenv /builds/slave/test/build/venv 19:12:31 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 19:12:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:12:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:12:31 INFO - Using real prefix '/usr' 19:12:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:12:32 INFO - Installing distribute.............................................................................................................................................................................................done. 19:12:34 INFO - Installing pip.................done. 19:12:34 INFO - Return code: 0 19:12:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:12:34 INFO - Using env: {'DISPLAY': ':0', 19:12:34 INFO - 'HOME': '/home/cltbld', 19:12:34 INFO - 'LANG': 'en_US.UTF-8', 19:12:34 INFO - 'LANGUAGE': 'en_US:en', 19:12:34 INFO - 'LOGNAME': 'cltbld', 19:12:34 INFO - 'MAIL': '/var/mail/cltbld', 19:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:34 INFO - 'MOZ_NO_REMOTE': '1', 19:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:34 INFO - 'NO_EM_RESTART': '1', 19:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:34 INFO - 'PWD': '/builds/slave/test', 19:12:34 INFO - 'SHELL': '/bin/bash', 19:12:34 INFO - 'SHLVL': '1', 19:12:34 INFO - 'TERM': 'linux', 19:12:34 INFO - 'TMOUT': '86400', 19:12:34 INFO - 'USER': 'cltbld', 19:12:34 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:34 INFO - '_': '/tools/buildbot/bin/python'} 19:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:34 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 19:12:34 INFO - Cleaning up... 19:12:34 INFO - Return code: 0 19:12:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:12:34 INFO - Using env: {'DISPLAY': ':0', 19:12:34 INFO - 'HOME': '/home/cltbld', 19:12:34 INFO - 'LANG': 'en_US.UTF-8', 19:12:34 INFO - 'LANGUAGE': 'en_US:en', 19:12:34 INFO - 'LOGNAME': 'cltbld', 19:12:34 INFO - 'MAIL': '/var/mail/cltbld', 19:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:34 INFO - 'MOZ_NO_REMOTE': '1', 19:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:34 INFO - 'NO_EM_RESTART': '1', 19:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:34 INFO - 'PWD': '/builds/slave/test', 19:12:34 INFO - 'SHELL': '/bin/bash', 19:12:34 INFO - 'SHLVL': '1', 19:12:34 INFO - 'TERM': 'linux', 19:12:34 INFO - 'TMOUT': '86400', 19:12:34 INFO - 'USER': 'cltbld', 19:12:34 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:34 INFO - '_': '/tools/buildbot/bin/python'} 19:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:34 INFO - Downloading/unpacking psutil>=3.1.1 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:12:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:36 INFO - Installing collected packages: psutil 19:12:36 INFO - Running setup.py install for psutil 19:12:36 INFO - building 'psutil._psutil_linux' extension 19:12:36 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 19:12:37 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 19:12:37 INFO - building 'psutil._psutil_posix' extension 19:12:37 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 19:12:37 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 19:12:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:37 INFO - Successfully installed psutil 19:12:37 INFO - Cleaning up... 19:12:37 INFO - Return code: 0 19:12:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:12: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 mozsystemmonitor==0.3 19:12:37 INFO - Using env: {'DISPLAY': ':0', 19:12:37 INFO - 'HOME': '/home/cltbld', 19:12:37 INFO - 'LANG': 'en_US.UTF-8', 19:12:37 INFO - 'LANGUAGE': 'en_US:en', 19:12:37 INFO - 'LOGNAME': 'cltbld', 19:12:37 INFO - 'MAIL': '/var/mail/cltbld', 19:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:37 INFO - 'MOZ_NO_REMOTE': '1', 19:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:37 INFO - 'NO_EM_RESTART': '1', 19:12:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:37 INFO - 'PWD': '/builds/slave/test', 19:12:37 INFO - 'SHELL': '/bin/bash', 19:12:37 INFO - 'SHLVL': '1', 19:12:37 INFO - 'TERM': 'linux', 19:12:37 INFO - 'TMOUT': '86400', 19:12:37 INFO - 'USER': 'cltbld', 19:12:37 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:37 INFO - '_': '/tools/buildbot/bin/python'} 19:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:12:37 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 19:12:37 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 19:12:37 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 19:12:37 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 19:12:37 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 19:12:37 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 19:12:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:12:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:12:39 INFO - Installing collected packages: mozsystemmonitor 19:12:39 INFO - Running setup.py install for mozsystemmonitor 19:12:39 INFO - Successfully installed mozsystemmonitor 19:12:39 INFO - Cleaning up... 19:12:39 INFO - Return code: 0 19:12:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:12:39 INFO - Using env: {'DISPLAY': ':0', 19:12:39 INFO - 'HOME': '/home/cltbld', 19:12:39 INFO - 'LANG': 'en_US.UTF-8', 19:12:39 INFO - 'LANGUAGE': 'en_US:en', 19:12:39 INFO - 'LOGNAME': 'cltbld', 19:12:39 INFO - 'MAIL': '/var/mail/cltbld', 19:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:39 INFO - 'MOZ_NO_REMOTE': '1', 19:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:39 INFO - 'NO_EM_RESTART': '1', 19:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:39 INFO - 'PWD': '/builds/slave/test', 19:12:39 INFO - 'SHELL': '/bin/bash', 19:12:39 INFO - 'SHLVL': '1', 19:12:39 INFO - 'TERM': 'linux', 19:12:39 INFO - 'TMOUT': '86400', 19:12:39 INFO - 'USER': 'cltbld', 19:12:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:39 INFO - '_': '/tools/buildbot/bin/python'} 19:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:39 INFO - Downloading/unpacking jsonschema==2.5.1 19:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:12:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:42 INFO - Downloading functools32-3.2.3-2.tar.gz 19:12:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:12:42 INFO - warning: no files found matching '*.txt' 19:12:42 INFO - no previously-included directories found matching 'build' 19:12:42 INFO - no previously-included directories found matching 'dist' 19:12:42 INFO - no previously-included directories found matching '.git*' 19:12:42 INFO - Installing collected packages: jsonschema, functools32 19:12:42 INFO - Running setup.py install for functools32 19:12:42 INFO - warning: no files found matching '*.txt' 19:12:42 INFO - no previously-included directories found matching 'build' 19:12:42 INFO - no previously-included directories found matching 'dist' 19:12:42 INFO - no previously-included directories found matching '.git*' 19:12:42 INFO - Successfully installed jsonschema functools32 19:12:42 INFO - Cleaning up... 19:12:42 INFO - Return code: 0 19:12:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:42 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 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:42 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 19:12:42 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 19:12:42 INFO - Using env: {'DISPLAY': ':0', 19:12:42 INFO - 'HOME': '/home/cltbld', 19:12:42 INFO - 'LANG': 'en_US.UTF-8', 19:12:42 INFO - 'LANGUAGE': 'en_US:en', 19:12:42 INFO - 'LOGNAME': 'cltbld', 19:12:42 INFO - 'MAIL': '/var/mail/cltbld', 19:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:42 INFO - 'MOZ_NO_REMOTE': '1', 19:12:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:42 INFO - 'NO_EM_RESTART': '1', 19:12:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:42 INFO - 'PWD': '/builds/slave/test', 19:12:42 INFO - 'SHELL': '/bin/bash', 19:12:42 INFO - 'SHLVL': '1', 19:12:42 INFO - 'TERM': 'linux', 19:12:42 INFO - 'TMOUT': '86400', 19:12:42 INFO - 'USER': 'cltbld', 19:12:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:42 INFO - '_': '/tools/buildbot/bin/python'} 19:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:12:42 INFO - Cleaning up... 19:12:42 INFO - Return code: 0 19:12:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:42 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 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:42 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 19:12:42 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 19:12:42 INFO - Using env: {'DISPLAY': ':0', 19:12:42 INFO - 'HOME': '/home/cltbld', 19:12:42 INFO - 'LANG': 'en_US.UTF-8', 19:12:42 INFO - 'LANGUAGE': 'en_US:en', 19:12:42 INFO - 'LOGNAME': 'cltbld', 19:12:42 INFO - 'MAIL': '/var/mail/cltbld', 19:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:42 INFO - 'MOZ_NO_REMOTE': '1', 19:12:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:42 INFO - 'NO_EM_RESTART': '1', 19:12:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:42 INFO - 'PWD': '/builds/slave/test', 19:12:42 INFO - 'SHELL': '/bin/bash', 19:12:42 INFO - 'SHLVL': '1', 19:12:42 INFO - 'TERM': 'linux', 19:12:42 INFO - 'TMOUT': '86400', 19:12:42 INFO - 'USER': 'cltbld', 19:12:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:42 INFO - '_': '/tools/buildbot/bin/python'} 19:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:42 INFO - Downloading/unpacking blobuploader==1.2.4 19:12:42 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 19:12:42 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 19:12:42 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 19:12:42 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 19:12:42 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 19:12:42 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 19:12:44 INFO - Downloading blobuploader-1.2.4.tar.gz 19:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:12:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:12:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:12:45 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 19:12:45 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 19:12:45 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 19:12:45 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 19:12:45 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 19:12:45 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 19:12:45 INFO - Downloading docopt-0.6.1.tar.gz 19:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:12:45 INFO - Installing collected packages: blobuploader, requests, docopt 19:12:45 INFO - Running setup.py install for blobuploader 19:12:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:12:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:12:45 INFO - Running setup.py install for requests 19:12:45 INFO - Running setup.py install for docopt 19:12:45 INFO - Successfully installed blobuploader requests docopt 19:12:45 INFO - Cleaning up... 19:12:45 INFO - Return code: 0 19:12:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:45 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')]} 19:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:45 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')]} 19:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:45 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 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:45 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 19:12:45 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 19:12:45 INFO - Using env: {'DISPLAY': ':0', 19:12:45 INFO - 'HOME': '/home/cltbld', 19:12:45 INFO - 'LANG': 'en_US.UTF-8', 19:12:45 INFO - 'LANGUAGE': 'en_US:en', 19:12:45 INFO - 'LOGNAME': 'cltbld', 19:12:45 INFO - 'MAIL': '/var/mail/cltbld', 19:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:45 INFO - 'MOZ_NO_REMOTE': '1', 19:12:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:45 INFO - 'NO_EM_RESTART': '1', 19:12:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:45 INFO - 'PWD': '/builds/slave/test', 19:12:46 INFO - 'SHELL': '/bin/bash', 19:12:46 INFO - 'SHLVL': '1', 19:12:46 INFO - 'TERM': 'linux', 19:12:46 INFO - 'TMOUT': '86400', 19:12:46 INFO - 'USER': 'cltbld', 19:12:46 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:46 INFO - '_': '/tools/buildbot/bin/python'} 19:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:46 INFO - Running setup.py (path:/tmp/pip-CD8RMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:46 INFO - Running setup.py (path:/tmp/pip-zHgq0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:46 INFO - Running setup.py (path:/tmp/pip-hMaS4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:46 INFO - Running setup.py (path:/tmp/pip-Y9o05k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:46 INFO - Running setup.py (path:/tmp/pip-tc59Ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:46 INFO - Running setup.py (path:/tmp/pip-Ad8CAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:46 INFO - Running setup.py (path:/tmp/pip-4OFFrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:46 INFO - Running setup.py (path:/tmp/pip-oRvBkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:46 INFO - Running setup.py (path:/tmp/pip-4lVTLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:46 INFO - Running setup.py (path:/tmp/pip-4CtUX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:46 INFO - Running setup.py (path:/tmp/pip-tnSg7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:47 INFO - Running setup.py (path:/tmp/pip-Te4om6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:47 INFO - Running setup.py (path:/tmp/pip-5tdtr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:47 INFO - Running setup.py (path:/tmp/pip-tenohV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:47 INFO - Running setup.py (path:/tmp/pip-Bvrdfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:47 INFO - Running setup.py (path:/tmp/pip-oIo9pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:47 INFO - Running setup.py (path:/tmp/pip-gDBLZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:12:47 INFO - Running setup.py install for manifestparser 19:12:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:12:47 INFO - Running setup.py install for mozcrash 19:12:47 INFO - Running setup.py install for mozdebug 19:12:47 INFO - Running setup.py install for mozdevice 19:12:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:12:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:12:47 INFO - Running setup.py install for mozfile 19:12:47 INFO - Running setup.py install for mozhttpd 19:12:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Running setup.py install for mozinfo 19:12:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Running setup.py install for mozInstall 19:12:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Running setup.py install for mozleak 19:12:48 INFO - Running setup.py install for mozlog 19:12:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Running setup.py install for moznetwork 19:12:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Running setup.py install for mozprocess 19:12:48 INFO - Running setup.py install for mozprofile 19:12:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Running setup.py install for mozrunner 19:12:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Running setup.py install for mozscreenshot 19:12:49 INFO - Running setup.py install for moztest 19:12:49 INFO - Running setup.py install for mozversion 19:12:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:12:49 INFO - Cleaning up... 19:12:49 INFO - Return code: 0 19:12:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:49 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 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:49 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 19:12:49 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 19:12:49 INFO - Using env: {'DISPLAY': ':0', 19:12:49 INFO - 'HOME': '/home/cltbld', 19:12:49 INFO - 'LANG': 'en_US.UTF-8', 19:12:49 INFO - 'LANGUAGE': 'en_US:en', 19:12:49 INFO - 'LOGNAME': 'cltbld', 19:12:49 INFO - 'MAIL': '/var/mail/cltbld', 19:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:49 INFO - 'MOZ_NO_REMOTE': '1', 19:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:49 INFO - 'NO_EM_RESTART': '1', 19:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:49 INFO - 'PWD': '/builds/slave/test', 19:12:49 INFO - 'SHELL': '/bin/bash', 19:12:49 INFO - 'SHLVL': '1', 19:12:49 INFO - 'TERM': 'linux', 19:12:49 INFO - 'TMOUT': '86400', 19:12:49 INFO - 'USER': 'cltbld', 19:12:49 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:49 INFO - '_': '/tools/buildbot/bin/python'} 19:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:49 INFO - Running setup.py (path:/tmp/pip-GlE7xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:49 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)) 19:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:49 INFO - Running setup.py (path:/tmp/pip-af0Q6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:49 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)) 19:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:49 INFO - Running setup.py (path:/tmp/pip-SYcYWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:49 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)) 19:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:49 INFO - Running setup.py (path:/tmp/pip-gAQFu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:49 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)) 19:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:49 INFO - Running setup.py (path:/tmp/pip-RHBgmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:49 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)) 19:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:49 INFO - Running setup.py (path:/tmp/pip-0Knkdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:49 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)) 19:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:49 INFO - Running setup.py (path:/tmp/pip-6ky2e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:49 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)) 19:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:49 INFO - Running setup.py (path:/tmp/pip-C7Yxxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:50 INFO - Running setup.py (path:/tmp/pip-vxRQZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:50 INFO - Running setup.py (path:/tmp/pip-RRDENK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:50 INFO - Running setup.py (path:/tmp/pip-ng_wAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:50 INFO - Running setup.py (path:/tmp/pip-_PuoMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:50 INFO - Running setup.py (path:/tmp/pip-KJUd_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:50 INFO - Running setup.py (path:/tmp/pip-yXZbFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:50 INFO - Running setup.py (path:/tmp/pip-NU8UDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:50 INFO - Running setup.py (path:/tmp/pip-ozSEPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:50 INFO - Running setup.py (path:/tmp/pip-5FmNH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:50 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)) 19:12:50 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)) 19:12:50 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)) 19:12:50 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)) 19:12:50 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)) 19:12:50 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)) 19:12:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:12:50 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 19:12:50 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 19:12:50 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 19:12:50 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 19:12:50 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 19:12:50 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 19:12:52 INFO - Downloading blessings-1.6.tar.gz 19:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:12:52 INFO - Installing collected packages: blessings 19:12:52 INFO - Running setup.py install for blessings 19:12:52 INFO - Successfully installed blessings 19:12:52 INFO - Cleaning up... 19:12:52 INFO - Return code: 0 19:12:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:12:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:53 INFO - Reading from file tmpfile_stdout 19:12:53 INFO - Current package versions: 19:12:53 INFO - argparse == 1.2.1 19:12:53 INFO - blessings == 1.6 19:12:53 INFO - blobuploader == 1.2.4 19:12:53 INFO - docopt == 0.6.1 19:12:53 INFO - functools32 == 3.2.3-2 19:12:53 INFO - jsonschema == 2.5.1 19:12:53 INFO - manifestparser == 1.1 19:12:53 INFO - mozInstall == 1.12 19:12:53 INFO - mozcrash == 0.17 19:12:53 INFO - mozdebug == 0.1 19:12:53 INFO - mozdevice == 0.48 19:12:53 INFO - mozfile == 1.2 19:12:53 INFO - mozhttpd == 0.7 19:12:53 INFO - mozinfo == 0.9 19:12:53 INFO - mozleak == 0.1 19:12:53 INFO - mozlog == 3.4 19:12:53 INFO - moznetwork == 0.27 19:12:53 INFO - mozprocess == 0.23 19:12:53 INFO - mozprofile == 0.28 19:12:53 INFO - mozrunner == 6.12 19:12:53 INFO - mozscreenshot == 0.1 19:12:53 INFO - mozsystemmonitor == 0.3 19:12:53 INFO - moztest == 0.7 19:12:53 INFO - mozversion == 1.4 19:12:53 INFO - psutil == 3.1.1 19:12:53 INFO - requests == 1.2.3 19:12:53 INFO - wsgiref == 0.1.2 19:12:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:53 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')]} 19:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:53 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')]} 19:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:53 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 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:53 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 19:12:53 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 19:12:53 INFO - Using env: {'DISPLAY': ':0', 19:12:53 INFO - 'HOME': '/home/cltbld', 19:12:53 INFO - 'LANG': 'en_US.UTF-8', 19:12:53 INFO - 'LANGUAGE': 'en_US:en', 19:12:53 INFO - 'LOGNAME': 'cltbld', 19:12:53 INFO - 'MAIL': '/var/mail/cltbld', 19:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:53 INFO - 'MOZ_NO_REMOTE': '1', 19:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:53 INFO - 'NO_EM_RESTART': '1', 19:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:53 INFO - 'PWD': '/builds/slave/test', 19:12:53 INFO - 'SHELL': '/bin/bash', 19:12:53 INFO - 'SHLVL': '1', 19:12:53 INFO - 'TERM': 'linux', 19:12:53 INFO - 'TMOUT': '86400', 19:12:53 INFO - 'USER': 'cltbld', 19:12:53 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:53 INFO - '_': '/tools/buildbot/bin/python'} 19:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 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)) 19:12:53 INFO - Cleaning up... 19:12:53 INFO - Return code: 0 19:12:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:12:53 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')]} 19:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:53 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')]} 19:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:53 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 0x7f198c9fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', '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 19:12:53 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 19:12:53 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 19:12:53 INFO - Using env: {'DISPLAY': ':0', 19:12:53 INFO - 'HOME': '/home/cltbld', 19:12:53 INFO - 'LANG': 'en_US.UTF-8', 19:12:53 INFO - 'LANGUAGE': 'en_US:en', 19:12:53 INFO - 'LOGNAME': 'cltbld', 19:12:53 INFO - 'MAIL': '/var/mail/cltbld', 19:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:53 INFO - 'MOZ_NO_REMOTE': '1', 19:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:53 INFO - 'NO_EM_RESTART': '1', 19:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:53 INFO - 'PWD': '/builds/slave/test', 19:12:53 INFO - 'SHELL': '/bin/bash', 19:12:53 INFO - 'SHLVL': '1', 19:12:53 INFO - 'TERM': 'linux', 19:12:53 INFO - 'TMOUT': '86400', 19:12:53 INFO - 'USER': 'cltbld', 19:12:53 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:53 INFO - '_': '/tools/buildbot/bin/python'} 19:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:12:53 INFO - Cleaning up... 19:12:53 INFO - Return code: 0 19:12:53 INFO - Running post-action listener: _resource_record_post_action 19:12:53 INFO - Running post-action listener: _start_resource_monitoring 19:12:53 INFO - Starting resource monitoring. 19:12:53 INFO - [mozharness: 2016-11-14 03:12:53.592937Z] Finished create-virtualenv step (success) 19:12:53 INFO - [mozharness: 2016-11-14 03:12:53.593388Z] Running install step. 19:12:53 INFO - Running pre-action listener: _resource_record_pre_action 19:12:53 INFO - Running main action method: install 19:12:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:53 INFO - Reading from file tmpfile_stdout 19:12:53 INFO - Detecting whether we're running mozinstall >=1.0... 19:12:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:12:53 INFO - Reading from file tmpfile_stdout 19:12:53 INFO - Output received: 19:12:53 INFO - Usage: mozinstall [options] installer 19:12:53 INFO - Options: 19:12:53 INFO - -h, --help show this help message and exit 19:12:53 INFO - -d DEST, --destination=DEST 19:12:53 INFO - Directory to install application into. [default: 19:12:53 INFO - "/builds/slave/test"] 19:12:53 INFO - --app=APP Application being installed. [default: firefox] 19:12:53 INFO - mkdir: /builds/slave/test/build/application 19:12:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:13:09 INFO - Reading from file tmpfile_stdout 19:13:09 INFO - Output received: 19:13:09 INFO - /builds/slave/test/build/application/firefox/firefox 19:13:09 INFO - Running post-action listener: _resource_record_post_action 19:13:09 INFO - [mozharness: 2016-11-14 03:13:09.905960Z] Finished install step (success) 19:13:09 INFO - [mozharness: 2016-11-14 03:13:09.906205Z] Running run-tests step. 19:13:09 INFO - Running pre-action listener: _resource_record_pre_action 19:13:09 INFO - Running main action method: run_tests 19:13:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:13:09 INFO - Python 2.7.3 19:13:09 INFO - Return code: 0 19:13:09 INFO - grabbing minidump binary from tooltool 19:13:09 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')]} 19:13:09 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 0x163bf60>, '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 0x163cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163d1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:13:09 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 19:13:09 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 19:13:09 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 19:13:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:13:10 INFO - Return code: 0 19:13:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:13:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:13:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:13:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:13:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 19:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 19:13:10 INFO - Using env: {'DISPLAY': ':0', 19:13:10 INFO - 'HOME': '/home/cltbld', 19:13:10 INFO - 'LANG': 'en_US.UTF-8', 19:13:10 INFO - 'LANGUAGE': 'en_US:en', 19:13:10 INFO - 'LOGNAME': 'cltbld', 19:13:10 INFO - 'MAIL': '/var/mail/cltbld', 19:13:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:13:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:10 INFO - 'MOZ_NO_REMOTE': '1', 19:13:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:10 INFO - 'NO_EM_RESTART': '1', 19:13:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:10 INFO - 'PWD': '/builds/slave/test', 19:13:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:13:10 INFO - 'SHELL': '/bin/bash', 19:13:10 INFO - 'SHLVL': '1', 19:13:10 INFO - 'TERM': 'linux', 19:13:10 INFO - 'TMOUT': '86400', 19:13:10 INFO - 'USER': 'cltbld', 19:13:10 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794', 19:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:10 INFO - '_': '/tools/buildbot/bin/python'} 19:13:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 19:13:10 INFO - mozversion application_buildid: 20161113163320 19:13:10 INFO - mozversion application_changeset: ec2757bbefb2134970ee18ff67ee43086a8ef4a0 19:13:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:13:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:13:10 INFO - mozversion application_name: Firefox 19:13:10 INFO - mozversion application_remotingname: firefox-dev 19:13:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:13:10 INFO - mozversion application_vendor: Mozilla 19:13:10 INFO - mozversion application_version: 51.0a2 19:13:10 INFO - mozversion platform_buildid: 20161113163320 19:13:10 INFO - mozversion platform_changeset: ec2757bbefb2134970ee18ff67ee43086a8ef4a0 19:13:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:13:10 INFO - mozversion platform_version: 51.0a2 19:13:10 INFO - using testdate: 1479093190 19:13:10 INFO - actual date: 1479093190 19:13:10 INFO - starting webserver on 'localhost:34547' 19:13:10 INFO - SUITE-START | Running 2 tests 19:13:10 INFO - TEST-START | dromaeo_css 19:13:10 INFO - operating with platform_type : linux_ 19:13:10 INFO - Initialising browser for dromaeo_css test... 19:13:10 INFO - TEST-INFO | started process 7321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkBEi7W/profile http://localhost:34547/getInfo.html) 19:13:14 INFO - PROCESS | 7321 | __metrics Screen width/height:1600/1200 19:13:14 INFO - PROCESS | 7321 | colorDepth:24 19:13:14 INFO - PROCESS | 7321 | Browser inner width/height: 1024/697 19:13:14 INFO - PROCESS | 7321 | __metrics 19:13:14 INFO - PROCESS | 7321 | 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" 19:13:14 INFO - PROCESS | 7321 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:13:15 INFO - PROCESS | 7321 | console.error: PushService: 19:13:15 INFO - PROCESS | 7321 | stateChangeProcessEnqueue: Error transitioning state 19:13:15 INFO - PROCESS | 7321 | UnknownError 19:13:15 INFO - TEST-INFO | 7321: exit 0 19:13:15 INFO - Browser initialized. 19:13:15 INFO - Running cycle 1/1 for dromaeo_css test... 19:13:15 INFO - TEST-INFO | started process 7411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkBEi7W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:27 INFO - PROCESS | 7411 | RSS: Main: 145047552 19:13:27 INFO - PROCESS | 7411 | 19:14:19 INFO - PROCESS | 7411 | 1479093259261 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] 19:16:27 INFO - PROCESS | 7411 | Cycle 1(1): loaded http://localhost:34547/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34547/tests/dromaeo/cssquery-ext.html) 19:16:28 INFO - PROCESS | 7411 | RSS: Main: 227332096 19:16:28 INFO - PROCESS | 7411 | 19:19:31 INFO - PROCESS | 7411 | Cycle 1(1): loaded http://localhost:34547/tests/dromaeo/cssquery-ext.html (next: http://localhost:34547/tests/dromaeo/cssquery-jquery.html) 19:19:31 INFO - PROCESS | 7411 | RSS: Main: 232452096 19:19:31 INFO - PROCESS | 7411 | 19:22:33 INFO - PROCESS | 7411 | Cycle 1(1): loaded http://localhost:34547/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34547/tests/dromaeo/cssquery-mootools.html) 19:22:34 INFO - PROCESS | 7411 | RSS: Main: 216231936 19:22:34 INFO - PROCESS | 7411 | 19:25:35 INFO - PROCESS | 7411 | Cycle 1(1): loaded http://localhost:34547/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34547/tests/dromaeo/cssquery-prototype.html) 19:25:36 INFO - PROCESS | 7411 | RSS: Main: 234381312 19:25:36 INFO - PROCESS | 7411 | 19:28:39 INFO - PROCESS | 7411 | Cycle 1(1): loaded http://localhost:34547/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34547/tests/dromaeo/cssquery-yui.html) 19:28:39 INFO - PROCESS | 7411 | RSS: Main: 177004544 19:28:39 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | Cycle 1(1): loaded http://localhost:34547/tests/dromaeo/cssquery-yui.html (next: http://localhost:34547/tests/dromaeo/cssquery-dojo.html) 19:31:42 INFO - PROCESS | 7411 | RSS: Main: 266502144 19:31:42 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | __start_tp_report 19:31:42 INFO - PROCESS | 7411 | _x_x_mozilla_page_load 19:31:42 INFO - PROCESS | 7411 | _x_x_mozilla_page_load_details 19:31:42 INFO - PROCESS | 7411 | |i|pagename|runs| 19:31:42 INFO - PROCESS | 7411 | |0;dojo.html;3122.5784519089925;3157.9210519737003;3173.159112835099;3195.3928753536843;3208.518722191671;5489.58828087893;5594.272744543214;5604.299462567179;5606.159076138581;5625.831225063244;5573.972130139344;5635.6055076144585;5644.096944488882;5649.576281778873;5676.375598684142;5088.236764485338;5101.872453188681;5127.615428842833;5163.251328557357;5182.637215394924;4942.481039490855;4954.777035033432;4960.280759289901;5140.203268493389;5185.429602743714;5003.649744517867;5105.668131571455;5113.335266415379;5116.692998420107;5122.667026643275;5107.4892510749;5111.207762796773;5118.573136707859;5125.436202017775;5126.282320475118;4909.0181963607065;4947.876303092451;5022.321986531844;5029.119904016819;5086.643934924557;5458.61789674723;5529.778808847641;5600.131979543177;5625.915611265835;5640.887182256373;5860.296764388258;5930.436608522184;5965.343812180657;5965.463108320272;5969.880602387928;5985.670788106652;6002.2197114375285;6006.909896351558;6014;6018.368071352533;5449.237106805051;5476.808311709071;5505.724713764336;5549.1676248562235;5549.556035517149;6060.090986351995;6090.969545152335;6091.42131497507;6094.421030002142;6100.176476175749;5836.212111364996;5845.3277873045145;5846.239988801513;5847.766089356463;5856.0630299151935;5799.391063938226;5814.592978491465;5870.530357571384;5960.76156953717;5966.045432730829;5830.970845145747;5881.941180588225;5882.088276317178;5909;5923.259592550931;5934.554908381802;5938.465538101592;5956.970215148983;5982.312034116132;5993.130996005555;4524.524924882906;4560.635149188057;4590.655700822452;4604.58558729716;4633.189191891405;4005.759654420686;4010.218007488512;4019.2162528307285;4072.2873497138476;4085.0604360997136;5724.370319264877;5740.971295143498;5742.425757424307;5753.827385178452;5770.336411312669;6010.519158467399;6013.54898382623;6016.548758843104;6017.518598512109;6020.458158765732;5547.029269877755;5593.916091258634;5610.859728506738;5658.97170514153;5704.458076482728;5153.742312884341;5172.250023746611;5203.349581302337;5245.3705555332845;5246.6065045121;6159.969200153971;6180.938190618036;6190.7523699052435;6191.752329906753;6192.318844927055;5803.216565763568;5887.587868849139;5942.494887934401;5986.940130598812;5993.220881285405;6174.25908890936;6236.844078898064;6257.2804127525915;6258.342873998165;6275;5917.437843404869;5931.406859314034;5940.198073260054;5941.554383421174;5964.940350596612;5825.330087040044;5882.852928676817;5998.700064996646;6005.159277701038;6063.272407310975;5748.137779333133;5749.597528172988;5802.129680547782;5837.795677151279;5869.8239052828485;5816.04035334182;5829.067349224104;5829.795957141479;5831.387704290988;5834.416558344132;5642.915356269741;5778.219940308005;5781.277340332458;5793.8551536211935;5798.15926690619;5747.7413516391025;5830.300363956352;5896.233489646319;5910.52715782745;5989.670568118621;5726.455986681259;5779.133130030529;5783.94216057834;5789.363170051207;5817.127430885232 19:31:42 INFO - PROCESS | 7411 | |1;ext.html;599.4515018757837;600.5795942840012;635.1045026512618;637.9983426021146;650.8047585724281;32501;33124.83437582812;35513;35536.822315888414;35545;44391.334129988;44960.550394496124;45054.09891802162;48739.756301218535;48863.26705099426;9066.32002599806;9202.723918282461;9209.953950230272;9211.86182207264;9215.539223038855;8471.110533393998;8657.69698060568;9106.362554621179;9217;9218.539073046355;10888.61889833856;11126.276792008493;11131.443427828617;11395.658130256106;11401.771964560703;10933;10978.780424391507;10982.450877456135;11005.559777608885;11009.61466348682;10951.616693415772;11022.283551569122;11028.558857645685;11028.613998510094;11032;42875.35686964698;42972;43136;43136.56863431357;43236.56763432357;3601.477785721082;3608.0799396154075;3616.0959760059986;3796.9810150949343;4401.5598440156045;32923.17692057694;33085.83457082699;33261.50107748386;33325.6667433325;33547.32905341904;4205.369194620801;4370.759608221547;4393.8462153824785;4461.21928662486;4461.241588929857;377558.99999999726;378512;379208;379828;385064;63839.76560820349;64275.35724642787;64337;64358;64375;35158.29683406318;35212.82393588067;35243.29513409718;35497.822510887796;66793.33206667968;18464.723029154575;18607.06964651762;18644.067796610387;18654.44036678902;18666.066696665384;11490.13823963206;11490.368029758276;11490.827637585442;11492.597759078391;11615.941920290514;17841.28634854617;17893.821061789477;19420.67214724163;19633.31283405074;20439.080241389172;7330.523515971445;7411.851163069625;7474.514156579914;7490.775276741707;7548.509346892434;958.6165533786402;980.2011360740973;998.6554638649654;1023.1303392116641;1110.5890820396505;32512.512312315335;34065.829670851985;34254.65745342515;34944;36129.819350903075;1093.0818112785298;1132.8753837077916;1145.6391236760394;1194.7610477904454;1204.1029433072417;14922.626934326512;15983.520494385188;16482.2582983766;17661.29354825818;18151.45545633633;585824;592879;593394;594066;594128;56919.43080569224;56983;57316;57383.42616573781;57387;1354.1739538881275;1405.4799724102147;1414.3281941077905;1416.2564653557172;1417.4613646814144;30610.604313026175;33097.338053238316;33276.334473309915;33325.66674333226;33557.49663755095;52630;53152.46847531475;53249.20126198033;53351.199732003275;53384.19923701225;485552;489371;489560;490678;491290;24397;25057;26006;29213.561796572645;29589.704102959557;38959.415608765325;43583.12833743371;43678;43796;43796.56203437925;37259.254814904096;37349.066273343386;38006.80996594999;38014.239715206095;38016.809915950245;37443.62556374401;38436.61563384442;38849;39058.80470597629;39540.60459395369;45606.77196613995;46104.53895461003;46897.29654055124;47472;48339.274910877066;39872.40191397073;40786;43277.13445731005;44139.77930110329;44898;34473;35573.1106722334;35663.28673426569;35908.820455897556;36188.27623447569 19:31:42 INFO - PROCESS | 7411 | |2;jquery.html;412.7172886572698;419.8383622305411;426.5435983497657;435.46872815165494;437.52309982119505;19177.42467725969;20499.69250461244;21155.788442115576;21353.466163345925;21596.784032159674;20006.499837504034;20220.191192352326;20250.08874600643;20313.289034883783;20487.078081486332;5730.341010783772;6032.215811944463;6039;6042.184305118797;6042.728077236513;5743.712814359286;5876.412358764111;5975.492083172936;5996.760129594811;6003.249593800775;5974.402559744012;5985.072313791369;5986.132010858445;5989.790357337494;5989.970050149743;5699.059655156894;5706.058500347438;5709.743061562198;5713.800016999385;5716.228309178249;5115.539601435851;5166.844994650129;5167.741612919339;5168.974155129238;5173.948260517384;23486.177983770485;23780.881095594585;23979.52040959171;24234.87882560576;24709.752902470926;2452.080469823816;2469.370310570811;2511.9246422607353;2536.5561026820233;2601.4536947241104;14658.560243192722;14765.114093154445;15006.699866002728;15232.695346093127;15266.38934442621;1981.7027445883232;2043.2746375036945;2056.598963202176;2071.8756874587575;2081.6565266731122;83997;85082.99999999937;85972;86340;88089.55955220247;10668.253222274367;10964.671059868217;11241.71895702614;11617.128715346384;11628.127890408254;16372.672546549002;16433.342666293454;16465.91767041157;16495.257713402927;16505.257263423176;8222.38332125081;8313.6674533018;8331.625076871554;8480.236778689825;8792.252658523968;7677.232276772278;7694;7784.610769461618;7858.5677787722225;8018.158093400226;6870.484713646397;7013.6142512161305;7040.9647951760935;7051.153861536546;7052.682629281695;1948.80511948804;2034.501547120965;2070.1615845582564;2080.5318803269142;2108.7891210879097;764.6291548598925;920.3603495570514;923.8060007398522;929.8186853563625;1064.5156453813497;20385.184592616424;20626.896865515875;20666.17335306601;20758.58482830335;21006.2647807326;1045.7542477517777;1054.9789004220027;1057.286331726082;1076.8546246256656;1106.8671759388924;11603;11947.581834635746;12048.096392770578;12112.273263604213;12218.877811221773;483673;488951;502113;509600;509949;89796.55101724449;90047;91141.54429227811;93284.53357733303;93651.53174234086;1050.1388861133796;1085.46812062089;1109.7003808971533;1116.7319843237565;1120.2158489057692;16919.323227070778;17605.383811566535;17938.282468701105;18021;18501.90749046246;45445.318320224564;45518.544814552755;46143.3078503816;46946.29580556226;47148.057038858344;57955;60383.396166037775;61369.386306136366;62277;62863.685681571296;6076.118962750288;6921.515493915377;8210.096889342287;8558.700445484377;8841.823163536565;11895.583654572049;13126.40931158081;13556.796648050484;13743.793843092562;13784.310784460937;11765.411729413323;11819.704507387383;11900.285982841056;12137.878621213675;12645.747085058063;11443.771124577628;11512.53949841997;11611.709707257049;11696.344330792372;12341.876581234432;11159.10727141843;11962.521499139937;12017;12454.128211025141;12535.122541422013;8925.018247992846;8933;9127.406718563272;9323.72028839136;9375.390599611004;9279.350445468752;11511.424428778695;11944.820827687765;12156.756864862477;12716.745665086832 19:31:42 INFO - PROCESS | 7411 | |3;mootools.html;1504.7291487532243;1547.1104115133796;1678.202853644519;1772.4328214971215;1815.7911840138388;8426.241638252555;9108.54457277136;9111.179993800557;9113.68102116426;9116.453012819218;7868.960655196716;8053.879191812128;8373.204545568176;8588.527630980308;8935.106489351077;2474.2082533589214;2492.950140997188;2494.2267896952003;2498.5127900059497;2501.799856011524;2395.4849707312924;2501.249625112468;2512.1584269269815;2512.798976081909;2515.55977703902;2458.1150785717127;2480.1443501991785;2480.4295012147218;2482.118847809029;2487.4154573675173;2425.0663494554724;2444.2422848916895;2445.8410203336816;2481.3424442522746;2481.739417361169;2363.988180059106;2426.59961106419;2435.8416702914433;2443.4624382635793;2468.0868078810954;8889.955550222272;8944.64221431142;8955.328350373748;8991.820163596756;9030.909690903072;5167.819126330561;5302.814401495968;5303.6552624079695;5307.495787900143;5310.548603368718;3552.5736911570775;3598.4062629666073;3656.5063716398477;3665.3402387570227;3685.2261025184744;1179.3277831635971;1193.2840295822523;1207.6739280394245;1221.8472690913636;1237.523553431926;12330.32183229922;12343;12349.19730217533;12356.876431235753;12369.072319575978;4586.610138138296;4625.121226966933;4734.124187025412;4739.075880203396;4833.226683730586;9724.95137524308;9890.208783297463;9909.405435673883;9910.851337229948;9924.354916930377;5384.838454846361;5388.946110538923;5396.9730151348995;5400.675959442446;5401.810936617199;4445.244308467568;4576.313552967082;4787.234042553175;4867.561919427269;5074.188129899198;3961.3661814110183;4001.8199181036925;4044.96853302406;4085.3259212230246;4103.835846566104;1174.6417342710386;1193.6538403862955;1194.235689158939;1195.5277665322149;1208.583038851595;945.8817647794026;957.707899090784;958.3818437108026;973.8928717841031;1001.979960400788;5877.030290002187;5924.259467566554;5974.940250597525;6074.36219196978;6152.292486364126;451.8057235388814;457.3231617206553;459.62081282941705;462.6391414696542;467.0332411907387;3928.135810121769;3993.5407428146136;4017.5580686124504;4030.1133750574827;4053.0677944073027;10785.676429707122;11083.778324433466;11164.441777911072;11168.329900205852;11184.888151118384;11039.613613523488;11040.889591104147;11049.889501105046;11050.115990720724;11059.281146725598;7803.297703206852;8088.069871964682;8179.918200818035;8180.836383272302;8182.386321025828;6054.212952316171;6107.236595425572;6119.84700382494;6190.133381326708;6246.562740608296;10182.287239893136;10294.073533382032;10296.742581435525;10358.637447689294;10391;10128.24038197153;10298.897011029794;10335;10362.637307694195;10518.053375196268;6891.138607674041;7070.823229419306;7081.681324340314;7887.5267483948855;7962.601869906597;7613.162552119152;8389.203025712548;8577.8713319299;8614.526201059001;8817.206451419403;7800.258975397328;7813.765587032398;7882.408819338458;7950.085740139958;8131.105578386255;7542.811429714301;7608.429367797548;7626.351760100453;7736.1490236075115;8007.959960200162;6766.729330826909;6848.760293389708;6853.109095817711;6858.4856135791015;6862.965685171542;4869.147899117569;5112.130937740668;5120.334356533627;5147.433782284021;5341.359036915595;5288.312519372457;5306.389765177054;5346.759395827275;5412.188171774265;5473.124300111963 19:31:42 INFO - PROCESS | 7411 | |4;prototype.html;328.93602982090715;339.0946173716178;345.41969491254684;346.34022187732364;347.47010808517007;5336.039512887679;5406.729663516819;5427.050266203413;5464.6994415307145;5519.365272993607;5334.5999050071305;5347.304850369456;5359.490848369408;5368.32895888014;5370.946290537094;4271.252530807112;4275.187714334282;4280.78596070196;4291.163223171483;4319.913601727963;3807.676347510465;3988.321985262498;4059.2490389278078;4173.770442625654;4234.851780187694;4176.498820141587;4178.665706743468;4178.707490475668;4199.853005144821;4203.789810509462;4220.662347012231;4232.433999420219;4234.830606775725;4242.257604919127;4265.914681706348;3627.657766626365;3666.5416822897137;3728.738988270822;3825.885223443301;3836.9808150959066;5239.580833533346;5256.81601143962;5258.789648414059;5263.184206447969;5268.631195816294;4858.149823780825;4864.756762161913;4873.439554451248;4879.609631229493;4892.779824907888;5521.889562208774;5565.220869078333;5596.384397716248;5630.831075067755;5654.15187721845;4152.771597562133;4162.41726158338;4164.541900390955;4185.623293903533;4203.97898010508;5636.126400407943;5835.474807267324;5921.644701317891;5951.166836642873;5953.345132035516;4048.7773172475795;4216.536181020086;4230.175115852441;4245.532991370947;4246.766427846436;4269.167512335065;4302.440682711227;4315.266404711207;4320.891977700519;4324.875532361546;4747.810087596457;4875.512448755097;4875.536824001785;4888;4901.730404827697;5630.831075067755;5643;5650.604457688005;5652.349979752299;5669.149627555899;1483.5920121966547;1502.9398824046916;1505.6160679026777;1507.811523559555;1515.931268455736;921.589892497832;969.1035791892472;971.2472833554052;975.5902520941222;981.6417007792104;4871.3667223260745;4880.926786098212;4892.486288939681;4948.48657453205;4988.476209997971;5108.667936584185;5124.256982737482;5147.897042059138;5150.562202212854;5155.304033955443;4583.289590113538;4842.806287748521;4896.608271338285;4910.705357678551;4941.196463290945;2739.1919383781833;2747.639918240484;2768.335599456116;2768.43247134337;2769.612254284402;5318.494742999409;5351.705656188947;5357.705326207096;5384.273123128406;5426.8371948841595;5205.323307969939;5217.373915130209;5581.49766521015;5932.851678708067;5939.2575928009;6040.456358927718;6055.303640081359;6061.393860613904;6083.361247068994;6095.451409373178;5624.775008999758;5878.911816322673;5907.881842363043;5955.927932972107;5976.7908123215475;5758.021136406905;5774.94225057744;5796.21751063601;5799.594028417971;5806.274215723035;5782.277215348082;5942.365849391452;5975.312839023394;5991;6085.391460853879;4057.4928133983253;4184.782065505287;4199.2441360555395;4216.093539888939;4245.495157856879;5719.5710321726865;5888.558358123244;5903.291605007426;5915.556333274935;5931.161339984618;5694.886102278014;5702.486776190163;5709.372828743788;5744.339400968942;5756.395578464201;5644.689542075225;5679.3184817820475;5699.2590963174525;5703.258576385043;5706.3152421709665;5424.647397919122;5659.603827732184;5664.0371136906;5681.517071049007;5686.800961966312;5681.488666020078;5746.683932383759;5748.137779333133;5749.137629355463;5752.741126649227;5605.215269862141;5610.615488417325;5639.407862174413;5677.318721753416;5693.28833895763 19:31:42 INFO - PROCESS | 7411 | |5;yui.html;799.8480288745138;820.9548474833882;824.7525742277315;879.147227189626;908.1644886704231;17633.11834408279;18449.53876153097;18460.16929238184;18558.443246702587;18563.72154417683;16942.661146777056;16952.23714932828;17064.829351706478;17257.223424945878;18204.271829126854;713.1014921199275;835.7952301686096;855.7946092937681;858.4420126917491;858.5707146426787;727.4362369163888;838.6310023589601;849.7068511363569;856.0839901305606;859.7463748194266;898.8516894712398;899.1592860675289;899.9100089991014;901.5447199164413;901.7565257380503;900.6307413960245;901.369041670833;902.1519771414881;902.5803001604253;902.9413088149282;551.5504863536245;557.2282388891374;558.5643198305328;559.7565059199261;646.6217262901208;16363.59091022734;16952.91523542387;17019.65960680792;17073.914630426894;17101.743473847902;14952;14979.475718349915;15076.472323468735;15121.773173402407;15345.156016419092;14194.077384969944;14210.644733881736;14263.78604320936;14705.338259778335;14741.852581474262;4704.106219818258;4731;4784.641151913585;4935.703857768545;4959.8016079356785;87902.56048719716;87929;87985;87986;88374.55812720895;12119.272843629235;12213.389330533615;12308.26150430977;12349;12445;21606.027728752244;21676.891615542136;21691;21745;21797.237096701538;8633.611487483078;8635.611397487128;8926.86609700855;9091.727248182566;9516.333856630108;5213.218017297436;5225.764840582183;5231.24146998683;5381.327334083239;5524.530414914777;3703.574088979748;3819.388897776342;3843.3466310727244;3862.9034274143373;3863.9806800966376;624.3350831364583;625.8842114208886;635.3646353646353;636.7293900092442;639.1626968670963;634.4924060751381;677.8373190434261;712.6436781609195;726.1395246632757;734.6583838515116;10264.43545604984;10376.481175941324;10974.890251097546;11106.833397499044;11232;783.2559068884583;785.4933567848819;809.8461292354551;809.9757007289791;825.2531459029587;5173.844784656466;5349.5720342372515;5356.196570514454;5406.432324605938;5418.593605479605;87411.12588874158;130876;132364;133864.33067834793;135534;60808.08787868186;85375.57312213523;114614;115773;116135;90268.5486572563;90734;90803;92015.4790712546;93206;10494.475276236006;10537.736556586147;10646.520906559379;10717.839232411365;10732.409717465614;53689;54712;55022;55188.44811551833;55617.44382556285;78068.60965695135;79941;81560;82238.17761822544;82509;13384.732305353644;14093.295335232991;14105.647358816112;14282.57152285433;14506.1296322221;15479.922600386926;15567.299471523582;15571.29929153168;15994;16114.516564503083;14960.476383326532;15166.469173578871;15183.544493665207;15252.542423727306;15347.386104555693;14612.196329201995;14820.925895370454;14963.700725985202;15209.239538023276;15478.090829276422;14816.333265002886;14871.85128148748;15042.097474151586;15475.535733928;15525.922370388076;11773.47019384147;12188.695282617651;12647.051471139517;13008.544700935421;13124.737505250032;11722.589709360473;12232.204906681038;12479.251244925334;12545.87454125447;12608.36958152107 19:31:42 INFO - PROCESS | 7411 | __end_tp_report 19:31:42 INFO - PROCESS | 7411 | __start_cc_report 19:31:42 INFO - PROCESS | 7411 | _x_x_mozilla_cycle_collect,1076 19:31:42 INFO - PROCESS | 7411 | __end_cc_report 19:31:42 INFO - PROCESS | 7411 | __startTimestamp1479094302587__endTimestamp 19:31:42 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | ------- Summary: start ------- 19:31:42 INFO - PROCESS | 7411 | Number of tests: 6 19:31:42 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | [#0] dojo.html Cycles:165 Average:5543.85 Median:5781.28 stddev:636.25 (11.0%) stddev-sans-first:609.18 19:31:42 INFO - PROCESS | 7411 | Values: 3122.6 3157.9 3173.2 3195.4 3208.5 5489.6 5594.3 5604.3 5606.2 5625.8 5574.0 5635.6 5644.1 5649.6 5676.4 5088.2 5101.9 5127.6 5163.3 5182.6 4942.5 4954.8 4960.3 5140.2 5185.4 5003.6 5105.7 5113.3 5116.7 5122.7 5107.5 5111.2 5118.6 5125.4 5126.3 4909.0 4947.9 5022.3 5029.1 5086.6 5458.6 5529.8 5600.1 5625.9 5640.9 5860.3 5930.4 5965.3 5965.5 5969.9 5985.7 6002.2 6006.9 6014.0 6018.4 5449.2 5476.8 5505.7 5549.2 5549.6 6060.1 6091.0 6091.4 6094.4 6100.2 5836.2 5845.3 5846.2 5847.8 5856.1 5799.4 5814.6 5870.5 5960.8 5966.0 5831.0 5881.9 5882.1 5909.0 5923.3 5934.6 5938.5 5957.0 5982.3 5993.1 4524.5 4560.6 4590.7 4604.6 4633.2 4005.8 4010.2 4019.2 4072.3 4085.1 5724.4 5741.0 5742.4 5753.8 5770.3 6010.5 6013.5 6016.5 6017.5 6020.5 5547.0 5593.9 5610.9 5659.0 5704.5 5153.7 5172.3 5203.3 5245.4 5246.6 6160.0 6180.9 6190.8 6191.8 6192.3 5803.2 5887.6 5942.5 5986.9 5993.2 6174.3 6236.8 6257.3 6258.3 6275.0 5917.4 5931.4 5940.2 5941.6 5964.9 5825.3 5882.9 5998.7 6005.2 6063.3 5748.1 5749.6 5802.1 5837.8 5869.8 5816.0 5829.1 5829.8 5831.4 5834.4 5642.9 5778.2 5781.3 5793.9 5798.2 5747.7 5830.3 5896.2 5910.5 5989.7 5726.5 5779.1 5783.9 5789.4 5817.1 19:31:42 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | [#1] ext.html Cycles:180 Average:64162.22 Median:191812.49 stddev:131531.61 (68.6%) stddev-sans-first:131814.01 19:31:42 INFO - PROCESS | 7411 | Values: 599.5 600.6 635.1 638.0 650.8 32501.0 33124.8 35513.0 35536.8 35545.0 44391.3 44960.6 45054.1 48739.8 48863.3 9066.3 9202.7 9210.0 9211.9 9215.5 8471.1 8657.7 9106.4 9217.0 9218.5 10888.6 11126.3 11131.4 11395.7 11401.8 10933.0 10978.8 10982.5 11005.6 11009.6 10951.6 11022.3 11028.6 11028.6 11032.0 42875.4 42972.0 43136.0 43136.6 43236.6 3601.5 3608.1 3616.1 3797.0 4401.6 32923.2 33085.8 33261.5 33325.7 33547.3 4205.4 4370.8 4393.8 4461.2 4461.2 377559.0 378512.0 379208.0 379828.0 385064.0 63839.8 64275.4 64337.0 64358.0 64375.0 35158.3 35212.8 35243.3 35497.8 66793.3 18464.7 18607.1 18644.1 18654.4 18666.1 11490.1 11490.4 11490.8 11492.6 11615.9 17841.3 17893.8 19420.7 19633.3 20439.1 7330.5 7411.9 7474.5 7490.8 7548.5 958.6 980.2 998.7 1023.1 1110.6 32512.5 34065.8 34254.7 34944.0 36129.8 1093.1 1132.9 1145.6 1194.8 1204.1 14922.6 15983.5 16482.3 17661.3 18151.5 585824.0 592879.0 593394.0 594066.0 594128.0 56919.4 56983.0 57316.0 57383.4 57387.0 1354.2 1405.5 1414.3 1416.3 1417.5 30610.6 33097.3 33276.3 33325.7 33557.5 52630.0 53152.5 53249.2 53351.2 53384.2 485552.0 489371.0 489560.0 490678.0 491290.0 24397.0 25057.0 26006.0 29213.6 29589.7 38959.4 43583.1 43678.0 43796.0 43796.6 37259.3 37349.1 38006.8 38014.2 38016.8 37443.6 38436.6 38849.0 39058.8 39540.6 45606.8 46104.5 46897.3 47472.0 48339.3 39872.4 40786.0 43277.1 44139.8 44898.0 34473.0 35573.1 35663.3 35908.8 36188.3 19:31:42 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | [#2] jquery.html Cycles:180 Average:30034.66 Median:13589.56 stddev:82269.66 (605.4%) stddev-sans-first:82470.39 19:31:42 INFO - PROCESS | 7411 | Values: 412.7 419.8 426.5 435.5 437.5 19177.4 20499.7 21155.8 21353.5 21596.8 20006.5 20220.2 20250.1 20313.3 20487.1 5730.3 6032.2 6039.0 6042.2 6042.7 5743.7 5876.4 5975.5 5996.8 6003.2 5974.4 5985.1 5986.1 5989.8 5990.0 5699.1 5706.1 5709.7 5713.8 5716.2 5115.5 5166.8 5167.7 5169.0 5173.9 23486.2 23780.9 23979.5 24234.9 24709.8 2452.1 2469.4 2511.9 2536.6 2601.5 14658.6 14765.1 15006.7 15232.7 15266.4 1981.7 2043.3 2056.6 2071.9 2081.7 83997.0 85083.0 85972.0 86340.0 88089.6 10668.3 10964.7 11241.7 11617.1 11628.1 16372.7 16433.3 16465.9 16495.3 16505.3 8222.4 8313.7 8331.6 8480.2 8792.3 7677.2 7694.0 7784.6 7858.6 8018.2 6870.5 7013.6 7041.0 7051.2 7052.7 1948.8 2034.5 2070.2 2080.5 2108.8 764.6 920.4 923.8 929.8 1064.5 20385.2 20626.9 20666.2 20758.6 21006.3 1045.8 1055.0 1057.3 1076.9 1106.9 11603.0 11947.6 12048.1 12112.3 12218.9 483673.0 488951.0 502113.0 509600.0 509949.0 89796.6 90047.0 91141.5 93284.5 93651.5 1050.1 1085.5 1109.7 1116.7 1120.2 16919.3 17605.4 17938.3 18021.0 18501.9 45445.3 45518.5 46143.3 46946.3 47148.1 57955.0 60383.4 61369.4 62277.0 62863.7 6076.1 6921.5 8210.1 8558.7 8841.8 11895.6 13126.4 13556.8 13743.8 13784.3 11765.4 11819.7 11900.3 12137.9 12645.7 11443.8 11512.5 11611.7 11696.3 12341.9 11159.1 11962.5 12017.0 12454.1 12535.1 8925.0 8933.0 9127.4 9323.7 9375.4 9279.4 11511.4 11944.8 12156.8 12716.7 19:31:42 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | [#3] mootools.html Cycles:180 Average:5829.74 Median:4736.60 stddev:3292.91 (69.5%) stddev-sans-first:3286.10 19:31:42 INFO - PROCESS | 7411 | Values: 1504.7 1547.1 1678.2 1772.4 1815.8 8426.2 9108.5 9111.2 9113.7 9116.5 7869.0 8053.9 8373.2 8588.5 8935.1 2474.2 2493.0 2494.2 2498.5 2501.8 2395.5 2501.2 2512.2 2512.8 2515.6 2458.1 2480.1 2480.4 2482.1 2487.4 2425.1 2444.2 2445.8 2481.3 2481.7 2364.0 2426.6 2435.8 2443.5 2468.1 8890.0 8944.6 8955.3 8991.8 9030.9 5167.8 5302.8 5303.7 5307.5 5310.5 3552.6 3598.4 3656.5 3665.3 3685.2 1179.3 1193.3 1207.7 1221.8 1237.5 12330.3 12343.0 12349.2 12356.9 12369.1 4586.6 4625.1 4734.1 4739.1 4833.2 9725.0 9890.2 9909.4 9910.9 9924.4 5384.8 5388.9 5397.0 5400.7 5401.8 4445.2 4576.3 4787.2 4867.6 5074.2 3961.4 4001.8 4045.0 4085.3 4103.8 1174.6 1193.7 1194.2 1195.5 1208.6 945.9 957.7 958.4 973.9 1002.0 5877.0 5924.3 5974.9 6074.4 6152.3 451.8 457.3 459.6 462.6 467.0 3928.1 3993.5 4017.6 4030.1 4053.1 10785.7 11083.8 11164.4 11168.3 11184.9 11039.6 11040.9 11049.9 11050.1 11059.3 7803.3 8088.1 8179.9 8180.8 8182.4 6054.2 6107.2 6119.8 6190.1 6246.6 10182.3 10294.1 10296.7 10358.6 10391.0 10128.2 10298.9 10335.0 10362.6 10518.1 6891.1 7070.8 7081.7 7887.5 7962.6 7613.2 8389.2 8577.9 8614.5 8817.2 7800.3 7813.8 7882.4 7950.1 8131.1 7542.8 7608.4 7626.4 7736.1 8008.0 6766.7 6848.8 6853.1 6858.5 6863.0 4869.1 5112.1 5120.3 5147.4 5341.4 5288.3 5306.4 5346.8 5412.2 5473.1 19:31:42 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | [#4] prototype.html Cycles:180 Average:4711.17 Median:5265.91 stddev:1379.87 (26.2%) stddev-sans-first:1343.97 19:31:42 INFO - PROCESS | 7411 | Values: 328.9 339.1 345.4 346.3 347.5 5336.0 5406.7 5427.1 5464.7 5519.4 5334.6 5347.3 5359.5 5368.3 5370.9 4271.3 4275.2 4280.8 4291.2 4319.9 3807.7 3988.3 4059.2 4173.8 4234.9 4176.5 4178.7 4178.7 4199.9 4203.8 4220.7 4232.4 4234.8 4242.3 4265.9 3627.7 3666.5 3728.7 3825.9 3837.0 5239.6 5256.8 5258.8 5263.2 5268.6 4858.1 4864.8 4873.4 4879.6 4892.8 5521.9 5565.2 5596.4 5630.8 5654.2 4152.8 4162.4 4164.5 4185.6 4204.0 5636.1 5835.5 5921.6 5951.2 5953.3 4048.8 4216.5 4230.2 4245.5 4246.8 4269.2 4302.4 4315.3 4320.9 4324.9 4747.8 4875.5 4875.5 4888.0 4901.7 5630.8 5643.0 5650.6 5652.3 5669.1 1483.6 1502.9 1505.6 1507.8 1515.9 921.6 969.1 971.2 975.6 981.6 4871.4 4880.9 4892.5 4948.5 4988.5 5108.7 5124.3 5147.9 5150.6 5155.3 4583.3 4842.8 4896.6 4910.7 4941.2 2739.2 2747.6 2768.3 2768.4 2769.6 5318.5 5351.7 5357.7 5384.3 5426.8 5205.3 5217.4 5581.5 5932.9 5939.3 6040.5 6055.3 6061.4 6083.4 6095.5 5624.8 5878.9 5907.9 5955.9 5976.8 5758.0 5774.9 5796.2 5799.6 5806.3 5782.3 5942.4 5975.3 5991.0 6085.4 4057.5 4184.8 4199.2 4216.1 4245.5 5719.6 5888.6 5903.3 5915.6 5931.2 5694.9 5702.5 5709.4 5744.3 5756.4 5644.7 5679.3 5699.3 5703.3 5706.3 5424.6 5659.6 5664.0 5681.5 5686.8 5681.5 5746.7 5748.1 5749.1 5752.7 5605.2 5610.6 5639.4 5677.3 5693.3 19:31:42 INFO - PROCESS | 7411 | 19:31:42 INFO - PROCESS | 7411 | [#5] yui.html Cycles:180 Average:22512.28 Median:3863.44 stddev:32218.05 (833.9%) stddev-sans-first:32267.18 19:31:42 INFO - PROCESS | 7411 | Values: 799.8 821.0 824.8 879.1 908.2 17633.1 18449.5 18460.2 18558.4 18563.7 16942.7 16952.2 17064.8 17257.2 18204.3 713.1 835.8 855.8 858.4 858.6 727.4 838.6 849.7 856.1 859.7 898.9 899.2 899.9 901.5 901.8 900.6 901.4 902.2 902.6 902.9 551.6 557.2 558.6 559.8 646.6 16363.6 16952.9 17019.7 17073.9 17101.7 14952.0 14979.5 15076.5 15121.8 15345.2 14194.1 14210.6 14263.8 14705.3 14741.9 4704.1 4731.0 4784.6 4935.7 4959.8 87902.6 87929.0 87985.0 87986.0 88374.6 12119.3 12213.4 12308.3 12349.0 12445.0 21606.0 21676.9 21691.0 21745.0 21797.2 8633.6 8635.6 8926.9 9091.7 9516.3 5213.2 5225.8 5231.2 5381.3 5524.5 3703.6 3819.4 3843.3 3862.9 3864.0 624.3 625.9 635.4 636.7 639.2 634.5 677.8 712.6 726.1 734.7 10264.4 10376.5 10974.9 11106.8 11232.0 783.3 785.5 809.8 810.0 825.3 5173.8 5349.6 5356.2 5406.4 5418.6 87411.1 130876.0 132364.0 133864.3 135534.0 60808.1 85375.6 114614.0 115773.0 116135.0 90268.5 90734.0 90803.0 92015.5 93206.0 10494.5 10537.7 10646.5 10717.8 10732.4 53689.0 54712.0 55022.0 55188.4 55617.4 78068.6 79941.0 81560.0 82238.2 82509.0 13384.7 14093.3 14105.6 14282.6 14506.1 15479.9 15567.3 15571.3 15994.0 16114.5 14960.5 15166.5 15183.5 15252.5 15347.4 14612.2 14820.9 14963.7 15209.2 15478.1 14816.3 14871.9 15042.1 15475.5 15525.9 11773.5 12188.7 12647.1 13008.5 13124.7 11722.6 12232.2 12479.3 12545.9 12608.4 19:31:42 INFO - PROCESS | 7411 | -------- Summary: end -------- 19:31:42 INFO - PROCESS | 7411 | 19:31:43 INFO - TEST-INFO | 7411: exit 0 19:31:43 INFO - TEST-OK | dromaeo_css | took 1113071ms 19:31:43 INFO - TEST-START | kraken 19:31:43 INFO - operating with platform_type : linux_ 19:31:43 INFO - Initialising browser for kraken test... 19:31:43 INFO - TEST-INFO | started process 7730 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpybn8Mi/profile http://localhost:34547/getInfo.html) 19:31:47 INFO - PROCESS | 7730 | __metrics Screen width/height:1600/1200 19:31:47 INFO - PROCESS | 7730 | colorDepth:24 19:31:47 INFO - PROCESS | 7730 | Browser inner width/height: 1024/697 19:31:47 INFO - PROCESS | 7730 | __metrics 19:31:47 INFO - PROCESS | 7730 | 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" 19:31:47 INFO - PROCESS | 7730 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:31:48 INFO - PROCESS | 7730 | console.error: PushService: 19:31:48 INFO - PROCESS | 7730 | stateChangeProcessEnqueue: Error transitioning state 19:31:48 INFO - PROCESS | 7730 | UnknownError 19:31:48 INFO - TEST-INFO | 7730: exit 0 19:31:48 INFO - Browser initialized. 19:31:48 INFO - Running cycle 1/1 for kraken test... 19:31:48 INFO - TEST-INFO | started process 7823 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpybn8Mi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:32:00 INFO - PROCESS | 7823 | RSS: Main: 145010688 19:32:00 INFO - PROCESS | 7823 | 19:32:02 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34547/tests/kraken/driver.html?testName=audio-beat-detection) 19:32:03 INFO - PROCESS | 7823 | RSS: Main: 186368000 19:32:03 INFO - PROCESS | 7823 | 19:32:06 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34547/tests/kraken/driver.html?testName=audio-dft) 19:32:06 INFO - PROCESS | 7823 | RSS: Main: 175546368 19:32:06 INFO - PROCESS | 7823 | 19:32:09 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34547/tests/kraken/driver.html?testName=audio-fft) 19:32:10 INFO - PROCESS | 7823 | RSS: Main: 267337728 19:32:10 INFO - PROCESS | 7823 | 19:32:12 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34547/tests/kraken/driver.html?testName=audio-oscillator) 19:32:13 INFO - PROCESS | 7823 | RSS: Main: 174112768 19:32:13 INFO - PROCESS | 7823 | 19:32:15 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34547/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:32:15 INFO - PROCESS | 7823 | RSS: Main: 176263168 19:32:15 INFO - PROCESS | 7823 | 19:32:18 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34547/tests/kraken/driver.html?testName=imaging-darkroom) 19:32:19 INFO - PROCESS | 7823 | RSS: Main: 211505152 19:32:19 INFO - PROCESS | 7823 | 19:32:22 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34547/tests/kraken/driver.html?testName=imaging-desaturate) 19:32:23 INFO - PROCESS | 7823 | RSS: Main: 210284544 19:32:23 INFO - PROCESS | 7823 | 19:32:25 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34547/tests/kraken/driver.html?testName=json-parse-financial) 19:32:26 INFO - PROCESS | 7823 | RSS: Main: 210415616 19:32:26 INFO - PROCESS | 7823 | 19:32:27 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34547/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:32:28 INFO - PROCESS | 7823 | RSS: Main: 172335104 19:32:28 INFO - PROCESS | 7823 | 19:32:30 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34547/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:32:30 INFO - PROCESS | 7823 | RSS: Main: 180154368 19:32:30 INFO - PROCESS | 7823 | 19:32:32 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34547/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:32:33 INFO - PROCESS | 7823 | RSS: Main: 188710912 19:32:33 INFO - PROCESS | 7823 | 19:32:35 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34547/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:32:36 INFO - PROCESS | 7823 | RSS: Main: 197304320 19:32:36 INFO - PROCESS | 7823 | 19:32:39 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34547/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:32:39 INFO - PROCESS | 7823 | RSS: Main: 194392064 19:32:39 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | Cycle 1(1): loaded http://localhost:34547/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34547/tests/kraken/driver.html?testName=ai-astar) 19:32:41 INFO - PROCESS | 7823 | RSS: Main: 245063680 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | __start_tp_report 19:32:41 INFO - PROCESS | 7823 | _x_x_mozilla_page_load 19:32:41 INFO - PROCESS | 7823 | _x_x_mozilla_page_load_details 19:32:41 INFO - PROCESS | 7823 | |i|pagename|runs| 19:32:41 INFO - PROCESS | 7823 | |0;ai-astar;110;112;110;111;112;113;112;110;110;111 19:32:41 INFO - PROCESS | 7823 | |1;audio-beat-detection;145;117;115;152;118;117;118;117;146;120 19:32:41 INFO - PROCESS | 7823 | |2;audio-dft;160;160;162;160;160;160;160;161;160;161 19:32:41 INFO - PROCESS | 7823 | |3;audio-fft;75;75;76;111;75;76;75;77;100;75 19:32:41 INFO - PROCESS | 7823 | |4;audio-oscillator;81;81;103;82;81;81;103;81;80;102 19:32:41 INFO - PROCESS | 7823 | |5;imaging-gaussian-blur;116;116;116;116;116;117;116;117;117;116 19:32:41 INFO - PROCESS | 7823 | |6;imaging-darkroom;147;146;146;145;146;146;146;146;146;146 19:32:41 INFO - PROCESS | 7823 | |7;imaging-desaturate;95;95;95;96;94;95;95;94;95;95 19:32:41 INFO - PROCESS | 7823 | |8;json-parse-financial;51;52;52;51;51;51;52;51;51;51 19:32:41 INFO - PROCESS | 7823 | |9;json-stringify-tinderbox;59;59;59;59;60;59;59;59;59;60 19:32:41 INFO - PROCESS | 7823 | |10;stanford-crypto-aes;73;73;82;76;72;73;74;79;73;73 19:32:41 INFO - PROCESS | 7823 | |11;stanford-crypto-ccm;124;122;121;123;121;126;119;117;155;121 19:32:41 INFO - PROCESS | 7823 | |12;stanford-crypto-pbkdf2;163;160;160;155;155;154;154;156;154;154 19:32:41 INFO - PROCESS | 7823 | |13;stanford-crypto-sha256-iterative;64;63;68;63;65;62;65;62;60;60 19:32:41 INFO - PROCESS | 7823 | __end_tp_report 19:32:41 INFO - PROCESS | 7823 | __start_cc_report 19:32:41 INFO - PROCESS | 7823 | _x_x_mozilla_cycle_collect,1585 19:32:41 INFO - PROCESS | 7823 | __end_cc_report 19:32:41 INFO - PROCESS | 7823 | __startTimestamp1479094361738__endTimestamp 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | ------- Summary: start ------- 19:32:41 INFO - PROCESS | 7823 | Number of tests: 14 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.50 stddev:1.10 (1.0%) stddev-sans-first:1.09 19:32:41 INFO - PROCESS | 7823 | Values: 110.0 112.0 110.0 111.0 112.0 113.0 112.0 110.0 110.0 111.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:119.00 stddev:14.77 (12.4%) stddev-sans-first:14.06 19:32:41 INFO - PROCESS | 7823 | Values: 145.0 117.0 115.0 152.0 118.0 117.0 118.0 117.0 146.0 120.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 19:32:41 INFO - PROCESS | 7823 | Values: 160.0 160.0 162.0 160.0 160.0 160.0 160.0 161.0 160.0 161.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.00 stddev:12.93 (17.2%) stddev-sans-first:13.50 19:32:41 INFO - PROCESS | 7823 | Values: 75.0 75.0 76.0 111.0 75.0 76.0 75.0 77.0 100.0 75.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#4] audio-oscillator Cycles:10 Average:87.50 Median:81.00 stddev:10.48 (12.9%) stddev-sans-first:10.85 19:32:41 INFO - PROCESS | 7823 | Values: 81.0 81.0 103.0 82.0 81.0 81.0 103.0 81.0 80.0 102.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:32:41 INFO - PROCESS | 7823 | Values: 116.0 116.0 116.0 116.0 116.0 117.0 116.0 117.0 117.0 116.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 19:32:41 INFO - PROCESS | 7823 | Values: 147.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 19:32:41 INFO - PROCESS | 7823 | Values: 95.0 95.0 95.0 96.0 94.0 95.0 95.0 94.0 95.0 95.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 19:32:41 INFO - PROCESS | 7823 | Values: 51.0 52.0 52.0 51.0 51.0 51.0 52.0 51.0 51.0 51.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 19:32:41 INFO - PROCESS | 7823 | Values: 59.0 59.0 59.0 59.0 60.0 59.0 59.0 59.0 59.0 60.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:73.50 stddev:3.26 (4.4%) stddev-sans-first:3.39 19:32:41 INFO - PROCESS | 7823 | Values: 73.0 73.0 82.0 76.0 72.0 73.0 74.0 79.0 73.0 73.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:122.50 stddev:10.87 (8.9%) stddev-sans-first:11.52 19:32:41 INFO - PROCESS | 7823 | Values: 124.0 122.0 121.0 123.0 121.0 126.0 119.0 117.0 155.0 121.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.50 Median:155.50 stddev:3.27 (2.1%) stddev-sans-first:2.49 19:32:41 INFO - PROCESS | 7823 | Values: 163.0 160.0 160.0 155.0 155.0 154.0 154.0 156.0 154.0 154.0 19:32:41 INFO - PROCESS | 7823 | 19:32:41 INFO - PROCESS | 7823 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.50 stddev:2.44 (3.8%) stddev-sans-first:2.57 19:32:41 INFO - PROCESS | 7823 | Values: 64.0 63.0 68.0 63.0 65.0 62.0 65.0 62.0 60.0 60.0 19:32:41 INFO - PROCESS | 7823 | -------- Summary: end -------- 19:32:41 INFO - PROCESS | 7823 | 19:32:42 INFO - TEST-INFO | 7823: exit 0 19:32:42 INFO - TEST-OK | kraken | took 59517ms 19:32:42 INFO - SUITE-END | took 1172s 19:32:42 INFO - Completed test suite (00:19:32) 19:32:42 INFO - javascript benchmark 19:32:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.5784519089925, 3157.9210519737003, 3173.159112835099, 3195.3928753536843, 3208.518722191671, 5489.58828087893, 5594.272744543214, 5604.299462567179, 5606.159076138581, 5625.831225063244, 5573.972130139344, 5635.6055076144585, 5644.096944488882, 5649.576281778873, 5676.375598684142, 5088.236764485338, 5101.872453188681, 5127.615428842833, 5163.251328557357, 5182.637215394924, 4942.481039490855, 4954.777035033432, 4960.280759289901, 5140.203268493389, 5185.429602743714, 5003.649744517867, 5105.668131571455, 5113.335266415379, 5116.692998420107, 5122.667026643275, 5107.4892510749, 5111.207762796773, 5118.573136707859, 5125.436202017775, 5126.282320475118, 4909.0181963607065, 4947.876303092451, 5022.321986531844, 5029.119904016819, 5086.643934924557, 5458.61789674723, 5529.778808847641, 5600.131979543177, 5625.915611265835, 5640.887182256373, 5860.296764388258, 5930.436608522184, 5965.343812180657, 5965.463108320272, 5969.880602387928, 5985.670788106652, 6002.2197114375285, 6006.909896351558, 6014.0, 6018.368071352533, 5449.237106805051, 5476.808311709071, 5505.724713764336, 5549.1676248562235, 5549.556035517149, 6060.090986351995, 6090.969545152335, 6091.42131497507, 6094.421030002142, 6100.176476175749, 5836.212111364996, 5845.3277873045145, 5846.239988801513, 5847.766089356463, 5856.0630299151935, 5799.391063938226, 5814.592978491465, 5870.530357571384, 5960.76156953717, 5966.045432730829, 5830.970845145747, 5881.941180588225, 5882.088276317178, 5909.0, 5923.259592550931, 5934.554908381802, 5938.465538101592, 5956.970215148983, 5982.312034116132, 5993.130996005555, 4524.524924882906, 4560.635149188057, 4590.655700822452, 4604.58558729716, 4633.189191891405, 4005.759654420686, 4010.218007488512, 4019.2162528307285, 4072.2873497138476, 4085.0604360997136, 5724.370319264877, 5740.971295143498, 5742.425757424307, 5753.827385178452, 5770.336411312669, 6010.519158467399, 6013.54898382623, 6016.548758843104, 6017.518598512109, 6020.458158765732, 5547.029269877755, 5593.916091258634, 5610.859728506738, 5658.97170514153, 5704.458076482728, 5153.742312884341, 5172.250023746611, 5203.349581302337, 5245.3705555332845, 5246.6065045121, 6159.969200153971, 6180.938190618036, 6190.7523699052435, 6191.752329906753, 6192.318844927055, 5803.216565763568, 5887.587868849139, 5942.494887934401, 5986.940130598812, 5993.220881285405, 6174.25908890936, 6236.844078898064, 6257.2804127525915, 6258.342873998165, 6275.0, 5917.437843404869, 5931.406859314034, 5940.198073260054, 5941.554383421174, 5964.940350596612, 5825.330087040044, 5882.852928676817, 5998.700064996646, 6005.159277701038, 6063.272407310975, 5748.137779333133, 5749.597528172988, 5802.129680547782, 5837.795677151279, 5869.8239052828485, 5816.04035334182, 5829.067349224104, 5829.795957141479, 5831.387704290988, 5834.416558344132, 5642.915356269741, 5778.219940308005, 5781.277340332458, 5793.8551536211935, 5798.15926690619, 5747.7413516391025, 5830.300363956352, 5896.233489646319, 5910.52715782745, 5989.670568118621, 5726.455986681259, 5779.133130030529, 5783.94216057834, 5789.363170051207, 5817.127430885232], "value": 5499.902508069854, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.4515018757837, 600.5795942840012, 635.1045026512618, 637.9983426021146, 650.8047585724281, 32501.0, 33124.83437582812, 35513.0, 35536.822315888414, 35545.0, 44391.334129988, 44960.550394496124, 45054.09891802162, 48739.756301218535, 48863.26705099426, 9066.32002599806, 9202.723918282461, 9209.953950230272, 9211.86182207264, 9215.539223038855, 8471.110533393998, 8657.69698060568, 9106.362554621179, 9217.0, 9218.539073046355, 10888.61889833856, 11126.276792008493, 11131.443427828617, 11395.658130256106, 11401.771964560703, 10933.0, 10978.780424391507, 10982.450877456135, 11005.559777608885, 11009.61466348682, 10951.616693415772, 11022.283551569122, 11028.558857645685, 11028.613998510094, 11032.0, 42875.35686964698, 42972.0, 43136.0, 43136.56863431357, 43236.56763432357, 3601.477785721082, 3608.0799396154075, 3616.0959760059986, 3796.9810150949343, 4401.5598440156045, 32923.17692057694, 33085.83457082699, 33261.50107748386, 33325.6667433325, 33547.32905341904, 4205.369194620801, 4370.759608221547, 4393.8462153824785, 4461.21928662486, 4461.241588929857, 377558.99999999726, 378512.0, 379208.0, 379828.0, 385064.0, 63839.76560820349, 64275.35724642787, 64337.0, 64358.0, 64375.0, 35158.29683406318, 35212.82393588067, 35243.29513409718, 35497.822510887796, 66793.33206667968, 18464.723029154575, 18607.06964651762, 18644.067796610387, 18654.44036678902, 18666.066696665384, 11490.13823963206, 11490.368029758276, 11490.827637585442, 11492.597759078391, 11615.941920290514, 17841.28634854617, 17893.821061789477, 19420.67214724163, 19633.31283405074, 20439.080241389172, 7330.523515971445, 7411.851163069625, 7474.514156579914, 7490.775276741707, 7548.509346892434, 958.6165533786402, 980.2011360740973, 998.6554638649654, 1023.1303392116641, 1110.5890820396505, 32512.512312315335, 34065.829670851985, 34254.65745342515, 34944.0, 36129.819350903075, 1093.0818112785298, 1132.8753837077916, 1145.6391236760394, 1194.7610477904454, 1204.1029433072417, 14922.626934326512, 15983.520494385188, 16482.2582983766, 17661.29354825818, 18151.45545633633, 585824.0, 592879.0, 593394.0, 594066.0, 594128.0, 56919.43080569224, 56983.0, 57316.0, 57383.42616573781, 57387.0, 1354.1739538881275, 1405.4799724102147, 1414.3281941077905, 1416.2564653557172, 1417.4613646814144, 30610.604313026175, 33097.338053238316, 33276.334473309915, 33325.66674333226, 33557.49663755095, 52630.0, 53152.46847531475, 53249.20126198033, 53351.199732003275, 53384.19923701225, 485552.0, 489371.0, 489560.0, 490678.0, 491290.0, 24397.0, 25057.0, 26006.0, 29213.561796572645, 29589.704102959557, 38959.415608765325, 43583.12833743371, 43678.0, 43796.0, 43796.56203437925, 37259.254814904096, 37349.066273343386, 38006.80996594999, 38014.239715206095, 38016.809915950245, 37443.62556374401, 38436.61563384442, 38849.0, 39058.80470597629, 39540.60459395369, 45606.77196613995, 46104.53895461003, 46897.29654055124, 47472.0, 48339.274910877066, 39872.40191397073, 40786.0, 43277.13445731005, 44139.77930110329, 44898.0, 34473.0, 35573.1106722334, 35663.28673426569, 35908.820455897556, 36188.27623447569], "value": 20761.938036546086, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.7172886572698, 419.8383622305411, 426.5435983497657, 435.46872815165494, 437.52309982119505, 19177.42467725969, 20499.69250461244, 21155.788442115576, 21353.466163345925, 21596.784032159674, 20006.499837504034, 20220.191192352326, 20250.08874600643, 20313.289034883783, 20487.078081486332, 5730.341010783772, 6032.215811944463, 6039.0, 6042.184305118797, 6042.728077236513, 5743.712814359286, 5876.412358764111, 5975.492083172936, 5996.760129594811, 6003.249593800775, 5974.402559744012, 5985.072313791369, 5986.132010858445, 5989.790357337494, 5989.970050149743, 5699.059655156894, 5706.058500347438, 5709.743061562198, 5713.800016999385, 5716.228309178249, 5115.539601435851, 5166.844994650129, 5167.741612919339, 5168.974155129238, 5173.948260517384, 23486.177983770485, 23780.881095594585, 23979.52040959171, 24234.87882560576, 24709.752902470926, 2452.080469823816, 2469.370310570811, 2511.9246422607353, 2536.5561026820233, 2601.4536947241104, 14658.560243192722, 14765.114093154445, 15006.699866002728, 15232.695346093127, 15266.38934442621, 1981.7027445883232, 2043.2746375036945, 2056.598963202176, 2071.8756874587575, 2081.6565266731122, 83997.0, 85082.99999999937, 85972.0, 86340.0, 88089.55955220247, 10668.253222274367, 10964.671059868217, 11241.71895702614, 11617.128715346384, 11628.127890408254, 16372.672546549002, 16433.342666293454, 16465.91767041157, 16495.257713402927, 16505.257263423176, 8222.38332125081, 8313.6674533018, 8331.625076871554, 8480.236778689825, 8792.252658523968, 7677.232276772278, 7694.0, 7784.610769461618, 7858.5677787722225, 8018.158093400226, 6870.484713646397, 7013.6142512161305, 7040.9647951760935, 7051.153861536546, 7052.682629281695, 1948.80511948804, 2034.501547120965, 2070.1615845582564, 2080.5318803269142, 2108.7891210879097, 764.6291548598925, 920.3603495570514, 923.8060007398522, 929.8186853563625, 1064.5156453813497, 20385.184592616424, 20626.896865515875, 20666.17335306601, 20758.58482830335, 21006.2647807326, 1045.7542477517777, 1054.9789004220027, 1057.286331726082, 1076.8546246256656, 1106.8671759388924, 11603.0, 11947.581834635746, 12048.096392770578, 12112.273263604213, 12218.877811221773, 483673.0, 488951.0, 502113.0, 509600.0, 509949.0, 89796.55101724449, 90047.0, 91141.54429227811, 93284.53357733303, 93651.53174234086, 1050.1388861133796, 1085.46812062089, 1109.7003808971533, 1116.7319843237565, 1120.2158489057692, 16919.323227070778, 17605.383811566535, 17938.282468701105, 18021.0, 18501.90749046246, 45445.318320224564, 45518.544814552755, 46143.3078503816, 46946.29580556226, 47148.057038858344, 57955.0, 60383.396166037775, 61369.386306136366, 62277.0, 62863.685681571296, 6076.118962750288, 6921.515493915377, 8210.096889342287, 8558.700445484377, 8841.823163536565, 11895.583654572049, 13126.40931158081, 13556.796648050484, 13743.793843092562, 13784.310784460937, 11765.411729413323, 11819.704507387383, 11900.285982841056, 12137.878621213675, 12645.747085058063, 11443.771124577628, 11512.53949841997, 11611.709707257049, 11696.344330792372, 12341.876581234432, 11159.10727141843, 11962.521499139937, 12017.0, 12454.128211025141, 12535.122541422013, 8925.018247992846, 8933.0, 9127.406718563272, 9323.72028839136, 9375.390599611004, 9279.350445468752, 11511.424428778695, 11944.820827687765, 12156.756864862477, 12716.745665086832], "value": 9712.196248414444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1504.7291487532243, 1547.1104115133796, 1678.202853644519, 1772.4328214971215, 1815.7911840138388, 8426.241638252555, 9108.54457277136, 9111.179993800557, 9113.68102116426, 9116.453012819218, 7868.960655196716, 8053.879191812128, 8373.204545568176, 8588.527630980308, 8935.106489351077, 2474.2082533589214, 2492.950140997188, 2494.2267896952003, 2498.5127900059497, 2501.799856011524, 2395.4849707312924, 2501.249625112468, 2512.1584269269815, 2512.798976081909, 2515.55977703902, 2458.1150785717127, 2480.1443501991785, 2480.4295012147218, 2482.118847809029, 2487.4154573675173, 2425.0663494554724, 2444.2422848916895, 2445.8410203336816, 2481.3424442522746, 2481.739417361169, 2363.988180059106, 2426.59961106419, 2435.8416702914433, 2443.4624382635793, 2468.0868078810954, 8889.955550222272, 8944.64221431142, 8955.328350373748, 8991.820163596756, 9030.909690903072, 5167.819126330561, 5302.814401495968, 5303.6552624079695, 5307.495787900143, 5310.548603368718, 3552.5736911570775, 3598.4062629666073, 3656.5063716398477, 3665.3402387570227, 3685.2261025184744, 1179.3277831635971, 1193.2840295822523, 1207.6739280394245, 1221.8472690913636, 1237.523553431926, 12330.32183229922, 12343.0, 12349.19730217533, 12356.876431235753, 12369.072319575978, 4586.610138138296, 4625.121226966933, 4734.124187025412, 4739.075880203396, 4833.226683730586, 9724.95137524308, 9890.208783297463, 9909.405435673883, 9910.851337229948, 9924.354916930377, 5384.838454846361, 5388.946110538923, 5396.9730151348995, 5400.675959442446, 5401.810936617199, 4445.244308467568, 4576.313552967082, 4787.234042553175, 4867.561919427269, 5074.188129899198, 3961.3661814110183, 4001.8199181036925, 4044.96853302406, 4085.3259212230246, 4103.835846566104, 1174.6417342710386, 1193.6538403862955, 1194.235689158939, 1195.5277665322149, 1208.583038851595, 945.8817647794026, 957.707899090784, 958.3818437108026, 973.8928717841031, 1001.979960400788, 5877.030290002187, 5924.259467566554, 5974.940250597525, 6074.36219196978, 6152.292486364126, 451.8057235388814, 457.3231617206553, 459.62081282941705, 462.6391414696542, 467.0332411907387, 3928.135810121769, 3993.5407428146136, 4017.5580686124504, 4030.1133750574827, 4053.0677944073027, 10785.676429707122, 11083.778324433466, 11164.441777911072, 11168.329900205852, 11184.888151118384, 11039.613613523488, 11040.889591104147, 11049.889501105046, 11050.115990720724, 11059.281146725598, 7803.297703206852, 8088.069871964682, 8179.918200818035, 8180.836383272302, 8182.386321025828, 6054.212952316171, 6107.236595425572, 6119.84700382494, 6190.133381326708, 6246.562740608296, 10182.287239893136, 10294.073533382032, 10296.742581435525, 10358.637447689294, 10391.0, 10128.24038197153, 10298.897011029794, 10335.0, 10362.637307694195, 10518.053375196268, 6891.138607674041, 7070.823229419306, 7081.681324340314, 7887.5267483948855, 7962.601869906597, 7613.162552119152, 8389.203025712548, 8577.8713319299, 8614.526201059001, 8817.206451419403, 7800.258975397328, 7813.765587032398, 7882.408819338458, 7950.085740139958, 8131.105578386255, 7542.811429714301, 7608.429367797548, 7626.351760100453, 7736.1490236075115, 8007.959960200162, 6766.729330826909, 6848.760293389708, 6853.109095817711, 6858.4856135791015, 6862.965685171542, 4869.147899117569, 5112.130937740668, 5120.334356533627, 5147.433782284021, 5341.359036915595, 5288.312519372457, 5306.389765177054, 5346.759395827275, 5412.188171774265, 5473.124300111963], "value": 4617.393476012343, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.93602982090715, 339.0946173716178, 345.41969491254684, 346.34022187732364, 347.47010808517007, 5336.039512887679, 5406.729663516819, 5427.050266203413, 5464.6994415307145, 5519.365272993607, 5334.5999050071305, 5347.304850369456, 5359.490848369408, 5368.32895888014, 5370.946290537094, 4271.252530807112, 4275.187714334282, 4280.78596070196, 4291.163223171483, 4319.913601727963, 3807.676347510465, 3988.321985262498, 4059.2490389278078, 4173.770442625654, 4234.851780187694, 4176.498820141587, 4178.665706743468, 4178.707490475668, 4199.853005144821, 4203.789810509462, 4220.662347012231, 4232.433999420219, 4234.830606775725, 4242.257604919127, 4265.914681706348, 3627.657766626365, 3666.5416822897137, 3728.738988270822, 3825.885223443301, 3836.9808150959066, 5239.580833533346, 5256.81601143962, 5258.789648414059, 5263.184206447969, 5268.631195816294, 4858.149823780825, 4864.756762161913, 4873.439554451248, 4879.609631229493, 4892.779824907888, 5521.889562208774, 5565.220869078333, 5596.384397716248, 5630.831075067755, 5654.15187721845, 4152.771597562133, 4162.41726158338, 4164.541900390955, 4185.623293903533, 4203.97898010508, 5636.126400407943, 5835.474807267324, 5921.644701317891, 5951.166836642873, 5953.345132035516, 4048.7773172475795, 4216.536181020086, 4230.175115852441, 4245.532991370947, 4246.766427846436, 4269.167512335065, 4302.440682711227, 4315.266404711207, 4320.891977700519, 4324.875532361546, 4747.810087596457, 4875.512448755097, 4875.536824001785, 4888.0, 4901.730404827697, 5630.831075067755, 5643.0, 5650.604457688005, 5652.349979752299, 5669.149627555899, 1483.5920121966547, 1502.9398824046916, 1505.6160679026777, 1507.811523559555, 1515.931268455736, 921.589892497832, 969.1035791892472, 971.2472833554052, 975.5902520941222, 981.6417007792104, 4871.3667223260745, 4880.926786098212, 4892.486288939681, 4948.48657453205, 4988.476209997971, 5108.667936584185, 5124.256982737482, 5147.897042059138, 5150.562202212854, 5155.304033955443, 4583.289590113538, 4842.806287748521, 4896.608271338285, 4910.705357678551, 4941.196463290945, 2739.1919383781833, 2747.639918240484, 2768.335599456116, 2768.43247134337, 2769.612254284402, 5318.494742999409, 5351.705656188947, 5357.705326207096, 5384.273123128406, 5426.8371948841595, 5205.323307969939, 5217.373915130209, 5581.49766521015, 5932.851678708067, 5939.2575928009, 6040.456358927718, 6055.303640081359, 6061.393860613904, 6083.361247068994, 6095.451409373178, 5624.775008999758, 5878.911816322673, 5907.881842363043, 5955.927932972107, 5976.7908123215475, 5758.021136406905, 5774.94225057744, 5796.21751063601, 5799.594028417971, 5806.274215723035, 5782.277215348082, 5942.365849391452, 5975.312839023394, 5991.0, 6085.391460853879, 4057.4928133983253, 4184.782065505287, 4199.2441360555395, 4216.093539888939, 4245.495157856879, 5719.5710321726865, 5888.558358123244, 5903.291605007426, 5915.556333274935, 5931.161339984618, 5694.886102278014, 5702.486776190163, 5709.372828743788, 5744.339400968942, 5756.395578464201, 5644.689542075225, 5679.3184817820475, 5699.2590963174525, 5703.258576385043, 5706.3152421709665, 5424.647397919122, 5659.603827732184, 5664.0371136906, 5681.517071049007, 5686.800961966312, 5681.488666020078, 5746.683932383759, 5748.137779333133, 5749.137629355463, 5752.741126649227, 5605.215269862141, 5610.615488417325, 5639.407862174413, 5677.318721753416, 5693.28833895763], "value": 4275.738332485945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.8480288745138, 820.9548474833882, 824.7525742277315, 879.147227189626, 908.1644886704231, 17633.11834408279, 18449.53876153097, 18460.16929238184, 18558.443246702587, 18563.72154417683, 16942.661146777056, 16952.23714932828, 17064.829351706478, 17257.223424945878, 18204.271829126854, 713.1014921199275, 835.7952301686096, 855.7946092937681, 858.4420126917491, 858.5707146426787, 727.4362369163888, 838.6310023589601, 849.7068511363569, 856.0839901305606, 859.7463748194266, 898.8516894712398, 899.1592860675289, 899.9100089991014, 901.5447199164413, 901.7565257380503, 900.6307413960245, 901.369041670833, 902.1519771414881, 902.5803001604253, 902.9413088149282, 551.5504863536245, 557.2282388891374, 558.5643198305328, 559.7565059199261, 646.6217262901208, 16363.59091022734, 16952.91523542387, 17019.65960680792, 17073.914630426894, 17101.743473847902, 14952.0, 14979.475718349915, 15076.472323468735, 15121.773173402407, 15345.156016419092, 14194.077384969944, 14210.644733881736, 14263.78604320936, 14705.338259778335, 14741.852581474262, 4704.106219818258, 4731.0, 4784.641151913585, 4935.703857768545, 4959.8016079356785, 87902.56048719716, 87929.0, 87985.0, 87986.0, 88374.55812720895, 12119.272843629235, 12213.389330533615, 12308.26150430977, 12349.0, 12445.0, 21606.027728752244, 21676.891615542136, 21691.0, 21745.0, 21797.237096701538, 8633.611487483078, 8635.611397487128, 8926.86609700855, 9091.727248182566, 9516.333856630108, 5213.218017297436, 5225.764840582183, 5231.24146998683, 5381.327334083239, 5524.530414914777, 3703.574088979748, 3819.388897776342, 3843.3466310727244, 3862.9034274143373, 3863.9806800966376, 624.3350831364583, 625.8842114208886, 635.3646353646353, 636.7293900092442, 639.1626968670963, 634.4924060751381, 677.8373190434261, 712.6436781609195, 726.1395246632757, 734.6583838515116, 10264.43545604984, 10376.481175941324, 10974.890251097546, 11106.833397499044, 11232.0, 783.2559068884583, 785.4933567848819, 809.8461292354551, 809.9757007289791, 825.2531459029587, 5173.844784656466, 5349.5720342372515, 5356.196570514454, 5406.432324605938, 5418.593605479605, 87411.12588874158, 130876.0, 132364.0, 133864.33067834793, 135534.0, 60808.08787868186, 85375.57312213523, 114614.0, 115773.0, 116135.0, 90268.5486572563, 90734.0, 90803.0, 92015.4790712546, 93206.0, 10494.475276236006, 10537.736556586147, 10646.520906559379, 10717.839232411365, 10732.409717465614, 53689.0, 54712.0, 55022.0, 55188.44811551833, 55617.44382556285, 78068.60965695135, 79941.0, 81560.0, 82238.17761822544, 82509.0, 13384.732305353644, 14093.295335232991, 14105.647358816112, 14282.57152285433, 14506.1296322221, 15479.922600386926, 15567.299471523582, 15571.29929153168, 15994.0, 16114.516564503083, 14960.476383326532, 15166.469173578871, 15183.544493665207, 15252.542423727306, 15347.386104555693, 14612.196329201995, 14820.925895370454, 14963.700725985202, 15209.239538023276, 15478.090829276422, 14816.333265002886, 14871.85128148748, 15042.097474151586, 15475.535733928, 15525.922370388076, 11773.47019384147, 12188.695282617651, 12647.051471139517, 13008.544700935421, 13124.737505250032, 11722.589709360473, 12232.204906681038, 12479.251244925334, 12545.87454125447, 12608.36958152107], "value": 8211.010573655869, "unit": "score"}], "name": "dromaeo_css", "value": 7512.7430324765655, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 110.0, 111.0, 112.0, 113.0, 112.0, 110.0, 110.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 115.0, 152.0, 118.0, 117.0, 118.0, 117.0, 146.0, 120.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 160.0, 160.0, 160.0, 160.0, 161.0, 160.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 111.0, 75.0, 76.0, 75.0, 77.0, 100.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 103.0, 82.0, 81.0, 81.0, 103.0, 81.0, 80.0, 102.0], "value": 87.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 51.0, 51.0, 51.0, 52.0, 51.0, 51.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 82.0, 76.0, 72.0, 73.0, 74.0, 79.0, 73.0, 73.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 122.0, 121.0, 123.0, 121.0, 126.0, 119.0, 117.0, 155.0, 121.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 155.0, 155.0, 154.0, 154.0, 156.0, 154.0, 154.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 68.0, 63.0, 65.0, 62.0, 65.0, 62.0, 60.0, 60.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1454.1, "alertThreshold": 2.0}]} 19:32:42 INFO - Return code: 0 19:32:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:32:43 INFO - # TBPL SUCCESS # 19:32:43 INFO - Running post-action listener: _resource_record_post_action 19:32:43 INFO - [mozharness: 2016-11-14 03:32:43.049523Z] Finished run-tests step (success) 19:32:43 INFO - Running post-run listener: _resource_record_post_run 19:32:43 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 5439488; Write bytes: 370196480; Read time: 636; Write time: 231200 19:32:43 INFO - TinderboxPrint: CPU usage
12.9% 19:32:43 INFO - TinderboxPrint: I/O read bytes / time
5,439,488 / 636 19:32:43 INFO - TinderboxPrint: I/O write bytes / time
370,196,480 / 231,200 19:32:43 INFO - TinderboxPrint: CPU idle
8,260.3 (87.0%) 19:32:43 INFO - TinderboxPrint: CPU user
1,186.5 (12.5%) 19:32:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:32:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118857728; Read time: 168; Write time: 71044 19:32:43 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 3567616; Write bytes: 251338752; Read time: 404; Write time: 160156 19:32:43 INFO - Running post-run listener: _upload_blobber_files 19:32:43 INFO - Blob upload gear active. 19:32:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:32:43 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 19:32:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:32:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:32:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:32:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:32:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:32:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:45 INFO - (blobuploader) - INFO - Done attempting. 19:32:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:32:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:32:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:46 INFO - (blobuploader) - INFO - Done attempting. 19:32:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:32:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:32:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:49 INFO - (blobuploader) - INFO - Done attempting. 19:32:49 INFO - (blobuploader) - INFO - Iteration through files over. 19:32:49 INFO - Return code: 0 19:32:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:32:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:32:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d46bc6fb1f5121f537dcdf8a29a6025515f5e1fda2516fd9d34017f39ffa938c4bc073d432c42811b75e70d0c52e55014865ecc9ad6786199814d92cfbe5d8f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0470a405704c570281b9748960acc930b517297dbfae58f48ad321dae6fd43feb4e67dc7283ea69ade42657bb305b2ab39693ca8e36da7d1330db422073fe85b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2ccfc88afbd666cf9da32c155a1ab1030833db28fed267543fb49796cb1f90f2364c1039cc45f3e40246ae61b62591f51e84d8f6b684c54df00d7b16dc17d0b"} 19:32:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:32:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:32:49 INFO - Contents: 19:32:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d46bc6fb1f5121f537dcdf8a29a6025515f5e1fda2516fd9d34017f39ffa938c4bc073d432c42811b75e70d0c52e55014865ecc9ad6786199814d92cfbe5d8f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0470a405704c570281b9748960acc930b517297dbfae58f48ad321dae6fd43feb4e67dc7283ea69ade42657bb305b2ab39693ca8e36da7d1330db422073fe85b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2ccfc88afbd666cf9da32c155a1ab1030833db28fed267543fb49796cb1f90f2364c1039cc45f3e40246ae61b62591f51e84d8f6b684c54df00d7b16dc17d0b"} 19:32:49 INFO - Running post-run listener: copy_logs_to_upload_dir 19:32:49 INFO - Copying logs to upload dir... 19:32:49 INFO - mkdir: /builds/slave/test/build/upload/logs 19:32:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.526705 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-13 19:32:49.623896) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-13 19:32:49.627553) ========= 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=6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d46bc6fb1f5121f537dcdf8a29a6025515f5e1fda2516fd9d34017f39ffa938c4bc073d432c42811b75e70d0c52e55014865ecc9ad6786199814d92cfbe5d8f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0470a405704c570281b9748960acc930b517297dbfae58f48ad321dae6fd43feb4e67dc7283ea69ade42657bb305b2ab39693ca8e36da7d1330db422073fe85b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2ccfc88afbd666cf9da32c155a1ab1030833db28fed267543fb49796cb1f90f2364c1039cc45f3e40246ae61b62591f51e84d8f6b684c54df00d7b16dc17d0b"} build_url:https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011734 build_url: 'https://queue.taskcluster.net/v1/task/T2sdOJK3RDmn0T6Ecfn2Dw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d46bc6fb1f5121f537dcdf8a29a6025515f5e1fda2516fd9d34017f39ffa938c4bc073d432c42811b75e70d0c52e55014865ecc9ad6786199814d92cfbe5d8f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0470a405704c570281b9748960acc930b517297dbfae58f48ad321dae6fd43feb4e67dc7283ea69ade42657bb305b2ab39693ca8e36da7d1330db422073fe85b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2ccfc88afbd666cf9da32c155a1ab1030833db28fed267543fb49796cb1f90f2364c1039cc45f3e40246ae61b62591f51e84d8f6b684c54df00d7b16dc17d0b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-13 19:32:49.653086) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 19:32:49.653413) ========= 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=6d15e502d8e29d5d2676d664000001c3-1479080421.394813-792819794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 19:32:49.737141) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-13 19:32:49.737480) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-13 19:32:49.737879) ========= ========= Total master_lag: 0.17 =========