builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1482497216.99 results: success (0) buildid: 20161223030226 builduid: 203c553bd3a047bdb489272a44201b47 revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:56.989755) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:56.990195) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:56.990475) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.006873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.007132) ========= 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=3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-23 04:46:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.2M=0s 2016-12-23 04:46:57 (66.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106917 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.127492) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.127784) ========= 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=3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017187 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.176975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.177281) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2785aaf276ba29fb2e1f5607d90d441fee42efb4 --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 2785aaf276ba29fb2e1f5607d90d441fee42efb4 --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=3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-23 04:46:57,455 truncating revision to first 12 chars 2016-12-23 04:46:57,455 Setting DEBUG logging. 2016-12-23 04:46:57,455 attempt 1/10 2016-12-23 04:46:57,455 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2785aaf276ba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 04:46:57,778 unpacking tar archive at: mozilla-central-2785aaf276ba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.618851 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.809314) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.809607) ========= script_repo_revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.809995) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.810260) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 04:46:57.821212) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-23 04:46:57.821470) ========= /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=3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:46:58 INFO - MultiFileLogger online at 20161223 04:46:58 in /builds/slave/test 04:46:58 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 04:46:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:58 INFO - {'append_to_log': False, 04:46:58 INFO - 'base_work_dir': '/builds/slave/test', 04:46:58 INFO - 'blob_upload_branch': 'Firefox', 04:46:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:58 INFO - 'branch': 'Firefox', 04:46:58 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:58 INFO - 'config_files': ('talos/linux_config.py',), 04:46:58 INFO - 'default_actions': ('clobber', 04:46:58 INFO - 'read-buildbot-config', 04:46:58 INFO - 'download-and-extract', 04:46:58 INFO - 'populate-webroot', 04:46:58 INFO - 'create-virtualenv', 04:46:58 INFO - 'install', 04:46:58 INFO - 'run-tests'), 04:46:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:58 INFO - 'download_minidump_stackwalk': True, 04:46:58 INFO - 'download_symbols': 'ondemand', 04:46:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:58 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:58 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:58 INFO - 'installer_path': 'installer.exe', 04:46:58 INFO - 'log_level': 'info', 04:46:58 INFO - 'log_name': 'talos', 04:46:58 INFO - 'log_to_console': True, 04:46:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:46:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:46:58 INFO - 'opt_config_files': (), 04:46:58 INFO - 'pip_index': False, 04:46:58 INFO - 'sps_profile': False, 04:46:58 INFO - 'sps_profile_interval': 0, 04:46:58 INFO - 'suite': 'dromaeojs', 04:46:58 INFO - 'system_bits': '32', 04:46:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:58 INFO - 'title': 'talos-linux64-ix-059', 04:46:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:58 INFO - 'use_talos_json': True, 04:46:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:58 INFO - 'work_dir': 'build'} 04:46:58 INFO - [mozharness: 2016-12-23 12:46:58.201619Z] Running clobber step. 04:46:58 INFO - Running pre-action listener: _resource_record_pre_action 04:46:58 INFO - Running main action method: clobber 04:46:58 INFO - rmtree: /builds/slave/test/build 04:46:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:58 INFO - Running post-action listener: _resource_record_post_action 04:46:58 INFO - [mozharness: 2016-12-23 12:46:58.650577Z] Finished clobber step (success) 04:46:58 INFO - [mozharness: 2016-12-23 12:46:58.650680Z] Running read-buildbot-config step. 04:46:58 INFO - Running pre-action listener: _resource_record_pre_action 04:46:58 INFO - Running main action method: read_buildbot_config 04:46:58 INFO - Using buildbot properties: 04:46:58 INFO - { 04:46:58 INFO - "project": "", 04:46:58 INFO - "product": "firefox", 04:46:58 INFO - "script_repo_revision": "production", 04:46:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:46:58 INFO - "repository": "", 04:46:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:46:58 INFO - "buildid": "20161223030226", 04:46:58 INFO - "stage_platform": "linux64-pgo", 04:46:58 INFO - "basedir": "/builds/slave/test", 04:46:58 INFO - "nightly_build": "True", 04:46:58 INFO - "buildnumber": 127, 04:46:58 INFO - "slavename": "talos-linux64-ix-059", 04:46:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:46:58 INFO - "platform": "ubuntu64_hw", 04:46:58 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:46:58 INFO - "branch": "mozilla-central", 04:46:58 INFO - "repo_path": "mozilla-central", 04:46:58 INFO - "revision": "2785aaf276ba29fb2e1f5607d90d441fee42efb4", 04:46:58 INFO - "pgo_build": "True", 04:46:58 INFO - "builduid": "203c553bd3a047bdb489272a44201b47", 04:46:58 INFO - "slavebuilddir": "test" 04:46:58 INFO - } 04:46:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 04:46:58 INFO - Running post-action listener: _resource_record_post_action 04:46:58 INFO - [mozharness: 2016-12-23 12:46:58.653186Z] Finished read-buildbot-config step (success) 04:46:58 INFO - [mozharness: 2016-12-23 12:46:58.653275Z] Running download-and-extract step. 04:46:58 INFO - Running pre-action listener: _resource_record_pre_action 04:46:58 INFO - Running main action method: download_and_extract 04:46:58 INFO - mkdir: /builds/slave/test/build/tests 04:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:58 INFO - trying https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:46:58 INFO - Downloading https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/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 04:46:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/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 04:46:59 INFO - Downloaded 1361 bytes. 04:46:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:46:59 INFO - Using the following test package requirements: 04:46:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:46:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:46:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:46:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:59 INFO - u'jsshell-linux-x86_64.zip'], 04:46:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:46:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:46:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:46:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:46:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:46:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:46:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:46:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:46:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:46:59 INFO - Fetch https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:47:00 INFO - Expected file size: 21827712 04:47:00 INFO - Obtained file size: 21827712 04:47:04 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 04:47:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:47:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:47:04 INFO - Fetch https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:47:04 INFO - Expected file size: 21827712 04:47:04 INFO - Obtained file size: 21827712 04:47:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 04:47:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:47:09 INFO - Fetch https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:47:10 INFO - Expected file size: 14409776 04:47:10 INFO - Obtained file size: 14409776 04:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:10 INFO - trying https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:47:10 INFO - Downloading https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/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 04:47:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/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 04:47:12 INFO - Downloaded 61383950 bytes. 04:47:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:47:12 INFO - mkdir: /builds/slave/test/properties 04:47:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:12 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:12 INFO - Contents: 04:47:12 INFO - build_url:https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:47:12 INFO - Running post-action listener: _resource_record_post_action 04:47:12 INFO - Running post-action listener: set_extra_try_arguments 04:47:12 INFO - [mozharness: 2016-12-23 12:47:12.995082Z] Finished download-and-extract step (success) 04:47:12 INFO - [mozharness: 2016-12-23 12:47:12.995307Z] Running populate-webroot step. 04:47:12 INFO - Running pre-action listener: _resource_record_pre_action 04:47:12 INFO - Running main action method: populate_webroot 04:47:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:13 INFO - '--tppagecycles', 04:47:13 INFO - '7'], 04:47:13 INFO - 'tests': ['tsvgm']}}, 04:47:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:47:13 INFO - 'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:47:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:47:13 INFO - 'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['damp', 'tps']}, 04:47:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:47:13 INFO - 'tests': ['damp', 'tps']}, 04:47:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['dromaeo_dom']}, 04:47:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['basic_compositor_video']}, 04:47:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:47:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['a11yr', 04:47:13 INFO - 'ts_paint', 04:47:13 INFO - 'tpaint', 04:47:13 INFO - 'sessionrestore', 04:47:13 INFO - 'sessionrestore_no_auto_restore', 04:47:13 INFO - 'tabpaint']}, 04:47:13 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:13 INFO - 'ts_paint', 04:47:13 INFO - 'tpaint', 04:47:13 INFO - 'sessionrestore', 04:47:13 INFO - 'sessionrestore_no_auto_restore', 04:47:13 INFO - 'tabpaint']}, 04:47:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:13 INFO - 'ts_paint', 04:47:13 INFO - 'tpaint', 04:47:13 INFO - 'sessionrestore', 04:47:13 INFO - 'sessionrestore_no_auto_restore', 04:47:13 INFO - 'tabpaint']}, 04:47:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:13 INFO - 'ts_paint', 04:47:13 INFO - 'tpaint', 04:47:13 INFO - 'sessionrestore', 04:47:13 INFO - 'sessionrestore_no_auto_restore', 04:47:13 INFO - 'tabpaint']}, 04:47:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['a11yr', 04:47:13 INFO - 'ts_paint', 04:47:13 INFO - 'tpaint', 04:47:13 INFO - 'sessionrestore', 04:47:13 INFO - 'sessionrestore_no_auto_restore', 04:47:13 INFO - 'tabpaint']}, 04:47:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['a11yr', 04:47:13 INFO - 'ts_paint', 04:47:13 INFO - 'tpaint', 04:47:13 INFO - 'sessionrestore', 04:47:13 INFO - 'sessionrestore_no_auto_restore', 04:47:13 INFO - 'tabpaint']}, 04:47:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['tsvgx', 04:47:13 INFO - 'tsvgr_opacity', 04:47:13 INFO - 'tart', 04:47:13 INFO - 'tscrollx', 04:47:13 INFO - 'cart', 04:47:13 INFO - 'tsvg_static']}, 04:47:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:13 INFO - 'tsvgr_opacity', 04:47:13 INFO - 'tart', 04:47:13 INFO - 'tscrollx', 04:47:13 INFO - 'cart', 04:47:13 INFO - 'tsvg_static']}, 04:47:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:47:13 INFO - 'talos_options': ['--disable-e10s'], 04:47:13 INFO - 'tests': ['tp5o']}, 04:47:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:47:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:47:13 INFO - 'talos_options': ['--disable-e10s', 04:47:13 INFO - '--xperf_path', 04:47:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:13 INFO - 'tests': ['tp5n']}, 04:47:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:47:13 INFO - 'talos_options': ['--xperf_path', 04:47:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:13 INFO - 'tests': ['tp5n']}}, 04:47:13 INFO - 'talos.zip': {'path': '', 04:47:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:13 INFO - Running post-action listener: _resource_record_post_action 04:47:13 INFO - [mozharness: 2016-12-23 12:47:13.015459Z] Finished populate-webroot step (success) 04:47:13 INFO - [mozharness: 2016-12-23 12:47:13.015549Z] Running create-virtualenv step. 04:47:13 INFO - Running pre-action listener: _resource_record_pre_action 04:47:13 INFO - Running main action method: create_virtualenv 04:47:13 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:13 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 04:47:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:13 INFO - Using real prefix '/usr' 04:47:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:13 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:15 INFO - Installing pip.................done. 04:47:15 INFO - Return code: 0 04:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:47:15 INFO - Reading from file tmpfile_stdout 04:47:15 INFO - Output received: 04:47:15 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) 04:47:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:47:15 INFO - Using env: {'DISPLAY': ':0', 04:47:15 INFO - 'HOME': '/home/cltbld', 04:47:15 INFO - 'LANG': 'en_US.UTF-8', 04:47:15 INFO - 'LANGUAGE': 'en_US:en', 04:47:15 INFO - 'LOGNAME': 'cltbld', 04:47:15 INFO - 'MAIL': '/var/mail/cltbld', 04:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:15 INFO - 'MOZ_NO_REMOTE': '1', 04:47:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:15 INFO - 'NO_EM_RESTART': '1', 04:47:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:15 INFO - 'PWD': '/builds/slave/test', 04:47:15 INFO - 'SHELL': '/bin/bash', 04:47:15 INFO - 'SHLVL': '1', 04:47:15 INFO - 'TERM': 'linux', 04:47:15 INFO - 'TMOUT': '86400', 04:47:15 INFO - 'USER': 'cltbld', 04:47:15 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:15 INFO - '_': '/tools/buildbot/bin/python'} 04:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:47:15 INFO - Cleaning up... 04:47:16 INFO - Return code: 0 04:47:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:47:16 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')]} 04:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:16 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')]} 04:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:16 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 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47:16 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 04:47:16 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 04:47:16 INFO - Using env: {'DISPLAY': ':0', 04:47:16 INFO - 'HOME': '/home/cltbld', 04:47:16 INFO - 'LANG': 'en_US.UTF-8', 04:47:16 INFO - 'LANGUAGE': 'en_US:en', 04:47:16 INFO - 'LOGNAME': 'cltbld', 04:47:16 INFO - 'MAIL': '/var/mail/cltbld', 04:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:16 INFO - 'MOZ_NO_REMOTE': '1', 04:47:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:16 INFO - 'NO_EM_RESTART': '1', 04:47:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:16 INFO - 'PWD': '/builds/slave/test', 04:47:16 INFO - 'SHELL': '/bin/bash', 04:47:16 INFO - 'SHLVL': '1', 04:47:16 INFO - 'TERM': 'linux', 04:47:16 INFO - 'TMOUT': '86400', 04:47:16 INFO - 'USER': 'cltbld', 04:47:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:16 INFO - '_': '/tools/buildbot/bin/python'} 04:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:16 INFO - Downloading/unpacking psutil>=3.1.1 04:47:16 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 04:47:16 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 04:47:16 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 04:47:16 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 04:47:16 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 04:47:16 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 04:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:18 INFO - Installing collected packages: psutil 04:47:18 INFO - Running setup.py install for psutil 04:47:18 INFO - building 'psutil._psutil_linux' extension 04:47:18 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 04:47:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:47:18 INFO - building 'psutil._psutil_posix' extension 04:47:18 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 04:47:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:47:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:18 INFO - Successfully installed psutil 04:47:18 INFO - Cleaning up... 04:47:18 INFO - Return code: 0 04:47:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:47:18 INFO - Using env: {'DISPLAY': ':0', 04:47:18 INFO - 'HOME': '/home/cltbld', 04:47:18 INFO - 'LANG': 'en_US.UTF-8', 04:47:18 INFO - 'LANGUAGE': 'en_US:en', 04:47:18 INFO - 'LOGNAME': 'cltbld', 04:47:18 INFO - 'MAIL': '/var/mail/cltbld', 04:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:18 INFO - 'MOZ_NO_REMOTE': '1', 04:47:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:18 INFO - 'NO_EM_RESTART': '1', 04:47:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:18 INFO - 'PWD': '/builds/slave/test', 04:47:18 INFO - 'SHELL': '/bin/bash', 04:47:18 INFO - 'SHLVL': '1', 04:47:18 INFO - 'TERM': 'linux', 04:47:18 INFO - 'TMOUT': '86400', 04:47:18 INFO - 'USER': 'cltbld', 04:47:18 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:18 INFO - '_': '/tools/buildbot/bin/python'} 04:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:47:21 INFO - Installing collected packages: mozsystemmonitor 04:47:21 INFO - Running setup.py install for mozsystemmonitor 04:47:21 INFO - Successfully installed mozsystemmonitor 04:47:21 INFO - Cleaning up... 04:47:21 INFO - Return code: 0 04:47:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:47:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:47:21 INFO - Using env: {'DISPLAY': ':0', 04:47:21 INFO - 'HOME': '/home/cltbld', 04:47:21 INFO - 'LANG': 'en_US.UTF-8', 04:47:21 INFO - 'LANGUAGE': 'en_US:en', 04:47:21 INFO - 'LOGNAME': 'cltbld', 04:47:21 INFO - 'MAIL': '/var/mail/cltbld', 04:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:21 INFO - 'MOZ_NO_REMOTE': '1', 04:47:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:21 INFO - 'NO_EM_RESTART': '1', 04:47:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:21 INFO - 'PWD': '/builds/slave/test', 04:47:21 INFO - 'SHELL': '/bin/bash', 04:47:21 INFO - 'SHLVL': '1', 04:47:21 INFO - 'TERM': 'linux', 04:47:21 INFO - 'TMOUT': '86400', 04:47:21 INFO - 'USER': 'cltbld', 04:47:21 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:21 INFO - '_': '/tools/buildbot/bin/python'} 04:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:21 INFO - Downloading/unpacking jsonschema==2.5.1 04:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:23 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:23 INFO - warning: no files found matching '*.txt' 04:47:23 INFO - no previously-included directories found matching 'build' 04:47:23 INFO - no previously-included directories found matching 'dist' 04:47:23 INFO - no previously-included directories found matching '.git*' 04:47:23 INFO - Installing collected packages: jsonschema, functools32 04:47:23 INFO - Running setup.py install for functools32 04:47:23 INFO - warning: no files found matching '*.txt' 04:47:23 INFO - no previously-included directories found matching 'build' 04:47:23 INFO - no previously-included directories found matching 'dist' 04:47:23 INFO - no previously-included directories found matching '.git*' 04:47:23 INFO - Successfully installed jsonschema functools32 04:47:23 INFO - Cleaning up... 04:47:24 INFO - Return code: 0 04:47:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', '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 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:47: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 functools32==3.2.3-2 04:47:24 INFO - Using env: {'DISPLAY': ':0', 04:47:24 INFO - 'HOME': '/home/cltbld', 04:47:24 INFO - 'LANG': 'en_US.UTF-8', 04:47:24 INFO - 'LANGUAGE': 'en_US:en', 04:47:24 INFO - 'LOGNAME': 'cltbld', 04:47:24 INFO - 'MAIL': '/var/mail/cltbld', 04:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:24 INFO - 'MOZ_NO_REMOTE': '1', 04:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:24 INFO - 'NO_EM_RESTART': '1', 04:47:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:24 INFO - 'PWD': '/builds/slave/test', 04:47:24 INFO - 'SHELL': '/bin/bash', 04:47:24 INFO - 'SHLVL': '1', 04:47:24 INFO - 'TERM': 'linux', 04:47:24 INFO - 'TMOUT': '86400', 04:47:24 INFO - 'USER': 'cltbld', 04:47:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:24 INFO - '_': '/tools/buildbot/bin/python'} 04:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:47:24 INFO - Cleaning up... 04:47:24 INFO - Return code: 0 04:47:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', '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 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:47: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 blobuploader==1.2.4 04:47:24 INFO - Using env: {'DISPLAY': ':0', 04:47:24 INFO - 'HOME': '/home/cltbld', 04:47:24 INFO - 'LANG': 'en_US.UTF-8', 04:47:24 INFO - 'LANGUAGE': 'en_US:en', 04:47:24 INFO - 'LOGNAME': 'cltbld', 04:47:24 INFO - 'MAIL': '/var/mail/cltbld', 04:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:24 INFO - 'MOZ_NO_REMOTE': '1', 04:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:24 INFO - 'NO_EM_RESTART': '1', 04:47:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:24 INFO - 'PWD': '/builds/slave/test', 04:47:24 INFO - 'SHELL': '/bin/bash', 04:47:24 INFO - 'SHLVL': '1', 04:47:24 INFO - 'TERM': 'linux', 04:47:24 INFO - 'TMOUT': '86400', 04:47:24 INFO - 'USER': 'cltbld', 04:47:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:24 INFO - '_': '/tools/buildbot/bin/python'} 04:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:24 INFO - Downloading/unpacking blobuploader==1.2.4 04:47:24 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 04:47:24 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 04:47:24 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 04:47:24 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 04:47:24 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 04:47:24 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 04:47:26 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:27 INFO - Downloading docopt-0.6.1.tar.gz 04:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:27 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:27 INFO - Running setup.py install for blobuploader 04:47:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:27 INFO - Running setup.py install for requests 04:47:27 INFO - Running setup.py install for docopt 04:47:27 INFO - Successfully installed blobuploader requests docopt 04:47:27 INFO - Cleaning up... 04:47:27 INFO - Return code: 0 04:47:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:27 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 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47:27 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 04:47:27 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 04:47:27 INFO - Using env: {'DISPLAY': ':0', 04:47:27 INFO - 'HOME': '/home/cltbld', 04:47:27 INFO - 'LANG': 'en_US.UTF-8', 04:47:27 INFO - 'LANGUAGE': 'en_US:en', 04:47:27 INFO - 'LOGNAME': 'cltbld', 04:47:27 INFO - 'MAIL': '/var/mail/cltbld', 04:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:27 INFO - 'MOZ_NO_REMOTE': '1', 04:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:27 INFO - 'NO_EM_RESTART': '1', 04:47:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:27 INFO - 'PWD': '/builds/slave/test', 04:47:27 INFO - 'SHELL': '/bin/bash', 04:47:27 INFO - 'SHLVL': '1', 04:47:27 INFO - 'TERM': 'linux', 04:47:27 INFO - 'TMOUT': '86400', 04:47:27 INFO - 'USER': 'cltbld', 04:47:27 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:27 INFO - '_': '/tools/buildbot/bin/python'} 04:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:28 INFO - Running setup.py (path:/tmp/pip-pGVxaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:28 INFO - Running setup.py (path:/tmp/pip-b4wCGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:28 INFO - Running setup.py (path:/tmp/pip-1PEPhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:28 INFO - Running setup.py (path:/tmp/pip-4k90nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:28 INFO - Running setup.py (path:/tmp/pip-Ts1K4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:28 INFO - Running setup.py (path:/tmp/pip-3o1Lwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:28 INFO - Running setup.py (path:/tmp/pip-rk0ZiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:28 INFO - Running setup.py (path:/tmp/pip-leaC1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:28 INFO - Running setup.py (path:/tmp/pip-wZqhfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:28 INFO - Running setup.py (path:/tmp/pip-wTAId8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:28 INFO - Running setup.py (path:/tmp/pip-Oh6GVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:28 INFO - Running setup.py (path:/tmp/pip-HER5Gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:29 INFO - Running setup.py (path:/tmp/pip-5wtX6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:29 INFO - Running setup.py (path:/tmp/pip-mfEiyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:29 INFO - Running setup.py (path:/tmp/pip-t6aJyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:29 INFO - Running setup.py (path:/tmp/pip-vvnDgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:29 INFO - Running setup.py (path:/tmp/pip-mWivZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:29 INFO - Running setup.py install for manifestparser 04:47:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:29 INFO - Running setup.py install for mozcrash 04:47:29 INFO - Running setup.py install for mozdebug 04:47:29 INFO - Running setup.py install for mozdevice 04:47:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:29 INFO - Running setup.py install for mozfile 04:47:29 INFO - Running setup.py install for mozhttpd 04:47:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Running setup.py install for mozinfo 04:47:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Running setup.py install for mozInstall 04:47:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Running setup.py install for mozleak 04:47:30 INFO - Running setup.py install for mozlog 04:47:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Running setup.py install for moznetwork 04:47:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Running setup.py install for mozprocess 04:47:30 INFO - Running setup.py install for mozprofile 04:47:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Running setup.py install for mozrunner 04:47:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Running setup.py install for mozscreenshot 04:47:30 INFO - Running setup.py install for moztest 04:47:31 INFO - Running setup.py install for mozversion 04:47:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:31 INFO - Cleaning up... 04:47:31 INFO - Return code: 0 04:47:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:31 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 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47:31 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 04:47:31 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 04:47:31 INFO - Using env: {'DISPLAY': ':0', 04:47:31 INFO - 'HOME': '/home/cltbld', 04:47:31 INFO - 'LANG': 'en_US.UTF-8', 04:47:31 INFO - 'LANGUAGE': 'en_US:en', 04:47:31 INFO - 'LOGNAME': 'cltbld', 04:47:31 INFO - 'MAIL': '/var/mail/cltbld', 04:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:31 INFO - 'MOZ_NO_REMOTE': '1', 04:47:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:31 INFO - 'NO_EM_RESTART': '1', 04:47:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:31 INFO - 'PWD': '/builds/slave/test', 04:47:31 INFO - 'SHELL': '/bin/bash', 04:47:31 INFO - 'SHLVL': '1', 04:47:31 INFO - 'TERM': 'linux', 04:47:31 INFO - 'TMOUT': '86400', 04:47:31 INFO - 'USER': 'cltbld', 04:47:31 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:31 INFO - '_': '/tools/buildbot/bin/python'} 04:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:31 INFO - Running setup.py (path:/tmp/pip-LzedgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:31 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)) 04:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:31 INFO - Running setup.py (path:/tmp/pip-DmgOxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:31 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)) 04:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:31 INFO - Running setup.py (path:/tmp/pip-NRasUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:31 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)) 04:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:31 INFO - Running setup.py (path:/tmp/pip-PUdDFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:31 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)) 04:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:31 INFO - Running setup.py (path:/tmp/pip-doFm7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:31 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)) 04:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:31 INFO - Running setup.py (path:/tmp/pip-4c7nPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:31 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)) 04:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:31 INFO - Running setup.py (path:/tmp/pip-DbQrjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:31 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)) 04:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:31 INFO - Running setup.py (path:/tmp/pip-meOFU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:32 INFO - Running setup.py (path:/tmp/pip-tbyZHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:32 INFO - Running setup.py (path:/tmp/pip-9VntQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:32 INFO - Running setup.py (path:/tmp/pip-TuUfXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:32 INFO - Running setup.py (path:/tmp/pip-Mq9CqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:32 INFO - Running setup.py (path:/tmp/pip-ys5JbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:32 INFO - Running setup.py (path:/tmp/pip-wNczc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:32 INFO - Running setup.py (path:/tmp/pip-FOlGGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:32 INFO - Running setup.py (path:/tmp/pip-fx9znt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:32 INFO - Running setup.py (path:/tmp/pip-uRcWxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:32 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)) 04:47:32 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)) 04:47:32 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)) 04:47:32 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)) 04:47:32 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)) 04:47:32 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)) 04:47:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:32 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 04:47:32 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 04:47:32 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 04:47:32 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 04:47:32 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 04:47:32 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 04:47:34 INFO - Downloading blessings-1.6.tar.gz 04:47:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:34 INFO - Installing collected packages: blessings 04:47:34 INFO - Running setup.py install for blessings 04:47:34 INFO - Successfully installed blessings 04:47:34 INFO - Cleaning up... 04:47:34 INFO - Return code: 0 04:47:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:35 INFO - Reading from file tmpfile_stdout 04:47:35 INFO - Current package versions: 04:47:35 INFO - argparse == 1.2.1 04:47:35 INFO - blessings == 1.6 04:47:35 INFO - blobuploader == 1.2.4 04:47:35 INFO - docopt == 0.6.1 04:47:35 INFO - functools32 == 3.2.3-2 04:47:35 INFO - jsonschema == 2.5.1 04:47:35 INFO - manifestparser == 1.1 04:47:35 INFO - mozInstall == 1.12 04:47:35 INFO - mozcrash == 1.0 04:47:35 INFO - mozdebug == 0.1 04:47:35 INFO - mozdevice == 0.49 04:47:35 INFO - mozfile == 1.2 04:47:35 INFO - mozhttpd == 0.7 04:47:35 INFO - mozinfo == 0.9 04:47:35 INFO - mozleak == 0.1 04:47:35 INFO - mozlog == 3.4 04:47:35 INFO - moznetwork == 0.27 04:47:35 INFO - mozprocess == 0.23 04:47:35 INFO - mozprofile == 0.28 04:47:35 INFO - mozrunner == 6.13 04:47:35 INFO - mozscreenshot == 0.1 04:47:35 INFO - mozsystemmonitor == 0.3 04:47:35 INFO - moztest == 0.7 04:47:35 INFO - mozversion == 1.4 04:47:35 INFO - psutil == 3.1.1 04:47:35 INFO - requests == 1.2.3 04:47:35 INFO - wsgiref == 0.1.2 04:47:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:35 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')]} 04:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:35 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')]} 04:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:35 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 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47:35 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 04:47:35 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 04:47:35 INFO - Using env: {'DISPLAY': ':0', 04:47:35 INFO - 'HOME': '/home/cltbld', 04:47:35 INFO - 'LANG': 'en_US.UTF-8', 04:47:35 INFO - 'LANGUAGE': 'en_US:en', 04:47:35 INFO - 'LOGNAME': 'cltbld', 04:47:35 INFO - 'MAIL': '/var/mail/cltbld', 04:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:35 INFO - 'MOZ_NO_REMOTE': '1', 04:47:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:35 INFO - 'NO_EM_RESTART': '1', 04:47:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:35 INFO - 'PWD': '/builds/slave/test', 04:47:35 INFO - 'SHELL': '/bin/bash', 04:47:35 INFO - 'SHLVL': '1', 04:47:35 INFO - 'TERM': 'linux', 04:47:35 INFO - 'TMOUT': '86400', 04:47:35 INFO - 'USER': 'cltbld', 04:47:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:35 INFO - '_': '/tools/buildbot/bin/python'} 04:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 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)) 04:47:35 INFO - Cleaning up... 04:47:35 INFO - Return code: 0 04:47:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47:35 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')]} 04:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:35 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')]} 04:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:35 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 0x20921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x225ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', '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 04:47:35 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 04:47:35 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 04:47:35 INFO - Using env: {'DISPLAY': ':0', 04:47:35 INFO - 'HOME': '/home/cltbld', 04:47:35 INFO - 'LANG': 'en_US.UTF-8', 04:47:35 INFO - 'LANGUAGE': 'en_US:en', 04:47:35 INFO - 'LOGNAME': 'cltbld', 04:47:35 INFO - 'MAIL': '/var/mail/cltbld', 04:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:35 INFO - 'MOZ_NO_REMOTE': '1', 04:47:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:35 INFO - 'NO_EM_RESTART': '1', 04:47:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:35 INFO - 'PWD': '/builds/slave/test', 04:47:35 INFO - 'SHELL': '/bin/bash', 04:47:35 INFO - 'SHLVL': '1', 04:47:35 INFO - 'TERM': 'linux', 04:47:35 INFO - 'TMOUT': '86400', 04:47:35 INFO - 'USER': 'cltbld', 04:47:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:35 INFO - '_': '/tools/buildbot/bin/python'} 04:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:47:35 INFO - Cleaning up... 04:47:35 INFO - Return code: 0 04:47:35 INFO - Running post-action listener: _resource_record_post_action 04:47:35 INFO - Running post-action listener: _start_resource_monitoring 04:47:35 INFO - Starting resource monitoring. 04:47:35 INFO - [mozharness: 2016-12-23 12:47:35.622935Z] Finished create-virtualenv step (success) 04:47:35 INFO - [mozharness: 2016-12-23 12:47:35.623418Z] Running install step. 04:47:35 INFO - Running pre-action listener: _resource_record_pre_action 04:47:35 INFO - Running main action method: install 04:47:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:35 INFO - Reading from file tmpfile_stdout 04:47:35 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:35 INFO - Reading from file tmpfile_stdout 04:47:35 INFO - Output received: 04:47:35 INFO - Usage: mozinstall [options] installer 04:47:35 INFO - Options: 04:47:35 INFO - -h, --help show this help message and exit 04:47:35 INFO - -d DEST, --destination=DEST 04:47:35 INFO - Directory to install application into. [default: 04:47:35 INFO - "/builds/slave/test"] 04:47:35 INFO - --app=APP Application being installed. [default: firefox] 04:47:35 INFO - mkdir: /builds/slave/test/build/application 04:47:35 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'] 04:47:35 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 04:47:53 INFO - Reading from file tmpfile_stdout 04:47:53 INFO - Output received: 04:47:53 INFO - /builds/slave/test/build/application/firefox/firefox 04:47:53 INFO - Running post-action listener: _resource_record_post_action 04:47:53 INFO - [mozharness: 2016-12-23 12:47:53.264383Z] Finished install step (success) 04:47:53 INFO - [mozharness: 2016-12-23 12:47:53.264629Z] Running run-tests step. 04:47:53 INFO - Running pre-action listener: _resource_record_pre_action 04:47:53 INFO - Running main action method: run_tests 04:47:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:47:53 INFO - Python 2.7.3 04:47:53 INFO - Return code: 0 04:47:53 INFO - grabbing minidump binary from tooltool 04:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:53 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 0x24013f0>, '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 0x2401cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:47:53 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 04:47:53 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 04:47:53 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 04:47:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:47:53 INFO - Return code: 0 04:47:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:47:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:47:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:47:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:47:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:47:53 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/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 04:47:53 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/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 04:47:53 INFO - Using env: {'DISPLAY': ':0', 04:47:53 INFO - 'HOME': '/home/cltbld', 04:47:53 INFO - 'LANG': 'en_US.UTF-8', 04:47:53 INFO - 'LANGUAGE': 'en_US:en', 04:47:53 INFO - 'LOGNAME': 'cltbld', 04:47:53 INFO - 'MAIL': '/var/mail/cltbld', 04:47:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:47:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:53 INFO - 'MOZ_NO_REMOTE': '1', 04:47:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:47:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:53 INFO - 'NO_EM_RESTART': '1', 04:47:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:53 INFO - 'PWD': '/builds/slave/test', 04:47:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:47:53 INFO - 'SHELL': '/bin/bash', 04:47:53 INFO - 'SHLVL': '1', 04:47:53 INFO - 'TERM': 'linux', 04:47:53 INFO - 'TMOUT': '86400', 04:47:53 INFO - 'USER': 'cltbld', 04:47:53 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566', 04:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:53 INFO - '_': '/tools/buildbot/bin/python'} 04:47:53 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/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 04:47:53 INFO - mozversion application_buildid: 20161223030226 04:47:53 INFO - mozversion application_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 04:47:53 INFO - mozversion application_display_name: Nightly 04:47:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:47:53 INFO - mozversion application_name: Firefox 04:47:53 INFO - mozversion application_remotingname: firefox 04:47:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:47:53 INFO - mozversion application_vendor: Mozilla 04:47:53 INFO - mozversion application_version: 53.0a1 04:47:53 INFO - mozversion platform_buildid: 20161223030226 04:47:53 INFO - mozversion platform_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 04:47:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:47:53 INFO - mozversion platform_version: 53.0a1 04:47:53 INFO - using testdate: 1482497273 04:47:53 INFO - actual date: 1482497273 04:47:53 INFO - starting webserver on 'localhost:48025' 04:47:53 INFO - SUITE-START | Running 2 tests 04:47:53 INFO - TEST-START | dromaeo_css 04:47:53 INFO - operating with platform_type : linux_ 04:47:53 INFO - Initialising browser for dromaeo_css test... 04:47:53 INFO - TEST-INFO | started process 4051 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMnYjdc/profile http://localhost:48025/getInfo.html) 04:47:53 INFO - PROCESS | 4051 | 1482497273992 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 04:47:56 INFO - PROCESS | 4051 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 04:47:56 INFO - PROCESS | 4051 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 04:47:57 INFO - PROCESS | 4051 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 04:47:57 INFO - PROCESS | 4051 | __metrics Screen width/height:1600/1200 04:47:57 INFO - PROCESS | 4051 | colorDepth:24 04:47:57 INFO - PROCESS | 4051 | Browser inner width/height: 1024/697 04:47:57 INFO - PROCESS | 4051 | __metrics 04:47:58 INFO - PROCESS | 4051 | 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" 04:47:58 INFO - PROCESS | 4051 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:47:58 INFO - PROCESS | 4051 | console.error: PushService: 04:47:58 INFO - PROCESS | 4051 | stateChangeProcessEnqueue: Error transitioning state 04:47:58 INFO - PROCESS | 4051 | UnknownError 04:47:59 INFO - TEST-INFO | 4051: exit 0 04:47:59 INFO - Browser initialized. 04:47:59 INFO - Running cycle 1/1 for dromaeo_css test... 04:47:59 INFO - TEST-INFO | started process 4136 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMnYjdc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:11 INFO - PROCESS | 4136 | RSS: Main: 147283968 04:48:11 INFO - PROCESS | 4136 | 04:49:02 INFO - PROCESS | 4136 | 1482497342897 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] 04:51:13 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:48025/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48025/tests/dromaeo/cssquery-ext.html) 04:51:14 INFO - PROCESS | 4136 | RSS: Main: 275296256 04:51:14 INFO - PROCESS | 4136 | 04:53:00 INFO - PROCESS | 4136 | ************************* 04:53:00 INFO - PROCESS | 4136 | A coding exception was thrown and uncaught in a Task. 04:53:00 INFO - PROCESS | 4136 | 04:53:00 INFO - PROCESS | 4136 | Full message: ReferenceError: fetch is not defined 04:53:00 INFO - PROCESS | 4136 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:53:00 INFO - PROCESS | 4136 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:53:00 INFO - PROCESS | 4136 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:53:00 INFO - PROCESS | 4136 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:53:00 INFO - PROCESS | 4136 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:53:00 INFO - PROCESS | 4136 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:53:00 INFO - PROCESS | 4136 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:53:00 INFO - PROCESS | 4136 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:53:00 INFO - PROCESS | 4136 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:53:00 INFO - PROCESS | 4136 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:53:00 INFO - PROCESS | 4136 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:53:00 INFO - PROCESS | 4136 | 04:53:00 INFO - PROCESS | 4136 | ************************* 04:54:16 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:48025/tests/dromaeo/cssquery-ext.html (next: http://localhost:48025/tests/dromaeo/cssquery-jquery.html) 04:54:17 INFO - PROCESS | 4136 | RSS: Main: 250351616 04:54:17 INFO - PROCESS | 4136 | 04:57:19 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:48025/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48025/tests/dromaeo/cssquery-mootools.html) 04:57:19 INFO - PROCESS | 4136 | RSS: Main: 191557632 04:57:19 INFO - PROCESS | 4136 | 05:00:21 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:48025/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48025/tests/dromaeo/cssquery-prototype.html) 05:00:22 INFO - PROCESS | 4136 | RSS: Main: 210317312 05:00:22 INFO - PROCESS | 4136 | 05:03:24 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:48025/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48025/tests/dromaeo/cssquery-yui.html) 05:03:25 INFO - PROCESS | 4136 | RSS: Main: 195751936 05:03:25 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | Cycle 1(1): loaded http://localhost:48025/tests/dromaeo/cssquery-yui.html (next: http://localhost:48025/tests/dromaeo/cssquery-dojo.html) 05:06:28 INFO - PROCESS | 4136 | RSS: Main: 280739840 05:06:28 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | __start_tp_report 05:06:28 INFO - PROCESS | 4136 | _x_x_mozilla_page_load 05:06:28 INFO - PROCESS | 4136 | _x_x_mozilla_page_load_details 05:06:28 INFO - PROCESS | 4136 | |i|pagename|runs| 05:06:28 INFO - PROCESS | 4136 | |0;dojo.html;3105.2857842696176;3192.0583427888755;3231.5798946137006;3233.030090972709;3233.7413006959446;5263.815766448175;5342.946570534294;5360.544353729928;5413.810516631914;5481;5411.756470958807;5435.211894275328;5437.564994800417;5456.045192091388;5488.094464413367;4890.266460030988;4945.9258111128365;4950.950490495094;4976.975115124419;5014.373203349582;4914.852554423373;5003.549680528752;5005.424376196747;5052.747362631853;5104.157813960692;4812.422509298872;5022.4726403727445;5034.194528875362;5079.898402031957;5091.414487333967;4857.635677324215;4870.14772414826;4900.58345040672;4925.852224433273;4972.353594032788;4910.435299940516;4911.729854857981;4942.283368911488;4944.505549445063;4974.502549745032;5386.865328366783;5423.484768946983;5426.5116139547235;5429.321334833146;5455.645383050129;5671.546276297928;5713.314402271754;5762.193292938992;5795.043817770105;5894.911576326359;5906.26171728534;5910.349861515273;5920.082387229986;5923.881522369572;5967.6419414835245;5477.5070243677865;5477.698726570077;5481.451854814526;5481.862953426196;5490.6431081979545;5759.308882933991;5798.333191682926;5838.649681019152;6003.969980150071;6008.609440386361;5651.943480565224;5700.201971723962;5715.199872017921;5753.280839895013;5762.135679648002;5733.541316694655;5739.483446489752;5790.565707571949;5791.131330300489;5806.506446952057;5668.319801623831;5721;5721.971390143106;5752.539796816244;5756.1653560233535;5696.829095127153;5730.942690573124;5737.483626473638;5797.2173756542325;5824.271966004249;4344.066025804467;4349.630281426051;4350.6736994725525;4354.238008348526;4385.890352741143;3823.713221508398;3868.980655096763;3874.9806250969127;3908.335582950848;3923.803809809498;5417.295751552273;5426.240326354314;5488.835334939959;5496.780128794883;5497.312835895513;5750.338711048199;5792.652440853562;5796.478316951495;5801.071828507418;5811.360750317462;5489.533389661923;5492.505674489315;5492.80775172867;5494.203340515683;5497.725113744297;4812.422509298906;4813.181759100961;4820.517948205222;4823.1800593899125;4823.204171311764;6004.639721616717;6032.758689652453;6043.425874541911;6084.23947006633;6134.171886795315;5780;5804;5805.274340707411;5812.5349972001295;5819.098039803837;5896.410358964069;6056.36408177135;6059.182010428536;6070.939290607214;6072.635641861502;5627.521660658726;5689.77240910359;5706.087026075808;5714.742836572281;5835.7373918174635;5821.068629019336;5822.592418530662;5838.970805145947;5843.357230704704;5849.912251316318;5682.147677848356;5687.971560142173;5692.31692196939;5694.117411801178;5698.430156984434;5528.834134975958;5579.330480342385;5585.609007369445;5621.747021383966;5631.8592035199445;5656.236408084856;5661.575381846295;5661.631993920382;5674.092145256739;5705.515031222392;5704.229928959538;5704.629199102045;5713.514351280021;5716.314042314949;5716.885662286648;5413.945860541344;5440.972795135999;5508.862278442911;5540.7783688652;5554.777808887761 05:06:28 INFO - PROCESS | 4136 | |1;ext.html;533.5704757670076;576.2652617912162;579.2730123694762;579.3974266762567;590.8877313310468;33069.503957440655;36644.450333245026;40258.19483610326;42274.15451690964;42474.57525424745;44784.32823507642;45658;45672.77163614177;45764.08471830561;45771;8915.554222288893;9042.140996605312;9058;9087.182153606174;9098.590563424661;8555.443896146762;8758.255548278405;10374.53314600841;10615.416152111631;10715.142788576895;10831.404272764994;10899.455027248645;10900.563977440894;10905.83641245378;10912.181586381013;10753.946230268877;10775.299605525615;10806.837897431546;10812.080973117294;10843.295185812896;10421.583136674524;10586.41774702391;10602.57589696419;10605.204609654229;10611;41667.37498937518;41695;41826.58173418288;41917.161656767;42123.15753684909;3454.2573346730323;3758.8872333830027;4054.3107671695884;4068.9389659155136;4135;32582.511262331085;32868.67131328704;32898.17754556131;32954;33418.665813341795;4074.9796251018556;4084.9183016339507;4092.774897380643;4134.627883490501;4154.543000269968;222098;222639.32082037564;222821;224100;226973;58003.70998144982;58029.419705803244;58292.417075829544;58309;58368.4163158363;59964.40035599588;60201.397986019576;60269.397306027255;60337.39662603405;60443.69778151169;15652.530424087068;16161.4343497977;16210.75683864743;16212.918935405247;16232.675346493004;11048.779024419466;11438.027767639678;12566.623001309976;12625.305608191638;12633.557825476064;16177.272022759003;16324.591885202964;16725.16374181286;17020;17074;6447.5505604502305;6790.456763458911;6924.861502769916;6939.271376505394;6970.546914450646;880.4585180114184;888.5290795878195;905.3707673167086;1112.710695219249;1129.1305694615264;31143.68856311453;31409.685903141133;31478.37043259122;31594.526082108787;31852.681473185436;1022.192467950311;1062.8618279623754;1076.05844885725;1101.6639924822846;1124.4434005167332;15461.768073478906;16478.588035298973;16544.917275413547;16565.08892010927;16808.495745127664;440788;492562;502635;502676.8126231202;502948;55027.724861376235;57357;57990.420095799345;58211.4178858206;64601.309803529846;1373.5535950816025;1383.4673650644575;1384.3078460769616;1384.473899918045;1410.0975375759522;30651.386972260876;33106.83446582752;33234.66765332316;33417.66582334243;34020.14949626279;52117;52717.73641131923;52763.47236527739;52836.73581632067;52898.47101528935;409668;410327;410710;411512;413319;28093.57859632066;28205.71794282031;28724.281892952844;28724.856375717987;28737.69516505545;46966.53033469622;47277.29084063673;47502.76248618735;48246;48642.5135748638;39847;40552.797236013634;40996;41170;42076;39960.600393995686;40314.19371612493;41246.6314003396;41469.79265103776;42211.78894105633;41053.589464104974;41355.58644413517;41445;42025.579744202165;42044.36933446062;33449.49825752664;35856;36189.457158143174;36569;36798.63201368059;27487.45025099527;27518.449631006868;27814.44371112607;28097.719022810328;28124.296892577848 05:06:28 INFO - PROCESS | 4136 | |2;jquery.html;384.85952627291044;401.67214626816826;406.69091490467247;408.5036680433273;431.3917376499137;13655;15518.534443966684;18362.265509379642;19016.429507114775;19162;17469.82530174698;17959.102044897736;18952.431427057178;19480.805191948075;19490.415287541397;4581.702189357681;5217.947820521784;5367.355917289911;5371.355437347524;5374.220737992989;5288.947110528884;5369.543588794957;5377.381601115863;5377.435369286227;5378.46215378463;5387.326584176978;5387.78448862045;5391.81128660497;5392.649477783951;5403.162509810985;5732.914006289909;5743.195952566623;5774.07614781635;5776.393478684719;5780.739866706008;5088.821891233826;5247.1866860636665;5247.816326428595;5250.44870288618;5321.547668448186;13723.941408788762;13924.860751392458;13958.302084895715;13959.511417100353;21178.470538236514;2153.6231159547015;2306.0545176477563;2350.753170917046;2727.3045373429845;2945.910013295073;9583.712488625353;10174.949125254327;10244.795104097877;10338.0695737384;10602.946985265102;1864.3381599532242;1939.059556115283;1946.240966023252;1948.7953764854628;1989.19437627761;73579;74155;80490;81067.5946620269;82219;10419.95800419952;10559.577616895238;10752.946235268773;10782.67651970442;11480.770384592428;14627;14642.707145857023;14651.633709157357;14678.339474723663;14699.26503674812;7629.2752188542;8028.839423211503;8067.112617612058;8204.097549269576;8228.794280142925;7624.504407213513;7711.344535714415;7765.378769698411;7867.803304917423;7895.842083158304;4556.453225612948;4598.195315819785;4626.306054091913;4633.189191891473;4703.717776933327;1924.191839427444;1955.5013471564944;1993.3421970749907;2030.1879248300502;2030.3807338761524;893.5353616119581;911.462237280008;933.9813203735887;961.7643677298967;967.4872317671645;17480.21339039746;18618.81381186198;18828.717569236473;18946;19052.428427147206;926.6339795780764;933.9439633622004;937.2080591899834;960.1037950048598;977.745786095606;10145.137663298703;10582.576696932036;10712;10715.892841071645;10763;395472;399838;408260;411073;412946;55743.721281394144;67958.8837004662;78269;80590.5970470144;81619.5919020413;992.1318846009885;1025.8922813104516;1044.5090807320698;1048.7483004078965;1052.2949623752104;16237.269322880262;16632.3347066116;17053.744193837352;17070.317187312867;17295.827041729422;36859.44710829286;38303.808480957414;38783.612163878;39013.609863900994;39196.216075678894;51915;52807.735961319944;53186.2022069677;53892.46107539031;56146.71926640341;8439.662413503636;8444.184500332458;8509.106543813057;8690.956545217234;8717.171868672465;12383.75232495363;12670.239785612892;12677.492900284255;12718;12822;10902.945485272523;10923.563057477611;11112.722181945515;11258.268212566485;11505.942470287595;10930.180236482136;11063.612773552886;11074.833877491683;11156.163287753614;11575.479103440533;11069.335839849635;11130.276532025393;11140.721481963015;11345.262557933707;11704.648860534198;8085.434019618806;8158.632861521127;8378.03652579961;8389.622466989122;8512.787180320542;10484.161267098767;11172.441377931233;11179.441027948733;11206.607768728381;11608.88391116078 05:06:28 INFO - PROCESS | 4136 | |3;mootools.html;1428;1474.1744623011111;1610.339760698114;1772.804991450941;1791.8476929460987;8084.231997960192;8608.698695545658;8610.956945215281;8621.956890215555;8637.22264996151;7493.138289096755;7769.3784497240085;8140.3487720982175;8338.374621903351;8349.29031032363;2350.5063936573342;2373.276150774022;2374.845635033726;2380.8809559521956;2383.0467812874817;2310.8266879983985;2346.343023953296;2379.238643634038;2384.892679829412;2395.3532546212514;2012.3258708332726;2079.1371580793952;2246.213825161197;2322.6632138339933;2328.057136859566;2341.7775311345395;2345.870977096259;2360.468894498741;2365.1367250953363;2368.39605900496;2334.0663734506334;2348.518553696488;2367.32531228599;2367.7277113132036;2369.822263330257;8555.23002929733;8630.050694423608;8653.610587523577;8664.480131192086;8682.652693892236;5132.332796736438;5285.788568457296;5301.867453313698;5305.310309659758;5308.097623404031;3298.950515742266;3462.3248466549035;3468.7814628001393;3555.964440355589;3574.6782789548806;1116.447358557511;1126.189143816451;2059.9176032958662;2089.540301133748;2117.830573554112;11663.59177428786;11683.649490515298;11729.12031597625;11744.94127529357;11752.118591105616;4254.680898932593;4351.434313539283;4457.487388950248;4463.709858859229;4512.2329204035395;9407;9422.34043616954;9427.198688111588;9429.811403771886;9430.528473576294;5045.01622183678;5113.8721531962;5115.437301896788;5116.718580478109;5119.539241468211;4723.4331880174595;4760.880977975509;4780.11567859947;4788.832390866303;4796.208625576811;3473.4095203815414;3508.754387192923;3519.982400087983;3649.489071529988;3670.724695647837;1107.7064577886867;1110.8222684370462;1115.8549388579434;1133.4049623947328;1158.1892675127444;772.3777774455691;853.3599800149888;893.6157452295446;913.2602591900582;916.2303664921365;5523.28197334352;5590.832275031674;5640.153976903497;5737.08206686928;5803.332616749043;868.8001759595328;886.3883920095113;908.8273228086774;919.5540163020929;955.4506158958627;3672.779633222015;3887.6501114899793;3942.1918506706056;4034.798260086984;4105.527864295645;9845.803083938425;10438.791224175475;10501.894981050245;10520.842187367196;10522.263441559018;10201.846972295421;10335.121514671337;10361.533730982124;10426.530806113744;10429.269951103503;7934.246246606564;8039.276465118168;8067.919320806835;8080.595970201466;8087.272145506932;5502.752376143003;5517.310336207974;5521.69630670317;5548.445155484581;5672.290963629546;9433.103855133751;9529.666461673809;9546.236301095756;9623.470709111067;9645.228381729305;9466.668666596635;9700.563474643799;9874.753131171778;9885.308028437941;10014.449205293779;7114.253003434565;7191.8561628768175;7197.352238298578;7198.8920166198595;7207.639618018973;8187.181281872004;8251.711190108317;8258.793530161794;8267.545285009142;8272.255497005299;7102.18324892644;7125.928740712527;7258.383037441877;7313.48805583604;7463.589502577411;7274.599897005715;7439.181690014202;7443.330100290783;7480.102387713509;7682.7311044113185;6647.401733843977;6770.390664840188;6772.9661351694895;6778.491613129134;6779.220389655253;4725.220338644085;4863.465018847854;4873.707577545359;5044.9243261350375;5125.333706618265;4503.211937910918;4507.6168525675685;4611.79246933882;4722.740249286322;4787.042591481787 05:06:28 INFO - PROCESS | 4136 | |4;prototype.html;377;377.99622003779956;384.51935081148565;394.84995701633386;396.1640937621619;5104.566111880495;5177.378714554249;5200.167973124301;5271.522384479298;5311.681299122055;5060.746962651871;5224.3730752309775;5245.501677340647;5253.4221235664145;5273.446288139738;4163.7293575917465;4178.770167640779;4195.391668208108;4215.873523794276;4216.325387937931;4100.282450571153;4172.979135104335;4194.559571245021;4234.682398820086;4243.745375277478;4137.834486620547;4172.7287726297845;4175.728577642459;4199.874003779892;4207.380158638915;4146.232946904832;4151.294279972412;4157.459530261077;4169.937450938223;4178.35235538492;3791.526059242595;3791.6777073948747;3794.829232684508;3809.7904615246152;3839.8272077756287;4990.30135780991;5030.647854650176;5053.747312634354;5069.417017043013;5097.133487307166;4742.5731684148595;4793.233082706785;4826.1795494765975;4850.029994001214;4870.6103511718975;5356.812511562077;5463.426340234298;5473.781048758045;5484.862878428032;5504.6421982571;4148.543660197376;4153.273177194009;4177.373393990895;4194.161167766459;4195.202911446815;5623.353314368859;5661.820199223329;5670.419958918682;5673.23411339468;5684.971575142139;4260.339647354665;4295.871123866289;4317.309230523101;4322.891927701832;4344.717593356421;4190.224808410454;4318.3090705486975;4335.284678028153;4360.738355698668;4367.650587952956;4741.95258047422;4795.352627395327;4815.903681926412;4819.795051237191;4829.1307564638;5419.3225846769155;5487.5884308677005;5494.945050549443;5503.284573005563;5516.448355164452;1392.1229625335973;1446.8987170897935;1466.2815220541859;1467.2810322941896;1479.6670749081586;1022.7164908040362;1043.645160645369;1053.5417093564172;1063.2557209953063;1063.4842101580728;5109.514596113363;5148.433672296044;5163.199704045879;5167.819126330561;5177.637565370464;5401.3248343957;5405.351357837085;5436.891262174734;5437.356970829554;5452.781888724407;4984.6261530384645;5106.923396149061;5119.616028797781;5135.306733590992;5148;2827.844468554208;2836.4468928559145;2865.4842128417085;2871.8851245949927;2886.336142687194;5552.222688823568;5566.693831839207;5587.608867379245;5606.383297837235;5653.23681303027;5243.580513558906;5400.351957765093;5409.998300628792;5495.296458098002;5500.614956952975;5607;5607.719614019201;5614.550835933197;5617.241672374259;5620.634658747168;5153.922691159561;5381.811636592701;5390.676559406448;5435.864103397446;5439.5104440600535;5284.77628646533;5316.388615309289;5322.201669749568;5330.8538664185835;5358.276632654698;5600.215969764317;5607.691576963306;5614.63504872182;5646.520045796154;5646.717664116695;4012.6589239914933;4089.8773036808943;4125.711200215956;4169.249535083714;4174.1443004184675;5314.415414304501;5327.227552005015;5327.573794096541;5329.854081372523;5331.3868905076415;5210.843674689765;5261.894762104813;5272.446393128666;5279.52484276417;5287.89424211506;5178.378594568676;5254.710990895537;5274.789008439773;5306.204069389622;5322.441143679858;5220.530152286313;5221.295125158055;5232.979568984164;5275.860862786636;5276.4886022450255;5148;5230.607704422107;5239.214117882196;5260.89478210426;5275.839315350617;4869.6591238612955;5114.133341330314;5119.718415487184;5127.333446651912;5141.948580514297 05:06:28 INFO - PROCESS | 4136 | |5;yui.html;698.231944860653;723.7068987060236;767.6507189228902;809.3403875841188;841.4572600672566;15871.99999999997;15957.84042159578;16555.420560280392;16580.585485362873;16600.833991660078;13260.734785304288;14862.851371486282;15199.77200341993;15221.467248646299;15226.619334516643;807.7859367267679;837.271573730852;837.6440012994483;838.1995194589152;838.4591938199858;834.453433001382;837.2422957223722;838.8070743728946;838.8280402517499;838.9077201507829;862.6980556805131;874.6894852327428;875.3566128895256;875.6759998800453;875.7022612311813;864.0236532717988;865.4634126841398;872.110447343709;872.5506364222399;872.5898827551073;636.7675798333621;642.1683919324486;642.3480167629859;642.9389208025277;642.9646369449679;15431;15584.844151558453;16183.595410114724;16258.837411625851;16481.17594120289;14214.715705685932;14227.14637121776;14521;14546.781798273034;14673;11913.166078374517;12152.878471215263;12218.389080546027;12374.319412432313;12389.876101238962;3693.3351996640595;3736.532933383327;3753.0617345663586;3802.714796390254;3852.5376954765607;48844.51155488435;55479.44520554783;56535;63328.366716332705;69693.30306696969;11639.47622356979;11681.591144309908;11764.94117529424;11773.705657358463;11991.400429978641;21910;22375.328740137822;22517.549649006927;22528.436789080075;22530.887345563166;8203.179682031749;8363.20549547792;8430.662773488992;8659.523726194993;8685.869711954325;4230.175115852441;4251.702380833374;4329.437173167531;4355.891102722458;4469.195544801902;3358.596968363812;3506.1760486285702;3511.385507536222;3513.2446523997464;3515.472679098156;629.1475051305507;631.5231999840091;633.7053270229366;642.8360768004129;653.0922018394435;674.8346655069441;703.3318347569829;717.6196615793638;726.4079774983371;729.0376702752422;9108.316876234308;9319.90680093204;9331.906680933103;9333.253339732804;9460.148586627236;677.3734295776387;689.7930620813736;703.114076263901;723.2911746488471;723.9384652304614;4366.978165109154;4602.4332986098625;4646.140464014168;4692.41344831896;4711.552402521755;77992;79723.55066488778;81203.5939820297;81865.18134818695;83332;70590;71177.28822711707;72147.27852721511;72592;73331;62197;63329;65150;66974.66512667405;69180.6540967292;8775.210231079234;8814;8865.955670221607;9054.275657947217;9055.139761722627;42786.14427711366;43328;43629.78185109054;44089.559104409826;44476.777616111714;52249.6400575906;56265.71867140638;59135.704321478115;59719;60636.393636063076;11475.540978360772;11517;11716.882831171579;11828.40857957116;12643;13154.276514791778;13177.802332965204;13863.514776982756;14072.78890816659;14206.147631142165;10811.135109191404;10872.293300935415;10973.890261097287;11008.724781880517;11257.380844053656;11240.437978101227;11556.479958401724;11582.42087895585;11962.880371196525;12141.57504487374;12001.339926304137;12443.253404795742;12470.750584988431;12554;12733.681657958625;10435.895641043493;10977.506012229629;11066.39134847591;11148.386838723947;11309.491072901574;10431.426271555138;10469.26715129964;10587.89412105869;10680.252382333472;10972.945135274593 05:06:28 INFO - PROCESS | 4136 | __end_tp_report 05:06:28 INFO - PROCESS | 4136 | __start_cc_report 05:06:28 INFO - PROCESS | 4136 | _x_x_mozilla_cycle_collect,1165 05:06:28 INFO - PROCESS | 4136 | __end_cc_report 05:06:28 INFO - PROCESS | 4136 | __startTimestamp1482498388407__endTimestamp 05:06:28 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | ------- Summary: start ------- 05:06:28 INFO - PROCESS | 4136 | Number of tests: 6 05:06:28 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | [#0] dojo.html Cycles:165 Average:5384.70 Median:5585.61 stddev:616.72 (11.0%) stddev-sans-first:592.12 05:06:28 INFO - PROCESS | 4136 | Values: 3105.3 3192.1 3231.6 3233.0 3233.7 5263.8 5342.9 5360.5 5413.8 5481.0 5411.8 5435.2 5437.6 5456.0 5488.1 4890.3 4945.9 4951.0 4977.0 5014.4 4914.9 5003.5 5005.4 5052.7 5104.2 4812.4 5022.5 5034.2 5079.9 5091.4 4857.6 4870.1 4900.6 4925.9 4972.4 4910.4 4911.7 4942.3 4944.5 4974.5 5386.9 5423.5 5426.5 5429.3 5455.6 5671.5 5713.3 5762.2 5795.0 5894.9 5906.3 5910.3 5920.1 5923.9 5967.6 5477.5 5477.7 5481.5 5481.9 5490.6 5759.3 5798.3 5838.6 6004.0 6008.6 5651.9 5700.2 5715.2 5753.3 5762.1 5733.5 5739.5 5790.6 5791.1 5806.5 5668.3 5721.0 5722.0 5752.5 5756.2 5696.8 5730.9 5737.5 5797.2 5824.3 4344.1 4349.6 4350.7 4354.2 4385.9 3823.7 3869.0 3875.0 3908.3 3923.8 5417.3 5426.2 5488.8 5496.8 5497.3 5750.3 5792.7 5796.5 5801.1 5811.4 5489.5 5492.5 5492.8 5494.2 5497.7 4812.4 4813.2 4820.5 4823.2 4823.2 6004.6 6032.8 6043.4 6084.2 6134.2 5780.0 5804.0 5805.3 5812.5 5819.1 5896.4 6056.4 6059.2 6070.9 6072.6 5627.5 5689.8 5706.1 5714.7 5835.7 5821.1 5822.6 5839.0 5843.4 5849.9 5682.1 5688.0 5692.3 5694.1 5698.4 5528.8 5579.3 5585.6 5621.7 5631.9 5656.2 5661.6 5661.6 5674.1 5705.5 5704.2 5704.6 5713.5 5716.3 5716.9 5413.9 5441.0 5508.9 5540.8 5554.8 05:06:28 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | [#1] ext.html Cycles:180 Average:54751.00 Median:40109.40 stddev:103532.27 (258.1%) stddev-sans-first:103742.68 05:06:28 INFO - PROCESS | 4136 | Values: 533.6 576.3 579.3 579.4 590.9 33069.5 36644.5 40258.2 42274.2 42474.6 44784.3 45658.0 45672.8 45764.1 45771.0 8915.6 9042.1 9058.0 9087.2 9098.6 8555.4 8758.3 10374.5 10615.4 10715.1 10831.4 10899.5 10900.6 10905.8 10912.2 10753.9 10775.3 10806.8 10812.1 10843.3 10421.6 10586.4 10602.6 10605.2 10611.0 41667.4 41695.0 41826.6 41917.2 42123.2 3454.3 3758.9 4054.3 4068.9 4135.0 32582.5 32868.7 32898.2 32954.0 33418.7 4075.0 4084.9 4092.8 4134.6 4154.5 222098.0 222639.3 222821.0 224100.0 226973.0 58003.7 58029.4 58292.4 58309.0 58368.4 59964.4 60201.4 60269.4 60337.4 60443.7 15652.5 16161.4 16210.8 16212.9 16232.7 11048.8 11438.0 12566.6 12625.3 12633.6 16177.3 16324.6 16725.2 17020.0 17074.0 6447.6 6790.5 6924.9 6939.3 6970.5 880.5 888.5 905.4 1112.7 1129.1 31143.7 31409.7 31478.4 31594.5 31852.7 1022.2 1062.9 1076.1 1101.7 1124.4 15461.8 16478.6 16544.9 16565.1 16808.5 440788.0 492562.0 502635.0 502676.8 502948.0 55027.7 57357.0 57990.4 58211.4 64601.3 1373.6 1383.5 1384.3 1384.5 1410.1 30651.4 33106.8 33234.7 33417.7 34020.1 52117.0 52717.7 52763.5 52836.7 52898.5 409668.0 410327.0 410710.0 411512.0 413319.0 28093.6 28205.7 28724.3 28724.9 28737.7 46966.5 47277.3 47502.8 48246.0 48642.5 39847.0 40552.8 40996.0 41170.0 42076.0 39960.6 40314.2 41246.6 41469.8 42211.8 41053.6 41355.6 41445.0 42025.6 42044.4 33449.5 35856.0 36189.5 36569.0 36798.6 27487.5 27518.4 27814.4 28097.7 28124.3 05:06:28 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | [#2] jquery.html Cycles:180 Average:25356.62 Median:19584.24 stddev:66940.81 (341.8%) stddev-sans-first:67102.34 05:06:28 INFO - PROCESS | 4136 | Values: 384.9 401.7 406.7 408.5 431.4 13655.0 15518.5 18362.3 19016.4 19162.0 17469.8 17959.1 18952.4 19480.8 19490.4 4581.7 5217.9 5367.4 5371.4 5374.2 5288.9 5369.5 5377.4 5377.4 5378.5 5387.3 5387.8 5391.8 5392.6 5403.2 5732.9 5743.2 5774.1 5776.4 5780.7 5088.8 5247.2 5247.8 5250.4 5321.5 13723.9 13924.9 13958.3 13959.5 21178.5 2153.6 2306.1 2350.8 2727.3 2945.9 9583.7 10174.9 10244.8 10338.1 10602.9 1864.3 1939.1 1946.2 1948.8 1989.2 73579.0 74155.0 80490.0 81067.6 82219.0 10420.0 10559.6 10752.9 10782.7 11480.8 14627.0 14642.7 14651.6 14678.3 14699.3 7629.3 8028.8 8067.1 8204.1 8228.8 7624.5 7711.3 7765.4 7867.8 7895.8 4556.5 4598.2 4626.3 4633.2 4703.7 1924.2 1955.5 1993.3 2030.2 2030.4 893.5 911.5 934.0 961.8 967.5 17480.2 18618.8 18828.7 18946.0 19052.4 926.6 933.9 937.2 960.1 977.7 10145.1 10582.6 10712.0 10715.9 10763.0 395472.0 399838.0 408260.0 411073.0 412946.0 55743.7 67958.9 78269.0 80590.6 81619.6 992.1 1025.9 1044.5 1048.7 1052.3 16237.3 16632.3 17053.7 17070.3 17295.8 36859.4 38303.8 38783.6 39013.6 39196.2 51915.0 52807.7 53186.2 53892.5 56146.7 8439.7 8444.2 8509.1 8691.0 8717.2 12383.8 12670.2 12677.5 12718.0 12822.0 10902.9 10923.6 11112.7 11258.3 11505.9 10930.2 11063.6 11074.8 11156.2 11575.5 11069.3 11130.3 11140.7 11345.3 11704.6 8085.4 8158.6 8378.0 8389.6 8512.8 10484.2 11172.4 11179.4 11206.6 11608.9 05:06:28 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | [#3] mootools.html Cycles:180 Average:5568.01 Median:5114.65 stddev:3090.91 (60.4%) stddev-sans-first:3083.92 05:06:28 INFO - PROCESS | 4136 | Values: 1428.0 1474.2 1610.3 1772.8 1791.8 8084.2 8608.7 8611.0 8622.0 8637.2 7493.1 7769.4 8140.3 8338.4 8349.3 2350.5 2373.3 2374.8 2380.9 2383.0 2310.8 2346.3 2379.2 2384.9 2395.4 2012.3 2079.1 2246.2 2322.7 2328.1 2341.8 2345.9 2360.5 2365.1 2368.4 2334.1 2348.5 2367.3 2367.7 2369.8 8555.2 8630.1 8653.6 8664.5 8682.7 5132.3 5285.8 5301.9 5305.3 5308.1 3299.0 3462.3 3468.8 3556.0 3574.7 1116.4 1126.2 2059.9 2089.5 2117.8 11663.6 11683.6 11729.1 11744.9 11752.1 4254.7 4351.4 4457.5 4463.7 4512.2 9407.0 9422.3 9427.2 9429.8 9430.5 5045.0 5113.9 5115.4 5116.7 5119.5 4723.4 4760.9 4780.1 4788.8 4796.2 3473.4 3508.8 3520.0 3649.5 3670.7 1107.7 1110.8 1115.9 1133.4 1158.2 772.4 853.4 893.6 913.3 916.2 5523.3 5590.8 5640.2 5737.1 5803.3 868.8 886.4 908.8 919.6 955.5 3672.8 3887.7 3942.2 4034.8 4105.5 9845.8 10438.8 10501.9 10520.8 10522.3 10201.8 10335.1 10361.5 10426.5 10429.3 7934.2 8039.3 8067.9 8080.6 8087.3 5502.8 5517.3 5521.7 5548.4 5672.3 9433.1 9529.7 9546.2 9623.5 9645.2 9466.7 9700.6 9874.8 9885.3 10014.4 7114.3 7191.9 7197.4 7198.9 7207.6 8187.2 8251.7 8258.8 8267.5 8272.3 7102.2 7125.9 7258.4 7313.5 7463.6 7274.6 7439.2 7443.3 7480.1 7682.7 6647.4 6770.4 6773.0 6778.5 6779.2 4725.2 4863.5 4873.7 5044.9 5125.3 4503.2 4507.6 4611.8 4722.7 4787.0 05:06:28 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | [#4] prototype.html Cycles:180 Average:4580.12 Median:5138.63 stddev:1267.25 (24.7%) stddev-sans-first:1230.91 05:06:28 INFO - PROCESS | 4136 | Values: 377.0 378.0 384.5 394.8 396.2 5104.6 5177.4 5200.2 5271.5 5311.7 5060.7 5224.4 5245.5 5253.4 5273.4 4163.7 4178.8 4195.4 4215.9 4216.3 4100.3 4173.0 4194.6 4234.7 4243.7 4137.8 4172.7 4175.7 4199.9 4207.4 4146.2 4151.3 4157.5 4169.9 4178.4 3791.5 3791.7 3794.8 3809.8 3839.8 4990.3 5030.6 5053.7 5069.4 5097.1 4742.6 4793.2 4826.2 4850.0 4870.6 5356.8 5463.4 5473.8 5484.9 5504.6 4148.5 4153.3 4177.4 4194.2 4195.2 5623.4 5661.8 5670.4 5673.2 5685.0 4260.3 4295.9 4317.3 4322.9 4344.7 4190.2 4318.3 4335.3 4360.7 4367.7 4742.0 4795.4 4815.9 4819.8 4829.1 5419.3 5487.6 5494.9 5503.3 5516.4 1392.1 1446.9 1466.3 1467.3 1479.7 1022.7 1043.6 1053.5 1063.3 1063.5 5109.5 5148.4 5163.2 5167.8 5177.6 5401.3 5405.4 5436.9 5437.4 5452.8 4984.6 5106.9 5119.6 5135.3 5148.0 2827.8 2836.4 2865.5 2871.9 2886.3 5552.2 5566.7 5587.6 5606.4 5653.2 5243.6 5400.4 5410.0 5495.3 5500.6 5607.0 5607.7 5614.6 5617.2 5620.6 5153.9 5381.8 5390.7 5435.9 5439.5 5284.8 5316.4 5322.2 5330.9 5358.3 5600.2 5607.7 5614.6 5646.5 5646.7 4012.7 4089.9 4125.7 4169.2 4174.1 5314.4 5327.2 5327.6 5329.9 5331.4 5210.8 5261.9 5272.4 5279.5 5287.9 5178.4 5254.7 5274.8 5306.2 5322.4 5220.5 5221.3 5233.0 5275.9 5276.5 5148.0 5230.6 5239.2 5260.9 5275.8 4869.7 5114.1 5119.7 5127.3 5141.9 05:06:28 INFO - PROCESS | 4136 | 05:06:28 INFO - PROCESS | 4136 | [#5] yui.html Cycles:180 Average:17168.19 Median:4535.81 stddev:21883.61 (482.5%) stddev-sans-first:21910.05 05:06:28 INFO - PROCESS | 4136 | Values: 698.2 723.7 767.7 809.3 841.5 15872.0 15957.8 16555.4 16580.6 16600.8 13260.7 14862.9 15199.8 15221.5 15226.6 807.8 837.3 837.6 838.2 838.5 834.5 837.2 838.8 838.8 838.9 862.7 874.7 875.4 875.7 875.7 864.0 865.5 872.1 872.6 872.6 636.8 642.2 642.3 642.9 643.0 15431.0 15584.8 16183.6 16258.8 16481.2 14214.7 14227.1 14521.0 14546.8 14673.0 11913.2 12152.9 12218.4 12374.3 12389.9 3693.3 3736.5 3753.1 3802.7 3852.5 48844.5 55479.4 56535.0 63328.4 69693.3 11639.5 11681.6 11764.9 11773.7 11991.4 21910.0 22375.3 22517.5 22528.4 22530.9 8203.2 8363.2 8430.7 8659.5 8685.9 4230.2 4251.7 4329.4 4355.9 4469.2 3358.6 3506.2 3511.4 3513.2 3515.5 629.1 631.5 633.7 642.8 653.1 674.8 703.3 717.6 726.4 729.0 9108.3 9319.9 9331.9 9333.3 9460.1 677.4 689.8 703.1 723.3 723.9 4367.0 4602.4 4646.1 4692.4 4711.6 77992.0 79723.6 81203.6 81865.2 83332.0 70590.0 71177.3 72147.3 72592.0 73331.0 62197.0 63329.0 65150.0 66974.7 69180.7 8775.2 8814.0 8866.0 9054.3 9055.1 42786.1 43328.0 43629.8 44089.6 44476.8 52249.6 56265.7 59135.7 59719.0 60636.4 11475.5 11517.0 11716.9 11828.4 12643.0 13154.3 13177.8 13863.5 14072.8 14206.1 10811.1 10872.3 10973.9 11008.7 11257.4 11240.4 11556.5 11582.4 11962.9 12141.6 12001.3 12443.3 12470.8 12554.0 12733.7 10435.9 10977.5 11066.4 11148.4 11309.5 10431.4 10469.3 10587.9 10680.3 10972.9 05:06:28 INFO - PROCESS | 4136 | -------- Summary: end -------- 05:06:28 INFO - PROCESS | 4136 | 05:06:29 INFO - TEST-INFO | 4136: exit 0 05:06:29 INFO - TEST-OK | dromaeo_css | took 1115530ms 05:06:29 INFO - TEST-START | kraken 05:06:29 INFO - operating with platform_type : linux_ 05:06:29 INFO - Initialising browser for kraken test... 05:06:29 INFO - TEST-INFO | started process 4438 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOVme0Y/profile http://localhost:48025/getInfo.html) 05:06:29 INFO - PROCESS | 4438 | 1482498389516 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 05:06:31 INFO - PROCESS | 4438 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:06:31 INFO - PROCESS | 4438 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:06:32 INFO - PROCESS | 4438 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:06:32 INFO - PROCESS | 4438 | __metrics Screen width/height:1600/1200 05:06:32 INFO - PROCESS | 4438 | colorDepth:24 05:06:32 INFO - PROCESS | 4438 | Browser inner width/height: 1024/697 05:06:32 INFO - PROCESS | 4438 | __metrics 05:06:33 INFO - PROCESS | 4438 | 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" 05:06:33 INFO - PROCESS | 4438 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:34 INFO - TEST-INFO | 4438: exit 0 05:06:34 INFO - Browser initialized. 05:06:34 INFO - Running cycle 1/1 for kraken test... 05:06:34 INFO - TEST-INFO | started process 4522 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOVme0Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:46 INFO - PROCESS | 4522 | RSS: Main: 146489344 05:06:46 INFO - PROCESS | 4522 | 05:06:48 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48025/tests/kraken/driver.html?testName=audio-beat-detection) 05:06:49 INFO - PROCESS | 4522 | RSS: Main: 201351168 05:06:49 INFO - PROCESS | 4522 | 05:06:52 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48025/tests/kraken/driver.html?testName=audio-dft) 05:06:52 INFO - PROCESS | 4522 | RSS: Main: 180682752 05:06:52 INFO - PROCESS | 4522 | 05:06:55 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48025/tests/kraken/driver.html?testName=audio-fft) 05:06:56 INFO - PROCESS | 4522 | RSS: Main: 263000064 05:06:56 INFO - PROCESS | 4522 | 05:06:58 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48025/tests/kraken/driver.html?testName=audio-oscillator) 05:06:59 INFO - PROCESS | 4522 | RSS: Main: 172630016 05:06:59 INFO - PROCESS | 4522 | 05:07:01 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48025/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:01 INFO - PROCESS | 4522 | RSS: Main: 178278400 05:07:01 INFO - PROCESS | 4522 | 05:07:04 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48025/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:05 INFO - PROCESS | 4522 | RSS: Main: 217350144 05:07:05 INFO - PROCESS | 4522 | 05:07:08 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48025/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:09 INFO - PROCESS | 4522 | RSS: Main: 217575424 05:07:09 INFO - PROCESS | 4522 | 05:07:12 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48025/tests/kraken/driver.html?testName=json-parse-financial) 05:07:12 INFO - PROCESS | 4522 | RSS: Main: 215920640 05:07:12 INFO - PROCESS | 4522 | 05:07:14 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48025/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:14 INFO - PROCESS | 4522 | RSS: Main: 178028544 05:07:14 INFO - PROCESS | 4522 | 05:07:16 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48025/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:17 INFO - PROCESS | 4522 | RSS: Main: 186015744 05:07:17 INFO - PROCESS | 4522 | 05:07:19 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48025/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:19 INFO - PROCESS | 4522 | RSS: Main: 193503232 05:07:19 INFO - PROCESS | 4522 | 05:07:22 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:23 INFO - PROCESS | 4522 | RSS: Main: 202764288 05:07:23 INFO - PROCESS | 4522 | 05:07:25 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:26 INFO - PROCESS | 4522 | RSS: Main: 199471104 05:07:26 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | Cycle 1(1): loaded http://localhost:48025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48025/tests/kraken/driver.html?testName=ai-astar) 05:07:28 INFO - PROCESS | 4522 | RSS: Main: 251346944 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | __start_tp_report 05:07:28 INFO - PROCESS | 4522 | _x_x_mozilla_page_load 05:07:28 INFO - PROCESS | 4522 | _x_x_mozilla_page_load_details 05:07:28 INFO - PROCESS | 4522 | |i|pagename|runs| 05:07:28 INFO - PROCESS | 4522 | |0;ai-astar;111;113;112;111;111;111;110;111;111;110 05:07:28 INFO - PROCESS | 4522 | |1;audio-beat-detection;150;119;123;169;125;120;119;119;138;120 05:07:28 INFO - PROCESS | 4522 | |2;audio-dft;159;161;161;161;160;159;161;162;161;162 05:07:28 INFO - PROCESS | 4522 | |3;audio-fft;73;76;75;103;76;76;76;75;94;75 05:07:28 INFO - PROCESS | 4522 | |4;audio-oscillator;83;83;107;82;82;82;93;82;83;104 05:07:28 INFO - PROCESS | 4522 | |5;imaging-gaussian-blur;117;117;118;116;117;117;117;117;117;117 05:07:28 INFO - PROCESS | 4522 | |6;imaging-darkroom;147;146;146;147;146;146;147;147;146;146 05:07:28 INFO - PROCESS | 4522 | |7;imaging-desaturate;96;96;96;95;96;95;96;96;97;95 05:07:28 INFO - PROCESS | 4522 | |8;json-parse-financial;51;53;49;51;52;52;51;52;52;52 05:07:28 INFO - PROCESS | 4522 | |9;json-stringify-tinderbox;65;66;65;66;65;65;66;65;65;66 05:07:28 INFO - PROCESS | 4522 | |10;stanford-crypto-aes;81;80;81;80;80;80;80;80;80;83 05:07:28 INFO - PROCESS | 4522 | |11;stanford-crypto-ccm;135;127;125;131;123;122;122;122;166;146 05:07:28 INFO - PROCESS | 4522 | |12;stanford-crypto-pbkdf2;169;166;166;170;175;167;166;169;164;162 05:07:28 INFO - PROCESS | 4522 | |13;stanford-crypto-sha256-iterative;65;63;62;62;62;62;63;62;61;59 05:07:28 INFO - PROCESS | 4522 | __end_tp_report 05:07:28 INFO - PROCESS | 4522 | __start_cc_report 05:07:28 INFO - PROCESS | 4522 | _x_x_mozilla_cycle_collect,1585 05:07:28 INFO - PROCESS | 4522 | __end_cc_report 05:07:28 INFO - PROCESS | 4522 | __startTimestamp1482498448440__endTimestamp 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | ------- Summary: start ------- 05:07:28 INFO - PROCESS | 4522 | Number of tests: 14 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 05:07:28 INFO - PROCESS | 4522 | Values: 111.0 113.0 112.0 111.0 111.0 111.0 110.0 111.0 111.0 110.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:124.00 stddev:17.07 (13.8%) stddev-sans-first:16.53 05:07:28 INFO - PROCESS | 4522 | Values: 150.0 119.0 123.0 169.0 125.0 120.0 119.0 119.0 138.0 120.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 05:07:28 INFO - PROCESS | 4522 | Values: 159.0 161.0 161.0 161.0 160.0 159.0 161.0 162.0 161.0 162.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#3] audio-fft Cycles:10 Average:79.90 Median:76.00 stddev:10.07 (13.3%) stddev-sans-first:10.37 05:07:28 INFO - PROCESS | 4522 | Values: 73.0 76.0 75.0 103.0 76.0 76.0 76.0 75.0 94.0 75.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:82.50 stddev:9.78 (11.9%) stddev-sans-first:10.20 05:07:28 INFO - PROCESS | 4522 | Values: 83.0 83.0 107.0 82.0 82.0 82.0 93.0 82.0 83.0 104.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 05:07:28 INFO - PROCESS | 4522 | Values: 117.0 117.0 118.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 05:07:28 INFO - PROCESS | 4522 | Values: 147.0 146.0 146.0 147.0 146.0 146.0 147.0 147.0 146.0 146.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 05:07:28 INFO - PROCESS | 4522 | Values: 96.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 97.0 95.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:1.08 (2.1%) stddev-sans-first:1.13 05:07:28 INFO - PROCESS | 4522 | Values: 51.0 53.0 49.0 51.0 52.0 52.0 51.0 52.0 52.0 52.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 05:07:28 INFO - PROCESS | 4522 | Values: 65.0 66.0 65.0 66.0 65.0 65.0 66.0 65.0 65.0 66.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.00 stddev:0.97 (1.2%) stddev-sans-first:1.01 05:07:28 INFO - PROCESS | 4522 | Values: 81.0 80.0 81.0 80.0 80.0 80.0 80.0 80.0 80.0 83.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#11] stanford-crypto-ccm Cycles:10 Average:131.90 Median:129.00 stddev:14.21 (11.0%) stddev-sans-first:15.03 05:07:28 INFO - PROCESS | 4522 | Values: 135.0 127.0 125.0 131.0 123.0 122.0 122.0 122.0 166.0 146.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:168.00 stddev:3.60 (2.1%) stddev-sans-first:3.77 05:07:28 INFO - PROCESS | 4522 | Values: 169.0 166.0 166.0 170.0 175.0 167.0 166.0 169.0 164.0 162.0 05:07:28 INFO - PROCESS | 4522 | 05:07:28 INFO - PROCESS | 4522 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:1.52 (2.5%) stddev-sans-first:1.20 05:07:28 INFO - PROCESS | 4522 | Values: 65.0 63.0 62.0 62.0 62.0 62.0 63.0 62.0 61.0 59.0 05:07:28 INFO - PROCESS | 4522 | -------- Summary: end -------- 05:07:28 INFO - PROCESS | 4522 | 05:07:29 INFO - TEST-INFO | 4522: exit 0 05:07:29 INFO - TEST-OK | kraken | took 60417ms 05:07:29 INFO - SUITE-END | took 1175s 05:07:29 INFO - Completed test suite (00:19:36) 05:07:29 INFO - javascript benchmark 05:07:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.2857842696176, 3192.0583427888755, 3231.5798946137006, 3233.030090972709, 3233.7413006959446, 5263.815766448175, 5342.946570534294, 5360.544353729928, 5413.810516631914, 5481.0, 5411.756470958807, 5435.211894275328, 5437.564994800417, 5456.045192091388, 5488.094464413367, 4890.266460030988, 4945.9258111128365, 4950.950490495094, 4976.975115124419, 5014.373203349582, 4914.852554423373, 5003.549680528752, 5005.424376196747, 5052.747362631853, 5104.157813960692, 4812.422509298872, 5022.4726403727445, 5034.194528875362, 5079.898402031957, 5091.414487333967, 4857.635677324215, 4870.14772414826, 4900.58345040672, 4925.852224433273, 4972.353594032788, 4910.435299940516, 4911.729854857981, 4942.283368911488, 4944.505549445063, 4974.502549745032, 5386.865328366783, 5423.484768946983, 5426.5116139547235, 5429.321334833146, 5455.645383050129, 5671.546276297928, 5713.314402271754, 5762.193292938992, 5795.043817770105, 5894.911576326359, 5906.26171728534, 5910.349861515273, 5920.082387229986, 5923.881522369572, 5967.6419414835245, 5477.5070243677865, 5477.698726570077, 5481.451854814526, 5481.862953426196, 5490.6431081979545, 5759.308882933991, 5798.333191682926, 5838.649681019152, 6003.969980150071, 6008.609440386361, 5651.943480565224, 5700.201971723962, 5715.199872017921, 5753.280839895013, 5762.135679648002, 5733.541316694655, 5739.483446489752, 5790.565707571949, 5791.131330300489, 5806.506446952057, 5668.319801623831, 5721.0, 5721.971390143106, 5752.539796816244, 5756.1653560233535, 5696.829095127153, 5730.942690573124, 5737.483626473638, 5797.2173756542325, 5824.271966004249, 4344.066025804467, 4349.630281426051, 4350.6736994725525, 4354.238008348526, 4385.890352741143, 3823.713221508398, 3868.980655096763, 3874.9806250969127, 3908.335582950848, 3923.803809809498, 5417.295751552273, 5426.240326354314, 5488.835334939959, 5496.780128794883, 5497.312835895513, 5750.338711048199, 5792.652440853562, 5796.478316951495, 5801.071828507418, 5811.360750317462, 5489.533389661923, 5492.505674489315, 5492.80775172867, 5494.203340515683, 5497.725113744297, 4812.422509298906, 4813.181759100961, 4820.517948205222, 4823.1800593899125, 4823.204171311764, 6004.639721616717, 6032.758689652453, 6043.425874541911, 6084.23947006633, 6134.171886795315, 5780.0, 5804.0, 5805.274340707411, 5812.5349972001295, 5819.098039803837, 5896.410358964069, 6056.36408177135, 6059.182010428536, 6070.939290607214, 6072.635641861502, 5627.521660658726, 5689.77240910359, 5706.087026075808, 5714.742836572281, 5835.7373918174635, 5821.068629019336, 5822.592418530662, 5838.970805145947, 5843.357230704704, 5849.912251316318, 5682.147677848356, 5687.971560142173, 5692.31692196939, 5694.117411801178, 5698.430156984434, 5528.834134975958, 5579.330480342385, 5585.609007369445, 5621.747021383966, 5631.8592035199445, 5656.236408084856, 5661.575381846295, 5661.631993920382, 5674.092145256739, 5705.515031222392, 5704.229928959538, 5704.629199102045, 5713.514351280021, 5716.314042314949, 5716.885662286648, 5413.945860541344, 5440.972795135999, 5508.862278442911, 5540.7783688652, 5554.777808887761], "value": 5342.990288181118, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.5704757670076, 576.2652617912162, 579.2730123694762, 579.3974266762567, 590.8877313310468, 33069.503957440655, 36644.450333245026, 40258.19483610326, 42274.15451690964, 42474.57525424745, 44784.32823507642, 45658.0, 45672.77163614177, 45764.08471830561, 45771.0, 8915.554222288893, 9042.140996605312, 9058.0, 9087.182153606174, 9098.590563424661, 8555.443896146762, 8758.255548278405, 10374.53314600841, 10615.416152111631, 10715.142788576895, 10831.404272764994, 10899.455027248645, 10900.563977440894, 10905.83641245378, 10912.181586381013, 10753.946230268877, 10775.299605525615, 10806.837897431546, 10812.080973117294, 10843.295185812896, 10421.583136674524, 10586.41774702391, 10602.57589696419, 10605.204609654229, 10611.0, 41667.37498937518, 41695.0, 41826.58173418288, 41917.161656767, 42123.15753684909, 3454.2573346730323, 3758.8872333830027, 4054.3107671695884, 4068.9389659155136, 4135.0, 32582.511262331085, 32868.67131328704, 32898.17754556131, 32954.0, 33418.665813341795, 4074.9796251018556, 4084.9183016339507, 4092.774897380643, 4134.627883490501, 4154.543000269968, 222098.0, 222639.32082037564, 222821.0, 224100.0, 226973.0, 58003.70998144982, 58029.419705803244, 58292.417075829544, 58309.0, 58368.4163158363, 59964.40035599588, 60201.397986019576, 60269.397306027255, 60337.39662603405, 60443.69778151169, 15652.530424087068, 16161.4343497977, 16210.75683864743, 16212.918935405247, 16232.675346493004, 11048.779024419466, 11438.027767639678, 12566.623001309976, 12625.305608191638, 12633.557825476064, 16177.272022759003, 16324.591885202964, 16725.16374181286, 17020.0, 17074.0, 6447.5505604502305, 6790.456763458911, 6924.861502769916, 6939.271376505394, 6970.546914450646, 880.4585180114184, 888.5290795878195, 905.3707673167086, 1112.710695219249, 1129.1305694615264, 31143.68856311453, 31409.685903141133, 31478.37043259122, 31594.526082108787, 31852.681473185436, 1022.192467950311, 1062.8618279623754, 1076.05844885725, 1101.6639924822846, 1124.4434005167332, 15461.768073478906, 16478.588035298973, 16544.917275413547, 16565.08892010927, 16808.495745127664, 440788.0, 492562.0, 502635.0, 502676.8126231202, 502948.0, 55027.724861376235, 57357.0, 57990.420095799345, 58211.4178858206, 64601.309803529846, 1373.5535950816025, 1383.4673650644575, 1384.3078460769616, 1384.473899918045, 1410.0975375759522, 30651.386972260876, 33106.83446582752, 33234.66765332316, 33417.66582334243, 34020.14949626279, 52117.0, 52717.73641131923, 52763.47236527739, 52836.73581632067, 52898.47101528935, 409668.0, 410327.0, 410710.0, 411512.0, 413319.0, 28093.57859632066, 28205.71794282031, 28724.281892952844, 28724.856375717987, 28737.69516505545, 46966.53033469622, 47277.29084063673, 47502.76248618735, 48246.0, 48642.5135748638, 39847.0, 40552.797236013634, 40996.0, 41170.0, 42076.0, 39960.600393995686, 40314.19371612493, 41246.6314003396, 41469.79265103776, 42211.78894105633, 41053.589464104974, 41355.58644413517, 41445.0, 42025.579744202165, 42044.36933446062, 33449.49825752664, 35856.0, 36189.457158143174, 36569.0, 36798.63201368059, 27487.45025099527, 27518.449631006868, 27814.44371112607, 28097.719022810328, 28124.296892577848], "value": 19947.0185057428, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.85952627291044, 401.67214626816826, 406.69091490467247, 408.5036680433273, 431.3917376499137, 13655.0, 15518.534443966684, 18362.265509379642, 19016.429507114775, 19162.0, 17469.82530174698, 17959.102044897736, 18952.431427057178, 19480.805191948075, 19490.415287541397, 4581.702189357681, 5217.947820521784, 5367.355917289911, 5371.355437347524, 5374.220737992989, 5288.947110528884, 5369.543588794957, 5377.381601115863, 5377.435369286227, 5378.46215378463, 5387.326584176978, 5387.78448862045, 5391.81128660497, 5392.649477783951, 5403.162509810985, 5732.914006289909, 5743.195952566623, 5774.07614781635, 5776.393478684719, 5780.739866706008, 5088.821891233826, 5247.1866860636665, 5247.816326428595, 5250.44870288618, 5321.547668448186, 13723.941408788762, 13924.860751392458, 13958.302084895715, 13959.511417100353, 21178.470538236514, 2153.6231159547015, 2306.0545176477563, 2350.753170917046, 2727.3045373429845, 2945.910013295073, 9583.712488625353, 10174.949125254327, 10244.795104097877, 10338.0695737384, 10602.946985265102, 1864.3381599532242, 1939.059556115283, 1946.240966023252, 1948.7953764854628, 1989.19437627761, 73579.0, 74155.0, 80490.0, 81067.5946620269, 82219.0, 10419.95800419952, 10559.577616895238, 10752.946235268773, 10782.67651970442, 11480.770384592428, 14627.0, 14642.707145857023, 14651.633709157357, 14678.339474723663, 14699.26503674812, 7629.2752188542, 8028.839423211503, 8067.112617612058, 8204.097549269576, 8228.794280142925, 7624.504407213513, 7711.344535714415, 7765.378769698411, 7867.803304917423, 7895.842083158304, 4556.453225612948, 4598.195315819785, 4626.306054091913, 4633.189191891473, 4703.717776933327, 1924.191839427444, 1955.5013471564944, 1993.3421970749907, 2030.1879248300502, 2030.3807338761524, 893.5353616119581, 911.462237280008, 933.9813203735887, 961.7643677298967, 967.4872317671645, 17480.21339039746, 18618.81381186198, 18828.717569236473, 18946.0, 19052.428427147206, 926.6339795780764, 933.9439633622004, 937.2080591899834, 960.1037950048598, 977.745786095606, 10145.137663298703, 10582.576696932036, 10712.0, 10715.892841071645, 10763.0, 395472.0, 399838.0, 408260.0, 411073.0, 412946.0, 55743.721281394144, 67958.8837004662, 78269.0, 80590.5970470144, 81619.5919020413, 992.1318846009885, 1025.8922813104516, 1044.5090807320698, 1048.7483004078965, 1052.2949623752104, 16237.269322880262, 16632.3347066116, 17053.744193837352, 17070.317187312867, 17295.827041729422, 36859.44710829286, 38303.808480957414, 38783.612163878, 39013.609863900994, 39196.216075678894, 51915.0, 52807.735961319944, 53186.2022069677, 53892.46107539031, 56146.71926640341, 8439.662413503636, 8444.184500332458, 8509.106543813057, 8690.956545217234, 8717.171868672465, 12383.75232495363, 12670.239785612892, 12677.492900284255, 12718.0, 12822.0, 10902.945485272523, 10923.563057477611, 11112.722181945515, 11258.268212566485, 11505.942470287595, 10930.180236482136, 11063.612773552886, 11074.833877491683, 11156.163287753614, 11575.479103440533, 11069.335839849635, 11130.276532025393, 11140.721481963015, 11345.262557933707, 11704.648860534198, 8085.434019618806, 8158.632861521127, 8378.03652579961, 8389.622466989122, 8512.787180320542, 10484.161267098767, 11172.441377931233, 11179.441027948733, 11206.607768728381, 11608.88391116078], "value": 8751.341838378838, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.0, 1474.1744623011111, 1610.339760698114, 1772.804991450941, 1791.8476929460987, 8084.231997960192, 8608.698695545658, 8610.956945215281, 8621.956890215555, 8637.22264996151, 7493.138289096755, 7769.3784497240085, 8140.3487720982175, 8338.374621903351, 8349.29031032363, 2350.5063936573342, 2373.276150774022, 2374.845635033726, 2380.8809559521956, 2383.0467812874817, 2310.8266879983985, 2346.343023953296, 2379.238643634038, 2384.892679829412, 2395.3532546212514, 2012.3258708332726, 2079.1371580793952, 2246.213825161197, 2322.6632138339933, 2328.057136859566, 2341.7775311345395, 2345.870977096259, 2360.468894498741, 2365.1367250953363, 2368.39605900496, 2334.0663734506334, 2348.518553696488, 2367.32531228599, 2367.7277113132036, 2369.822263330257, 8555.23002929733, 8630.050694423608, 8653.610587523577, 8664.480131192086, 8682.652693892236, 5132.332796736438, 5285.788568457296, 5301.867453313698, 5305.310309659758, 5308.097623404031, 3298.950515742266, 3462.3248466549035, 3468.7814628001393, 3555.964440355589, 3574.6782789548806, 1116.447358557511, 1126.189143816451, 2059.9176032958662, 2089.540301133748, 2117.830573554112, 11663.59177428786, 11683.649490515298, 11729.12031597625, 11744.94127529357, 11752.118591105616, 4254.680898932593, 4351.434313539283, 4457.487388950248, 4463.709858859229, 4512.2329204035395, 9407.0, 9422.34043616954, 9427.198688111588, 9429.811403771886, 9430.528473576294, 5045.01622183678, 5113.8721531962, 5115.437301896788, 5116.718580478109, 5119.539241468211, 4723.4331880174595, 4760.880977975509, 4780.11567859947, 4788.832390866303, 4796.208625576811, 3473.4095203815414, 3508.754387192923, 3519.982400087983, 3649.489071529988, 3670.724695647837, 1107.7064577886867, 1110.8222684370462, 1115.8549388579434, 1133.4049623947328, 1158.1892675127444, 772.3777774455691, 853.3599800149888, 893.6157452295446, 913.2602591900582, 916.2303664921365, 5523.28197334352, 5590.832275031674, 5640.153976903497, 5737.08206686928, 5803.332616749043, 868.8001759595328, 886.3883920095113, 908.8273228086774, 919.5540163020929, 955.4506158958627, 3672.779633222015, 3887.6501114899793, 3942.1918506706056, 4034.798260086984, 4105.527864295645, 9845.803083938425, 10438.791224175475, 10501.894981050245, 10520.842187367196, 10522.263441559018, 10201.846972295421, 10335.121514671337, 10361.533730982124, 10426.530806113744, 10429.269951103503, 7934.246246606564, 8039.276465118168, 8067.919320806835, 8080.595970201466, 8087.272145506932, 5502.752376143003, 5517.310336207974, 5521.69630670317, 5548.445155484581, 5672.290963629546, 9433.103855133751, 9529.666461673809, 9546.236301095756, 9623.470709111067, 9645.228381729305, 9466.668666596635, 9700.563474643799, 9874.753131171778, 9885.308028437941, 10014.449205293779, 7114.253003434565, 7191.8561628768175, 7197.352238298578, 7198.8920166198595, 7207.639618018973, 8187.181281872004, 8251.711190108317, 8258.793530161794, 8267.545285009142, 8272.255497005299, 7102.18324892644, 7125.928740712527, 7258.383037441877, 7313.48805583604, 7463.589502577411, 7274.599897005715, 7439.181690014202, 7443.330100290783, 7480.102387713509, 7682.7311044113185, 6647.401733843977, 6770.390664840188, 6772.9661351694895, 6778.491613129134, 6779.220389655253, 4725.220338644085, 4863.465018847854, 4873.707577545359, 5044.9243261350375, 5125.333706618265, 4503.211937910918, 4507.6168525675685, 4611.79246933882, 4722.740249286322, 4787.042591481787], "value": 4516.817293331597, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.0, 377.99622003779956, 384.51935081148565, 394.84995701633386, 396.1640937621619, 5104.566111880495, 5177.378714554249, 5200.167973124301, 5271.522384479298, 5311.681299122055, 5060.746962651871, 5224.3730752309775, 5245.501677340647, 5253.4221235664145, 5273.446288139738, 4163.7293575917465, 4178.770167640779, 4195.391668208108, 4215.873523794276, 4216.325387937931, 4100.282450571153, 4172.979135104335, 4194.559571245021, 4234.682398820086, 4243.745375277478, 4137.834486620547, 4172.7287726297845, 4175.728577642459, 4199.874003779892, 4207.380158638915, 4146.232946904832, 4151.294279972412, 4157.459530261077, 4169.937450938223, 4178.35235538492, 3791.526059242595, 3791.6777073948747, 3794.829232684508, 3809.7904615246152, 3839.8272077756287, 4990.30135780991, 5030.647854650176, 5053.747312634354, 5069.417017043013, 5097.133487307166, 4742.5731684148595, 4793.233082706785, 4826.1795494765975, 4850.029994001214, 4870.6103511718975, 5356.812511562077, 5463.426340234298, 5473.781048758045, 5484.862878428032, 5504.6421982571, 4148.543660197376, 4153.273177194009, 4177.373393990895, 4194.161167766459, 4195.202911446815, 5623.353314368859, 5661.820199223329, 5670.419958918682, 5673.23411339468, 5684.971575142139, 4260.339647354665, 4295.871123866289, 4317.309230523101, 4322.891927701832, 4344.717593356421, 4190.224808410454, 4318.3090705486975, 4335.284678028153, 4360.738355698668, 4367.650587952956, 4741.95258047422, 4795.352627395327, 4815.903681926412, 4819.795051237191, 4829.1307564638, 5419.3225846769155, 5487.5884308677005, 5494.945050549443, 5503.284573005563, 5516.448355164452, 1392.1229625335973, 1446.8987170897935, 1466.2815220541859, 1467.2810322941896, 1479.6670749081586, 1022.7164908040362, 1043.645160645369, 1053.5417093564172, 1063.2557209953063, 1063.4842101580728, 5109.514596113363, 5148.433672296044, 5163.199704045879, 5167.819126330561, 5177.637565370464, 5401.3248343957, 5405.351357837085, 5436.891262174734, 5437.356970829554, 5452.781888724407, 4984.6261530384645, 5106.923396149061, 5119.616028797781, 5135.306733590992, 5148.0, 2827.844468554208, 2836.4468928559145, 2865.4842128417085, 2871.8851245949927, 2886.336142687194, 5552.222688823568, 5566.693831839207, 5587.608867379245, 5606.383297837235, 5653.23681303027, 5243.580513558906, 5400.351957765093, 5409.998300628792, 5495.296458098002, 5500.614956952975, 5607.0, 5607.719614019201, 5614.550835933197, 5617.241672374259, 5620.634658747168, 5153.922691159561, 5381.811636592701, 5390.676559406448, 5435.864103397446, 5439.5104440600535, 5284.77628646533, 5316.388615309289, 5322.201669749568, 5330.8538664185835, 5358.276632654698, 5600.215969764317, 5607.691576963306, 5614.63504872182, 5646.520045796154, 5646.717664116695, 4012.6589239914933, 4089.8773036808943, 4125.711200215956, 4169.249535083714, 4174.1443004184675, 5314.415414304501, 5327.227552005015, 5327.573794096541, 5329.854081372523, 5331.3868905076415, 5210.843674689765, 5261.894762104813, 5272.446393128666, 5279.52484276417, 5287.89424211506, 5178.378594568676, 5254.710990895537, 5274.789008439773, 5306.204069389622, 5322.441143679858, 5220.530152286313, 5221.295125158055, 5232.979568984164, 5275.860862786636, 5276.4886022450255, 5148.0, 5230.607704422107, 5239.214117882196, 5260.89478210426, 5275.839315350617, 4869.6591238612955, 5114.133341330314, 5119.718415487184, 5127.333446651912, 5141.948580514297], "value": 4197.687692960524, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.231944860653, 723.7068987060236, 767.6507189228902, 809.3403875841188, 841.4572600672566, 15871.99999999997, 15957.84042159578, 16555.420560280392, 16580.585485362873, 16600.833991660078, 13260.734785304288, 14862.851371486282, 15199.77200341993, 15221.467248646299, 15226.619334516643, 807.7859367267679, 837.271573730852, 837.6440012994483, 838.1995194589152, 838.4591938199858, 834.453433001382, 837.2422957223722, 838.8070743728946, 838.8280402517499, 838.9077201507829, 862.6980556805131, 874.6894852327428, 875.3566128895256, 875.6759998800453, 875.7022612311813, 864.0236532717988, 865.4634126841398, 872.110447343709, 872.5506364222399, 872.5898827551073, 636.7675798333621, 642.1683919324486, 642.3480167629859, 642.9389208025277, 642.9646369449679, 15431.0, 15584.844151558453, 16183.595410114724, 16258.837411625851, 16481.17594120289, 14214.715705685932, 14227.14637121776, 14521.0, 14546.781798273034, 14673.0, 11913.166078374517, 12152.878471215263, 12218.389080546027, 12374.319412432313, 12389.876101238962, 3693.3351996640595, 3736.532933383327, 3753.0617345663586, 3802.714796390254, 3852.5376954765607, 48844.51155488435, 55479.44520554783, 56535.0, 63328.366716332705, 69693.30306696969, 11639.47622356979, 11681.591144309908, 11764.94117529424, 11773.705657358463, 11991.400429978641, 21910.0, 22375.328740137822, 22517.549649006927, 22528.436789080075, 22530.887345563166, 8203.179682031749, 8363.20549547792, 8430.662773488992, 8659.523726194993, 8685.869711954325, 4230.175115852441, 4251.702380833374, 4329.437173167531, 4355.891102722458, 4469.195544801902, 3358.596968363812, 3506.1760486285702, 3511.385507536222, 3513.2446523997464, 3515.472679098156, 629.1475051305507, 631.5231999840091, 633.7053270229366, 642.8360768004129, 653.0922018394435, 674.8346655069441, 703.3318347569829, 717.6196615793638, 726.4079774983371, 729.0376702752422, 9108.316876234308, 9319.90680093204, 9331.906680933103, 9333.253339732804, 9460.148586627236, 677.3734295776387, 689.7930620813736, 703.114076263901, 723.2911746488471, 723.9384652304614, 4366.978165109154, 4602.4332986098625, 4646.140464014168, 4692.41344831896, 4711.552402521755, 77992.0, 79723.55066488778, 81203.5939820297, 81865.18134818695, 83332.0, 70590.0, 71177.28822711707, 72147.27852721511, 72592.0, 73331.0, 62197.0, 63329.0, 65150.0, 66974.66512667405, 69180.6540967292, 8775.210231079234, 8814.0, 8865.955670221607, 9054.275657947217, 9055.139761722627, 42786.14427711366, 43328.0, 43629.78185109054, 44089.559104409826, 44476.777616111714, 52249.6400575906, 56265.71867140638, 59135.704321478115, 59719.0, 60636.393636063076, 11475.540978360772, 11517.0, 11716.882831171579, 11828.40857957116, 12643.0, 13154.276514791778, 13177.802332965204, 13863.514776982756, 14072.78890816659, 14206.147631142165, 10811.135109191404, 10872.293300935415, 10973.890261097287, 11008.724781880517, 11257.380844053656, 11240.437978101227, 11556.479958401724, 11582.42087895585, 11962.880371196525, 12141.57504487374, 12001.339926304137, 12443.253404795742, 12470.750584988431, 12554.0, 12733.681657958625, 10435.895641043493, 10977.506012229629, 11066.39134847591, 11148.386838723947, 11309.491072901574, 10431.426271555138, 10469.26715129964, 10587.89412105869, 10680.252382333472, 10972.945135274593], "value": 7111.154958397973, "unit": "score"}], "name": "dromaeo_css", "value": 7078.2761984168155, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 112.0, 111.0, 111.0, 111.0, 110.0, 111.0, 111.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 119.0, 123.0, 169.0, 125.0, 120.0, 119.0, 119.0, 138.0, 120.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 161.0, 161.0, 160.0, 159.0, 161.0, 162.0, 161.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 75.0, 103.0, 76.0, 76.0, 76.0, 75.0, 94.0, 75.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 107.0, 82.0, 82.0, 82.0, 93.0, 82.0, 83.0, 104.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 97.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 53.0, 49.0, 51.0, 52.0, 52.0, 51.0, 52.0, 52.0, 52.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 80.0, 80.0, 80.0, 80.0, 80.0, 80.0, 83.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 127.0, 125.0, 131.0, 123.0, 122.0, 122.0, 122.0, 166.0, 146.0], "value": 131.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 166.0, 170.0, 175.0, 167.0, 166.0, 169.0, 164.0, 162.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 61.0, 59.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1488.0, "alertThreshold": 2.0}]} 05:07:29 INFO - Return code: 0 05:07:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:07:29 INFO - # TBPL SUCCESS # 05:07:29 INFO - Running post-action listener: _resource_record_post_action 05:07:29 INFO - [mozharness: 2016-12-23 13:07:29.761980Z] Finished run-tests step (success) 05:07:29 INFO - Running post-run listener: _resource_record_post_run 05:07:29 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 425984; Write bytes: 414523392; Read time: 48; Write time: 253840 05:07:29 INFO - TinderboxPrint: CPU usage
13.1% 05:07:29 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 48 05:07:29 INFO - TinderboxPrint: I/O write bytes / time
414,523,392 / 253,840 05:07:29 INFO - TinderboxPrint: CPU idle
8,280.2 (86.9%) 05:07:29 INFO - TinderboxPrint: CPU user
1,204.2 (12.6%) 05:07:29 INFO - TinderboxPrint: Swap in / out
0 / 0 05:07:29 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116916224; Read time: 0; Write time: 74344 05:07:29 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 421888; Write bytes: 297607168; Read time: 40; Write time: 179496 05:07:30 INFO - Running post-run listener: _upload_blobber_files 05:07:30 INFO - Blob upload gear active. 05:07:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:30 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 05:07:30 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'] 05:07:30 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 05:07:30 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:07:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:07:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:33 INFO - (blobuploader) - INFO - Done attempting. 05:07:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:07:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:07:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:34 INFO - (blobuploader) - INFO - Done attempting. 05:07:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:07:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:07:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:35 INFO - (blobuploader) - INFO - Done attempting. 05:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:07:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:36 INFO - (blobuploader) - INFO - Done attempting. 05:07:36 INFO - (blobuploader) - INFO - Iteration through files over. 05:07:36 INFO - Return code: 0 05:07:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:07:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:07:36 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc38d9480c3364c39fb9c2b3cb7970eb63b2f93698ce40db40cf272e2b5c0ab5a1c6c5635f953ff882bc2a6122b98fbf430bb3a107a78e9238a57b6cc03e19b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2272a67cf9f7bcaa2569346319643e8c7cab17acb7a41026b655cd8c092435c5a07c5d9b970b9473521024992d96fbd2807b769b4f36c237bc691a0969cf9c1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d7125f728d261427d5ac304d02ece00b124b27e8d2908518079959a134695b97fac44a67c2f62d89f7c70ef21c31c1f391589f48fd185ae220ff18b2fb203f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/429dd7ef78c4dd5a0583998cfa3756cb88e19972ce8e4cdb453239246b6575f2ce554439facd45b596cb5101832e35fec3a157ea9bf89750beb3a114182ca3fa"} 05:07:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:07:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:07:36 INFO - Contents: 05:07:36 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc38d9480c3364c39fb9c2b3cb7970eb63b2f93698ce40db40cf272e2b5c0ab5a1c6c5635f953ff882bc2a6122b98fbf430bb3a107a78e9238a57b6cc03e19b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2272a67cf9f7bcaa2569346319643e8c7cab17acb7a41026b655cd8c092435c5a07c5d9b970b9473521024992d96fbd2807b769b4f36c237bc691a0969cf9c1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d7125f728d261427d5ac304d02ece00b124b27e8d2908518079959a134695b97fac44a67c2f62d89f7c70ef21c31c1f391589f48fd185ae220ff18b2fb203f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/429dd7ef78c4dd5a0583998cfa3756cb88e19972ce8e4cdb453239246b6575f2ce554439facd45b596cb5101832e35fec3a157ea9bf89750beb3a114182ca3fa"} 05:07:36 INFO - Running post-run listener: copy_logs_to_upload_dir 05:07:36 INFO - Copying logs to upload dir... 05:07:36 INFO - mkdir: /builds/slave/test/build/upload/logs 05:07:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.635396 ========= master_lag: 0.46 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-23 05:07:36.921413) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 05:07:36.924915) ========= 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=3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc38d9480c3364c39fb9c2b3cb7970eb63b2f93698ce40db40cf272e2b5c0ab5a1c6c5635f953ff882bc2a6122b98fbf430bb3a107a78e9238a57b6cc03e19b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2272a67cf9f7bcaa2569346319643e8c7cab17acb7a41026b655cd8c092435c5a07c5d9b970b9473521024992d96fbd2807b769b4f36c237bc691a0969cf9c1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d7125f728d261427d5ac304d02ece00b124b27e8d2908518079959a134695b97fac44a67c2f62d89f7c70ef21c31c1f391589f48fd185ae220ff18b2fb203f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/429dd7ef78c4dd5a0583998cfa3756cb88e19972ce8e4cdb453239246b6575f2ce554439facd45b596cb5101832e35fec3a157ea9bf89750beb3a114182ca3fa"} build_url:https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011581 build_url: 'https://queue.taskcluster.net/v1/task/WS-HTJBfTGCHMwhEKtLPfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc38d9480c3364c39fb9c2b3cb7970eb63b2f93698ce40db40cf272e2b5c0ab5a1c6c5635f953ff882bc2a6122b98fbf430bb3a107a78e9238a57b6cc03e19b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2272a67cf9f7bcaa2569346319643e8c7cab17acb7a41026b655cd8c092435c5a07c5d9b970b9473521024992d96fbd2807b769b4f36c237bc691a0969cf9c1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d7125f728d261427d5ac304d02ece00b124b27e8d2908518079959a134695b97fac44a67c2f62d89f7c70ef21c31c1f391589f48fd185ae220ff18b2fb203f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/429dd7ef78c4dd5a0583998cfa3756cb88e19972ce8e4cdb453239246b6575f2ce554439facd45b596cb5101832e35fec3a157ea9bf89750beb3a114182ca3fa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 05:07:36.949699) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:07:36.949994) ========= 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=3a3620fb28aea1604ab609ef000001d2-1482489818.206624-1935348566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:07:36.999115) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 05:07:36.999401) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 05:07:36.999692) ========= ========= Total master_lag: 0.58 =========