builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1482599697.98 results: success (0) buildid: 20161224073122 builduid: 4626862bbb0b4c04878fc25cd246665b revision: 1656c870416d7779f832bd74b21f743fe5b83893 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:57.978639) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:57.979102) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:57.979387) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:57.998560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:57.998964) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-24 09:14:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 23.2M=0.001s 2016-12-24 09:14:59 (23.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.395424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:58.409875) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:58.410153) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019600 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:58.458879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:58.459189) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1656c870416d7779f832bd74b21f743fe5b83893 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1656c870416d7779f832bd74b21f743fe5b83893 --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=17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-24 09:14:59,220 truncating revision to first 12 chars 2016-12-24 09:14:59,220 Setting DEBUG logging. 2016-12-24 09:14:59,220 attempt 1/10 2016-12-24 09:14:59,220 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1656c870416d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-24 09:14:59,525 unpacking tar archive at: mozilla-central-1656c870416d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.589960 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:59.062273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:59.062587) ========= script_repo_revision: 1656c870416d7779f832bd74b21f743fe5b83893 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:59.062990) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:59.063257) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 09:14:59.073112) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-12-24 09:14:59.073439) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:14:59 INFO - MultiFileLogger online at 20161224 09:14:59 in /builds/slave/test 09:14:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:14:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:14:59 INFO - {'append_to_log': False, 09:14:59 INFO - 'base_work_dir': '/builds/slave/test', 09:14:59 INFO - 'blob_upload_branch': 'Firefox', 09:14:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:14:59 INFO - 'branch': 'Firefox', 09:14:59 INFO - 'buildbot_json_path': 'buildprops.json', 09:14:59 INFO - 'config_files': ('talos/linux_config.py',), 09:14:59 INFO - 'default_actions': ('clobber', 09:14:59 INFO - 'read-buildbot-config', 09:14:59 INFO - 'download-and-extract', 09:14:59 INFO - 'populate-webroot', 09:14:59 INFO - 'create-virtualenv', 09:14:59 INFO - 'install', 09:14:59 INFO - 'run-tests'), 09:14:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:14:59 INFO - 'download_minidump_stackwalk': True, 09:14:59 INFO - 'download_symbols': 'ondemand', 09:14:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:14:59 INFO - 'tooltool.py': '/tools/tooltool.py', 09:14:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:14:59 INFO - '/tools/misc-python/virtualenv.py')}, 09:14:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:14:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:14:59 INFO - 'installer_path': 'installer.exe', 09:14:59 INFO - 'log_level': 'info', 09:14:59 INFO - 'log_name': 'talos', 09:14:59 INFO - 'log_to_console': True, 09:14:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:14:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:14:59 INFO - 'opt_config_files': (), 09:14:59 INFO - 'pip_index': False, 09:14:59 INFO - 'sps_profile': False, 09:14:59 INFO - 'sps_profile_interval': 0, 09:14:59 INFO - 'suite': 'dromaeojs', 09:14:59 INFO - 'system_bits': '32', 09:14:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:14:59 INFO - 'title': 'talos-linux64-ix-009', 09:14:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:14:59 INFO - 'use_talos_json': True, 09:14:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:14:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:14:59 INFO - 'work_dir': 'build'} 09:14:59 INFO - [mozharness: 2016-12-24 17:14:59.933479Z] Running clobber step. 09:14:59 INFO - Running pre-action listener: _resource_record_pre_action 09:14:59 INFO - Running main action method: clobber 09:14:59 INFO - rmtree: /builds/slave/test/build 09:14:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:00 INFO - Running post-action listener: _resource_record_post_action 09:15:00 INFO - [mozharness: 2016-12-24 17:15:00.906898Z] Finished clobber step (success) 09:15:00 INFO - [mozharness: 2016-12-24 17:15:00.906998Z] Running read-buildbot-config step. 09:15:00 INFO - Running pre-action listener: _resource_record_pre_action 09:15:00 INFO - Running main action method: read_buildbot_config 09:15:00 INFO - Using buildbot properties: 09:15:00 INFO - { 09:15:00 INFO - "project": "", 09:15:00 INFO - "product": "firefox", 09:15:00 INFO - "script_repo_revision": "production", 09:15:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:15:00 INFO - "repository": "", 09:15:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:15:00 INFO - "buildid": "20161224073122", 09:15:00 INFO - "pgo_build": "True", 09:15:00 INFO - "basedir": "/builds/slave/test", 09:15:00 INFO - "buildnumber": 130, 09:15:00 INFO - "slavename": "talos-linux64-ix-009", 09:15:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:15:00 INFO - "platform": "ubuntu64_hw", 09:15:00 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:15:00 INFO - "branch": "mozilla-central", 09:15:00 INFO - "repo_path": "mozilla-central", 09:15:00 INFO - "revision": "1656c870416d7779f832bd74b21f743fe5b83893", 09:15:00 INFO - "stage_platform": "linux64-pgo", 09:15:00 INFO - "builduid": "4626862bbb0b4c04878fc25cd246665b", 09:15:00 INFO - "slavebuilddir": "test" 09:15:00 INFO - } 09:15:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:15:00 INFO - Running post-action listener: _resource_record_post_action 09:15:00 INFO - [mozharness: 2016-12-24 17:15:00.909456Z] Finished read-buildbot-config step (success) 09:15:00 INFO - [mozharness: 2016-12-24 17:15:00.909547Z] Running download-and-extract step. 09:15:00 INFO - Running pre-action listener: _resource_record_pre_action 09:15:00 INFO - Running main action method: download_and_extract 09:15:00 INFO - mkdir: /builds/slave/test/build/tests 09:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:00 INFO - trying https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:15:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:15:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:15:01 INFO - Downloaded 1361 bytes. 09:15:01 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:15:01 INFO - Using the following test package requirements: 09:15:01 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:01 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:15:01 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:15:01 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'jsshell-linux-x86_64.zip'], 09:15:01 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:15:01 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:01 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:15:01 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:15:01 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:15:01 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:15:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:15:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:15:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:15:02 INFO - Expected file size: 21824373 09:15:02 INFO - Obtained file size: 21824373 09:15:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:15:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:15:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:15:09 INFO - Expected file size: 21824373 09:15:09 INFO - Obtained file size: 21824373 09:15:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:15:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:15:14 INFO - Expected file size: 14409776 09:15:14 INFO - Obtained file size: 14409776 09:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:14 INFO - trying https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:15:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:15:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:15:15 INFO - Downloaded 61395112 bytes. 09:15:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:15:15 INFO - mkdir: /builds/slave/test/properties 09:15:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:15 INFO - Contents: 09:15:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:15:16 INFO - Running post-action listener: _resource_record_post_action 09:15:16 INFO - Running post-action listener: set_extra_try_arguments 09:15:16 INFO - [mozharness: 2016-12-24 17:15:16.565212Z] Finished download-and-extract step (success) 09:15:16 INFO - [mozharness: 2016-12-24 17:15:16.565440Z] Running populate-webroot step. 09:15:16 INFO - Running pre-action listener: _resource_record_pre_action 09:15:16 INFO - Running main action method: populate_webroot 09:15:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:16 INFO - '--tppagecycles', 09:15:16 INFO - '7'], 09:15:16 INFO - 'tests': ['tsvgm']}}, 09:15:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:15:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:15:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:15:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:15:16 INFO - 'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:15:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:15:16 INFO - 'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['damp', 'tps']}, 09:15:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:15:16 INFO - 'tests': ['damp', 'tps']}, 09:15:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['dromaeo_dom']}, 09:15:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['basic_compositor_video']}, 09:15:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:15:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['a11yr', 09:15:16 INFO - 'ts_paint', 09:15:16 INFO - 'tpaint', 09:15:16 INFO - 'sessionrestore', 09:15:16 INFO - 'sessionrestore_no_auto_restore', 09:15:16 INFO - 'tabpaint']}, 09:15:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:15:16 INFO - 'ts_paint', 09:15:16 INFO - 'tpaint', 09:15:16 INFO - 'sessionrestore', 09:15:16 INFO - 'sessionrestore_no_auto_restore', 09:15:16 INFO - 'tabpaint']}, 09:15:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:15:16 INFO - 'ts_paint', 09:15:16 INFO - 'tpaint', 09:15:16 INFO - 'sessionrestore', 09:15:16 INFO - 'sessionrestore_no_auto_restore', 09:15:16 INFO - 'tabpaint']}, 09:15:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:15:16 INFO - 'ts_paint', 09:15:16 INFO - 'tpaint', 09:15:16 INFO - 'sessionrestore', 09:15:16 INFO - 'sessionrestore_no_auto_restore', 09:15:16 INFO - 'tabpaint']}, 09:15:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['a11yr', 09:15:16 INFO - 'ts_paint', 09:15:16 INFO - 'tpaint', 09:15:16 INFO - 'sessionrestore', 09:15:16 INFO - 'sessionrestore_no_auto_restore', 09:15:16 INFO - 'tabpaint']}, 09:15:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['a11yr', 09:15:16 INFO - 'ts_paint', 09:15:16 INFO - 'tpaint', 09:15:16 INFO - 'sessionrestore', 09:15:16 INFO - 'sessionrestore_no_auto_restore', 09:15:16 INFO - 'tabpaint']}, 09:15:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['tsvgx', 09:15:16 INFO - 'tsvgr_opacity', 09:15:16 INFO - 'tart', 09:15:16 INFO - 'tscrollx', 09:15:16 INFO - 'cart', 09:15:16 INFO - 'tsvg_static']}, 09:15:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:15:16 INFO - 'tsvgr_opacity', 09:15:16 INFO - 'tart', 09:15:16 INFO - 'tscrollx', 09:15:16 INFO - 'cart', 09:15:16 INFO - 'tsvg_static']}, 09:15:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:15:16 INFO - 'talos_options': ['--disable-e10s'], 09:15:16 INFO - 'tests': ['tp5o']}, 09:15:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:15:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:15:16 INFO - 'talos_options': ['--disable-e10s', 09:15:16 INFO - '--xperf_path', 09:15:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:16 INFO - 'tests': ['tp5n']}, 09:15:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:15:16 INFO - 'talos_options': ['--xperf_path', 09:15:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:16 INFO - 'tests': ['tp5n']}}, 09:15:16 INFO - 'talos.zip': {'path': '', 09:15:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:16 INFO - Running post-action listener: _resource_record_post_action 09:15:16 INFO - [mozharness: 2016-12-24 17:15:16.583814Z] Finished populate-webroot step (success) 09:15:16 INFO - [mozharness: 2016-12-24 17:15:16.583903Z] Running create-virtualenv step. 09:15:16 INFO - Running pre-action listener: _resource_record_pre_action 09:15:16 INFO - Running main action method: create_virtualenv 09:15:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:15:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:16 INFO - Using real prefix '/usr' 09:15:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:19 INFO - Installing pip.................done. 09:15:19 INFO - Return code: 0 09:15:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:15:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:15:19 INFO - Reading from file tmpfile_stdout 09:15:19 INFO - Output received: 09:15:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:15:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:19 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:15:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:15:19 INFO - Using env: {'DISPLAY': ':0', 09:15:19 INFO - 'HOME': '/home/cltbld', 09:15:19 INFO - 'LANG': 'en_US.UTF-8', 09:15:19 INFO - 'LANGUAGE': 'en_US:en', 09:15:19 INFO - 'LOGNAME': 'cltbld', 09:15:19 INFO - 'MAIL': '/var/mail/cltbld', 09:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:19 INFO - 'MOZ_NO_REMOTE': '1', 09:15:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:19 INFO - 'NO_EM_RESTART': '1', 09:15:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:19 INFO - 'PWD': '/builds/slave/test', 09:15:19 INFO - 'SHELL': '/bin/bash', 09:15:19 INFO - 'SHLVL': '1', 09:15:19 INFO - 'TERM': 'linux', 09:15:19 INFO - 'TMOUT': '86400', 09:15:19 INFO - 'USER': 'cltbld', 09:15:19 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:19 INFO - '_': '/tools/buildbot/bin/python'} 09:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:15:19 INFO - Cleaning up... 09:15:19 INFO - Return code: 0 09:15:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:19 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:15:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:15:19 INFO - Using env: {'DISPLAY': ':0', 09:15:19 INFO - 'HOME': '/home/cltbld', 09:15:19 INFO - 'LANG': 'en_US.UTF-8', 09:15:19 INFO - 'LANGUAGE': 'en_US:en', 09:15:19 INFO - 'LOGNAME': 'cltbld', 09:15:19 INFO - 'MAIL': '/var/mail/cltbld', 09:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:19 INFO - 'MOZ_NO_REMOTE': '1', 09:15:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:19 INFO - 'NO_EM_RESTART': '1', 09:15:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:19 INFO - 'PWD': '/builds/slave/test', 09:15:19 INFO - 'SHELL': '/bin/bash', 09:15:19 INFO - 'SHLVL': '1', 09:15:19 INFO - 'TERM': 'linux', 09:15:19 INFO - 'TMOUT': '86400', 09:15:19 INFO - 'USER': 'cltbld', 09:15:19 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:19 INFO - '_': '/tools/buildbot/bin/python'} 09:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:19 INFO - Downloading/unpacking psutil>=3.1.1 09:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:21 INFO - Installing collected packages: psutil 09:15:21 INFO - Running setup.py install for psutil 09:15:21 INFO - building 'psutil._psutil_linux' extension 09:15:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:15:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:15:22 INFO - building 'psutil._psutil_posix' extension 09:15:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:15:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:15:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:22 INFO - Successfully installed psutil 09:15:22 INFO - Cleaning up... 09:15:22 INFO - Return code: 0 09:15:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:22 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:15:22 INFO - Using env: {'DISPLAY': ':0', 09:15:22 INFO - 'HOME': '/home/cltbld', 09:15:22 INFO - 'LANG': 'en_US.UTF-8', 09:15:22 INFO - 'LANGUAGE': 'en_US:en', 09:15:22 INFO - 'LOGNAME': 'cltbld', 09:15:22 INFO - 'MAIL': '/var/mail/cltbld', 09:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:22 INFO - 'MOZ_NO_REMOTE': '1', 09:15:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:22 INFO - 'NO_EM_RESTART': '1', 09:15:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:22 INFO - 'PWD': '/builds/slave/test', 09:15:22 INFO - 'SHELL': '/bin/bash', 09:15:22 INFO - 'SHLVL': '1', 09:15:22 INFO - 'TERM': 'linux', 09:15:22 INFO - 'TMOUT': '86400', 09:15:22 INFO - 'USER': 'cltbld', 09:15:22 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:22 INFO - '_': '/tools/buildbot/bin/python'} 09:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:15:24 INFO - Installing collected packages: mozsystemmonitor 09:15:24 INFO - Running setup.py install for mozsystemmonitor 09:15:24 INFO - Successfully installed mozsystemmonitor 09:15:24 INFO - Cleaning up... 09:15:24 INFO - Return code: 0 09:15:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:24 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:15:24 INFO - Using env: {'DISPLAY': ':0', 09:15:24 INFO - 'HOME': '/home/cltbld', 09:15:24 INFO - 'LANG': 'en_US.UTF-8', 09:15:24 INFO - 'LANGUAGE': 'en_US:en', 09:15:24 INFO - 'LOGNAME': 'cltbld', 09:15:24 INFO - 'MAIL': '/var/mail/cltbld', 09:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:24 INFO - 'MOZ_NO_REMOTE': '1', 09:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:24 INFO - 'NO_EM_RESTART': '1', 09:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:24 INFO - 'PWD': '/builds/slave/test', 09:15:24 INFO - 'SHELL': '/bin/bash', 09:15:24 INFO - 'SHLVL': '1', 09:15:24 INFO - 'TERM': 'linux', 09:15:24 INFO - 'TMOUT': '86400', 09:15:24 INFO - 'USER': 'cltbld', 09:15:24 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:24 INFO - '_': '/tools/buildbot/bin/python'} 09:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:15:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:15:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:15:27 INFO - warning: no files found matching '*.txt' 09:15:27 INFO - no previously-included directories found matching 'build' 09:15:27 INFO - no previously-included directories found matching 'dist' 09:15:27 INFO - no previously-included directories found matching '.git*' 09:15:27 INFO - Installing collected packages: jsonschema, functools32 09:15:27 INFO - Running setup.py install for functools32 09:15:27 INFO - warning: no files found matching '*.txt' 09:15:27 INFO - no previously-included directories found matching 'build' 09:15:27 INFO - no previously-included directories found matching 'dist' 09:15:27 INFO - no previously-included directories found matching '.git*' 09:15:27 INFO - Successfully installed jsonschema functools32 09:15:27 INFO - Cleaning up... 09:15:27 INFO - Return code: 0 09:15:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:27 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:15:27 INFO - Using env: {'DISPLAY': ':0', 09:15:27 INFO - 'HOME': '/home/cltbld', 09:15:27 INFO - 'LANG': 'en_US.UTF-8', 09:15:27 INFO - 'LANGUAGE': 'en_US:en', 09:15:27 INFO - 'LOGNAME': 'cltbld', 09:15:27 INFO - 'MAIL': '/var/mail/cltbld', 09:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:27 INFO - 'MOZ_NO_REMOTE': '1', 09:15:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:27 INFO - 'NO_EM_RESTART': '1', 09:15:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:27 INFO - 'PWD': '/builds/slave/test', 09:15:27 INFO - 'SHELL': '/bin/bash', 09:15:27 INFO - 'SHLVL': '1', 09:15:27 INFO - 'TERM': 'linux', 09:15:27 INFO - 'TMOUT': '86400', 09:15:27 INFO - 'USER': 'cltbld', 09:15:27 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:27 INFO - '_': '/tools/buildbot/bin/python'} 09:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:15:27 INFO - Cleaning up... 09:15:27 INFO - Return code: 0 09:15:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:27 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:15:27 INFO - Using env: {'DISPLAY': ':0', 09:15:27 INFO - 'HOME': '/home/cltbld', 09:15:27 INFO - 'LANG': 'en_US.UTF-8', 09:15:27 INFO - 'LANGUAGE': 'en_US:en', 09:15:27 INFO - 'LOGNAME': 'cltbld', 09:15:27 INFO - 'MAIL': '/var/mail/cltbld', 09:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:27 INFO - 'MOZ_NO_REMOTE': '1', 09:15:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:27 INFO - 'NO_EM_RESTART': '1', 09:15:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:27 INFO - 'PWD': '/builds/slave/test', 09:15:27 INFO - 'SHELL': '/bin/bash', 09:15:27 INFO - 'SHLVL': '1', 09:15:27 INFO - 'TERM': 'linux', 09:15:27 INFO - 'TMOUT': '86400', 09:15:27 INFO - 'USER': 'cltbld', 09:15:27 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:27 INFO - '_': '/tools/buildbot/bin/python'} 09:15:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:15:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:15:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:15:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:30 INFO - Downloading docopt-0.6.1.tar.gz 09:15:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:15:30 INFO - Installing collected packages: blobuploader, requests, docopt 09:15:30 INFO - Running setup.py install for blobuploader 09:15:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:15:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:15:30 INFO - Running setup.py install for requests 09:15:31 INFO - Running setup.py install for docopt 09:15:31 INFO - Successfully installed blobuploader requests docopt 09:15:31 INFO - Cleaning up... 09:15:31 INFO - Return code: 0 09:15:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:31 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:15:31 INFO - Using env: {'DISPLAY': ':0', 09:15:31 INFO - 'HOME': '/home/cltbld', 09:15:31 INFO - 'LANG': 'en_US.UTF-8', 09:15:31 INFO - 'LANGUAGE': 'en_US:en', 09:15:31 INFO - 'LOGNAME': 'cltbld', 09:15:31 INFO - 'MAIL': '/var/mail/cltbld', 09:15:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:31 INFO - 'MOZ_NO_REMOTE': '1', 09:15:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:31 INFO - 'NO_EM_RESTART': '1', 09:15:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:31 INFO - 'PWD': '/builds/slave/test', 09:15:31 INFO - 'SHELL': '/bin/bash', 09:15:31 INFO - 'SHLVL': '1', 09:15:31 INFO - 'TERM': 'linux', 09:15:31 INFO - 'TMOUT': '86400', 09:15:31 INFO - 'USER': 'cltbld', 09:15:31 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:31 INFO - '_': '/tools/buildbot/bin/python'} 09:15:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:31 INFO - Running setup.py (path:/tmp/pip-zO7zs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:31 INFO - Running setup.py (path:/tmp/pip-oSk_Q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:31 INFO - Running setup.py (path:/tmp/pip-jiPswl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:31 INFO - Running setup.py (path:/tmp/pip-TqCXbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:32 INFO - Running setup.py (path:/tmp/pip-vYy9Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:32 INFO - Running setup.py (path:/tmp/pip-9aaGHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:32 INFO - Running setup.py (path:/tmp/pip-9ZtotU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:32 INFO - Running setup.py (path:/tmp/pip-nU5ljH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:32 INFO - Running setup.py (path:/tmp/pip-PNRDV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:32 INFO - Running setup.py (path:/tmp/pip-ku5jGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:32 INFO - Running setup.py (path:/tmp/pip-941YlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:32 INFO - Running setup.py (path:/tmp/pip-_TvpZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:32 INFO - Running setup.py (path:/tmp/pip-9JxOXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:32 INFO - Running setup.py (path:/tmp/pip-cOAJyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:32 INFO - Running setup.py (path:/tmp/pip-sVQDhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:32 INFO - Running setup.py (path:/tmp/pip-BU6pdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:32 INFO - Running setup.py (path:/tmp/pip-GtsAzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:15:32 INFO - Running setup.py install for manifestparser 09:15:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Running setup.py install for mozcrash 09:15:33 INFO - Running setup.py install for mozdebug 09:15:33 INFO - Running setup.py install for mozdevice 09:15:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Running setup.py install for mozfile 09:15:33 INFO - Running setup.py install for mozhttpd 09:15:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Running setup.py install for mozinfo 09:15:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Running setup.py install for mozInstall 09:15:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Running setup.py install for mozleak 09:15:33 INFO - Running setup.py install for mozlog 09:15:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:15:33 INFO - Running setup.py install for moznetwork 09:15:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:15:34 INFO - Running setup.py install for mozprocess 09:15:34 INFO - Running setup.py install for mozprofile 09:15:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:15:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:15:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:15:34 INFO - Running setup.py install for mozrunner 09:15:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:15:34 INFO - Running setup.py install for mozscreenshot 09:15:34 INFO - Running setup.py install for moztest 09:15:34 INFO - Running setup.py install for mozversion 09:15:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:15:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:15:34 INFO - Cleaning up... 09:15:34 INFO - Return code: 0 09:15:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:34 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:15:34 INFO - Using env: {'DISPLAY': ':0', 09:15:34 INFO - 'HOME': '/home/cltbld', 09:15:34 INFO - 'LANG': 'en_US.UTF-8', 09:15:34 INFO - 'LANGUAGE': 'en_US:en', 09:15:34 INFO - 'LOGNAME': 'cltbld', 09:15:34 INFO - 'MAIL': '/var/mail/cltbld', 09:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:34 INFO - 'MOZ_NO_REMOTE': '1', 09:15:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:34 INFO - 'NO_EM_RESTART': '1', 09:15:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:34 INFO - 'PWD': '/builds/slave/test', 09:15:34 INFO - 'SHELL': '/bin/bash', 09:15:34 INFO - 'SHLVL': '1', 09:15:34 INFO - 'TERM': 'linux', 09:15:34 INFO - 'TMOUT': '86400', 09:15:34 INFO - 'USER': 'cltbld', 09:15:34 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:34 INFO - '_': '/tools/buildbot/bin/python'} 09:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:34 INFO - Running setup.py (path:/tmp/pip-5xshDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:35 INFO - Running setup.py (path:/tmp/pip-NvGuHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:35 INFO - Running setup.py (path:/tmp/pip-hNEtNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:35 INFO - Running setup.py (path:/tmp/pip-Cs8oKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:35 INFO - Running setup.py (path:/tmp/pip-aepl_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:35 INFO - Running setup.py (path:/tmp/pip-kPjCt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:35 INFO - Running setup.py (path:/tmp/pip-kqhD3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:35 INFO - Running setup.py (path:/tmp/pip-T_043X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:35 INFO - Running setup.py (path:/tmp/pip-iEfMCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:35 INFO - Running setup.py (path:/tmp/pip-nMhd4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:35 INFO - Running setup.py (path:/tmp/pip-lfkeWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:35 INFO - Running setup.py (path:/tmp/pip-1SEXCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:35 INFO - Running setup.py (path:/tmp/pip-xypJxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:35 INFO - Running setup.py (path:/tmp/pip-HpIfUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:35 INFO - Running setup.py (path:/tmp/pip-2SIwY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:36 INFO - Running setup.py (path:/tmp/pip-h38L1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:36 INFO - Running setup.py (path:/tmp/pip-lelOAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:15:36 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:36 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:38 INFO - Downloading blessings-1.6.tar.gz 09:15:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:15:38 INFO - Installing collected packages: blessings 09:15:38 INFO - Running setup.py install for blessings 09:15:38 INFO - Successfully installed blessings 09:15:38 INFO - Cleaning up... 09:15:38 INFO - Return code: 0 09:15:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:15:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:38 INFO - Reading from file tmpfile_stdout 09:15:38 INFO - Current package versions: 09:15:38 INFO - argparse == 1.2.1 09:15:38 INFO - blessings == 1.6 09:15:38 INFO - blobuploader == 1.2.4 09:15:38 INFO - docopt == 0.6.1 09:15:38 INFO - functools32 == 3.2.3-2 09:15:38 INFO - jsonschema == 2.5.1 09:15:38 INFO - manifestparser == 1.1 09:15:38 INFO - mozInstall == 1.12 09:15:38 INFO - mozcrash == 1.0 09:15:38 INFO - mozdebug == 0.1 09:15:38 INFO - mozdevice == 0.49 09:15:38 INFO - mozfile == 1.2 09:15:38 INFO - mozhttpd == 0.7 09:15:38 INFO - mozinfo == 0.9 09:15:38 INFO - mozleak == 0.1 09:15:38 INFO - mozlog == 3.4 09:15:38 INFO - moznetwork == 0.27 09:15:38 INFO - mozprocess == 0.23 09:15:38 INFO - mozprofile == 0.28 09:15:38 INFO - mozrunner == 6.13 09:15:38 INFO - mozscreenshot == 0.1 09:15:38 INFO - mozsystemmonitor == 0.3 09:15:38 INFO - moztest == 0.7 09:15:38 INFO - mozversion == 1.4 09:15:38 INFO - psutil == 3.1.1 09:15:38 INFO - requests == 1.2.3 09:15:38 INFO - wsgiref == 0.1.2 09:15:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:38 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:15:38 INFO - Using env: {'DISPLAY': ':0', 09:15:38 INFO - 'HOME': '/home/cltbld', 09:15:38 INFO - 'LANG': 'en_US.UTF-8', 09:15:38 INFO - 'LANGUAGE': 'en_US:en', 09:15:38 INFO - 'LOGNAME': 'cltbld', 09:15:38 INFO - 'MAIL': '/var/mail/cltbld', 09:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:38 INFO - 'MOZ_NO_REMOTE': '1', 09:15:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:38 INFO - 'NO_EM_RESTART': '1', 09:15:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:38 INFO - 'PWD': '/builds/slave/test', 09:15:38 INFO - 'SHELL': '/bin/bash', 09:15:38 INFO - 'SHLVL': '1', 09:15:38 INFO - 'TERM': 'linux', 09:15:38 INFO - 'TMOUT': '86400', 09:15:38 INFO - 'USER': 'cltbld', 09:15:38 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:38 INFO - '_': '/tools/buildbot/bin/python'} 09:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:15:38 INFO - Cleaning up... 09:15:38 INFO - Return code: 0 09:15:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:38 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 0x1c401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:15:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:15:38 INFO - Using env: {'DISPLAY': ':0', 09:15:38 INFO - 'HOME': '/home/cltbld', 09:15:38 INFO - 'LANG': 'en_US.UTF-8', 09:15:38 INFO - 'LANGUAGE': 'en_US:en', 09:15:38 INFO - 'LOGNAME': 'cltbld', 09:15:38 INFO - 'MAIL': '/var/mail/cltbld', 09:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:38 INFO - 'MOZ_NO_REMOTE': '1', 09:15:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:38 INFO - 'NO_EM_RESTART': '1', 09:15:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:38 INFO - 'PWD': '/builds/slave/test', 09:15:38 INFO - 'SHELL': '/bin/bash', 09:15:38 INFO - 'SHLVL': '1', 09:15:38 INFO - 'TERM': 'linux', 09:15:38 INFO - 'TMOUT': '86400', 09:15:38 INFO - 'USER': 'cltbld', 09:15:38 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:38 INFO - '_': '/tools/buildbot/bin/python'} 09:15:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:15:39 INFO - Cleaning up... 09:15:39 INFO - Return code: 0 09:15:39 INFO - Running post-action listener: _resource_record_post_action 09:15:39 INFO - Running post-action listener: _start_resource_monitoring 09:15:39 INFO - Starting resource monitoring. 09:15:39 INFO - [mozharness: 2016-12-24 17:15:39.151615Z] Finished create-virtualenv step (success) 09:15:39 INFO - [mozharness: 2016-12-24 17:15:39.152141Z] Running install step. 09:15:39 INFO - Running pre-action listener: _resource_record_pre_action 09:15:39 INFO - Running main action method: install 09:15:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:39 INFO - Reading from file tmpfile_stdout 09:15:39 INFO - Detecting whether we're running mozinstall >=1.0... 09:15:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:15:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:15:39 INFO - Reading from file tmpfile_stdout 09:15:39 INFO - Output received: 09:15:39 INFO - Usage: mozinstall [options] installer 09:15:39 INFO - Options: 09:15:39 INFO - -h, --help show this help message and exit 09:15:39 INFO - -d DEST, --destination=DEST 09:15:39 INFO - Directory to install application into. [default: 09:15:39 INFO - "/builds/slave/test"] 09:15:39 INFO - --app=APP Application being installed. [default: firefox] 09:15:39 INFO - mkdir: /builds/slave/test/build/application 09:15:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:15:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:15:56 INFO - Reading from file tmpfile_stdout 09:15:56 INFO - Output received: 09:15:56 INFO - /builds/slave/test/build/application/firefox/firefox 09:15:56 INFO - Running post-action listener: _resource_record_post_action 09:15:56 INFO - [mozharness: 2016-12-24 17:15:56.796342Z] Finished install step (success) 09:15:56 INFO - [mozharness: 2016-12-24 17:15:56.796589Z] Running run-tests step. 09:15:56 INFO - Running pre-action listener: _resource_record_pre_action 09:15:56 INFO - Running main action method: run_tests 09:15:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:15:56 INFO - Python 2.7.3 09:15:56 INFO - Return code: 0 09:15:56 INFO - grabbing minidump binary from tooltool 09:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:56 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 0x1faf3f0>, '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 0x1fafcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb01a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:15:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:15:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:15:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:15:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:15:56 INFO - Return code: 0 09:15:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:15:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:15:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:15:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:15:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:15:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:15:56 INFO - Using env: {'DISPLAY': ':0', 09:15:56 INFO - 'HOME': '/home/cltbld', 09:15:56 INFO - 'LANG': 'en_US.UTF-8', 09:15:56 INFO - 'LANGUAGE': 'en_US:en', 09:15:56 INFO - 'LOGNAME': 'cltbld', 09:15:56 INFO - 'MAIL': '/var/mail/cltbld', 09:15:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:15:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:56 INFO - 'MOZ_NO_REMOTE': '1', 09:15:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:56 INFO - 'NO_EM_RESTART': '1', 09:15:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:56 INFO - 'PWD': '/builds/slave/test', 09:15:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:15:56 INFO - 'SHELL': '/bin/bash', 09:15:56 INFO - 'SHLVL': '1', 09:15:56 INFO - 'TERM': 'linux', 09:15:56 INFO - 'TMOUT': '86400', 09:15:56 INFO - 'USER': 'cltbld', 09:15:56 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487', 09:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:56 INFO - '_': '/tools/buildbot/bin/python'} 09:15:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:15:57 INFO - mozversion application_buildid: 20161224073122 09:15:57 INFO - mozversion application_changeset: 1656c870416d7779f832bd74b21f743fe5b83893 09:15:57 INFO - mozversion application_display_name: Nightly 09:15:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:15:57 INFO - mozversion application_name: Firefox 09:15:57 INFO - mozversion application_remotingname: firefox 09:15:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:15:57 INFO - mozversion application_vendor: Mozilla 09:15:57 INFO - mozversion application_version: 53.0a1 09:15:57 INFO - mozversion platform_buildid: 20161224073122 09:15:57 INFO - mozversion platform_changeset: 1656c870416d7779f832bd74b21f743fe5b83893 09:15:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:15:57 INFO - mozversion platform_version: 53.0a1 09:15:57 INFO - using testdate: 1482599757 09:15:57 INFO - actual date: 1482599757 09:15:57 INFO - starting webserver on 'localhost:39086' 09:15:57 INFO - SUITE-START | Running 2 tests 09:15:57 INFO - TEST-START | dromaeo_css 09:15:57 INFO - operating with platform_type : linux_ 09:15:57 INFO - Initialising browser for dromaeo_css test... 09:15:57 INFO - TEST-INFO | started process 28207 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHUn3TS/profile http://localhost:39086/getInfo.html) 09:15:57 INFO - PROCESS | 28207 | 1482599757545 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:15:59 INFO - PROCESS | 28207 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:15:59 INFO - PROCESS | 28207 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:16:00 INFO - PROCESS | 28207 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:16:00 INFO - PROCESS | 28207 | __metrics Screen width/height:1600/1200 09:16:00 INFO - PROCESS | 28207 | colorDepth:24 09:16:00 INFO - PROCESS | 28207 | Browser inner width/height: 1024/697 09:16:00 INFO - PROCESS | 28207 | __metrics 09:16:01 INFO - PROCESS | 28207 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:16:01 INFO - PROCESS | 28207 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:16:01 INFO - PROCESS | 28207 | console.error: PushService: 09:16:01 INFO - PROCESS | 28207 | stateChangeProcessEnqueue: Error transitioning state 09:16:01 INFO - PROCESS | 28207 | UnknownError 09:16:02 INFO - TEST-INFO | 28207: exit 0 09:16:02 INFO - Browser initialized. 09:16:02 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:02 INFO - TEST-INFO | started process 28291 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHUn3TS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:14 INFO - PROCESS | 28291 | RSS: Main: 147488768 09:16:14 INFO - PROCESS | 28291 | 09:17:05 INFO - PROCESS | 28291 | 1482599825958 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:19:16 INFO - PROCESS | 28291 | Cycle 1(1): loaded http://localhost:39086/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39086/tests/dromaeo/cssquery-ext.html) 09:19:18 INFO - PROCESS | 28291 | RSS: Main: 263159808 09:19:18 INFO - PROCESS | 28291 | 09:21:03 INFO - PROCESS | 28291 | ************************* 09:21:03 INFO - PROCESS | 28291 | A coding exception was thrown and uncaught in a Task. 09:21:03 INFO - PROCESS | 28291 | 09:21:03 INFO - PROCESS | 28291 | Full message: ReferenceError: fetch is not defined 09:21:03 INFO - PROCESS | 28291 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:21:03 INFO - PROCESS | 28291 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:21:03 INFO - PROCESS | 28291 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:21:03 INFO - PROCESS | 28291 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:21:03 INFO - PROCESS | 28291 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:21:03 INFO - PROCESS | 28291 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:21:03 INFO - PROCESS | 28291 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:21:03 INFO - PROCESS | 28291 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:21:03 INFO - PROCESS | 28291 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:21:03 INFO - PROCESS | 28291 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:21:03 INFO - PROCESS | 28291 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:21:03 INFO - PROCESS | 28291 | 09:21:03 INFO - PROCESS | 28291 | ************************* 09:22:20 INFO - PROCESS | 28291 | Cycle 1(1): loaded http://localhost:39086/tests/dromaeo/cssquery-ext.html (next: http://localhost:39086/tests/dromaeo/cssquery-jquery.html) 09:22:21 INFO - PROCESS | 28291 | RSS: Main: 246644736 09:22:21 INFO - PROCESS | 28291 | 09:25:23 INFO - PROCESS | 28291 | Cycle 1(1): loaded http://localhost:39086/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39086/tests/dromaeo/cssquery-mootools.html) 09:25:23 INFO - PROCESS | 28291 | RSS: Main: 189562880 09:25:23 INFO - PROCESS | 28291 | 09:28:25 INFO - PROCESS | 28291 | Cycle 1(1): loaded http://localhost:39086/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39086/tests/dromaeo/cssquery-prototype.html) 09:28:26 INFO - PROCESS | 28291 | RSS: Main: 230592512 09:28:26 INFO - PROCESS | 28291 | 09:31:29 INFO - PROCESS | 28291 | Cycle 1(1): loaded http://localhost:39086/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39086/tests/dromaeo/cssquery-yui.html) 09:31:29 INFO - PROCESS | 28291 | RSS: Main: 189296640 09:31:29 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | Cycle 1(1): loaded http://localhost:39086/tests/dromaeo/cssquery-yui.html (next: http://localhost:39086/tests/dromaeo/cssquery-dojo.html) 09:34:32 INFO - PROCESS | 28291 | RSS: Main: 286011392 09:34:32 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | __start_tp_report 09:34:32 INFO - PROCESS | 28291 | _x_x_mozilla_page_load 09:34:32 INFO - PROCESS | 28291 | _x_x_mozilla_page_load_details 09:34:32 INFO - PROCESS | 28291 | |i|pagename|runs| 09:34:32 INFO - PROCESS | 28291 | |0;dojo.html;3165.7150856422923;3176.9682303176974;3198.7600929930254;3203.1511649412923;3214;5199.506046925536;5265.315508983832;5294.311739473873;5344.786208551658;5393.6763794172375;5300.973495132529;5319.494648008443;5334.306540149775;5391.865203369918;5413.783448662059;4794.35276237707;4809.663323567352;4829.034193161364;4830.806767729287;4880.194767863316;4888.462269150408;4905.141600219948;4935.087008903346;4935.925961110568;4970.478099799523;4746.572808447222;4796.8081276749235;4879.853604391874;4885.7068575885205;4941.876453088666;4795.448523419782;4814.855554333376;4831.492693267227;4840.733759643218;4918.680285781413;4775.570198682135;4783.832565860213;4806.519348065201;4815.6388270879825;4857.077155340509;5212.556932660728;5317.73411329432;5332.386775520826;5350.5987050971335;5378.865528361783;5635.943640563582;5707.77168913243;5720.8283751487525;5721.5708821838525;5725.914111288334;5665.291838520185;5697.287839020122;5708.743106560173;5752.13717942312;5757.798477053283;5264.684118952875;5300.09898317287;5302.655327403706;5303.390110137345;5304.761285742151;5710.371859095496;5816.796412125555;5816.861838343188;5818.679972601463;5876.1479585459865;5562.916556251659;5591.161325801162;5607.43925607436;5612.410696876851;5692.7722891083895;5582.190582365615;5617.32592088952;5749.108888122348;5761.366249712528;5762.1068734346245;5654.321481422256;5678.318601767732;5681.062624666966;5726.685032323179;5787.160861675117;5578.665280083208;5778.3643799182055;5785.074388097884;5786.392428795;5791.333996590446;4237.194932962727;4246.426732391088;4265.360195970629;4285.207236661227;4287.142571485715;3819.656230939229;3832.4826148470156;3845.2693988142164;3845.750026248285;3854.4989151410136;5470.5350045245805;5474.124140137559;5475.342958844884;5475.808346707846;5505.697186654692;5686.687232202268;5691.516221121168;5733.025385684444;5741.540676745851;5814;5476.397596264408;5479.232907392969;5481.369642491165;5512.4211957743855;5524.889502210013;4747.406574178228;4758.690685105526;4759.571638552547;4762;4799.424069111659;5824.708764561839;5877.382874798084;5911.586188966731;5989.311229208697;5994.28068631767;5665.065264231355;5717.37108918027;5718.285214348207;5762.538996880324;5766.250387449605;5860.794872179454;5866.530677545701;5920.822375328747;5923.111533269872;5925.792597259076;5590.888182236251;5636.239107720406;5646.3506696728755;5648.971755141198;5652.773889044557;5684.431556844283;5706.400827913176;5724.055530837365;5763.049096898964;5814.447627475383;5626.74679639409;5629.437056294501;5634.23937768396;5636.577256705681;5638.7462564185535;5533.114701647717;5549.722513874371;5555.527780138572;5622.32532096151;5628.718564071862;5516.66899985986;5534.1975413564005;5536.390996990408;5583.916241256303;5642.605017648725;5598.384177740364;5621.775128994795;5627.7467513961155;5639.520640745583;5643.746031428514;5336.866578335573;5443.863903402288;5487.917681234864;5491.791805802717;5544.944550554443 09:34:32 INFO - PROCESS | 28291 | |1;ext.html;569.9968056221051;586.3227971692695;593.0925683703932;593.6556797057705;607.9604825686333;32409.83795081024;33192;40997.180056398895;42497.575024249745;42771;44073;44363;44427.55572444275;44545.77727111368;44650.33024504635;8892.866607000884;9060.909390906072;9068.727938161832;9083.091690830897;9083.409578377385;8764.25503832175;8994.28045756338;10450.63422780203;10739.301945373565;10748.247622666377;10726.463676816167;10783.946080269627;10784.622538211164;10789.892101079007;10794.838077428845;10718.517666705016;10727.463626818628;10781.083607893299;10836.374543818412;10855.945720271427;10499.687539057617;10581.418022008786;10582.100521455686;10586.31188972722;10588.417637029961;41320.586794131974;41777.28447650893;41846.581534184574;41931.580684193075;42079.948001299905;3542.929141417183;3549.6627820357025;3580.212353282274;3686.207465394926;3689.1330537323706;31642.367152657047;31781.364372712647;31791.682083179105;31954;32883.6711632883;4158.189153115144;4280.315149576053;4315.158544083905;4351.347297905308;4400.581944715247;343589;344056;344412;344905;346717;55640.93974831423;55946.720266398406;55984;56101.15848262279;56260.71869640626;58882;58949.70525147347;58979.756113413314;58992.7050364754;59170;15380.230988450532;16013.119278439564;16015.759763603555;16018.279177437043;16018.919905400398;11304.15218858589;11671.299722016705;12071.939640301918;12233.143679942497;12258.141930064989;15647.374105035899;15880.20598970047;15986.680266394607;16156.919215403848;16215;6307.5269354798575;6310.6213627182515;6340.207474065742;6373.330800265998;6454.419102280817;869.1135042256989;884.2483888694687;902.4134312696799;1034.0125180452649;1043.7547176413536;30431.695683042886;30450.847745761574;30560.54159187614;30658.693413065583;30848.228794279872;1059.0256963593513;1101.8677758669012;1148.9310641361378;1170.3153655111855;1173.1142987044898;15191.316390762442;16027.358905643643;16196.777905638533;16198.595035124215;16305.510834674978;530589;534085;535337;535468;535877;57169;57290.42709572851;57323.71338143366;57347.42652573504;57393;1324.8542660307357;1355.830521184852;1366.289529444703;1379.0966916669597;1381.1022835157228;28034.579481307388;29303.604459331273;29467.25388549298;29551.556726648687;30022.84988575043;49539.25691114708;49955.250671239235;49978.75010624924;49996.25005624991;50033;443189;443525;444190;445232;445898;24714.629280561167;25405.491890162462;27635.032773852818;27989.580156297263;28163.859180703967;46240.768796155804;46504;46703.29945050895;50737.23894141517;50971.490285096676;37089.25821483609;37379.626203738706;38170.427443588924;39478;39829.60170398259;34615.480767789006;39234.80382598183;39565.40651890166;41397.79301103596;41499.58500414957;36108.63891361053;36327;36671.449928250564;36861.81569092137;37077.81461092677;30434.239144020692;32765.672343276263;32767;33109.66890331066;33337.66662333443;25363.87318063398;25738.485230294915;25861.482770344865;26362.86818565895;26363.73636263613 09:34:32 INFO - PROCESS | 28291 | |2;jquery.html;385.2833729263571;400.21957183492196;402.1755401427074;410.2512913932074;425.96592272618193;13797.862021379784;15755.763663545042;18535.165917533708;18872.05639718012;19384.51538711533;18029.63940721185;18513.629727405445;18527;18736.906315468437;18854.71717924232;5594.832155035356;5640.351359593658;5813.30240371156;5818.0400233961345;5821.912671309934;5710;5738.971305143468;5773.971130144343;5779.566532510057;5794.913076303858;5545.722713864311;5726.742296596663;5739.540836733051;5750.3962083981;5754.6547207167705;5722.513586345166;5758.971205143947;5764.394738552433;5764.8847023059725;5770.567207459437;4952.826651067231;4986.925196122061;5035.823746168867;5159.380874295071;5169.638125331228;21678.349649510383;21811.890940545196;21887.781122188735;21920;22133.66799498009;2843.3033906692995;3011.653659829126;3053.328267781085;3089.2431354318337;3136.639286482061;13699.383527741276;13755.449782008707;14047.578572642837;14076.225807580578;14079.71840563183;1909.809019098093;1940.388777535086;1955.3351860367543;1999.2502811445709;2003.2988454041115;76813.61593192055;76905;77569;78243.21756782416;79175.99999999942;10466.319689220176;10627.468626568641;10668.253222274521;10920.2901811382;11043.503042363112;15043.924780376028;15106.471273505374;15201.923990379977;15244.466443674419;15258.694826103416;8060.677572897019;8110.188981101843;8228.341732661373;8286.295664868554;8324.791880203044;7739.148693643695;7747.496412733154;7762.184970578121;7860.253275938777;7981.800454988555;6615.6030638161865;6682.3317668232785;6721.966390168018;6734.393904548614;6772.501462582638;1825.7352683860765;1926.1524929031182;1966.6263409952064;1976.5651556657513;2016.1733689187363;843.6541018182515;901.1664210605164;946.9526523673788;971.9416834989923;1011.9981218593539;18231;19124.80875191258;19134.90432547856;19163;19427.22291108368;960.8174446855076;962.9614815407306;976.7167521418851;982.9262805289632;1029.5418538750334;10640.787184256425;10950.288231264942;10992.120630349553;11158.665240042812;11190.272632278877;320306;326874;327443;435049;438013;83654.16345836464;87384.56307718421;87773.56113219394;88636;90722.54638726765;480.7692307692327;505.94687557806634;508.14885067512506;994.955227014786;1042.4683411460057;10370.844437333597;11054.170937179904;11166.385848778395;11322.490487927898;11388.430578471209;39364.60635393609;39736.40395394129;39953.40069899012;40089.39865901956;40781.59218407778;51905.7404712974;53225;53432;53758.46241537535;53888.18621299968;8753.868691969752;8836.602352894264;8904.287656987555;9006.819863602823;9098;12077.69805754828;12755.87244127547;13379.44204501043;13466.259355735529;13870.722585548434;11324.32054076758;11452.198346115692;11568.363739994382;11752.412379380825;12005.159638825548;9850.950745246228;10260.17918566498;10857.891421085687;11214.887851121384;11424.657260282205;11255.380954047607;11608.767824643292;11685.123615728684;11817.645470635895;12286.81569776472;8646.351523635627;8692.91307086921;8716.956415217883;8916;9163.954180229322;10681.89318106809;11405;11516.29367098748;11555.191136620357;11713.531458741554 09:34:32 INFO - PROCESS | 28291 | |3;mootools.html;1354.857739937307;1395.9232242226678;1421.7014426970336;1525.4965861265775;1715.0652894172683;7856.332211762;8105.878411823827;8407.117252688471;8411.411201215917;8637.74086777396;8449.49303041818;8510.27662648674;8526.87209691855;8542;8543.91456085439;2039.857209995301;2135.7543882453483;2302.4819415631473;2335.3694502484404;2336.8247381446376;2262.151693115082;2363.633636786301;2367.443650742074;2380.8928598213124;2383.4279772854475;2325.3721495196287;2325.6046472099783;2337;2341.227394959668;2360.7285162206394;2308.376738280672;2312.930612081632;2334.0780391745166;2339.5905716499547;2342.4495243617907;2329.149860300978;2339.228054741948;2357.3752955466816;2360.079568968104;2367.5501654685727;8585.399022068457;8595.656173753043;8632.697855575025;8644.092370301154;8666.913330866673;5124.4875512448825;5266.8683282917855;5267.051930652481;5268.5521730652945;5269.104252277122;3109.4713898637287;3157.2896098377682;3345.5149003394613;3348.313595712873;3362.646922073196;993.8012397520525;1014.9188064954786;1027.938323700573;1028.6142696488816;1041.1670663469195;11211.66365009043;11562.07503399726;11661.825072623917;11812.527498900034;12104.971077458427;4328.11273688893;4474.39595654589;4489.214387482178;4604.608608268268;4612.3542704021465;9173.58718857653;9184.357095003286;9184.678536251198;9188.49463279526;9190.954045229864;5169.844904652867;5234.607404444682;5244.134717771602;5244.344456942882;5249.055170069348;4291.098869237433;4437.667174961826;4712.2224832902875;4898.53463920927;4901.779419926112;3846.769193848378;3857.324968130566;3879.18537107205;3936.6653834423823;3939.7242193046213;1170.4791367841399;1191.9582814601447;1208.0577149823152;1216.6289281769145;1217.6407959651924;797.6410615223057;892.5180402582532;905.3707673167218;906.0622255965045;935.7099299217265;5395.082835917825;5547.362053363915;5610.1304297833085;5709.514691251207;5803.332616749043;411.3314557262194;427.05833636830715;429.1053154173521;435.5883689913002;449.59087230620634;3704.0924973381693;3771.245750849841;3806.200697853482;3841;3891.571927088018;10303.536340864679;10574.471276436301;10672.039516443558;10696.304740192018;10704.518296676668;10608.840867386996;10609.363438193734;10619.30974486656;10635.41505217205;10649.254552181428;7653.272939070779;7967.760967170994;8030.19698030204;8037.638306276232;8125.75622731319;5615.466530679579;5719.714014299351;5767.826965191051;5823.883522329615;5903.822885313448;9285.164335209864;9384.718458446258;9413.34106612558;9499.57251923679;9634.229261659191;9522.0954009369;9558.474283914451;9562.56968436436;9572.186364159124;9680.370775899544;6665;6667.2666006738245;7277.563346199033;7607.505512141693;7766.223377662189;7937.206279372017;8033.959830200812;8063.032436107705;8068.273855353046;8075.232852878967;7127.786166415016;7154.2130365658695;7229.09636295463;7262.89105663426;7429.33136017761;7131.964340178266;7331.816704582428;7344.30229128232;7526.096868375611;7565.886511702219;6537.640429776408;6609.735610575523;6622.10601568782;6624.403803657694;6625.536212465081;4870.537298956593;4885.682430641997;4976.477469865757;4998.4501704813165;5068.417132029864;4961.50384961501;5097.08252514551;5163.328767260232;5211.0359583477175;5227.320448341691 09:34:32 INFO - PROCESS | 28291 | |4;prototype.html;395.9663428608568;398.9461422707935;406.9816858241379;411.56579808302246;414.53779036374453;5093.210552364385;5150.330457040589;5160.2517634942915;5181.202150838552;5182.559482443992;4908;4935.555799978001;5003.1994880819075;5036.219385995177;5094.337736094302;4139.275626765319;4141.233871733723;4141.7514949103;4141.772202528859;4163.646090082347;4109.0959988802415;4120.793960301988;4135.710500264982;4155.231282212801;4169.749815011109;3905.1408690088133;3992.301347264217;3998.2403343364667;4000.5599384067873;4121.773302468363;3983.1834473933063;3990.5011873515955;3992.3811809169624;4002.8398864045403;4012.0371110933456;3579.2282820004;3581.928361432757;3583.623719509466;3587.2287458196615;3591.8742844000335;4837.32277481153;4961.330220420233;4987.202047672355;4996.800767815735;5003.824866129668;4576.565226303496;4584.931226031612;4593.19619066662;4611.123886461595;4621.168189725848;5301.867453313659;5323.254744335796;5335.359756829166;5342.252084708144;5349.3045904032615;3800.7929137082097;3905.9609403906165;3937.625925537069;3950.150717595731;3958.4062390641348;5520.558355331564;5536.806211782568;5540.3683250456115;5545.223668686387;5558.082916318803;4011.3782363733667;4034.45534852797;4036.737612055266;4037.6366127048705;4040.6565441937764;4011.1777085697363;4032;4056.553779084298;4073.1446396256524;4077.959220407817;4501.3022981437925;4585.885352866138;4594.97702511492;4596.4254468191475;4598.8620341389815;5352.57179425645;5357.249985002103;5364.14173732201;5367.275417818544;5377.381601115843;1302.667819705969;1359.9660008499668;1361.659585103724;1362.529927175123;1372.567641193041;921.6728061538057;938.8216238914584;954.8684808501947;959.0553304994575;968.0445500089779;4983.426905905795;4987.800487980513;5013.924791128136;5022.824201152942;5031.496850315012;5214.7914083437;5220.686758794409;5231.686098834082;5268.28878101459;5285.392179899284;4858.58702010333;4977.303177555145;5020.171671674133;5026.396832380065;5032.194848824171;2747.7252274772363;2770.8664328466966;2784.3874347643487;2802.341449759305;2807.3683421230467;5223.582113430916;5413.945860541344;5424.589606702207;5432.185172224198;5441.6190866639745;5323.86690332737;5352.090144675337;5362.812301569426;5375.946240537544;5381.009026660161;5449.730161902827;5463.235147079412;5482.5613950883835;5485.835424937259;5517.751701173457;5100.0874671998;5263.34208223972;5264.552513036435;5265.89468210626;5280.498352656491;5233.502817232356;5239.318888544465;5243.475652434879;5251.897101608705;5261.4738526147075;5495;5497.615166938436;5503.532199763065;5505.788726480168;5521.475459831309;3722.7766334020043;3915.5105611798526;3943.7830919298567;3953.802309884552;3962.881113566598;5221.399539053057;5243.501867322599;5260.552853007537;5313.707746074003;5318.308619879391;5147.996140752517;5161.896762064813;5162.483751624958;5239.554637855825;5255.213227502667;5084.847454576368;5170.5346518813485;5380;5395.946040539701;5431.4840090191365;5186.6369354144845;5305.840824775263;5391.4608539145775;5405.702686352289;5416.2688037114485;5294.682319060868;5419.376771671151;5465.398806131402;5486.259354987025;5503.642263252876;5203;5298.251576979665;5322.654027488201;5341;5342.599305052059 09:34:32 INFO - PROCESS | 28291 | |5;yui.html;694.4756708684943;744.7691215723127;749.3480671815518;760.2359628573283;769.2230846844685;15181.54455366338;16686.58283542912;16700.415485458012;16725.912815666987;16763.91618041911;13476.865231347683;14341.067830591004;15303.846961530382;15357;15419.845801541982;786.6184900323353;796.9840603187932;804.6057431858405;807.6728924785472;807.8263173417714;787.3504358903928;796.7211475983418;800.8318253166842;803.0844836885955;803.6423791412824;825.224289168183;832.4589017138848;833.4624167412015;833.8290650416681;834.3408707121397;828.3207769628907;828.4987582512554;829.5561874397199;830.543201239322;830.8836762853206;603.1616053685381;607.5534482155612;607.7295603456464;608.85996220869;610.1336102734128;12688.555900543437;12806.551770688075;12816.615501534969;14169.645758856008;14227.14637121776;14394.640133996629;14615.78076328856;14738.852611473856;15131.621709457242;15180.924095379452;11558.768824623545;11574.768504629945;11700.18098733089;11738.413079346084;11943.9847612953;3695.501107350527;3698.500702405168;3751.5744017273532;3777.8111094445444;3842.4044273137706;78902.60548697277;80001.59999200025;80999.99999999942;82081;82413;11192.328460292409;11222.270552414067;11281.774364512663;11390.316581005167;11412.51918164951;21171.576568468543;21173;21184.25855095095;21185.258515951868;21418.357449276547;7385.9630701846145;7506.174320824705;7691.346235570039;7743.8838417423785;7763;3927.9018024549046;3961.16815468749;3965.38536526833;3974.3044967130636;3988.920221595552;3647.2158485925124;3677.3748462761396;3747.5315585551807;3757.210985693035;3760.383035294795;596.31126049413;610.8533951851524;628.5882746800853;640.164585216288;658.06554692337;716.1263258824226;729.1687476277049;732.7984804178893;759.1080480435467;768.6848392159188;8697.652093916298;8714.288542636026;8954.820903581893;9053.77365565866;9164.312676549152;690.892911598703;704.2534912992245;722.523134731075;730.831908661011;741.0625558668181;4269.402283680225;4330;4374.693771435969;4379.671524635665;4388.978055109768;62349.68825155936;63624.68187659032;67267.32732672709;86708.56645716858;89602;61841.690791545756;68809.65595171992;68891.31108688848;69169.30830691729;69972.30027699658;49171;59653.40346596565;63159;64115.35884641187;64510.35489645044;9552.235821134154;9835.557399916877;9877.703668889944;9911.752206194902;9962.252831037846;41028.384574232005;41383.37924931189;41452;42156.57843421526;42844;53907;55570;58859.70570147122;59837;60155.699221503615;12996.610101696964;13138.802917956047;13361.66595835112;13512.797308040585;13763.967702422158;15027.849721502645;15151.772723408938;15241.847581524044;15377.38490460369;15888.205589720237;13835.86164138386;13855.23796191179;14172.362243699265;14436.917231207912;14730.926345368205;13451.865481345061;13791.379387927767;13805.51680691171;14515.92742036283;14633;13102.868971310547;13595.86404135946;13902.582922512342;14050.578482645536;14578.23258416842;10548.947255263982;10618.256722029693;11238.831417528907;11253;11722.238054526428;10748.193885458779;10985.890141098487;11141.72145696364;11385.203035787747;11700.70748231301 09:34:32 INFO - PROCESS | 28291 | __end_tp_report 09:34:32 INFO - PROCESS | 28291 | __start_cc_report 09:34:32 INFO - PROCESS | 28291 | _x_x_mozilla_cycle_collect,1241 09:34:32 INFO - PROCESS | 28291 | __end_cc_report 09:34:32 INFO - PROCESS | 28291 | __startTimestamp1482600872684__endTimestamp 09:34:32 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | ------- Summary: start ------- 09:34:32 INFO - PROCESS | 28291 | Number of tests: 6 09:34:32 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | [#0] dojo.html Cycles:165 Average:5304.59 Median:5536.39 stddev:607.47 (11.0%) stddev-sans-first:585.70 09:34:32 INFO - PROCESS | 28291 | Values: 3165.7 3177.0 3198.8 3203.2 3214.0 5199.5 5265.3 5294.3 5344.8 5393.7 5301.0 5319.5 5334.3 5391.9 5413.8 4794.4 4809.7 4829.0 4830.8 4880.2 4888.5 4905.1 4935.1 4935.9 4970.5 4746.6 4796.8 4879.9 4885.7 4941.9 4795.4 4814.9 4831.5 4840.7 4918.7 4775.6 4783.8 4806.5 4815.6 4857.1 5212.6 5317.7 5332.4 5350.6 5378.9 5635.9 5707.8 5720.8 5721.6 5725.9 5665.3 5697.3 5708.7 5752.1 5757.8 5264.7 5300.1 5302.7 5303.4 5304.8 5710.4 5816.8 5816.9 5818.7 5876.1 5562.9 5591.2 5607.4 5612.4 5692.8 5582.2 5617.3 5749.1 5761.4 5762.1 5654.3 5678.3 5681.1 5726.7 5787.2 5578.7 5778.4 5785.1 5786.4 5791.3 4237.2 4246.4 4265.4 4285.2 4287.1 3819.7 3832.5 3845.3 3845.8 3854.5 5470.5 5474.1 5475.3 5475.8 5505.7 5686.7 5691.5 5733.0 5741.5 5814.0 5476.4 5479.2 5481.4 5512.4 5524.9 4747.4 4758.7 4759.6 4762.0 4799.4 5824.7 5877.4 5911.6 5989.3 5994.3 5665.1 5717.4 5718.3 5762.5 5766.3 5860.8 5866.5 5920.8 5923.1 5925.8 5590.9 5636.2 5646.4 5649.0 5652.8 5684.4 5706.4 5724.1 5763.0 5814.4 5626.7 5629.4 5634.2 5636.6 5638.7 5533.1 5549.7 5555.5 5622.3 5628.7 5516.7 5534.2 5536.4 5583.9 5642.6 5598.4 5621.8 5627.7 5639.5 5643.7 5336.9 5443.9 5487.9 5491.8 5544.9 09:34:32 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | [#1] ext.html Cycles:180 Average:59414.13 Median:19844.43 stddev:118860.95 (599.0%) stddev-sans-first:119112.28 09:34:32 INFO - PROCESS | 28291 | Values: 570.0 586.3 593.1 593.7 608.0 32409.8 33192.0 40997.2 42497.6 42771.0 44073.0 44363.0 44427.6 44545.8 44650.3 8892.9 9060.9 9068.7 9083.1 9083.4 8764.3 8994.3 10450.6 10739.3 10748.2 10726.5 10783.9 10784.6 10789.9 10794.8 10718.5 10727.5 10781.1 10836.4 10855.9 10499.7 10581.4 10582.1 10586.3 10588.4 41320.6 41777.3 41846.6 41931.6 42079.9 3542.9 3549.7 3580.2 3686.2 3689.1 31642.4 31781.4 31791.7 31954.0 32883.7 4158.2 4280.3 4315.2 4351.3 4400.6 343589.0 344056.0 344412.0 344905.0 346717.0 55640.9 55946.7 55984.0 56101.2 56260.7 58882.0 58949.7 58979.8 58992.7 59170.0 15380.2 16013.1 16015.8 16018.3 16018.9 11304.2 11671.3 12071.9 12233.1 12258.1 15647.4 15880.2 15986.7 16156.9 16215.0 6307.5 6310.6 6340.2 6373.3 6454.4 869.1 884.2 902.4 1034.0 1043.8 30431.7 30450.8 30560.5 30658.7 30848.2 1059.0 1101.9 1148.9 1170.3 1173.1 15191.3 16027.4 16196.8 16198.6 16305.5 530589.0 534085.0 535337.0 535468.0 535877.0 57169.0 57290.4 57323.7 57347.4 57393.0 1324.9 1355.8 1366.3 1379.1 1381.1 28034.6 29303.6 29467.3 29551.6 30022.8 49539.3 49955.3 49978.8 49996.3 50033.0 443189.0 443525.0 444190.0 445232.0 445898.0 24714.6 25405.5 27635.0 27989.6 28163.9 46240.8 46504.0 46703.3 50737.2 50971.5 37089.3 37379.6 38170.4 39478.0 39829.6 34615.5 39234.8 39565.4 41397.8 41499.6 36108.6 36327.0 36671.4 36861.8 37077.8 30434.2 32765.7 32767.0 33109.7 33337.7 25363.9 25738.5 25861.5 26362.9 26363.7 09:34:32 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | [#2] jquery.html Cycles:180 Average:25104.05 Median:19874.94 stddev:62199.16 (313.0%) stddev-sans-first:62345.95 09:34:32 INFO - PROCESS | 28291 | Values: 385.3 400.2 402.2 410.3 426.0 13797.9 15755.8 18535.2 18872.1 19384.5 18029.6 18513.6 18527.0 18736.9 18854.7 5594.8 5640.4 5813.3 5818.0 5821.9 5710.0 5739.0 5774.0 5779.6 5794.9 5545.7 5726.7 5739.5 5750.4 5754.7 5722.5 5759.0 5764.4 5764.9 5770.6 4952.8 4986.9 5035.8 5159.4 5169.6 21678.3 21811.9 21887.8 21920.0 22133.7 2843.3 3011.7 3053.3 3089.2 3136.6 13699.4 13755.4 14047.6 14076.2 14079.7 1909.8 1940.4 1955.3 1999.3 2003.3 76813.6 76905.0 77569.0 78243.2 79176.0 10466.3 10627.5 10668.3 10920.3 11043.5 15043.9 15106.5 15201.9 15244.5 15258.7 8060.7 8110.2 8228.3 8286.3 8324.8 7739.1 7747.5 7762.2 7860.3 7981.8 6615.6 6682.3 6722.0 6734.4 6772.5 1825.7 1926.2 1966.6 1976.6 2016.2 843.7 901.2 947.0 971.9 1012.0 18231.0 19124.8 19134.9 19163.0 19427.2 960.8 963.0 976.7 982.9 1029.5 10640.8 10950.3 10992.1 11158.7 11190.3 320306.0 326874.0 327443.0 435049.0 438013.0 83654.2 87384.6 87773.6 88636.0 90722.5 480.8 505.9 508.1 995.0 1042.5 10370.8 11054.2 11166.4 11322.5 11388.4 39364.6 39736.4 39953.4 40089.4 40781.6 51905.7 53225.0 53432.0 53758.5 53888.2 8753.9 8836.6 8904.3 9006.8 9098.0 12077.7 12755.9 13379.4 13466.3 13870.7 11324.3 11452.2 11568.4 11752.4 12005.2 9851.0 10260.2 10857.9 11214.9 11424.7 11255.4 11608.8 11685.1 11817.6 12286.8 8646.4 8692.9 8717.0 8916.0 9164.0 10681.9 11405.0 11516.3 11555.2 11713.5 09:34:32 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | [#3] mootools.html Cycles:180 Average:5544.55 Median:5110.79 stddev:3133.13 (61.3%) stddev-sans-first:3126.10 09:34:32 INFO - PROCESS | 28291 | Values: 1354.9 1395.9 1421.7 1525.5 1715.1 7856.3 8105.9 8407.1 8411.4 8637.7 8449.5 8510.3 8526.9 8542.0 8543.9 2039.9 2135.8 2302.5 2335.4 2336.8 2262.2 2363.6 2367.4 2380.9 2383.4 2325.4 2325.6 2337.0 2341.2 2360.7 2308.4 2312.9 2334.1 2339.6 2342.4 2329.1 2339.2 2357.4 2360.1 2367.6 8585.4 8595.7 8632.7 8644.1 8666.9 5124.5 5266.9 5267.1 5268.6 5269.1 3109.5 3157.3 3345.5 3348.3 3362.6 993.8 1014.9 1027.9 1028.6 1041.2 11211.7 11562.1 11661.8 11812.5 12105.0 4328.1 4474.4 4489.2 4604.6 4612.4 9173.6 9184.4 9184.7 9188.5 9191.0 5169.8 5234.6 5244.1 5244.3 5249.1 4291.1 4437.7 4712.2 4898.5 4901.8 3846.8 3857.3 3879.2 3936.7 3939.7 1170.5 1192.0 1208.1 1216.6 1217.6 797.6 892.5 905.4 906.1 935.7 5395.1 5547.4 5610.1 5709.5 5803.3 411.3 427.1 429.1 435.6 449.6 3704.1 3771.2 3806.2 3841.0 3891.6 10303.5 10574.5 10672.0 10696.3 10704.5 10608.8 10609.4 10619.3 10635.4 10649.3 7653.3 7967.8 8030.2 8037.6 8125.8 5615.5 5719.7 5767.8 5823.9 5903.8 9285.2 9384.7 9413.3 9499.6 9634.2 9522.1 9558.5 9562.6 9572.2 9680.4 6665.0 6667.3 7277.6 7607.5 7766.2 7937.2 8034.0 8063.0 8068.3 8075.2 7127.8 7154.2 7229.1 7262.9 7429.3 7132.0 7331.8 7344.3 7526.1 7565.9 6537.6 6609.7 6622.1 6624.4 6625.5 4870.5 4885.7 4976.5 4998.5 5068.4 4961.5 5097.1 5163.3 5211.0 5227.3 09:34:32 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | [#4] prototype.html Cycles:180 Average:4473.43 Median:5093.77 stddev:1272.83 (25.0%) stddev-sans-first:1239.06 09:34:32 INFO - PROCESS | 28291 | Values: 396.0 398.9 407.0 411.6 414.5 5093.2 5150.3 5160.3 5181.2 5182.6 4908.0 4935.6 5003.2 5036.2 5094.3 4139.3 4141.2 4141.8 4141.8 4163.6 4109.1 4120.8 4135.7 4155.2 4169.7 3905.1 3992.3 3998.2 4000.6 4121.8 3983.2 3990.5 3992.4 4002.8 4012.0 3579.2 3581.9 3583.6 3587.2 3591.9 4837.3 4961.3 4987.2 4996.8 5003.8 4576.6 4584.9 4593.2 4611.1 4621.2 5301.9 5323.3 5335.4 5342.3 5349.3 3800.8 3906.0 3937.6 3950.2 3958.4 5520.6 5536.8 5540.4 5545.2 5558.1 4011.4 4034.5 4036.7 4037.6 4040.7 4011.2 4032.0 4056.6 4073.1 4078.0 4501.3 4585.9 4595.0 4596.4 4598.9 5352.6 5357.2 5364.1 5367.3 5377.4 1302.7 1360.0 1361.7 1362.5 1372.6 921.7 938.8 954.9 959.1 968.0 4983.4 4987.8 5013.9 5022.8 5031.5 5214.8 5220.7 5231.7 5268.3 5285.4 4858.6 4977.3 5020.2 5026.4 5032.2 2747.7 2770.9 2784.4 2802.3 2807.4 5223.6 5413.9 5424.6 5432.2 5441.6 5323.9 5352.1 5362.8 5375.9 5381.0 5449.7 5463.2 5482.6 5485.8 5517.8 5100.1 5263.3 5264.6 5265.9 5280.5 5233.5 5239.3 5243.5 5251.9 5261.5 5495.0 5497.6 5503.5 5505.8 5521.5 3722.8 3915.5 3943.8 3953.8 3962.9 5221.4 5243.5 5260.6 5313.7 5318.3 5148.0 5161.9 5162.5 5239.6 5255.2 5084.8 5170.5 5380.0 5395.9 5431.5 5186.6 5305.8 5391.5 5405.7 5416.3 5294.7 5419.4 5465.4 5486.3 5503.6 5203.0 5298.3 5322.7 5341.0 5342.6 09:34:32 INFO - PROCESS | 28291 | 09:34:32 INFO - PROCESS | 28291 | [#5] yui.html Cycles:180 Average:17432.19 Median:23587.00 stddev:22225.07 (94.2%) stddev-sans-first:22251.88 09:34:32 INFO - PROCESS | 28291 | Values: 694.5 744.8 749.3 760.2 769.2 15181.5 16686.6 16700.4 16725.9 16763.9 13476.9 14341.1 15303.8 15357.0 15419.8 786.6 797.0 804.6 807.7 807.8 787.4 796.7 800.8 803.1 803.6 825.2 832.5 833.5 833.8 834.3 828.3 828.5 829.6 830.5 830.9 603.2 607.6 607.7 608.9 610.1 12688.6 12806.6 12816.6 14169.6 14227.1 14394.6 14615.8 14738.9 15131.6 15180.9 11558.8 11574.8 11700.2 11738.4 11944.0 3695.5 3698.5 3751.6 3777.8 3842.4 78902.6 80001.6 81000.0 82081.0 82413.0 11192.3 11222.3 11281.8 11390.3 11412.5 21171.6 21173.0 21184.3 21185.3 21418.4 7386.0 7506.2 7691.3 7743.9 7763.0 3927.9 3961.2 3965.4 3974.3 3988.9 3647.2 3677.4 3747.5 3757.2 3760.4 596.3 610.9 628.6 640.2 658.1 716.1 729.2 732.8 759.1 768.7 8697.7 8714.3 8954.8 9053.8 9164.3 690.9 704.3 722.5 730.8 741.1 4269.4 4330.0 4374.7 4379.7 4389.0 62349.7 63624.7 67267.3 86708.6 89602.0 61841.7 68809.7 68891.3 69169.3 69972.3 49171.0 59653.4 63159.0 64115.4 64510.4 9552.2 9835.6 9877.7 9911.8 9962.3 41028.4 41383.4 41452.0 42156.6 42844.0 53907.0 55570.0 58859.7 59837.0 60155.7 12996.6 13138.8 13361.7 13512.8 13764.0 15027.8 15151.8 15241.8 15377.4 15888.2 13835.9 13855.2 14172.4 14436.9 14730.9 13451.9 13791.4 13805.5 14515.9 14633.0 13102.9 13595.9 13902.6 14050.6 14578.2 10548.9 10618.3 11238.8 11253.0 11722.2 10748.2 10985.9 11141.7 11385.2 11700.7 09:34:32 INFO - PROCESS | 28291 | -------- Summary: end -------- 09:34:32 INFO - PROCESS | 28291 | 09:34:33 INFO - TEST-INFO | 28291: exit 0 09:34:33 INFO - TEST-OK | dromaeo_css | took 1116390ms 09:34:33 INFO - TEST-START | kraken 09:34:33 INFO - operating with platform_type : linux_ 09:34:33 INFO - Initialising browser for kraken test... 09:34:33 INFO - TEST-INFO | started process 28596 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd3k9Tn/profile http://localhost:39086/getInfo.html) 09:34:33 INFO - PROCESS | 28596 | 1482600873927 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:34:36 INFO - PROCESS | 28596 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:34:36 INFO - PROCESS | 28596 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:34:37 INFO - PROCESS | 28596 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:34:37 INFO - PROCESS | 28596 | __metrics Screen width/height:1600/1200 09:34:37 INFO - PROCESS | 28596 | colorDepth:24 09:34:37 INFO - PROCESS | 28596 | Browser inner width/height: 1024/697 09:34:37 INFO - PROCESS | 28596 | __metrics 09:34:37 INFO - PROCESS | 28596 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:34:37 INFO - PROCESS | 28596 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:38 INFO - PROCESS | 28596 | console.error: PushService: 09:34:38 INFO - PROCESS | 28596 | stateChangeProcessEnqueue: Error transitioning state 09:34:38 INFO - PROCESS | 28596 | UnknownError 09:34:38 INFO - TEST-INFO | 28596: exit 0 09:34:38 INFO - Browser initialized. 09:34:38 INFO - Running cycle 1/1 for kraken test... 09:34:38 INFO - TEST-INFO | started process 28678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd3k9Tn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:50 INFO - PROCESS | 28678 | RSS: Main: 146657280 09:34:50 INFO - PROCESS | 28678 | 09:34:53 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39086/tests/kraken/driver.html?testName=audio-beat-detection) 09:34:54 INFO - PROCESS | 28678 | RSS: Main: 201633792 09:34:54 INFO - PROCESS | 28678 | 09:34:56 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39086/tests/kraken/driver.html?testName=audio-dft) 09:34:57 INFO - PROCESS | 28678 | RSS: Main: 180858880 09:34:57 INFO - PROCESS | 28678 | 09:35:00 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39086/tests/kraken/driver.html?testName=audio-fft) 09:35:00 INFO - PROCESS | 28678 | RSS: Main: 261808128 09:35:00 INFO - PROCESS | 28678 | 09:35:02 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39086/tests/kraken/driver.html?testName=audio-oscillator) 09:35:03 INFO - PROCESS | 28678 | RSS: Main: 173445120 09:35:03 INFO - PROCESS | 28678 | 09:35:05 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39086/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:06 INFO - PROCESS | 28678 | RSS: Main: 177864704 09:35:06 INFO - PROCESS | 28678 | 09:35:09 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39086/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:09 INFO - PROCESS | 28678 | RSS: Main: 217108480 09:35:09 INFO - PROCESS | 28678 | 09:35:13 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39086/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:13 INFO - PROCESS | 28678 | RSS: Main: 217567232 09:35:13 INFO - PROCESS | 28678 | 09:35:16 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39086/tests/kraken/driver.html?testName=json-parse-financial) 09:35:17 INFO - PROCESS | 28678 | RSS: Main: 215490560 09:35:17 INFO - PROCESS | 28678 | 09:35:18 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39086/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:19 INFO - PROCESS | 28678 | RSS: Main: 177807360 09:35:19 INFO - PROCESS | 28678 | 09:35:21 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39086/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:35:21 INFO - PROCESS | 28678 | RSS: Main: 184082432 09:35:21 INFO - PROCESS | 28678 | 09:35:23 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39086/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:35:24 INFO - PROCESS | 28678 | RSS: Main: 193642496 09:35:24 INFO - PROCESS | 28678 | 09:35:26 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39086/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:35:27 INFO - PROCESS | 28678 | RSS: Main: 202907648 09:35:27 INFO - PROCESS | 28678 | 09:35:30 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39086/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:35:30 INFO - PROCESS | 28678 | RSS: Main: 200019968 09:35:30 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | Cycle 1(1): loaded http://localhost:39086/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39086/tests/kraken/driver.html?testName=ai-astar) 09:35:32 INFO - PROCESS | 28678 | RSS: Main: 251314176 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | __start_tp_report 09:35:32 INFO - PROCESS | 28678 | _x_x_mozilla_page_load 09:35:32 INFO - PROCESS | 28678 | _x_x_mozilla_page_load_details 09:35:32 INFO - PROCESS | 28678 | |i|pagename|runs| 09:35:32 INFO - PROCESS | 28678 | |0;ai-astar;106;113;113;113;113;114;113;112;112;112 09:35:32 INFO - PROCESS | 28678 | |1;audio-beat-detection;146;120;118;160;122;119;121;118;136;119 09:35:32 INFO - PROCESS | 28678 | |2;audio-dft;162;161;161;159;161;161;162;162;161;161 09:35:32 INFO - PROCESS | 28678 | |3;audio-fft;76;77;77;113;76;75;79;74;91;76 09:35:32 INFO - PROCESS | 28678 | |4;audio-oscillator;84;84;114;83;84;84;94;84;84;109 09:35:32 INFO - PROCESS | 28678 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;117;117;117 09:35:32 INFO - PROCESS | 28678 | |6;imaging-darkroom;146;148;147;146;146;146;148;153;146;146 09:35:32 INFO - PROCESS | 28678 | |7;imaging-desaturate;95;96;95;95;95;96;95;95;96;95 09:35:32 INFO - PROCESS | 28678 | |8;json-parse-financial;53;48;48;49;50;51;50;51;53;51 09:35:32 INFO - PROCESS | 28678 | |9;json-stringify-tinderbox;63;68;64;65;65;64;65;64;65;64 09:35:32 INFO - PROCESS | 28678 | |10;stanford-crypto-aes;82;80;80;80;80;89;81;80;79;83 09:35:32 INFO - PROCESS | 28678 | |11;stanford-crypto-ccm;135;124;127;132;129;129;131;132;169;134 09:35:32 INFO - PROCESS | 28678 | |12;stanford-crypto-pbkdf2;166;164;163;167;166;163;166;167;166;162 09:35:32 INFO - PROCESS | 28678 | |13;stanford-crypto-sha256-iterative;62;62;62;61;62;63;66;63;67;60 09:35:32 INFO - PROCESS | 28678 | __end_tp_report 09:35:32 INFO - PROCESS | 28678 | __start_cc_report 09:35:32 INFO - PROCESS | 28678 | _x_x_mozilla_cycle_collect,1574 09:35:32 INFO - PROCESS | 28678 | __end_cc_report 09:35:32 INFO - PROCESS | 28678 | __startTimestamp1482600932870__endTimestamp 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | ------- Summary: start ------- 09:35:32 INFO - PROCESS | 28678 | Number of tests: 14 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.23 (2.0%) stddev-sans-first:0.67 09:35:32 INFO - PROCESS | 28678 | Values: 106.0 113.0 113.0 113.0 113.0 114.0 113.0 112.0 112.0 112.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:121.50 stddev:14.62 (12.0%) stddev-sans-first:13.96 09:35:32 INFO - PROCESS | 28678 | Values: 146.0 120.0 118.0 160.0 122.0 119.0 121.0 118.0 136.0 119.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.87 09:35:32 INFO - PROCESS | 28678 | Values: 162.0 161.0 161.0 159.0 161.0 161.0 162.0 162.0 161.0 161.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.50 stddev:12.10 (15.8%) stddev-sans-first:12.68 09:35:32 INFO - PROCESS | 28678 | Values: 76.0 77.0 77.0 113.0 76.0 75.0 79.0 74.0 91.0 76.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:84.00 stddev:11.63 (13.8%) stddev-sans-first:12.10 09:35:32 INFO - PROCESS | 28678 | Values: 84.0 84.0 114.0 83.0 84.0 84.0 94.0 84.0 84.0 109.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:35:32 INFO - PROCESS | 28678 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:146.50 stddev:2.20 (1.5%) stddev-sans-first:2.29 09:35:32 INFO - PROCESS | 28678 | Values: 146.0 148.0 147.0 146.0 146.0 146.0 148.0 153.0 146.0 146.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:35:32 INFO - PROCESS | 28678 | Values: 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:51.00 stddev:1.78 (3.5%) stddev-sans-first:1.62 09:35:32 INFO - PROCESS | 28678 | Values: 53.0 48.0 48.0 49.0 50.0 51.0 50.0 51.0 53.0 51.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:1.34 (2.1%) stddev-sans-first:1.27 09:35:32 INFO - PROCESS | 28678 | Values: 63.0 68.0 64.0 65.0 65.0 64.0 65.0 64.0 65.0 64.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:80.50 stddev:2.91 (3.6%) stddev-sans-first:3.08 09:35:32 INFO - PROCESS | 28678 | Values: 82.0 80.0 80.0 80.0 80.0 89.0 81.0 80.0 79.0 83.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#11] stanford-crypto-ccm Cycles:10 Average:134.20 Median:132.00 stddev:12.66 (9.6%) stddev-sans-first:13.42 09:35:32 INFO - PROCESS | 28678 | Values: 135.0 124.0 127.0 132.0 129.0 129.0 131.0 132.0 169.0 134.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.00 stddev:1.83 (1.1%) stddev-sans-first:1.90 09:35:32 INFO - PROCESS | 28678 | Values: 166.0 164.0 163.0 167.0 166.0 163.0 166.0 167.0 166.0 162.0 09:35:32 INFO - PROCESS | 28678 | 09:35:32 INFO - PROCESS | 28678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:62.50 stddev:2.15 (3.4%) stddev-sans-first:2.26 09:35:32 INFO - PROCESS | 28678 | Values: 62.0 62.0 62.0 61.0 62.0 63.0 66.0 63.0 67.0 60.0 09:35:32 INFO - PROCESS | 28678 | -------- Summary: end -------- 09:35:32 INFO - PROCESS | 28678 | 09:35:33 INFO - TEST-INFO | 28678: exit 0 09:35:33 INFO - TEST-OK | kraken | took 60430ms 09:35:33 INFO - SUITE-END | took 1176s 09:35:34 INFO - Completed test suite (00:19:36) 09:35:34 INFO - javascript benchmark 09:35:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3165.7150856422923, 3176.9682303176974, 3198.7600929930254, 3203.1511649412923, 3214.0, 5199.506046925536, 5265.315508983832, 5294.311739473873, 5344.786208551658, 5393.6763794172375, 5300.973495132529, 5319.494648008443, 5334.306540149775, 5391.865203369918, 5413.783448662059, 4794.35276237707, 4809.663323567352, 4829.034193161364, 4830.806767729287, 4880.194767863316, 4888.462269150408, 4905.141600219948, 4935.087008903346, 4935.925961110568, 4970.478099799523, 4746.572808447222, 4796.8081276749235, 4879.853604391874, 4885.7068575885205, 4941.876453088666, 4795.448523419782, 4814.855554333376, 4831.492693267227, 4840.733759643218, 4918.680285781413, 4775.570198682135, 4783.832565860213, 4806.519348065201, 4815.6388270879825, 4857.077155340509, 5212.556932660728, 5317.73411329432, 5332.386775520826, 5350.5987050971335, 5378.865528361783, 5635.943640563582, 5707.77168913243, 5720.8283751487525, 5721.5708821838525, 5725.914111288334, 5665.291838520185, 5697.287839020122, 5708.743106560173, 5752.13717942312, 5757.798477053283, 5264.684118952875, 5300.09898317287, 5302.655327403706, 5303.390110137345, 5304.761285742151, 5710.371859095496, 5816.796412125555, 5816.861838343188, 5818.679972601463, 5876.1479585459865, 5562.916556251659, 5591.161325801162, 5607.43925607436, 5612.410696876851, 5692.7722891083895, 5582.190582365615, 5617.32592088952, 5749.108888122348, 5761.366249712528, 5762.1068734346245, 5654.321481422256, 5678.318601767732, 5681.062624666966, 5726.685032323179, 5787.160861675117, 5578.665280083208, 5778.3643799182055, 5785.074388097884, 5786.392428795, 5791.333996590446, 4237.194932962727, 4246.426732391088, 4265.360195970629, 4285.207236661227, 4287.142571485715, 3819.656230939229, 3832.4826148470156, 3845.2693988142164, 3845.750026248285, 3854.4989151410136, 5470.5350045245805, 5474.124140137559, 5475.342958844884, 5475.808346707846, 5505.697186654692, 5686.687232202268, 5691.516221121168, 5733.025385684444, 5741.540676745851, 5814.0, 5476.397596264408, 5479.232907392969, 5481.369642491165, 5512.4211957743855, 5524.889502210013, 4747.406574178228, 4758.690685105526, 4759.571638552547, 4762.0, 4799.424069111659, 5824.708764561839, 5877.382874798084, 5911.586188966731, 5989.311229208697, 5994.28068631767, 5665.065264231355, 5717.37108918027, 5718.285214348207, 5762.538996880324, 5766.250387449605, 5860.794872179454, 5866.530677545701, 5920.822375328747, 5923.111533269872, 5925.792597259076, 5590.888182236251, 5636.239107720406, 5646.3506696728755, 5648.971755141198, 5652.773889044557, 5684.431556844283, 5706.400827913176, 5724.055530837365, 5763.049096898964, 5814.447627475383, 5626.74679639409, 5629.437056294501, 5634.23937768396, 5636.577256705681, 5638.7462564185535, 5533.114701647717, 5549.722513874371, 5555.527780138572, 5622.32532096151, 5628.718564071862, 5516.66899985986, 5534.1975413564005, 5536.390996990408, 5583.916241256303, 5642.605017648725, 5598.384177740364, 5621.775128994795, 5627.7467513961155, 5639.520640745583, 5643.746031428514, 5336.866578335573, 5443.863903402288, 5487.917681234864, 5491.791805802717, 5544.944550554443], "value": 5263.7858146508925, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.9968056221051, 586.3227971692695, 593.0925683703932, 593.6556797057705, 607.9604825686333, 32409.83795081024, 33192.0, 40997.180056398895, 42497.575024249745, 42771.0, 44073.0, 44363.0, 44427.55572444275, 44545.77727111368, 44650.33024504635, 8892.866607000884, 9060.909390906072, 9068.727938161832, 9083.091690830897, 9083.409578377385, 8764.25503832175, 8994.28045756338, 10450.63422780203, 10739.301945373565, 10748.247622666377, 10726.463676816167, 10783.946080269627, 10784.622538211164, 10789.892101079007, 10794.838077428845, 10718.517666705016, 10727.463626818628, 10781.083607893299, 10836.374543818412, 10855.945720271427, 10499.687539057617, 10581.418022008786, 10582.100521455686, 10586.31188972722, 10588.417637029961, 41320.586794131974, 41777.28447650893, 41846.581534184574, 41931.580684193075, 42079.948001299905, 3542.929141417183, 3549.6627820357025, 3580.212353282274, 3686.207465394926, 3689.1330537323706, 31642.367152657047, 31781.364372712647, 31791.682083179105, 31954.0, 32883.6711632883, 4158.189153115144, 4280.315149576053, 4315.158544083905, 4351.347297905308, 4400.581944715247, 343589.0, 344056.0, 344412.0, 344905.0, 346717.0, 55640.93974831423, 55946.720266398406, 55984.0, 56101.15848262279, 56260.71869640626, 58882.0, 58949.70525147347, 58979.756113413314, 58992.7050364754, 59170.0, 15380.230988450532, 16013.119278439564, 16015.759763603555, 16018.279177437043, 16018.919905400398, 11304.15218858589, 11671.299722016705, 12071.939640301918, 12233.143679942497, 12258.141930064989, 15647.374105035899, 15880.20598970047, 15986.680266394607, 16156.919215403848, 16215.0, 6307.5269354798575, 6310.6213627182515, 6340.207474065742, 6373.330800265998, 6454.419102280817, 869.1135042256989, 884.2483888694687, 902.4134312696799, 1034.0125180452649, 1043.7547176413536, 30431.695683042886, 30450.847745761574, 30560.54159187614, 30658.693413065583, 30848.228794279872, 1059.0256963593513, 1101.8677758669012, 1148.9310641361378, 1170.3153655111855, 1173.1142987044898, 15191.316390762442, 16027.358905643643, 16196.777905638533, 16198.595035124215, 16305.510834674978, 530589.0, 534085.0, 535337.0, 535468.0, 535877.0, 57169.0, 57290.42709572851, 57323.71338143366, 57347.42652573504, 57393.0, 1324.8542660307357, 1355.830521184852, 1366.289529444703, 1379.0966916669597, 1381.1022835157228, 28034.579481307388, 29303.604459331273, 29467.25388549298, 29551.556726648687, 30022.84988575043, 49539.25691114708, 49955.250671239235, 49978.75010624924, 49996.25005624991, 50033.0, 443189.0, 443525.0, 444190.0, 445232.0, 445898.0, 24714.629280561167, 25405.491890162462, 27635.032773852818, 27989.580156297263, 28163.859180703967, 46240.768796155804, 46504.0, 46703.29945050895, 50737.23894141517, 50971.490285096676, 37089.25821483609, 37379.626203738706, 38170.427443588924, 39478.0, 39829.60170398259, 34615.480767789006, 39234.80382598183, 39565.40651890166, 41397.79301103596, 41499.58500414957, 36108.63891361053, 36327.0, 36671.449928250564, 36861.81569092137, 37077.81461092677, 30434.239144020692, 32765.672343276263, 32767.0, 33109.66890331066, 33337.66662333443, 25363.87318063398, 25738.485230294915, 25861.482770344865, 26362.86818565895, 26363.73636263613], "value": 19742.264651581863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.2833729263571, 400.21957183492196, 402.1755401427074, 410.2512913932074, 425.96592272618193, 13797.862021379784, 15755.763663545042, 18535.165917533708, 18872.05639718012, 19384.51538711533, 18029.63940721185, 18513.629727405445, 18527.0, 18736.906315468437, 18854.71717924232, 5594.832155035356, 5640.351359593658, 5813.30240371156, 5818.0400233961345, 5821.912671309934, 5710.0, 5738.971305143468, 5773.971130144343, 5779.566532510057, 5794.913076303858, 5545.722713864311, 5726.742296596663, 5739.540836733051, 5750.3962083981, 5754.6547207167705, 5722.513586345166, 5758.971205143947, 5764.394738552433, 5764.8847023059725, 5770.567207459437, 4952.826651067231, 4986.925196122061, 5035.823746168867, 5159.380874295071, 5169.638125331228, 21678.349649510383, 21811.890940545196, 21887.781122188735, 21920.0, 22133.66799498009, 2843.3033906692995, 3011.653659829126, 3053.328267781085, 3089.2431354318337, 3136.639286482061, 13699.383527741276, 13755.449782008707, 14047.578572642837, 14076.225807580578, 14079.71840563183, 1909.809019098093, 1940.388777535086, 1955.3351860367543, 1999.2502811445709, 2003.2988454041115, 76813.61593192055, 76905.0, 77569.0, 78243.21756782416, 79175.99999999942, 10466.319689220176, 10627.468626568641, 10668.253222274521, 10920.2901811382, 11043.503042363112, 15043.924780376028, 15106.471273505374, 15201.923990379977, 15244.466443674419, 15258.694826103416, 8060.677572897019, 8110.188981101843, 8228.341732661373, 8286.295664868554, 8324.791880203044, 7739.148693643695, 7747.496412733154, 7762.184970578121, 7860.253275938777, 7981.800454988555, 6615.6030638161865, 6682.3317668232785, 6721.966390168018, 6734.393904548614, 6772.501462582638, 1825.7352683860765, 1926.1524929031182, 1966.6263409952064, 1976.5651556657513, 2016.1733689187363, 843.6541018182515, 901.1664210605164, 946.9526523673788, 971.9416834989923, 1011.9981218593539, 18231.0, 19124.80875191258, 19134.90432547856, 19163.0, 19427.22291108368, 960.8174446855076, 962.9614815407306, 976.7167521418851, 982.9262805289632, 1029.5418538750334, 10640.787184256425, 10950.288231264942, 10992.120630349553, 11158.665240042812, 11190.272632278877, 320306.0, 326874.0, 327443.0, 435049.0, 438013.0, 83654.16345836464, 87384.56307718421, 87773.56113219394, 88636.0, 90722.54638726765, 480.7692307692327, 505.94687557806634, 508.14885067512506, 994.955227014786, 1042.4683411460057, 10370.844437333597, 11054.170937179904, 11166.385848778395, 11322.490487927898, 11388.430578471209, 39364.60635393609, 39736.40395394129, 39953.40069899012, 40089.39865901956, 40781.59218407778, 51905.7404712974, 53225.0, 53432.0, 53758.46241537535, 53888.18621299968, 8753.868691969752, 8836.602352894264, 8904.287656987555, 9006.819863602823, 9098.0, 12077.69805754828, 12755.87244127547, 13379.44204501043, 13466.259355735529, 13870.722585548434, 11324.32054076758, 11452.198346115692, 11568.363739994382, 11752.412379380825, 12005.159638825548, 9850.950745246228, 10260.17918566498, 10857.891421085687, 11214.887851121384, 11424.657260282205, 11255.380954047607, 11608.767824643292, 11685.123615728684, 11817.645470635895, 12286.81569776472, 8646.351523635627, 8692.91307086921, 8716.956415217883, 8916.0, 9163.954180229322, 10681.89318106809, 11405.0, 11516.29367098748, 11555.191136620357, 11713.531458741554], "value": 9019.26316278686, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.857739937307, 1395.9232242226678, 1421.7014426970336, 1525.4965861265775, 1715.0652894172683, 7856.332211762, 8105.878411823827, 8407.117252688471, 8411.411201215917, 8637.74086777396, 8449.49303041818, 8510.27662648674, 8526.87209691855, 8542.0, 8543.91456085439, 2039.857209995301, 2135.7543882453483, 2302.4819415631473, 2335.3694502484404, 2336.8247381446376, 2262.151693115082, 2363.633636786301, 2367.443650742074, 2380.8928598213124, 2383.4279772854475, 2325.3721495196287, 2325.6046472099783, 2337.0, 2341.227394959668, 2360.7285162206394, 2308.376738280672, 2312.930612081632, 2334.0780391745166, 2339.5905716499547, 2342.4495243617907, 2329.149860300978, 2339.228054741948, 2357.3752955466816, 2360.079568968104, 2367.5501654685727, 8585.399022068457, 8595.656173753043, 8632.697855575025, 8644.092370301154, 8666.913330866673, 5124.4875512448825, 5266.8683282917855, 5267.051930652481, 5268.5521730652945, 5269.104252277122, 3109.4713898637287, 3157.2896098377682, 3345.5149003394613, 3348.313595712873, 3362.646922073196, 993.8012397520525, 1014.9188064954786, 1027.938323700573, 1028.6142696488816, 1041.1670663469195, 11211.66365009043, 11562.07503399726, 11661.825072623917, 11812.527498900034, 12104.971077458427, 4328.11273688893, 4474.39595654589, 4489.214387482178, 4604.608608268268, 4612.3542704021465, 9173.58718857653, 9184.357095003286, 9184.678536251198, 9188.49463279526, 9190.954045229864, 5169.844904652867, 5234.607404444682, 5244.134717771602, 5244.344456942882, 5249.055170069348, 4291.098869237433, 4437.667174961826, 4712.2224832902875, 4898.53463920927, 4901.779419926112, 3846.769193848378, 3857.324968130566, 3879.18537107205, 3936.6653834423823, 3939.7242193046213, 1170.4791367841399, 1191.9582814601447, 1208.0577149823152, 1216.6289281769145, 1217.6407959651924, 797.6410615223057, 892.5180402582532, 905.3707673167218, 906.0622255965045, 935.7099299217265, 5395.082835917825, 5547.362053363915, 5610.1304297833085, 5709.514691251207, 5803.332616749043, 411.3314557262194, 427.05833636830715, 429.1053154173521, 435.5883689913002, 449.59087230620634, 3704.0924973381693, 3771.245750849841, 3806.200697853482, 3841.0, 3891.571927088018, 10303.536340864679, 10574.471276436301, 10672.039516443558, 10696.304740192018, 10704.518296676668, 10608.840867386996, 10609.363438193734, 10619.30974486656, 10635.41505217205, 10649.254552181428, 7653.272939070779, 7967.760967170994, 8030.19698030204, 8037.638306276232, 8125.75622731319, 5615.466530679579, 5719.714014299351, 5767.826965191051, 5823.883522329615, 5903.822885313448, 9285.164335209864, 9384.718458446258, 9413.34106612558, 9499.57251923679, 9634.229261659191, 9522.0954009369, 9558.474283914451, 9562.56968436436, 9572.186364159124, 9680.370775899544, 6665.0, 6667.2666006738245, 7277.563346199033, 7607.505512141693, 7766.223377662189, 7937.206279372017, 8033.959830200812, 8063.032436107705, 8068.273855353046, 8075.232852878967, 7127.786166415016, 7154.2130365658695, 7229.09636295463, 7262.89105663426, 7429.33136017761, 7131.964340178266, 7331.816704582428, 7344.30229128232, 7526.096868375611, 7565.886511702219, 6537.640429776408, 6609.735610575523, 6622.10601568782, 6624.403803657694, 6625.536212465081, 4870.537298956593, 4885.682430641997, 4976.477469865757, 4998.4501704813165, 5068.417132029864, 4961.50384961501, 5097.08252514551, 5163.328767260232, 5211.0359583477175, 5227.320448341691], "value": 4371.096722287445, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.9663428608568, 398.9461422707935, 406.9816858241379, 411.56579808302246, 414.53779036374453, 5093.210552364385, 5150.330457040589, 5160.2517634942915, 5181.202150838552, 5182.559482443992, 4908.0, 4935.555799978001, 5003.1994880819075, 5036.219385995177, 5094.337736094302, 4139.275626765319, 4141.233871733723, 4141.7514949103, 4141.772202528859, 4163.646090082347, 4109.0959988802415, 4120.793960301988, 4135.710500264982, 4155.231282212801, 4169.749815011109, 3905.1408690088133, 3992.301347264217, 3998.2403343364667, 4000.5599384067873, 4121.773302468363, 3983.1834473933063, 3990.5011873515955, 3992.3811809169624, 4002.8398864045403, 4012.0371110933456, 3579.2282820004, 3581.928361432757, 3583.623719509466, 3587.2287458196615, 3591.8742844000335, 4837.32277481153, 4961.330220420233, 4987.202047672355, 4996.800767815735, 5003.824866129668, 4576.565226303496, 4584.931226031612, 4593.19619066662, 4611.123886461595, 4621.168189725848, 5301.867453313659, 5323.254744335796, 5335.359756829166, 5342.252084708144, 5349.3045904032615, 3800.7929137082097, 3905.9609403906165, 3937.625925537069, 3950.150717595731, 3958.4062390641348, 5520.558355331564, 5536.806211782568, 5540.3683250456115, 5545.223668686387, 5558.082916318803, 4011.3782363733667, 4034.45534852797, 4036.737612055266, 4037.6366127048705, 4040.6565441937764, 4011.1777085697363, 4032.0, 4056.553779084298, 4073.1446396256524, 4077.959220407817, 4501.3022981437925, 4585.885352866138, 4594.97702511492, 4596.4254468191475, 4598.8620341389815, 5352.57179425645, 5357.249985002103, 5364.14173732201, 5367.275417818544, 5377.381601115843, 1302.667819705969, 1359.9660008499668, 1361.659585103724, 1362.529927175123, 1372.567641193041, 921.6728061538057, 938.8216238914584, 954.8684808501947, 959.0553304994575, 968.0445500089779, 4983.426905905795, 4987.800487980513, 5013.924791128136, 5022.824201152942, 5031.496850315012, 5214.7914083437, 5220.686758794409, 5231.686098834082, 5268.28878101459, 5285.392179899284, 4858.58702010333, 4977.303177555145, 5020.171671674133, 5026.396832380065, 5032.194848824171, 2747.7252274772363, 2770.8664328466966, 2784.3874347643487, 2802.341449759305, 2807.3683421230467, 5223.582113430916, 5413.945860541344, 5424.589606702207, 5432.185172224198, 5441.6190866639745, 5323.86690332737, 5352.090144675337, 5362.812301569426, 5375.946240537544, 5381.009026660161, 5449.730161902827, 5463.235147079412, 5482.5613950883835, 5485.835424937259, 5517.751701173457, 5100.0874671998, 5263.34208223972, 5264.552513036435, 5265.89468210626, 5280.498352656491, 5233.502817232356, 5239.318888544465, 5243.475652434879, 5251.897101608705, 5261.4738526147075, 5495.0, 5497.615166938436, 5503.532199763065, 5505.788726480168, 5521.475459831309, 3722.7766334020043, 3915.5105611798526, 3943.7830919298567, 3953.802309884552, 3962.881113566598, 5221.399539053057, 5243.501867322599, 5260.552853007537, 5313.707746074003, 5318.308619879391, 5147.996140752517, 5161.896762064813, 5162.483751624958, 5239.554637855825, 5255.213227502667, 5084.847454576368, 5170.5346518813485, 5380.0, 5395.946040539701, 5431.4840090191365, 5186.6369354144845, 5305.840824775263, 5391.4608539145775, 5405.702686352289, 5416.2688037114485, 5294.682319060868, 5419.376771671151, 5465.398806131402, 5486.259354987025, 5503.642263252876, 5203.0, 5298.251576979665, 5322.654027488201, 5341.0, 5342.599305052059], "value": 4090.293537371643, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.4756708684943, 744.7691215723127, 749.3480671815518, 760.2359628573283, 769.2230846844685, 15181.54455366338, 16686.58283542912, 16700.415485458012, 16725.912815666987, 16763.91618041911, 13476.865231347683, 14341.067830591004, 15303.846961530382, 15357.0, 15419.845801541982, 786.6184900323353, 796.9840603187932, 804.6057431858405, 807.6728924785472, 807.8263173417714, 787.3504358903928, 796.7211475983418, 800.8318253166842, 803.0844836885955, 803.6423791412824, 825.224289168183, 832.4589017138848, 833.4624167412015, 833.8290650416681, 834.3408707121397, 828.3207769628907, 828.4987582512554, 829.5561874397199, 830.543201239322, 830.8836762853206, 603.1616053685381, 607.5534482155612, 607.7295603456464, 608.85996220869, 610.1336102734128, 12688.555900543437, 12806.551770688075, 12816.615501534969, 14169.645758856008, 14227.14637121776, 14394.640133996629, 14615.78076328856, 14738.852611473856, 15131.621709457242, 15180.924095379452, 11558.768824623545, 11574.768504629945, 11700.18098733089, 11738.413079346084, 11943.9847612953, 3695.501107350527, 3698.500702405168, 3751.5744017273532, 3777.8111094445444, 3842.4044273137706, 78902.60548697277, 80001.59999200025, 80999.99999999942, 82081.0, 82413.0, 11192.328460292409, 11222.270552414067, 11281.774364512663, 11390.316581005167, 11412.51918164951, 21171.576568468543, 21173.0, 21184.25855095095, 21185.258515951868, 21418.357449276547, 7385.9630701846145, 7506.174320824705, 7691.346235570039, 7743.8838417423785, 7763.0, 3927.9018024549046, 3961.16815468749, 3965.38536526833, 3974.3044967130636, 3988.920221595552, 3647.2158485925124, 3677.3748462761396, 3747.5315585551807, 3757.210985693035, 3760.383035294795, 596.31126049413, 610.8533951851524, 628.5882746800853, 640.164585216288, 658.06554692337, 716.1263258824226, 729.1687476277049, 732.7984804178893, 759.1080480435467, 768.6848392159188, 8697.652093916298, 8714.288542636026, 8954.820903581893, 9053.77365565866, 9164.312676549152, 690.892911598703, 704.2534912992245, 722.523134731075, 730.831908661011, 741.0625558668181, 4269.402283680225, 4330.0, 4374.693771435969, 4379.671524635665, 4388.978055109768, 62349.68825155936, 63624.68187659032, 67267.32732672709, 86708.56645716858, 89602.0, 61841.690791545756, 68809.65595171992, 68891.31108688848, 69169.30830691729, 69972.30027699658, 49171.0, 59653.40346596565, 63159.0, 64115.35884641187, 64510.35489645044, 9552.235821134154, 9835.557399916877, 9877.703668889944, 9911.752206194902, 9962.252831037846, 41028.384574232005, 41383.37924931189, 41452.0, 42156.57843421526, 42844.0, 53907.0, 55570.0, 58859.70570147122, 59837.0, 60155.699221503615, 12996.610101696964, 13138.802917956047, 13361.66595835112, 13512.797308040585, 13763.967702422158, 15027.849721502645, 15151.772723408938, 15241.847581524044, 15377.38490460369, 15888.205589720237, 13835.86164138386, 13855.23796191179, 14172.362243699265, 14436.917231207912, 14730.926345368205, 13451.865481345061, 13791.379387927767, 13805.51680691171, 14515.92742036283, 14633.0, 13102.868971310547, 13595.86404135946, 13902.582922512342, 14050.578482645536, 14578.23258416842, 10548.947255263982, 10618.256722029693, 11238.831417528907, 11253.0, 11722.238054526428, 10748.193885458779, 10985.890141098487, 11141.72145696364, 11385.203035787747, 11700.70748231301], "value": 7159.3364859436915, "unit": "score"}], "name": "dromaeo_css", "value": 7022.98840391543, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 112.0, 112.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 120.0, 118.0, 160.0, 122.0, 119.0, 121.0, 118.0, 136.0, 119.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 159.0, 161.0, 161.0, 162.0, 162.0, 161.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 113.0, 76.0, 75.0, 79.0, 74.0, 91.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 114.0, 83.0, 84.0, 84.0, 94.0, 84.0, 84.0, 109.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 147.0, 146.0, 146.0, 146.0, 148.0, 153.0, 146.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 48.0, 48.0, 49.0, 50.0, 51.0, 50.0, 51.0, 53.0, 51.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 64.0, 65.0, 65.0, 64.0, 65.0, 64.0, 65.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 80.0, 80.0, 80.0, 89.0, 81.0, 80.0, 79.0, 83.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 124.0, 127.0, 132.0, 129.0, 129.0, 131.0, 132.0, 169.0, 134.0], "value": 134.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 163.0, 167.0, 166.0, 163.0, 166.0, 167.0, 166.0, 162.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 62.0, 63.0, 66.0, 63.0, 67.0, 60.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1490.6999999999998, "alertThreshold": 2.0}]} 09:35:34 INFO - Return code: 0 09:35:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:35:34 INFO - # TBPL SUCCESS # 09:35:34 INFO - Running post-action listener: _resource_record_post_action 09:35:34 INFO - [mozharness: 2016-12-24 17:35:34.162032Z] Finished run-tests step (success) 09:35:34 INFO - Running post-run listener: _resource_record_post_run 09:35:34 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 402075648; Read time: 0; Write time: 316296 09:35:34 INFO - TinderboxPrint: CPU usage
13.1% 09:35:34 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:35:34 INFO - TinderboxPrint: I/O write bytes / time
402,075,648 / 316,296 09:35:34 INFO - TinderboxPrint: CPU idle
8,282.4 (86.9%) 09:35:34 INFO - TinderboxPrint: CPU user
1,199.9 (12.6%) 09:35:34 INFO - TinderboxPrint: Swap in / out
0 / 0 09:35:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105553920; Read time: 0; Write time: 118976 09:35:34 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 296521728; Read time: 0; Write time: 197320 09:35:34 INFO - Running post-run listener: _upload_blobber_files 09:35:34 INFO - Blob upload gear active. 09:35:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:35:34 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 09:35:34 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:35:34 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:35:35 INFO - (blobuploader) - INFO - Open directory for files ... 09:35:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:35:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:35:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:36 INFO - (blobuploader) - INFO - Done attempting. 09:35:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:35:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:35:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:37 INFO - (blobuploader) - INFO - Done attempting. 09:35:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:35:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:35:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:38 INFO - (blobuploader) - INFO - Done attempting. 09:35:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:35:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:35:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:41 INFO - (blobuploader) - INFO - Done attempting. 09:35:41 INFO - (blobuploader) - INFO - Iteration through files over. 09:35:41 INFO - Return code: 0 09:35:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:35:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:35:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bca3b96c058d26ea4ed986587ef1b3ba30f7deefb3ad030b04ba1d18a246cd13d5a2c389b51c3074d4590fbba20b8e9678997057d2045cbffa0d744d86d42217", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d978e6b12df108cfbf3a029ae32cf1e6e877a61cd749ab5d72b6dc830ce99163504d790d263a4207ef08f4fdcd7f5593a6e518ce76865c4485c4da6c660e5404", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dad2f4f6cd431b3878d956d23db4ae37c3e50ad8fa80407bbe012f70b5d99c79abf662a440de582944924cc0bce8c103e34783a633a54654c8ee3acde2831c5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adea481afb8c1e80794aaeb973ee7200ee405e02614ed64893842d07576e8304754917cf49fe238108e4a28e5228f247866d6d15ba114750f00f0071be19700f"} 09:35:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:35:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:35:41 INFO - Contents: 09:35:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bca3b96c058d26ea4ed986587ef1b3ba30f7deefb3ad030b04ba1d18a246cd13d5a2c389b51c3074d4590fbba20b8e9678997057d2045cbffa0d744d86d42217", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d978e6b12df108cfbf3a029ae32cf1e6e877a61cd749ab5d72b6dc830ce99163504d790d263a4207ef08f4fdcd7f5593a6e518ce76865c4485c4da6c660e5404", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dad2f4f6cd431b3878d956d23db4ae37c3e50ad8fa80407bbe012f70b5d99c79abf662a440de582944924cc0bce8c103e34783a633a54654c8ee3acde2831c5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adea481afb8c1e80794aaeb973ee7200ee405e02614ed64893842d07576e8304754917cf49fe238108e4a28e5228f247866d6d15ba114750f00f0071be19700f"} 09:35:41 INFO - Running post-run listener: copy_logs_to_upload_dir 09:35:41 INFO - Copying logs to upload dir... 09:35:41 INFO - mkdir: /builds/slave/test/build/upload/logs 09:35:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.551187 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-12-24 09:35:40.651339) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 09:35:40.655174) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bca3b96c058d26ea4ed986587ef1b3ba30f7deefb3ad030b04ba1d18a246cd13d5a2c389b51c3074d4590fbba20b8e9678997057d2045cbffa0d744d86d42217", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d978e6b12df108cfbf3a029ae32cf1e6e877a61cd749ab5d72b6dc830ce99163504d790d263a4207ef08f4fdcd7f5593a6e518ce76865c4485c4da6c660e5404", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dad2f4f6cd431b3878d956d23db4ae37c3e50ad8fa80407bbe012f70b5d99c79abf662a440de582944924cc0bce8c103e34783a633a54654c8ee3acde2831c5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adea481afb8c1e80794aaeb973ee7200ee405e02614ed64893842d07576e8304754917cf49fe238108e4a28e5228f247866d6d15ba114750f00f0071be19700f"} build_url:https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011476 build_url: 'https://queue.taskcluster.net/v1/task/Cl52_5rdRPSXWXtIuOhdag/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bca3b96c058d26ea4ed986587ef1b3ba30f7deefb3ad030b04ba1d18a246cd13d5a2c389b51c3074d4590fbba20b8e9678997057d2045cbffa0d744d86d42217", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d978e6b12df108cfbf3a029ae32cf1e6e877a61cd749ab5d72b6dc830ce99163504d790d263a4207ef08f4fdcd7f5593a6e518ce76865c4485c4da6c660e5404", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dad2f4f6cd431b3878d956d23db4ae37c3e50ad8fa80407bbe012f70b5d99c79abf662a440de582944924cc0bce8c103e34783a633a54654c8ee3acde2831c5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adea481afb8c1e80794aaeb973ee7200ee405e02614ed64893842d07576e8304754917cf49fe238108e4a28e5228f247866d6d15ba114750f00f0071be19700f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 09:35:40.681678) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 09:35:40.681980) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482597794.404423-938840487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 09:35:40.765221) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 09:35:40.765531) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 09:35:40.765804) ========= ========= Total master_lag: 0.18 =========