builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1471079877.6 results: success (0) buildid: 20160812233009 builduid: 29f275f2cf534ec2aaca7367444ac8c4 revision: 4cf5aa807f27376197743b62eff2b2c3c2891b44 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.597651) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.598279) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.598562) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.616405) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.616669) ========= 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=bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-13 02:17:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2016-08-13 02:17:58 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123591 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.754691) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.755007) ========= 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=bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016356 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.804457) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:57.804807) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4cf5aa807f27376197743b62eff2b2c3c2891b44 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4cf5aa807f27376197743b62eff2b2c3c2891b44 --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=bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-13 02:17:58,586 truncating revision to first 12 chars 2016-08-13 02:17:58,586 Setting DEBUG logging. 2016-08-13 02:17:58,586 attempt 1/10 2016-08-13 02:17:58,586 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4cf5aa807f27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-13 02:17:58,947 unpacking tar archive at: fx-team-4cf5aa807f27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.699836 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:58.520126) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:58.520448) ========= script_repo_revision: 4cf5aa807f27376197743b62eff2b2c3c2891b44 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:58.520829) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:58.521098) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-13 02:17:58.535659) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-13 02:17:58.535933) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:17:59 INFO - MultiFileLogger online at 20160813 02:17:59 in /builds/slave/test 02:17:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:17:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:17:59 INFO - {'append_to_log': False, 02:17:59 INFO - 'base_work_dir': '/builds/slave/test', 02:17:59 INFO - 'blob_upload_branch': 'Fx-Team', 02:17:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:17:59 INFO - 'branch': 'Fx-Team', 02:17:59 INFO - 'buildbot_json_path': 'buildprops.json', 02:17:59 INFO - 'config_files': ('talos/linux_config.py',), 02:17:59 INFO - 'default_actions': ('clobber', 02:17:59 INFO - 'read-buildbot-config', 02:17:59 INFO - 'download-and-extract', 02:17:59 INFO - 'populate-webroot', 02:17:59 INFO - 'create-virtualenv', 02:17:59 INFO - 'install', 02:17:59 INFO - 'run-tests'), 02:17:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:17:59 INFO - 'download_minidump_stackwalk': True, 02:17:59 INFO - 'download_symbols': 'ondemand', 02:17:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:17:59 INFO - 'tooltool.py': '/tools/tooltool.py', 02:17:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:17:59 INFO - '/tools/misc-python/virtualenv.py')}, 02:17:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:17:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:17:59 INFO - 'installer_path': 'installer.exe', 02:17:59 INFO - 'log_level': 'info', 02:17:59 INFO - 'log_name': 'talos', 02:17:59 INFO - 'log_to_console': True, 02:17:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:17:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:17:59 INFO - 'opt_config_files': (), 02:17:59 INFO - 'pip_index': False, 02:17:59 INFO - 'sps_profile': False, 02:17:59 INFO - 'sps_profile_interval': 0, 02:17:59 INFO - 'suite': 'dromaeojs', 02:17:59 INFO - 'system_bits': '32', 02:17:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:17:59 INFO - 'title': 'talos-linux64-ix-055', 02:17:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:17:59 INFO - 'use_talos_json': True, 02:17:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:17:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:17:59 INFO - 'work_dir': 'build'} 02:17:59 INFO - [mozharness: 2016-08-13 09:17:59.406572Z] Running clobber step. 02:17:59 INFO - Running pre-action listener: _resource_record_pre_action 02:17:59 INFO - Running main action method: clobber 02:17:59 INFO - rmtree: /builds/slave/test/build 02:17:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:17:59 INFO - Running post-action listener: _resource_record_post_action 02:17:59 INFO - [mozharness: 2016-08-13 09:17:59.867648Z] Finished clobber step (success) 02:17:59 INFO - [mozharness: 2016-08-13 09:17:59.867748Z] Running read-buildbot-config step. 02:17:59 INFO - Running pre-action listener: _resource_record_pre_action 02:17:59 INFO - Running main action method: read_buildbot_config 02:17:59 INFO - Using buildbot properties: 02:17:59 INFO - { 02:17:59 INFO - "project": "", 02:17:59 INFO - "product": "firefox", 02:17:59 INFO - "script_repo_revision": "production", 02:17:59 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 02:17:59 INFO - "repository": "", 02:17:59 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 02:17:59 INFO - "buildid": "20160812233009", 02:17:59 INFO - "pgo_build": "True", 02:17:59 INFO - "basedir": "/builds/slave/test", 02:17:59 INFO - "buildnumber": 363, 02:17:59 INFO - "slavename": "talos-linux64-ix-055", 02:17:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:17:59 INFO - "platform": "ubuntu64_hw", 02:17:59 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 02:17:59 INFO - "branch": "fx-team", 02:17:59 INFO - "repo_path": "integration/fx-team", 02:17:59 INFO - "revision": "4cf5aa807f27376197743b62eff2b2c3c2891b44", 02:17:59 INFO - "stage_platform": "linux64-pgo", 02:17:59 INFO - "builduid": "29f275f2cf534ec2aaca7367444ac8c4", 02:17:59 INFO - "slavebuilddir": "test" 02:17:59 INFO - } 02:17:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 02:17:59 INFO - Running post-action listener: _resource_record_post_action 02:17:59 INFO - [mozharness: 2016-08-13 09:17:59.870172Z] Finished read-buildbot-config step (success) 02:17:59 INFO - [mozharness: 2016-08-13 09:17:59.870259Z] Running download-and-extract step. 02:17:59 INFO - Running pre-action listener: _resource_record_pre_action 02:17:59 INFO - Running main action method: download_and_extract 02:17:59 INFO - mkdir: /builds/slave/test/build/tests 02:17:59 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')]} 02:17:59 INFO - trying https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:17:59 INFO - Downloading https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:18:00 INFO - Downloaded 1361 bytes. 02:18:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:18:00 INFO - Using the following test package requirements: 02:18:00 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:18:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:18:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:18:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:00 INFO - u'jsshell-linux-x86_64.zip'], 02:18:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:18:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:18:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:18:00 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:18:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:18:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:18:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:00 INFO - trying https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:18:00 INFO - Downloading https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:18:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:18:01 INFO - Downloaded 21835886 bytes. 02:18:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 02:18:05 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:18:05 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')]} 02:18:05 INFO - trying https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:18:05 INFO - Downloading https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:18:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:18:06 INFO - Downloaded 21835886 bytes. 02:18:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 02:18:11 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')]} 02:18:11 INFO - trying https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 02:18:11 INFO - Downloading https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 02:18:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:18:11 INFO - Downloaded 14413719 bytes. 02:18:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 02:18:12 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')]} 02:18:12 INFO - trying https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:18:12 INFO - Downloading https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:18:13 INFO - Downloaded 59406926 bytes. 02:18:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:18:13 INFO - mkdir: /builds/slave/test/properties 02:18:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:18:13 INFO - Writing to file /builds/slave/test/properties/build_url 02:18:13 INFO - Contents: 02:18:13 INFO - build_url:https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:18:14 INFO - Running post-action listener: _resource_record_post_action 02:18:14 INFO - Running post-action listener: set_extra_try_arguments 02:18:14 INFO - [mozharness: 2016-08-13 09:18:14.130775Z] Finished download-and-extract step (success) 02:18:14 INFO - [mozharness: 2016-08-13 09:18:14.130999Z] Running populate-webroot step. 02:18:14 INFO - Running pre-action listener: _resource_record_pre_action 02:18:14 INFO - Running main action method: populate_webroot 02:18:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:18:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:18:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:18:14 INFO - '--tppagecycles', 02:18:14 INFO - '7'], 02:18:14 INFO - 'tests': ['tsvgm']}}, 02:18:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:18:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:18:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:18:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:18:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:14 INFO - 'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:14 INFO - 'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['damp', 'tps']}, 02:18:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:14 INFO - 'tests': ['damp', 'tps']}, 02:18:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['dromaeo_dom']}, 02:18:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:18:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['basic_compositor_video']}, 02:18:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:18:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['a11yr', 02:18:14 INFO - 'ts_paint', 02:18:14 INFO - 'tpaint', 02:18:14 INFO - 'sessionrestore', 02:18:14 INFO - 'sessionrestore_no_auto_restore', 02:18:14 INFO - 'tabpaint']}, 02:18:14 INFO - 'other-e10s': {'tests': ['a11yr', 02:18:14 INFO - 'ts_paint', 02:18:14 INFO - 'tpaint', 02:18:14 INFO - 'sessionrestore', 02:18:14 INFO - 'sessionrestore_no_auto_restore', 02:18:14 INFO - 'tabpaint']}, 02:18:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:18:14 INFO - 'ts_paint', 02:18:14 INFO - 'tpaint', 02:18:14 INFO - 'sessionrestore', 02:18:14 INFO - 'sessionrestore_no_auto_restore', 02:18:14 INFO - 'tabpaint']}, 02:18:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:18:14 INFO - 'ts_paint', 02:18:14 INFO - 'tpaint', 02:18:14 INFO - 'sessionrestore', 02:18:14 INFO - 'sessionrestore_no_auto_restore', 02:18:14 INFO - 'tabpaint']}, 02:18:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['a11yr', 02:18:14 INFO - 'ts_paint', 02:18:14 INFO - 'tpaint', 02:18:14 INFO - 'sessionrestore', 02:18:14 INFO - 'sessionrestore_no_auto_restore', 02:18:14 INFO - 'tabpaint']}, 02:18:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['a11yr', 02:18:14 INFO - 'ts_paint', 02:18:14 INFO - 'tpaint', 02:18:14 INFO - 'sessionrestore', 02:18:14 INFO - 'sessionrestore_no_auto_restore', 02:18:14 INFO - 'tabpaint']}, 02:18:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['tsvgx', 02:18:14 INFO - 'tsvgr_opacity', 02:18:14 INFO - 'tart', 02:18:14 INFO - 'tscrollx', 02:18:14 INFO - 'cart']}, 02:18:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:18:14 INFO - 'tsvgr_opacity', 02:18:14 INFO - 'tart', 02:18:14 INFO - 'tscrollx', 02:18:14 INFO - 'cart']}, 02:18:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:14 INFO - 'talos_options': ['--disable-e10s'], 02:18:14 INFO - 'tests': ['tp5o']}, 02:18:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:14 INFO - 'tests': ['tp5o']}, 02:18:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:14 INFO - 'talos_options': ['--disable-e10s', 02:18:14 INFO - '--xperf_path', 02:18:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:14 INFO - 'tests': ['tp5n']}, 02:18:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:14 INFO - 'talos_options': ['--xperf_path', 02:18:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:14 INFO - 'tests': ['tp5n']}}, 02:18:14 INFO - 'talos.zip': {'path': '', 02:18:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:18:14 INFO - Running post-action listener: _resource_record_post_action 02:18:14 INFO - [mozharness: 2016-08-13 09:18:14.150267Z] Finished populate-webroot step (success) 02:18:14 INFO - [mozharness: 2016-08-13 09:18:14.150355Z] Running create-virtualenv step. 02:18:14 INFO - Running pre-action listener: _resource_record_pre_action 02:18:14 INFO - Running main action method: create_virtualenv 02:18:14 INFO - Creating virtualenv /builds/slave/test/build/venv 02:18:14 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 02:18:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:18:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:18:14 INFO - Using real prefix '/usr' 02:18:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:18:14 INFO - Installing distribute.............................................................................................................................................................................................done. 02:18:16 INFO - Installing pip.................done. 02:18:16 INFO - Return code: 0 02:18:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18: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', 'pip>=1.5'] in /builds/slave/test/build 02:18: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 pip>=1.5 02:18:16 INFO - Using env: {'DISPLAY': ':0', 02:18:16 INFO - 'HOME': '/home/cltbld', 02:18:16 INFO - 'LANG': 'en_US.UTF-8', 02:18:16 INFO - 'LANGUAGE': 'en_US:en', 02:18:16 INFO - 'LOGNAME': 'cltbld', 02:18:16 INFO - 'MAIL': '/var/mail/cltbld', 02:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:16 INFO - 'MOZ_NO_REMOTE': '1', 02:18:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:16 INFO - 'NO_EM_RESTART': '1', 02:18:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:16 INFO - 'PWD': '/builds/slave/test', 02:18:16 INFO - 'SHELL': '/bin/bash', 02:18:16 INFO - 'SHLVL': '1', 02:18:16 INFO - 'TERM': 'linux', 02:18:16 INFO - 'TMOUT': '86400', 02:18:16 INFO - 'USER': 'cltbld', 02:18:16 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:16 INFO - '_': '/tools/buildbot/bin/python'} 02:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:16 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 02:18:16 INFO - Cleaning up... 02:18:16 INFO - Return code: 0 02:18:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18: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 02:18: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 02:18:16 INFO - Using env: {'DISPLAY': ':0', 02:18:16 INFO - 'HOME': '/home/cltbld', 02:18:16 INFO - 'LANG': 'en_US.UTF-8', 02:18:16 INFO - 'LANGUAGE': 'en_US:en', 02:18:16 INFO - 'LOGNAME': 'cltbld', 02:18:16 INFO - 'MAIL': '/var/mail/cltbld', 02:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:16 INFO - 'MOZ_NO_REMOTE': '1', 02:18:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:16 INFO - 'NO_EM_RESTART': '1', 02:18:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:16 INFO - 'PWD': '/builds/slave/test', 02:18:16 INFO - 'SHELL': '/bin/bash', 02:18:16 INFO - 'SHLVL': '1', 02:18:16 INFO - 'TERM': 'linux', 02:18:16 INFO - 'TMOUT': '86400', 02:18:16 INFO - 'USER': 'cltbld', 02:18:16 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:16 INFO - '_': '/tools/buildbot/bin/python'} 02:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:17 INFO - Downloading/unpacking psutil>=3.1.1 02:18:17 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 02:18:17 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 02:18:17 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 02:18:17 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 02:18:17 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 02:18:17 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 02:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:18:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:19 INFO - Installing collected packages: psutil 02:18:19 INFO - Running setup.py install for psutil 02:18:19 INFO - building 'psutil._psutil_linux' extension 02:18:19 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 02:18:19 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 02:18:19 INFO - building 'psutil._psutil_posix' extension 02:18:19 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 02:18:19 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 02:18:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:19 INFO - Successfully installed psutil 02:18:19 INFO - Cleaning up... 02:18:19 INFO - Return code: 0 02:18:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:18:19 INFO - Using env: {'DISPLAY': ':0', 02:18:19 INFO - 'HOME': '/home/cltbld', 02:18:19 INFO - 'LANG': 'en_US.UTF-8', 02:18:19 INFO - 'LANGUAGE': 'en_US:en', 02:18:19 INFO - 'LOGNAME': 'cltbld', 02:18:19 INFO - 'MAIL': '/var/mail/cltbld', 02:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:19 INFO - 'MOZ_NO_REMOTE': '1', 02:18:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:19 INFO - 'NO_EM_RESTART': '1', 02:18:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:19 INFO - 'PWD': '/builds/slave/test', 02:18:19 INFO - 'SHELL': '/bin/bash', 02:18:19 INFO - 'SHLVL': '1', 02:18:19 INFO - 'TERM': 'linux', 02:18:19 INFO - 'TMOUT': '86400', 02:18:19 INFO - 'USER': 'cltbld', 02:18:19 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:19 INFO - '_': '/tools/buildbot/bin/python'} 02:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:18:21 INFO - Installing collected packages: mozsystemmonitor 02:18:21 INFO - Running setup.py install for mozsystemmonitor 02:18:21 INFO - Successfully installed mozsystemmonitor 02:18:21 INFO - Cleaning up... 02:18:22 INFO - Return code: 0 02:18:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:18:22 INFO - Using env: {'DISPLAY': ':0', 02:18:22 INFO - 'HOME': '/home/cltbld', 02:18:22 INFO - 'LANG': 'en_US.UTF-8', 02:18:22 INFO - 'LANGUAGE': 'en_US:en', 02:18:22 INFO - 'LOGNAME': 'cltbld', 02:18:22 INFO - 'MAIL': '/var/mail/cltbld', 02:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:22 INFO - 'MOZ_NO_REMOTE': '1', 02:18:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:22 INFO - 'NO_EM_RESTART': '1', 02:18:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:22 INFO - 'PWD': '/builds/slave/test', 02:18:22 INFO - 'SHELL': '/bin/bash', 02:18:22 INFO - 'SHLVL': '1', 02:18:22 INFO - 'TERM': 'linux', 02:18:22 INFO - 'TMOUT': '86400', 02:18:22 INFO - 'USER': 'cltbld', 02:18:22 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:22 INFO - '_': '/tools/buildbot/bin/python'} 02:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:22 INFO - Downloading/unpacking jsonschema==2.5.1 02:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:18:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:24 INFO - Downloading functools32-3.2.3-2.tar.gz 02:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:18:24 INFO - warning: no files found matching '*.txt' 02:18:24 INFO - no previously-included directories found matching 'build' 02:18:24 INFO - no previously-included directories found matching 'dist' 02:18:24 INFO - no previously-included directories found matching '.git*' 02:18:24 INFO - Installing collected packages: jsonschema, functools32 02:18:24 INFO - Running setup.py install for functools32 02:18:24 INFO - warning: no files found matching '*.txt' 02:18:24 INFO - no previously-included directories found matching 'build' 02:18:24 INFO - no previously-included directories found matching 'dist' 02:18:24 INFO - no previously-included directories found matching '.git*' 02:18:24 INFO - Successfully installed jsonschema functools32 02:18:24 INFO - Cleaning up... 02:18:24 INFO - Return code: 0 02:18:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18: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 02:18: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 02:18:24 INFO - Using env: {'DISPLAY': ':0', 02:18:24 INFO - 'HOME': '/home/cltbld', 02:18:24 INFO - 'LANG': 'en_US.UTF-8', 02:18:24 INFO - 'LANGUAGE': 'en_US:en', 02:18:24 INFO - 'LOGNAME': 'cltbld', 02:18:24 INFO - 'MAIL': '/var/mail/cltbld', 02:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:24 INFO - 'MOZ_NO_REMOTE': '1', 02:18:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:24 INFO - 'NO_EM_RESTART': '1', 02:18:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:24 INFO - 'PWD': '/builds/slave/test', 02:18:24 INFO - 'SHELL': '/bin/bash', 02:18:24 INFO - 'SHLVL': '1', 02:18:24 INFO - 'TERM': 'linux', 02:18:24 INFO - 'TMOUT': '86400', 02:18:24 INFO - 'USER': 'cltbld', 02:18:24 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:24 INFO - '_': '/tools/buildbot/bin/python'} 02:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:18:24 INFO - Cleaning up... 02:18:24 INFO - Return code: 0 02:18:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18: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 02:18: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 02:18:24 INFO - Using env: {'DISPLAY': ':0', 02:18:24 INFO - 'HOME': '/home/cltbld', 02:18:24 INFO - 'LANG': 'en_US.UTF-8', 02:18:24 INFO - 'LANGUAGE': 'en_US:en', 02:18:24 INFO - 'LOGNAME': 'cltbld', 02:18:24 INFO - 'MAIL': '/var/mail/cltbld', 02:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:24 INFO - 'MOZ_NO_REMOTE': '1', 02:18:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:24 INFO - 'NO_EM_RESTART': '1', 02:18:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:24 INFO - 'PWD': '/builds/slave/test', 02:18:24 INFO - 'SHELL': '/bin/bash', 02:18:24 INFO - 'SHLVL': '1', 02:18:24 INFO - 'TERM': 'linux', 02:18:24 INFO - 'TMOUT': '86400', 02:18:24 INFO - 'USER': 'cltbld', 02:18:24 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:24 INFO - '_': '/tools/buildbot/bin/python'} 02:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:24 INFO - Downloading/unpacking blobuploader==1.2.4 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:26 INFO - Downloading blobuploader-1.2.4.tar.gz 02:18:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:18:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:18:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:27 INFO - Downloading docopt-0.6.1.tar.gz 02:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:18:27 INFO - Installing collected packages: blobuploader, requests, docopt 02:18:27 INFO - Running setup.py install for blobuploader 02:18:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:18:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:18:27 INFO - Running setup.py install for requests 02:18:28 INFO - Running setup.py install for docopt 02:18:28 INFO - Successfully installed blobuploader requests docopt 02:18:28 INFO - Cleaning up... 02:18:28 INFO - Return code: 0 02:18:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18:28 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')]} 02:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:28 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')]} 02:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:28 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 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18:28 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 02:18:28 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 02:18:28 INFO - Using env: {'DISPLAY': ':0', 02:18:28 INFO - 'HOME': '/home/cltbld', 02:18:28 INFO - 'LANG': 'en_US.UTF-8', 02:18:28 INFO - 'LANGUAGE': 'en_US:en', 02:18:28 INFO - 'LOGNAME': 'cltbld', 02:18:28 INFO - 'MAIL': '/var/mail/cltbld', 02:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:28 INFO - 'MOZ_NO_REMOTE': '1', 02:18:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:28 INFO - 'NO_EM_RESTART': '1', 02:18:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:28 INFO - 'PWD': '/builds/slave/test', 02:18:28 INFO - 'SHELL': '/bin/bash', 02:18:28 INFO - 'SHLVL': '1', 02:18:28 INFO - 'TERM': 'linux', 02:18:28 INFO - 'TMOUT': '86400', 02:18:28 INFO - 'USER': 'cltbld', 02:18:28 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:28 INFO - '_': '/tools/buildbot/bin/python'} 02:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:28 INFO - Running setup.py (path:/tmp/pip-fiWCpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:28 INFO - Running setup.py (path:/tmp/pip-Pz7HqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:28 INFO - Running setup.py (path:/tmp/pip-7sMmw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:28 INFO - Running setup.py (path:/tmp/pip-cLzwrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:28 INFO - Running setup.py (path:/tmp/pip-a4_0Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:28 INFO - Running setup.py (path:/tmp/pip-1l93gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:28 INFO - Running setup.py (path:/tmp/pip-nSwpjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:28 INFO - Running setup.py (path:/tmp/pip-dNgY0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:28 INFO - Running setup.py (path:/tmp/pip-ChICoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:29 INFO - Running setup.py (path:/tmp/pip-gPKbES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:29 INFO - Running setup.py (path:/tmp/pip-V7jKY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:29 INFO - Running setup.py (path:/tmp/pip-zYEXxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:29 INFO - Running setup.py (path:/tmp/pip-gDR5ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:29 INFO - Running setup.py (path:/tmp/pip-zZx7yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:29 INFO - Running setup.py (path:/tmp/pip-FWbFe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:29 INFO - Running setup.py (path:/tmp/pip-F2djqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:29 INFO - Running setup.py (path:/tmp/pip-Zwedo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:18:29 INFO - Running setup.py install for manifestparser 02:18:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:18:29 INFO - Running setup.py install for mozcrash 02:18:29 INFO - Running setup.py install for mozdebug 02:18:29 INFO - Running setup.py install for mozdevice 02:18:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Running setup.py install for mozfile 02:18:30 INFO - Running setup.py install for mozhttpd 02:18:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Running setup.py install for mozinfo 02:18:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Running setup.py install for mozInstall 02:18:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Running setup.py install for mozleak 02:18:30 INFO - Running setup.py install for mozlog 02:18:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Running setup.py install for moznetwork 02:18:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:18:30 INFO - Running setup.py install for mozprocess 02:18:30 INFO - Running setup.py install for mozprofile 02:18:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:18:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:18:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:18:31 INFO - Running setup.py install for mozrunner 02:18:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:18:31 INFO - Running setup.py install for mozscreenshot 02:18:31 INFO - Running setup.py install for moztest 02:18:31 INFO - Running setup.py install for mozversion 02:18:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:18:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:18:31 INFO - Cleaning up... 02:18:31 INFO - Return code: 0 02:18:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18: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 02:18: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 02:18:31 INFO - Using env: {'DISPLAY': ':0', 02:18:31 INFO - 'HOME': '/home/cltbld', 02:18:31 INFO - 'LANG': 'en_US.UTF-8', 02:18:31 INFO - 'LANGUAGE': 'en_US:en', 02:18:31 INFO - 'LOGNAME': 'cltbld', 02:18:31 INFO - 'MAIL': '/var/mail/cltbld', 02:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:31 INFO - 'MOZ_NO_REMOTE': '1', 02:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:31 INFO - 'NO_EM_RESTART': '1', 02:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:31 INFO - 'PWD': '/builds/slave/test', 02:18:31 INFO - 'SHELL': '/bin/bash', 02:18:31 INFO - 'SHLVL': '1', 02:18:31 INFO - 'TERM': 'linux', 02:18:31 INFO - 'TMOUT': '86400', 02:18:31 INFO - 'USER': 'cltbld', 02:18:31 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:31 INFO - '_': '/tools/buildbot/bin/python'} 02:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:31 INFO - Running setup.py (path:/tmp/pip-8hLqo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18: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)) 02:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:31 INFO - Running setup.py (path:/tmp/pip-_Nietv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:31 INFO - Running setup.py (path:/tmp/pip-aw4yBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18: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)) 02:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:31 INFO - Running setup.py (path:/tmp/pip-jI66Cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:31 INFO - Running setup.py (path:/tmp/pip-cCw6rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:32 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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:32 INFO - Running setup.py (path:/tmp/pip-PBr6WV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18:32 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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:32 INFO - Running setup.py (path:/tmp/pip-FWB2HJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:32 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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:32 INFO - Running setup.py (path:/tmp/pip-LgwkSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18: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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:32 INFO - Running setup.py (path:/tmp/pip-RjgPVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18: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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:32 INFO - Running setup.py (path:/tmp/pip-cbEA8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:32 INFO - Running setup.py (path:/tmp/pip-VWEZXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18: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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:32 INFO - Running setup.py (path:/tmp/pip-0Ih0dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18: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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:32 INFO - Running setup.py (path:/tmp/pip-X_MquV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18: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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:32 INFO - Running setup.py (path:/tmp/pip-l9M88E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:32 INFO - Running setup.py (path:/tmp/pip-tVhSe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18: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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:32 INFO - Running setup.py (path:/tmp/pip-CnLNxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18: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)) 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:32 INFO - Running setup.py (path:/tmp/pip-St5Frp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18: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)) 02:18: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:18: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:18: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)) 02:18: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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:18: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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:18:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:34 INFO - Downloading blessings-1.6.tar.gz 02:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:18:34 INFO - Installing collected packages: blessings 02:18:34 INFO - Running setup.py install for blessings 02:18:35 INFO - Successfully installed blessings 02:18:35 INFO - Cleaning up... 02:18:35 INFO - Return code: 0 02:18:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:35 INFO - Reading from file tmpfile_stdout 02:18:35 INFO - Current package versions: 02:18:35 INFO - argparse == 1.2.1 02:18:35 INFO - blessings == 1.6 02:18:35 INFO - blobuploader == 1.2.4 02:18:35 INFO - docopt == 0.6.1 02:18:35 INFO - functools32 == 3.2.3-2 02:18:35 INFO - jsonschema == 2.5.1 02:18:35 INFO - manifestparser == 1.1 02:18:35 INFO - mozInstall == 1.12 02:18:35 INFO - mozcrash == 0.17 02:18:35 INFO - mozdebug == 0.1 02:18:35 INFO - mozdevice == 0.48 02:18:35 INFO - mozfile == 1.2 02:18:35 INFO - mozhttpd == 0.7 02:18:35 INFO - mozinfo == 0.9 02:18:35 INFO - mozleak == 0.1 02:18:35 INFO - mozlog == 3.3 02:18:35 INFO - moznetwork == 0.27 02:18:35 INFO - mozprocess == 0.23 02:18:35 INFO - mozprofile == 0.28 02:18:35 INFO - mozrunner == 6.12 02:18:35 INFO - mozscreenshot == 0.1 02:18:35 INFO - mozsystemmonitor == 0.3 02:18:35 INFO - moztest == 0.7 02:18:35 INFO - mozversion == 1.4 02:18:35 INFO - psutil == 3.1.1 02:18:35 INFO - requests == 1.2.3 02:18:35 INFO - wsgiref == 0.1.2 02:18:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18: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 02:18: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 02:18:35 INFO - Using env: {'DISPLAY': ':0', 02:18:35 INFO - 'HOME': '/home/cltbld', 02:18:35 INFO - 'LANG': 'en_US.UTF-8', 02:18:35 INFO - 'LANGUAGE': 'en_US:en', 02:18:35 INFO - 'LOGNAME': 'cltbld', 02:18:35 INFO - 'MAIL': '/var/mail/cltbld', 02:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:35 INFO - 'MOZ_NO_REMOTE': '1', 02:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:35 INFO - 'NO_EM_RESTART': '1', 02:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:35 INFO - 'PWD': '/builds/slave/test', 02:18:35 INFO - 'SHELL': '/bin/bash', 02:18:35 INFO - 'SHLVL': '1', 02:18:35 INFO - 'TERM': 'linux', 02:18:35 INFO - 'TMOUT': '86400', 02:18:35 INFO - 'USER': 'cltbld', 02:18:35 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:35 INFO - '_': '/tools/buildbot/bin/python'} 02:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18:35 INFO - Cleaning up... 02:18:35 INFO - Return code: 0 02:18:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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 0x7fe68a788f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1753850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, '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': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', '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 02:18: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 02:18: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 02:18:35 INFO - Using env: {'DISPLAY': ':0', 02:18:35 INFO - 'HOME': '/home/cltbld', 02:18:35 INFO - 'LANG': 'en_US.UTF-8', 02:18:35 INFO - 'LANGUAGE': 'en_US:en', 02:18:35 INFO - 'LOGNAME': 'cltbld', 02:18:35 INFO - 'MAIL': '/var/mail/cltbld', 02:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:35 INFO - 'MOZ_NO_REMOTE': '1', 02:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:35 INFO - 'NO_EM_RESTART': '1', 02:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:35 INFO - 'PWD': '/builds/slave/test', 02:18:35 INFO - 'SHELL': '/bin/bash', 02:18:35 INFO - 'SHLVL': '1', 02:18:35 INFO - 'TERM': 'linux', 02:18:35 INFO - 'TMOUT': '86400', 02:18:35 INFO - 'USER': 'cltbld', 02:18:35 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:35 INFO - '_': '/tools/buildbot/bin/python'} 02:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:18:35 INFO - Cleaning up... 02:18:35 INFO - Return code: 0 02:18:35 INFO - Running post-action listener: _resource_record_post_action 02:18:35 INFO - Running post-action listener: _start_resource_monitoring 02:18:35 INFO - Starting resource monitoring. 02:18:35 INFO - [mozharness: 2016-08-13 09:18:35.738020Z] Finished create-virtualenv step (success) 02:18:35 INFO - [mozharness: 2016-08-13 09:18:35.738473Z] Running install step. 02:18:35 INFO - Running pre-action listener: _resource_record_pre_action 02:18:35 INFO - Running main action method: install 02:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:35 INFO - Reading from file tmpfile_stdout 02:18:35 INFO - Detecting whether we're running mozinstall >=1.0... 02:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:18:36 INFO - Reading from file tmpfile_stdout 02:18:36 INFO - Output received: 02:18:36 INFO - Usage: mozinstall [options] installer 02:18:36 INFO - Options: 02:18:36 INFO - -h, --help show this help message and exit 02:18:36 INFO - -d DEST, --destination=DEST 02:18:36 INFO - Directory to install application into. [default: 02:18:36 INFO - "/builds/slave/test"] 02:18:36 INFO - --app=APP Application being installed. [default: firefox] 02:18:36 INFO - mkdir: /builds/slave/test/build/application 02:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:18:52 INFO - Reading from file tmpfile_stdout 02:18:52 INFO - Output received: 02:18:52 INFO - /builds/slave/test/build/application/firefox/firefox 02:18:52 INFO - Running post-action listener: _resource_record_post_action 02:18:52 INFO - [mozharness: 2016-08-13 09:18:52.929219Z] Finished install step (success) 02:18:52 INFO - [mozharness: 2016-08-13 09:18:52.929465Z] Running run-tests step. 02:18:52 INFO - Running pre-action listener: _resource_record_pre_action 02:18:52 INFO - Running main action method: run_tests 02:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:18:52 INFO - Python 2.7.3 02:18:52 INFO - Return code: 0 02:18:52 INFO - grabbing minidump binary from tooltool 02:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:52 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 0x174e370>, '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 0x17518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1751d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:18:52 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 02:18:52 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 02:18:52 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 02:18:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:18:53 INFO - Return code: 0 02:18:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:18:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:18:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:18:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:18:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 02:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 02:18:53 INFO - Using env: {'DISPLAY': ':0', 02:18:53 INFO - 'HOME': '/home/cltbld', 02:18:53 INFO - 'LANG': 'en_US.UTF-8', 02:18:53 INFO - 'LANGUAGE': 'en_US:en', 02:18:53 INFO - 'LOGNAME': 'cltbld', 02:18:53 INFO - 'MAIL': '/var/mail/cltbld', 02:18:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:18:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:53 INFO - 'MOZ_NO_REMOTE': '1', 02:18:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:53 INFO - 'NO_EM_RESTART': '1', 02:18:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:53 INFO - 'PWD': '/builds/slave/test', 02:18:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:18:53 INFO - 'SHELL': '/bin/bash', 02:18:53 INFO - 'SHLVL': '1', 02:18:53 INFO - 'TERM': 'linux', 02:18:53 INFO - 'TMOUT': '86400', 02:18:53 INFO - 'USER': 'cltbld', 02:18:53 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419', 02:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:53 INFO - '_': '/tools/buildbot/bin/python'} 02:18:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 02:18:53 INFO - mozversion application_buildid: 20160812233009 02:18:53 INFO - mozversion application_changeset: 4cf5aa807f27376197743b62eff2b2c3c2891b44 02:18:53 INFO - mozversion application_display_name: Nightly 02:18:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:18:53 INFO - mozversion application_name: Firefox 02:18:53 INFO - mozversion application_remotingname: firefox 02:18:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:18:53 INFO - mozversion application_vendor: Mozilla 02:18:53 INFO - mozversion application_version: 51.0a1 02:18:53 INFO - mozversion platform_buildid: 20160812233009 02:18:53 INFO - mozversion platform_changeset: 4cf5aa807f27376197743b62eff2b2c3c2891b44 02:18:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:18:53 INFO - mozversion platform_version: 51.0a1 02:18:53 INFO - using testdate: 1471079933 02:18:53 INFO - actual date: 1471079933 02:18:53 INFO - starting webserver on 'localhost:50767' 02:18:53 INFO - SUITE-START | Running 2 tests 02:18:53 INFO - TEST-START | dromaeo_css 02:18:53 INFO - operating with platform_type : linux_ 02:18:53 INFO - Initialising browser for dromaeo_css test... 02:18:53 INFO - TEST-INFO | started process 19301 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdrA8dI/profile http://localhost:50767/getInfo.html) 02:18:57 INFO - PROCESS | 19301 | __metrics Screen width/height:1600/1200 02:18:57 INFO - PROCESS | 19301 | colorDepth:24 02:18:57 INFO - PROCESS | 19301 | Browser inner width/height: 1024/697 02:18:57 INFO - PROCESS | 19301 | __metrics 02:18:58 INFO - PROCESS | 19301 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:18:58 INFO - PROCESS | 19301 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:18:59 INFO - TEST-INFO | 19301: exit 0 02:18:59 INFO - Browser initialized. 02:18:59 INFO - Running cycle 1/1 for dromaeo_css test... 02:18:59 INFO - TEST-INFO | started process 19381 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdrA8dI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:19:10 INFO - PROCESS | 19381 | RSS: Main: 155607040 02:19:10 INFO - PROCESS | 19381 | 02:20:02 INFO - PROCESS | 19381 | 1471080002505 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] 02:22:11 INFO - PROCESS | 19381 | Cycle 1(1): loaded http://localhost:50767/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50767/tests/dromaeo/cssquery-ext.html) 02:22:13 INFO - PROCESS | 19381 | RSS: Main: 264704000 02:22:13 INFO - PROCESS | 19381 | 02:25:15 INFO - PROCESS | 19381 | Cycle 1(1): loaded http://localhost:50767/tests/dromaeo/cssquery-ext.html (next: http://localhost:50767/tests/dromaeo/cssquery-jquery.html) 02:25:16 INFO - PROCESS | 19381 | RSS: Main: 244277248 02:25:16 INFO - PROCESS | 19381 | 02:28:18 INFO - PROCESS | 19381 | Cycle 1(1): loaded http://localhost:50767/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50767/tests/dromaeo/cssquery-mootools.html) 02:28:18 INFO - PROCESS | 19381 | RSS: Main: 184000512 02:28:18 INFO - PROCESS | 19381 | 02:31:20 INFO - PROCESS | 19381 | Cycle 1(1): loaded http://localhost:50767/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50767/tests/dromaeo/cssquery-prototype.html) 02:31:21 INFO - PROCESS | 19381 | RSS: Main: 236777472 02:31:21 INFO - PROCESS | 19381 | 02:34:24 INFO - PROCESS | 19381 | Cycle 1(1): loaded http://localhost:50767/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50767/tests/dromaeo/cssquery-yui.html) 02:34:24 INFO - PROCESS | 19381 | RSS: Main: 182251520 02:34:24 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | Cycle 1(1): loaded http://localhost:50767/tests/dromaeo/cssquery-yui.html (next: http://localhost:50767/tests/dromaeo/cssquery-dojo.html) 02:37:27 INFO - PROCESS | 19381 | RSS: Main: 262754304 02:37:27 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | __start_tp_report 02:37:27 INFO - PROCESS | 19381 | _x_x_mozilla_page_load 02:37:27 INFO - PROCESS | 19381 | _x_x_mozilla_page_load_details 02:37:27 INFO - PROCESS | 19381 | |i|pagename|runs| 02:37:27 INFO - PROCESS | 19381 | |0;dojo.html;3063.2188791858075;3082.4605694003544;3084.1672748357946;3099.0702789163247;3104.9689503104964;5309.814156504523;5319.228711836781;5398.4331645177235;5402.459754024596;5468.617196796231;5422.322209723785;5439.755211015504;5462.672239665623;5492.368377636573;5507.421720719327;4874.46380898101;4890.242012488052;4964.826231081913;4976.129177393942;5087.745612719349;5011.273365362021;5019.974900125494;5061.139606266925;5068.366454193226;5071.087204303224;4798.664093513456;4813.78337974792;4878.634102442331;4931.80272789088;4966.503349665023;4816.975915120437;4826.420829500447;4829.613630909519;4900.093482705711;4944.085344211297;4855.635827312965;4870.123377791996;4871.050145221683;4871.8294859679745;4909.754512274408;5297.046531624306;5312.681239125664;5364.195370694388;5373.435789242112;5403.8108666196495;5811.418858114197;5835.620684655484;5837.29952405714;5839.474447299764;5839.591228613998;5518.2826232589905;5882.058870580687;5884.293884733816;5887.646741195542;5891.793787217427;5364.14173732201;5429.321334833146;5430.59270554706;5502.119660854244;5511.3937466878615;6042.939570604326;6052.243469566304;6053;6054.939450605526;6056.727447264883;5743.368229494752;5746.856328591819;5786.1610066540115;5787.594868359259;5793.594448388572;5583.916241256385;5709.143628455682;5736.282964629421;5743.54051675865;5838.095095260242;5732.426757324318;5766.394528574439;5770.913436298459;5801.245838041112;5821.53427725781;5703.458171473703;5758.2514273144225;5765.740541675635;5773.913391299134;5778.711064446761;4277.786110694453;4342.500612429548;4406.228909940812;4444.644428445717;4476.7090139140855;3929.587393323717;3932.508436445444;3956.090099277125;3970.007498125469;3983.4224037514978;5390.191471279261;5394.757235924393;5396.163594642837;5401.3248343957;5437.673739575559;5675.063614503643;5793.971030144822;5808.680522571299;5827.417258274223;5859.589828711949;5470.179473078991;5475.6440831345835;5479;5493.862653433616;5499.477549632778;5027.371578552681;5038.067957427815;5038.974805126024;5042.168042273057;5076.670016448919;5777;5907.527397808251;6032.065029920369;6125.9693701531205;6135.60118592299;5824.9417505826095;5832.950068987453;5841.415858414124;5857.765689372377;5869.4717475427415;6028.57799953999;6029.8191054268445;6030.276366836008;6031.065184896348;6035.758569657164;5497.862553436036;5711.628744131618;5722.62802917809;5774.82675519735;5795.33353664334;5881.7647294109465;5931.169636250842;5937.643741375531;5955;5981.521478281814;5769.134629805395;5771.220885180447;5786.247787787561;5791.276090488689;5792.131180322816;5506.559475241891;5674.659520428788;5698.914516282342;5757.193992841089;5763.538916886723;5706.28671416073;5707.086866101404;5708.914366284426;5746.454086861741;5757.827265182052;5797.91303130445;5805.680687562224;5838.299404071538;5841.065429531254;5852.590318677651;5357.303550538561;5398.46015398457;5531.474509921551;5597.076482380362;5649.4633009864 02:37:27 INFO - PROCESS | 19381 | |1;ext.html;587.2219309415024;595.6842873277159;605.470213563132;607.0378450156502;625.4152367536354;29580.704192958063;31902;44261.557384426145;45403.545964540346;45484;45989.770051149695;46322.30516542255;46487.30269045958;46550.76724616381;46594.76702616482;8762.737117886458;8939.955300223522;8958.32812539059;8961.731148065568;8965;8897.866532002025;9005.594748236312;9007.684731034382;9015.413998090136;9016.504092274923;8989.730308090768;9036.186743193111;9036.638534458647;9039.593218305192;9044.23124034458;8978.551072446384;9031.367804253705;9034;9045.412048216846;9048.909510904872;8928.73213803587;9004.774880628036;9005.189532942;9009.684661036898;9011.549422528913;44342;44656.55343446589;44736.10527789426;44810.32784508235;44844.55155448469;3756.492873462075;3824.560175579816;3994.9201015979515;4083.8774836754947;4365.956340436586;33700.49449258263;34120.48819267713;34226.82886585576;34258.828705856315;35166.29667406663;4088.7955602220068;4093.2018256440006;4109.0959988802415;4218.093109981338;4261.6803739719335;347309.26345368364;347944;348635;349436;350242;36376.09059773527;59978.70010650006;60320.39679603148;60360;60394.39605603975;37277.627223727955;38830.22339553193;38985.6101438982;39204.411933821015;39226.803865981055;15663.138527380876;15784.36862525509;15898.125603091941;15933.681326373408;15948.840511594968;11609.593664221711;11691.240069395462;11695.824562631567;11697.473613687234;11697.473613687405;8752.081031491729;8807.515586642796;8811.691590794291;8851.114888511227;8870.955645221733;3318;3325.517799918998;3331.800091994488;3353.7987720736833;3362.2603027334435;1011.4335971855784;1069.5828626835541;1071.769569542552;1094.8084085285043;1148.4889224295275;33018.33358175566;33248;33597.66402335945;33610.327793444;33840.49239261413;1092.874319453273;1106.4578356605205;1122.3770807201918;1123.466353482106;1150.9654710358702;17387.82612173862;17558.12209389551;17577.91211043962;17634.206460709298;17694.557636059202;563206;579750;580303;586316;589670;60348;60885.391146088856;60905.39094609086;60962.695186523786;60994;1237.6039667306557;1258.0250306012913;1308.8363954505687;1318.5253308808822;1340.4571148684993;30631.161246849006;35475.67554130249;35722;35780.64219357773;35830.215328195234;54590;60631.39368606434;63644.68177659082;63651.36348636454;63758.68120659367;474696;482448.9387765334;485505;486177;486903;36865.815670922544;43857.56142438662;44848.55151448444;47038;47444;50488.74755626198;50689.493105068475;50749;50932.74533627308;51274;40576.166316049996;42852.78573607112;43228.56771432245;43264.78367608142;43836.34245486256;42902.14195716131;43052.13895722006;43057;43625;43832;43052.35421468618;43402.13195736131;43947.34078988754;44014.339784902615;44097.338539921286;35193;37934.8103259493;38802.6119738799;38845;38901.22197555976;28421.573676395285;28758.29402829959;29008.854955725088;29019.85490072536;29222.707772922 02:37:27 INFO - PROCESS | 19381 | |2;jquery.html;399.8400639744102;399.96800255979485;403.26404312130364;409.44111288091756;421.10305050242977;13972.860271397309;19510.21959121635;20404.489887752796;20424;20540.589188216225;16158.75761863573;19340;20211;20391.490212744688;20410.89794551029;5649.265595472582;5848.327442344143;5851.356350801409;5856.648601083927;5859.384764599719;5564.777408903659;5696.746715722535;5736.455036771521;5787.826365209029;5814.825555233349;5676.233708449347;5710.600257981943;5722.141678748179;5722.628029178111;5722.885542289152;5522;5697.6296540724725;5702.600817942745;5704.800331988381;5756.82729518111;5154.613403994678;5156.819511317086;5160.767765450526;5167.0957582422925;5168.379794424655;23453.765462345327;24455.510889782105;24726.876365618056;24815.13147039845;25049.624255635998;2402.7957623602015;2550.85970271635;2600.180943002948;2617.9869100654373;2988.3126880817317;9957.5021248938;14755.409783608642;15419.460318888898;15572.29924653382;15902.840971590253;1904.8951608067289;1924.1052910396734;1958.7649482062243;1976.3774411060613;2009.8995050247574;84751.57624211902;87583.56208218982;89321;89658.99999999935;92307.07692923119;11431.542738290542;11596.304221746723;11664.94167529157;11779.646610601696;11912.583059592873;16320.591985200464;16325.26536305869;16355;16361.100139492204;16362.345506179858;8285.917140828515;8456.746297611082;8461.180846118114;8613.353998450017;8732;8100.594970251464;8168.305694016075;8230.711925082593;8280.254777069973;8404.831903362021;4190.55999120088;4458.130664520388;4516.480604730433;4527.298268768346;4606.585407313358;2026.8277196438469;2060.7939206079272;2093.8324934005245;2106.2206983416236;2139.8074173324476;879.8548239540532;933.2767105493297;945.0832692288469;996.6860438961704;1036.5698235232444;18600.906995465208;20661.380158595268;20873.582528349347;20970.895145524173;21136.682949755766;1036.6730584851402;1063.691529456449;1096.7696783675517;1098.4288170151635;1106.6679996001167;10536.262461627612;11569;11650.825237621442;11658.766824663458;11684.824727629093;477718;491269;501597;502387;505483;89518;92202;93591;94259;94600;959.4387283439127;962.8363178259713;983.1299300119302;984.1683777208248;985.4973963278779;16330.346786128897;17695.203715832562;18315.450536483928;18375.356862509747;18651.813481865007;41715.58284417117;43809.78095109631;43954;44050.77974610106;44563.77718111389;56612;57539.7123014399;58081;58416.4158358411;59944.70027649834;8200.343972482306;8460.323174146177;8739.12608739121;8827.646894124164;9251.722448326562;10765.946170269099;13249.735005300034;13253.80119298192;13343.933280333536;13476.730465390441;11512.079033677119;11652;11820.404245426756;11932.164748467523;12040.819387709367;11550.364729939934;11605.709857253636;11801.055915526566;11864.940675296568;12103.697407564881;11939.76120477603;12009.699757506132;12044.75910481768;12319.076069295019;12443.191192572454;8665.43674661145;9012.954935225544;9079.409838360485;9087.409318394282;9270.304727145356;9622.37454565451;12283.38583070825;12489.750204995667;12555;12670.683232918882 02:37:27 INFO - PROCESS | 19381 | |3;mootools.html;1174.0666170394536;1460.5545308680855;1496.1995332497115;1612.2261314569007;1747.3709464592753;8341.12418196089;8800.867986980193;8839.116088391158;9140;9165.587548560314;8937.597808098635;9060.592273347698;9061.546922653857;9064.320175986812;9064.320175986812;2059.927902523412;2351.0360752091647;2426.4297889995837;2429.137656132074;2450.3261603059123;2417.2385698504913;2418.5888398972215;2419.987900060506;2423.3578101803037;2423.5274121546304;2357.8231632627535;2387.152560840894;2391.330427480308;2392.031227352916;2395.2574701842395;2267.501149747053;2311.2257393773107;2313.571989181998;2317.351141680332;2350.6003979323555;2286.8627882326946;2295.426143464134;2300.1029598456616;2302.2747834432103;2303.124812571229;9221.400608960395;9241.306901982378;9267.582958766872;9275.67535136267;9353.859692104625;5166.870828229286;5285.603579731535;5294.391145018334;5299.788008479656;5307.177387504943;3375.3586818504405;3402.5576675032335;3554.306910152521;3560.2167523144867;3562.9643703562892;1120.618989543559;1169.5380324771752;1174.6769638349438;1200.8138738495547;1204.4579939027474;12652.81020784689;12737.745245095139;12755;12768.170068945488;12798.872011279862;4340.891477713082;4475.932861007088;4630.699004564693;4633.72197668141;4649.883752906205;9856.063673950963;9865.901340986498;9877.160441362565;9898.554565044591;9898.80202395948;5383.192521121863;5386.48828361305;5388.757505912244;5395.460453954652;5396.676199428047;4136.276151673445;4853.247746599282;4921.704697718148;5056.620753443506;5057.089723849743;3688.63113688629;3780.9621903781162;3842.269968705937;3850.942235866464;3925.4700615417123;1143.891330323618;1156.196443471779;1165.609520810538;1165.8426112474876;1173.5716463490764;877.6489404238356;995.9452230127267;1000.9949950250202;1007.6171054999054;1022.1669339488293;5967.19442875215;6003.909941350796;6091.817245482643;6149.046897730769;6164.969175154096;457.69334545854355;460.39688008708697;462.11966204380707;1015.1878497202209;1029.5264178477835;3714.33142034431;4049.716519843583;4092.283850326181;4211.515675697273;4239.872803815891;11274.54901803935;11543.595974140864;11626.244294120856;11649.708757281136;11672.766544669059;11153.88461153878;11340.546378144782;11355.886441135648;11359.034482068952;11427.60003399877;8224.876626850602;8278.420510564203;8300.418970672115;8363;8375.45559538628;5719.685417302088;5933.673647949404;5982.551308651956;6043.3352331244405;6107.786227482017;10128.594856205964;10267.537960791633;10345.63790267337;10486;10493.160547156056;10530.736731581772;10763.623273185402;10823.51294191779;10830.025297723243;10868;6243.406876346921;7337.926620733724;7544.094708634999;7624.161342252237;7756.88364674519;8807.735767926972;8985.101489850962;8994.640214391351;8995.010548839491;9002.50486223264;7633.274838890296;7738.690452382067;7923.484973476706;8193.754187374388;8205.220504052106;7824.882626760717;7950.602469876368;7960.522368657899;7977.162397948132;7980.680772769024;7286.562806231643;7311.086114235721;7388.667509961957;7391.778246652609;7397.26027397256;5190.870228244324;5242.18746094356;5266.236395722675;5286.603504737237;5446.782128714807;5357.651752636066;5413.756380962787;5428.91856622143;5457.035467231873;5470.507654311131 02:37:27 INFO - PROCESS | 19381 | |4;prototype.html;331.4377592440063;341.9213580876398;345.56113735555846;352.79008989651146;354.969827564657;5215.478452154787;5297.470252974701;5304.787808487661;5315.760790764415;5318.6171595385185;4993.675411098276;5067.873303167423;5081.898362032757;5110.948890511094;5268.8155914542995;3998.7800670963084;4042.676585873138;4081.8979525511804;4131.917361652765;4169.958300416987;4134.37984302354;4138.52406973199;4143.233501802176;4146.212219678251;4170.311898536753;4032.6169013943777;4047.919041619166;4085.1318347206243;4117.938230926524;4152.335626299792;4061.167460670585;4093.2166127033606;4096.9552764045275;4101.528324242706;4129.091599848029;3628.129248980226;3628.983884512341;3642.562892452896;3660.816959152032;3700.5744339400776;4876.268559716071;5089;5094.490550944913;5120.3855537335385;5132.640715149941;4633.328167415706;4660.16117098922;4668.976655116737;4674.789634466457;4683.625309975194;5440.401555828856;5453.454654534555;5453.536449401806;5454.427285135043;5465;3852.364359880616;3947.8618248361167;3950.506186726659;3954.358941039465;3964.5638979712207;5640.717964101778;5645;5650.463205995424;5667.263255776723;5691.203231547545;4005.118873847749;4007.8998025049023;4022.356422972369;4024;4033.6369726724733;4045.1505183911117;4054.6958978076173;4068.2880495913096;4094.877153685394;4106.377980697608;4548.7725613719185;4604.595598342537;4613.700109492872;4622.815087396534;4648.2562789953445;5405.270288511001;5422.620416570802;5428.75570599324;5441.292631957899;5443.891122177534;1337.939792709311;1385.7297826923652;1386.7989141574415;1390.7009992851586;1400.096937475343;951.9524023798782;965.75856035991;966.2415004221792;978.2858513285199;985.3398223190479;5178.818741344035;5203;5209.306975233065;5218.452062533379;5236.16221404581;5456.263404440429;5563.748156664799;5599.244102046253;5611.971940140274;5615.410381909841;5164.579740571297;5175.45657705943;5183.611229157828;5198.948010519922;5210.030282738802;2918.4600848843033;2920.6495220573665;2934.6184995950816;2936.9265768355963;2945.9410811784073;5557.44425557441;5638.323401191883;5701.885962280731;5718.79984200551;5732.627379220337;5535;5592.860178495489;5618.803341883015;5620.381758006616;5626.662400255916;5602.215689803431;5612.428520014402;5621.7189140542805;5627.043402621646;5658.066419040812;5254.632175747814;5446.183072538993;5487.396386397573;5508.228847961369;5514.228008078952;5425.620206585501;5437.238786569805;5447.428020057995;5468.4531546845;5468.890622187613;5519.420460851553;5694.829155125353;5708.771649133988;5712.457316554921;5728.742206600629;4038.7980600969245;4040.393940908887;4054.736442131252;4056.8985775355854;4064.451299074587;5445.972770136124;5540.584456165884;5543;5564.666120032811;5580.576952876991;5216.191490319006;5425.538829199562;5438.755256013568;5442.673439593637;5464.3442786865835;5458.972705136449;5463.590230732791;5465.535429488379;5485.31219702335;5487.338640759308;5208.479152084761;5291.444398338119;5380.677159370451;5447.836564903059;5450.945490545044;5478.534324582455;5484.643498172606;5484.860904247267;5515.889682206413;5517.668939863621;5388.56891448691;5395.757190926339;5412.945870541244;5419.83740487786;5423.728813559227 02:37:27 INFO - PROCESS | 19381 | |5;yui.html;715.7602203261907;778.0740918307214;796.5260669901406;830.2569200565493;835.2607941971354;17001.14994250287;17651.20569574369;17684;17686.911565442188;17782.911085444586;15664.921675391606;15807.209639518036;15843.287052082655;15850.52448426549;15854.28655710493;641.8106658535743;782.084960596101;784.9607519624025;803.9959800200991;809.550699361854;790.2018960849526;792.1800936031209;792.3740245206309;792.5799326357011;792.6670798264714;857.1257317536109;857.8284343131368;858.8024754306513;858.8926384201975;859.0464584311409;684.3122661724984;825.0635528674937;833.2584000239791;841.1168273313058;851.8211175653121;625.558980918456;625.5808608232495;625.7278084033414;626.1077963901422;626.1765778001958;14626.561203163923;14667.119972801665;14670.046446980912;14671.77992330116;14673.486427975076;14941;15040.774388384183;15161;15243.314050867737;15392.384304627802;12722.74554508914;12865.935670321682;12974.41615127312;13912.860871391358;14228;4593.014271543159;4639.814407423699;4785.042991401733;4789.616830653539;4868.464468908417;82297;82515.97228471235;82896.17103828945;83415.16584834135;84701.57649211776;11524.078073754079;11581.88418115825;11589.362585057734;11787.469563869647;11834.763304733857;21297.36107916765;21320.466988325417;21331.146754129662;21346.466338341666;21367;8760.8685869712;8799.824003519894;8924.196822285896;9003.549822508849;9122.36143469964;4809.21167410215;4989.975050124726;5031.169856973632;5104.693718376835;5182.6113041521285;3401.098708842159;3536.5225694531423;3538.1685303954064;3539.4159963605716;3540.5751309842985;606.9666168360694;616.5006344860677;617.4535536050628;624.7126321891981;631.3560168627973;556.9972150139223;642.7846671365145;649.7953144759385;649.8927676933253;650.7722297196038;9301.906980930104;9947.452890091114;10028;10040;10154.492275386201;602.4668168670757;608.6439432931696;629.0281515059245;641.887669657808;673.7473447457204;4418.447694038246;4779.928301075487;4796.472388037313;4801.279808028754;4823.397075365579;80340;132148;133316;136135;136444.31777841048;87549.56225218833;89051;111059.44470277597;114744;115755.42122289335;81259;85254.57372713096;85829.14170858337;92048.2722764368;92870.07129928749;9638.469884156439;9702.175315098295;9837.163841073303;10281.948590257001;10327.690169294932;52424.213636796245;53106.73446632742;53992.46007539874;54144;55804.44195558155;72092.27907720856;72219.63890180516;78407.60796195983;80802;82641.58679206566;12459.93770031144;12633;12723.363831808187;12857.871421285667;12999.415026324028;14122.364493598017;14323.641908952359;14361.497347592784;14384.28078596045;15590.298436570154;14521.419143234574;14625.85374146245;15836.92081539585;16113.113778742281;16116.27476763525;13920.51278205258;14138.575842724735;14190.432382704576;14430.917681173743;14518.056326338754;13530.661733456742;13868.791968120688;14142.929285353508;14256.714865702836;14500.129992200502;11245;11946.880531194576;11955.402229888297;12026.27842329463;12147.210431321935;11454.541818327174;11993.34036627959;12093.75812483763;12153.57462488844;12660.936695316464 02:37:27 INFO - PROCESS | 19381 | __end_tp_report 02:37:27 INFO - PROCESS | 19381 | __start_cc_report 02:37:27 INFO - PROCESS | 19381 | _x_x_mozilla_cycle_collect,953 02:37:27 INFO - PROCESS | 19381 | __end_cc_report 02:37:27 INFO - PROCESS | 19381 | __startTimestamp1471081047620__endTimestamp 02:37:27 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | ------- Summary: start ------- 02:37:27 INFO - PROCESS | 19381 | Number of tests: 6 02:37:27 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | [#0] dojo.html Cycles:165 Average:5399.31 Median:5597.08 stddev:633.91 (11.3%) stddev-sans-first:608.79 02:37:27 INFO - PROCESS | 19381 | Values: 3063.2 3082.5 3084.2 3099.1 3105.0 5309.8 5319.2 5398.4 5402.5 5468.6 5422.3 5439.8 5462.7 5492.4 5507.4 4874.5 4890.2 4964.8 4976.1 5087.7 5011.3 5020.0 5061.1 5068.4 5071.1 4798.7 4813.8 4878.6 4931.8 4966.5 4817.0 4826.4 4829.6 4900.1 4944.1 4855.6 4870.1 4871.1 4871.8 4909.8 5297.0 5312.7 5364.2 5373.4 5403.8 5811.4 5835.6 5837.3 5839.5 5839.6 5518.3 5882.1 5884.3 5887.6 5891.8 5364.1 5429.3 5430.6 5502.1 5511.4 6042.9 6052.2 6053.0 6054.9 6056.7 5743.4 5746.9 5786.2 5787.6 5793.6 5583.9 5709.1 5736.3 5743.5 5838.1 5732.4 5766.4 5770.9 5801.2 5821.5 5703.5 5758.3 5765.7 5773.9 5778.7 4277.8 4342.5 4406.2 4444.6 4476.7 3929.6 3932.5 3956.1 3970.0 3983.4 5390.2 5394.8 5396.2 5401.3 5437.7 5675.1 5794.0 5808.7 5827.4 5859.6 5470.2 5475.6 5479.0 5493.9 5499.5 5027.4 5038.1 5039.0 5042.2 5076.7 5777.0 5907.5 6032.1 6126.0 6135.6 5824.9 5833.0 5841.4 5857.8 5869.5 6028.6 6029.8 6030.3 6031.1 6035.8 5497.9 5711.6 5722.6 5774.8 5795.3 5881.8 5931.2 5937.6 5955.0 5981.5 5769.1 5771.2 5786.2 5791.3 5792.1 5506.6 5674.7 5698.9 5757.2 5763.5 5706.3 5707.1 5708.9 5746.5 5757.8 5797.9 5805.7 5838.3 5841.1 5852.6 5357.3 5398.5 5531.5 5597.1 5649.5 02:37:27 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | [#1] ext.html Cycles:180 Average:62963.35 Median:43333.46 stddev:127686.12 (294.7%) stddev-sans-first:127958.43 02:37:27 INFO - PROCESS | 19381 | Values: 587.2 595.7 605.5 607.0 625.4 29580.7 31902.0 44261.6 45403.5 45484.0 45989.8 46322.3 46487.3 46550.8 46594.8 8762.7 8940.0 8958.3 8961.7 8965.0 8897.9 9005.6 9007.7 9015.4 9016.5 8989.7 9036.2 9036.6 9039.6 9044.2 8978.6 9031.4 9034.0 9045.4 9048.9 8928.7 9004.8 9005.2 9009.7 9011.5 44342.0 44656.6 44736.1 44810.3 44844.6 3756.5 3824.6 3994.9 4083.9 4366.0 33700.5 34120.5 34226.8 34258.8 35166.3 4088.8 4093.2 4109.1 4218.1 4261.7 347309.3 347944.0 348635.0 349436.0 350242.0 36376.1 59978.7 60320.4 60360.0 60394.4 37277.6 38830.2 38985.6 39204.4 39226.8 15663.1 15784.4 15898.1 15933.7 15948.8 11609.6 11691.2 11695.8 11697.5 11697.5 8752.1 8807.5 8811.7 8851.1 8871.0 3318.0 3325.5 3331.8 3353.8 3362.3 1011.4 1069.6 1071.8 1094.8 1148.5 33018.3 33248.0 33597.7 33610.3 33840.5 1092.9 1106.5 1122.4 1123.5 1151.0 17387.8 17558.1 17577.9 17634.2 17694.6 563206.0 579750.0 580303.0 586316.0 589670.0 60348.0 60885.4 60905.4 60962.7 60994.0 1237.6 1258.0 1308.8 1318.5 1340.5 30631.2 35475.7 35722.0 35780.6 35830.2 54590.0 60631.4 63644.7 63651.4 63758.7 474696.0 482448.9 485505.0 486177.0 486903.0 36865.8 43857.6 44848.6 47038.0 47444.0 50488.7 50689.5 50749.0 50932.7 51274.0 40576.2 42852.8 43228.6 43264.8 43836.3 42902.1 43052.1 43057.0 43625.0 43832.0 43052.4 43402.1 43947.3 44014.3 44097.3 35193.0 37934.8 38802.6 38845.0 38901.2 28421.6 28758.3 29008.9 29019.9 29222.7 02:37:27 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | [#2] jquery.html Cycles:180 Average:29761.56 Median:401.62 stddev:81836.82 (20376.9%) stddev-sans-first:82036.70 02:37:27 INFO - PROCESS | 19381 | Values: 399.8 400.0 403.3 409.4 421.1 13972.9 19510.2 20404.5 20424.0 20540.6 16158.8 19340.0 20211.0 20391.5 20410.9 5649.3 5848.3 5851.4 5856.6 5859.4 5564.8 5696.7 5736.5 5787.8 5814.8 5676.2 5710.6 5722.1 5722.6 5722.9 5522.0 5697.6 5702.6 5704.8 5756.8 5154.6 5156.8 5160.8 5167.1 5168.4 23453.8 24455.5 24726.9 24815.1 25049.6 2402.8 2550.9 2600.2 2618.0 2988.3 9957.5 14755.4 15419.5 15572.3 15902.8 1904.9 1924.1 1958.8 1976.4 2009.9 84751.6 87583.6 89321.0 89659.0 92307.1 11431.5 11596.3 11664.9 11779.6 11912.6 16320.6 16325.3 16355.0 16361.1 16362.3 8285.9 8456.7 8461.2 8613.4 8732.0 8100.6 8168.3 8230.7 8280.3 8404.8 4190.6 4458.1 4516.5 4527.3 4606.6 2026.8 2060.8 2093.8 2106.2 2139.8 879.9 933.3 945.1 996.7 1036.6 18600.9 20661.4 20873.6 20970.9 21136.7 1036.7 1063.7 1096.8 1098.4 1106.7 10536.3 11569.0 11650.8 11658.8 11684.8 477718.0 491269.0 501597.0 502387.0 505483.0 89518.0 92202.0 93591.0 94259.0 94600.0 959.4 962.8 983.1 984.2 985.5 16330.3 17695.2 18315.5 18375.4 18651.8 41715.6 43809.8 43954.0 44050.8 44563.8 56612.0 57539.7 58081.0 58416.4 59944.7 8200.3 8460.3 8739.1 8827.6 9251.7 10765.9 13249.7 13253.8 13343.9 13476.7 11512.1 11652.0 11820.4 11932.2 12040.8 11550.4 11605.7 11801.1 11864.9 12103.7 11939.8 12009.7 12044.8 12319.1 12443.2 8665.4 9013.0 9079.4 9087.4 9270.3 9622.4 12283.4 12489.8 12555.0 12670.7 02:37:27 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | [#3] mootools.html Cycles:180 Average:5915.58 Median:2546.41 stddev:3434.21 (134.9%) stddev-sans-first:3425.35 02:37:27 INFO - PROCESS | 19381 | Values: 1174.1 1460.6 1496.2 1612.2 1747.4 8341.1 8800.9 8839.1 9140.0 9165.6 8937.6 9060.6 9061.5 9064.3 9064.3 2059.9 2351.0 2426.4 2429.1 2450.3 2417.2 2418.6 2420.0 2423.4 2423.5 2357.8 2387.2 2391.3 2392.0 2395.3 2267.5 2311.2 2313.6 2317.4 2350.6 2286.9 2295.4 2300.1 2302.3 2303.1 9221.4 9241.3 9267.6 9275.7 9353.9 5166.9 5285.6 5294.4 5299.8 5307.2 3375.4 3402.6 3554.3 3560.2 3563.0 1120.6 1169.5 1174.7 1200.8 1204.5 12652.8 12737.7 12755.0 12768.2 12798.9 4340.9 4475.9 4630.7 4633.7 4649.9 9856.1 9865.9 9877.2 9898.6 9898.8 5383.2 5386.5 5388.8 5395.5 5396.7 4136.3 4853.2 4921.7 5056.6 5057.1 3688.6 3781.0 3842.3 3850.9 3925.5 1143.9 1156.2 1165.6 1165.8 1173.6 877.6 995.9 1001.0 1007.6 1022.2 5967.2 6003.9 6091.8 6149.0 6165.0 457.7 460.4 462.1 1015.2 1029.5 3714.3 4049.7 4092.3 4211.5 4239.9 11274.5 11543.6 11626.2 11649.7 11672.8 11153.9 11340.5 11355.9 11359.0 11427.6 8224.9 8278.4 8300.4 8363.0 8375.5 5719.7 5933.7 5982.6 6043.3 6107.8 10128.6 10267.5 10345.6 10486.0 10493.2 10530.7 10763.6 10823.5 10830.0 10868.0 6243.4 7337.9 7544.1 7624.2 7756.9 8807.7 8985.1 8994.6 8995.0 9002.5 7633.3 7738.7 7923.5 8193.8 8205.2 7824.9 7950.6 7960.5 7977.2 7980.7 7286.6 7311.1 7388.7 7391.8 7397.3 5190.9 5242.2 5266.2 5286.6 5446.8 5357.7 5413.8 5428.9 5457.0 5470.5 02:37:27 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | [#4] prototype.html Cycles:180 Average:4586.91 Median:5227.31 stddev:1326.16 (25.4%) stddev-sans-first:1290.84 02:37:27 INFO - PROCESS | 19381 | Values: 331.4 341.9 345.6 352.8 355.0 5215.5 5297.5 5304.8 5315.8 5318.6 4993.7 5067.9 5081.9 5110.9 5268.8 3998.8 4042.7 4081.9 4131.9 4170.0 4134.4 4138.5 4143.2 4146.2 4170.3 4032.6 4047.9 4085.1 4117.9 4152.3 4061.2 4093.2 4097.0 4101.5 4129.1 3628.1 3629.0 3642.6 3660.8 3700.6 4876.3 5089.0 5094.5 5120.4 5132.6 4633.3 4660.2 4669.0 4674.8 4683.6 5440.4 5453.5 5453.5 5454.4 5465.0 3852.4 3947.9 3950.5 3954.4 3964.6 5640.7 5645.0 5650.5 5667.3 5691.2 4005.1 4007.9 4022.4 4024.0 4033.6 4045.2 4054.7 4068.3 4094.9 4106.4 4548.8 4604.6 4613.7 4622.8 4648.3 5405.3 5422.6 5428.8 5441.3 5443.9 1337.9 1385.7 1386.8 1390.7 1400.1 952.0 965.8 966.2 978.3 985.3 5178.8 5203.0 5209.3 5218.5 5236.2 5456.3 5563.7 5599.2 5612.0 5615.4 5164.6 5175.5 5183.6 5198.9 5210.0 2918.5 2920.6 2934.6 2936.9 2945.9 5557.4 5638.3 5701.9 5718.8 5732.6 5535.0 5592.9 5618.8 5620.4 5626.7 5602.2 5612.4 5621.7 5627.0 5658.1 5254.6 5446.2 5487.4 5508.2 5514.2 5425.6 5437.2 5447.4 5468.5 5468.9 5519.4 5694.8 5708.8 5712.5 5728.7 4038.8 4040.4 4054.7 4056.9 4064.5 5446.0 5540.6 5543.0 5564.7 5580.6 5216.2 5425.5 5438.8 5442.7 5464.3 5459.0 5463.6 5465.5 5485.3 5487.3 5208.5 5291.4 5380.7 5447.8 5450.9 5478.5 5484.6 5484.9 5515.9 5517.7 5388.6 5395.8 5412.9 5419.8 5423.7 02:37:27 INFO - PROCESS | 19381 | 02:37:27 INFO - PROCESS | 19381 | [#5] yui.html Cycles:180 Average:21856.35 Median:4782.49 stddev:31878.38 (666.6%) stddev-sans-first:31928.28 02:37:27 INFO - PROCESS | 19381 | Values: 715.8 778.1 796.5 830.3 835.3 17001.1 17651.2 17684.0 17686.9 17782.9 15664.9 15807.2 15843.3 15850.5 15854.3 641.8 782.1 785.0 804.0 809.6 790.2 792.2 792.4 792.6 792.7 857.1 857.8 858.8 858.9 859.0 684.3 825.1 833.3 841.1 851.8 625.6 625.6 625.7 626.1 626.2 14626.6 14667.1 14670.0 14671.8 14673.5 14941.0 15040.8 15161.0 15243.3 15392.4 12722.7 12865.9 12974.4 13912.9 14228.0 4593.0 4639.8 4785.0 4789.6 4868.5 82297.0 82516.0 82896.2 83415.2 84701.6 11524.1 11581.9 11589.4 11787.5 11834.8 21297.4 21320.5 21331.1 21346.5 21367.0 8760.9 8799.8 8924.2 9003.5 9122.4 4809.2 4990.0 5031.2 5104.7 5182.6 3401.1 3536.5 3538.2 3539.4 3540.6 607.0 616.5 617.5 624.7 631.4 557.0 642.8 649.8 649.9 650.8 9301.9 9947.5 10028.0 10040.0 10154.5 602.5 608.6 629.0 641.9 673.7 4418.4 4779.9 4796.5 4801.3 4823.4 80340.0 132148.0 133316.0 136135.0 136444.3 87549.6 89051.0 111059.4 114744.0 115755.4 81259.0 85254.6 85829.1 92048.3 92870.1 9638.5 9702.2 9837.2 10281.9 10327.7 52424.2 53106.7 53992.5 54144.0 55804.4 72092.3 72219.6 78407.6 80802.0 82641.6 12459.9 12633.0 12723.4 12857.9 12999.4 14122.4 14323.6 14361.5 14384.3 15590.3 14521.4 14625.9 15836.9 16113.1 16116.3 13920.5 14138.6 14190.4 14430.9 14518.1 13530.7 13868.8 14142.9 14256.7 14500.1 11245.0 11946.9 11955.4 12026.3 12147.2 11454.5 11993.3 12093.8 12153.6 12660.9 02:37:27 INFO - PROCESS | 19381 | -------- Summary: end -------- 02:37:27 INFO - PROCESS | 19381 | 02:37:28 INFO - TEST-INFO | 19381: exit 0 02:37:28 INFO - TEST-OK | dromaeo_css | took 1115172ms 02:37:28 INFO - TEST-START | kraken 02:37:28 INFO - operating with platform_type : linux_ 02:37:28 INFO - Initialising browser for kraken test... 02:37:28 INFO - TEST-INFO | started process 19687 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKBW61i/profile http://localhost:50767/getInfo.html) 02:37:32 INFO - PROCESS | 19687 | __metrics Screen width/height:1600/1200 02:37:32 INFO - PROCESS | 19687 | colorDepth:24 02:37:32 INFO - PROCESS | 19687 | Browser inner width/height: 1024/697 02:37:32 INFO - PROCESS | 19687 | __metrics 02:37:33 INFO - PROCESS | 19687 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:37:33 INFO - PROCESS | 19687 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:37:34 INFO - TEST-INFO | 19687: exit 0 02:37:34 INFO - Browser initialized. 02:37:34 INFO - Running cycle 1/1 for kraken test... 02:37:34 INFO - TEST-INFO | started process 19767 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKBW61i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:37:45 INFO - PROCESS | 19767 | RSS: Main: 155795456 02:37:45 INFO - PROCESS | 19767 | 02:37:48 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50767/tests/kraken/driver.html?testName=audio-beat-detection) 02:37:49 INFO - PROCESS | 19767 | RSS: Main: 204029952 02:37:49 INFO - PROCESS | 19767 | 02:37:51 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50767/tests/kraken/driver.html?testName=audio-dft) 02:37:52 INFO - PROCESS | 19767 | RSS: Main: 195125248 02:37:52 INFO - PROCESS | 19767 | 02:37:55 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50767/tests/kraken/driver.html?testName=audio-fft) 02:37:56 INFO - PROCESS | 19767 | RSS: Main: 275607552 02:37:56 INFO - PROCESS | 19767 | 02:37:58 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50767/tests/kraken/driver.html?testName=audio-oscillator) 02:37:58 INFO - PROCESS | 19767 | RSS: Main: 186363904 02:37:58 INFO - PROCESS | 19767 | 02:38:00 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50767/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:38:01 INFO - PROCESS | 19767 | RSS: Main: 191111168 02:38:01 INFO - PROCESS | 19767 | 02:38:04 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50767/tests/kraken/driver.html?testName=imaging-darkroom) 02:38:05 INFO - PROCESS | 19767 | RSS: Main: 225402880 02:38:05 INFO - PROCESS | 19767 | 02:38:08 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50767/tests/kraken/driver.html?testName=imaging-desaturate) 02:38:09 INFO - PROCESS | 19767 | RSS: Main: 226156544 02:38:09 INFO - PROCESS | 19767 | 02:38:11 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50767/tests/kraken/driver.html?testName=json-parse-financial) 02:38:12 INFO - PROCESS | 19767 | RSS: Main: 226390016 02:38:12 INFO - PROCESS | 19767 | 02:38:13 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50767/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:38:14 INFO - PROCESS | 19767 | RSS: Main: 188391424 02:38:14 INFO - PROCESS | 19767 | 02:38:16 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50767/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:38:16 INFO - PROCESS | 19767 | RSS: Main: 196173824 02:38:16 INFO - PROCESS | 19767 | 02:38:18 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50767/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:38:19 INFO - PROCESS | 19767 | RSS: Main: 194064384 02:38:19 INFO - PROCESS | 19767 | 02:38:22 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50767/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:38:22 INFO - PROCESS | 19767 | RSS: Main: 188149760 02:38:22 INFO - PROCESS | 19767 | 02:38:25 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50767/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:38:26 INFO - PROCESS | 19767 | RSS: Main: 191881216 02:38:26 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | Cycle 1(1): loaded http://localhost:50767/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50767/tests/kraken/driver.html?testName=ai-astar) 02:38:27 INFO - PROCESS | 19767 | RSS: Main: 242638848 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | __start_tp_report 02:38:27 INFO - PROCESS | 19767 | _x_x_mozilla_page_load 02:38:27 INFO - PROCESS | 19767 | _x_x_mozilla_page_load_details 02:38:27 INFO - PROCESS | 19767 | |i|pagename|runs| 02:38:27 INFO - PROCESS | 19767 | |0;ai-astar;102;111;111;111;110;112;111;113;112;111 02:38:27 INFO - PROCESS | 19767 | |1;audio-beat-detection;152;117;119;178;119;129;145;117;159;121 02:38:27 INFO - PROCESS | 19767 | |2;audio-dft;161;160;160;161;162;159;159;161;161;160 02:38:27 INFO - PROCESS | 19767 | |3;audio-fft;75;74;74;110;76;75;74;75;108;74 02:38:27 INFO - PROCESS | 19767 | |4;audio-oscillator;84;83;126;84;84;84;119;85;84;122 02:38:27 INFO - PROCESS | 19767 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;118;117;117 02:38:27 INFO - PROCESS | 19767 | |6;imaging-darkroom;146;145;147;145;145;146;146;145;146;145 02:38:27 INFO - PROCESS | 19767 | |7;imaging-desaturate;95;94;94;95;95;94;94;95;95;95 02:38:27 INFO - PROCESS | 19767 | |8;json-parse-financial;54;54;51;51;51;53;53;52;54;52 02:38:27 INFO - PROCESS | 19767 | |9;json-stringify-tinderbox;61;62;62;62;61;62;62;62;61;62 02:38:27 INFO - PROCESS | 19767 | |10;stanford-crypto-aes;77;80;83;78;77;78;78;83;77;76 02:38:27 INFO - PROCESS | 19767 | |11;stanford-crypto-ccm;123;120;122;123;119;127;131;130;138;134 02:38:27 INFO - PROCESS | 19767 | |12;stanford-crypto-pbkdf2;160;162;170;163;160;167;159;161;160;163 02:38:27 INFO - PROCESS | 19767 | |13;stanford-crypto-sha256-iterative;69;62;63;61;62;64;63;63;62;62 02:38:27 INFO - PROCESS | 19767 | __end_tp_report 02:38:27 INFO - PROCESS | 19767 | __start_cc_report 02:38:27 INFO - PROCESS | 19767 | _x_x_mozilla_cycle_collect,1662 02:38:27 INFO - PROCESS | 19767 | __end_cc_report 02:38:27 INFO - PROCESS | 19767 | __startTimestamp1471081107935__endTimestamp 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | ------- Summary: start ------- 02:38:27 INFO - PROCESS | 19767 | Number of tests: 14 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:3.06 (2.8%) stddev-sans-first:0.87 02:38:27 INFO - PROCESS | 19767 | Values: 102.0 111.0 111.0 111.0 110.0 112.0 111.0 113.0 112.0 111.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:137.00 stddev:21.61 (15.8%) stddev-sans-first:22.09 02:38:27 INFO - PROCESS | 19767 | Values: 152.0 117.0 119.0 178.0 119.0 129.0 145.0 117.0 159.0 121.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 02:38:27 INFO - PROCESS | 19767 | Values: 161.0 160.0 160.0 161.0 162.0 159.0 159.0 161.0 161.0 160.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#3] audio-fft Cycles:10 Average:81.50 Median:74.50 stddev:14.52 (19.5%) stddev-sans-first:15.21 02:38:27 INFO - PROCESS | 19767 | Values: 75.0 74.0 74.0 110.0 76.0 75.0 74.0 75.0 108.0 74.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:84.00 stddev:18.60 (22.1%) stddev-sans-first:19.25 02:38:27 INFO - PROCESS | 19767 | Values: 84.0 83.0 126.0 84.0 84.0 84.0 119.0 85.0 84.0 122.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:38:27 INFO - PROCESS | 19767 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 02:38:27 INFO - PROCESS | 19767 | Values: 146.0 145.0 147.0 145.0 145.0 146.0 146.0 145.0 146.0 145.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 02:38:27 INFO - PROCESS | 19767 | Values: 95.0 94.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:1.27 (2.4%) stddev-sans-first:1.22 02:38:27 INFO - PROCESS | 19767 | Values: 54.0 54.0 51.0 51.0 51.0 53.0 53.0 52.0 54.0 52.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 02:38:27 INFO - PROCESS | 19767 | Values: 61.0 62.0 62.0 62.0 61.0 62.0 62.0 62.0 61.0 62.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:78.00 stddev:2.50 (3.2%) stddev-sans-first:2.57 02:38:27 INFO - PROCESS | 19767 | Values: 77.0 80.0 83.0 78.0 77.0 78.0 78.0 83.0 77.0 76.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:128.50 stddev:6.36 (4.9%) stddev-sans-first:6.60 02:38:27 INFO - PROCESS | 19767 | Values: 123.0 120.0 122.0 123.0 119.0 127.0 131.0 130.0 138.0 134.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:162.50 stddev:3.50 (2.2%) stddev-sans-first:3.60 02:38:27 INFO - PROCESS | 19767 | Values: 160.0 162.0 170.0 163.0 160.0 167.0 159.0 161.0 160.0 163.0 02:38:27 INFO - PROCESS | 19767 | 02:38:27 INFO - PROCESS | 19767 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:2.23 (3.5%) stddev-sans-first:0.88 02:38:27 INFO - PROCESS | 19767 | Values: 69.0 62.0 63.0 61.0 62.0 64.0 63.0 63.0 62.0 62.0 02:38:27 INFO - PROCESS | 19767 | -------- Summary: end -------- 02:38:27 INFO - PROCESS | 19767 | 02:38:28 INFO - TEST-INFO | 19767: exit 0 02:38:28 INFO - TEST-OK | kraken | took 60590ms 02:38:28 INFO - SUITE-END | took 1175s 02:38:29 INFO - Completed test suite (00:19:35) 02:38:29 INFO - javascript benchmark 02:38:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3063.2188791858075, 3082.4605694003544, 3084.1672748357946, 3099.0702789163247, 3104.9689503104964, 5309.814156504523, 5319.228711836781, 5398.4331645177235, 5402.459754024596, 5468.617196796231, 5422.322209723785, 5439.755211015504, 5462.672239665623, 5492.368377636573, 5507.421720719327, 4874.46380898101, 4890.242012488052, 4964.826231081913, 4976.129177393942, 5087.745612719349, 5011.273365362021, 5019.974900125494, 5061.139606266925, 5068.366454193226, 5071.087204303224, 4798.664093513456, 4813.78337974792, 4878.634102442331, 4931.80272789088, 4966.503349665023, 4816.975915120437, 4826.420829500447, 4829.613630909519, 4900.093482705711, 4944.085344211297, 4855.635827312965, 4870.123377791996, 4871.050145221683, 4871.8294859679745, 4909.754512274408, 5297.046531624306, 5312.681239125664, 5364.195370694388, 5373.435789242112, 5403.8108666196495, 5811.418858114197, 5835.620684655484, 5837.29952405714, 5839.474447299764, 5839.591228613998, 5518.2826232589905, 5882.058870580687, 5884.293884733816, 5887.646741195542, 5891.793787217427, 5364.14173732201, 5429.321334833146, 5430.59270554706, 5502.119660854244, 5511.3937466878615, 6042.939570604326, 6052.243469566304, 6053.0, 6054.939450605526, 6056.727447264883, 5743.368229494752, 5746.856328591819, 5786.1610066540115, 5787.594868359259, 5793.594448388572, 5583.916241256385, 5709.143628455682, 5736.282964629421, 5743.54051675865, 5838.095095260242, 5732.426757324318, 5766.394528574439, 5770.913436298459, 5801.245838041112, 5821.53427725781, 5703.458171473703, 5758.2514273144225, 5765.740541675635, 5773.913391299134, 5778.711064446761, 4277.786110694453, 4342.500612429548, 4406.228909940812, 4444.644428445717, 4476.7090139140855, 3929.587393323717, 3932.508436445444, 3956.090099277125, 3970.007498125469, 3983.4224037514978, 5390.191471279261, 5394.757235924393, 5396.163594642837, 5401.3248343957, 5437.673739575559, 5675.063614503643, 5793.971030144822, 5808.680522571299, 5827.417258274223, 5859.589828711949, 5470.179473078991, 5475.6440831345835, 5479.0, 5493.862653433616, 5499.477549632778, 5027.371578552681, 5038.067957427815, 5038.974805126024, 5042.168042273057, 5076.670016448919, 5777.0, 5907.527397808251, 6032.065029920369, 6125.9693701531205, 6135.60118592299, 5824.9417505826095, 5832.950068987453, 5841.415858414124, 5857.765689372377, 5869.4717475427415, 6028.57799953999, 6029.8191054268445, 6030.276366836008, 6031.065184896348, 6035.758569657164, 5497.862553436036, 5711.628744131618, 5722.62802917809, 5774.82675519735, 5795.33353664334, 5881.7647294109465, 5931.169636250842, 5937.643741375531, 5955.0, 5981.521478281814, 5769.134629805395, 5771.220885180447, 5786.247787787561, 5791.276090488689, 5792.131180322816, 5506.559475241891, 5674.659520428788, 5698.914516282342, 5757.193992841089, 5763.538916886723, 5706.28671416073, 5707.086866101404, 5708.914366284426, 5746.454086861741, 5757.827265182052, 5797.91303130445, 5805.680687562224, 5838.299404071538, 5841.065429531254, 5852.590318677651, 5357.303550538561, 5398.46015398457, 5531.474509921551, 5597.076482380362, 5649.4633009864], "value": 5355.008831919507, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.2219309415024, 595.6842873277159, 605.470213563132, 607.0378450156502, 625.4152367536354, 29580.704192958063, 31902.0, 44261.557384426145, 45403.545964540346, 45484.0, 45989.770051149695, 46322.30516542255, 46487.30269045958, 46550.76724616381, 46594.76702616482, 8762.737117886458, 8939.955300223522, 8958.32812539059, 8961.731148065568, 8965.0, 8897.866532002025, 9005.594748236312, 9007.684731034382, 9015.413998090136, 9016.504092274923, 8989.730308090768, 9036.186743193111, 9036.638534458647, 9039.593218305192, 9044.23124034458, 8978.551072446384, 9031.367804253705, 9034.0, 9045.412048216846, 9048.909510904872, 8928.73213803587, 9004.774880628036, 9005.189532942, 9009.684661036898, 9011.549422528913, 44342.0, 44656.55343446589, 44736.10527789426, 44810.32784508235, 44844.55155448469, 3756.492873462075, 3824.560175579816, 3994.9201015979515, 4083.8774836754947, 4365.956340436586, 33700.49449258263, 34120.48819267713, 34226.82886585576, 34258.828705856315, 35166.29667406663, 4088.7955602220068, 4093.2018256440006, 4109.0959988802415, 4218.093109981338, 4261.6803739719335, 347309.26345368364, 347944.0, 348635.0, 349436.0, 350242.0, 36376.09059773527, 59978.70010650006, 60320.39679603148, 60360.0, 60394.39605603975, 37277.627223727955, 38830.22339553193, 38985.6101438982, 39204.411933821015, 39226.803865981055, 15663.138527380876, 15784.36862525509, 15898.125603091941, 15933.681326373408, 15948.840511594968, 11609.593664221711, 11691.240069395462, 11695.824562631567, 11697.473613687234, 11697.473613687405, 8752.081031491729, 8807.515586642796, 8811.691590794291, 8851.114888511227, 8870.955645221733, 3318.0, 3325.517799918998, 3331.800091994488, 3353.7987720736833, 3362.2603027334435, 1011.4335971855784, 1069.5828626835541, 1071.769569542552, 1094.8084085285043, 1148.4889224295275, 33018.33358175566, 33248.0, 33597.66402335945, 33610.327793444, 33840.49239261413, 1092.874319453273, 1106.4578356605205, 1122.3770807201918, 1123.466353482106, 1150.9654710358702, 17387.82612173862, 17558.12209389551, 17577.91211043962, 17634.206460709298, 17694.557636059202, 563206.0, 579750.0, 580303.0, 586316.0, 589670.0, 60348.0, 60885.391146088856, 60905.39094609086, 60962.695186523786, 60994.0, 1237.6039667306557, 1258.0250306012913, 1308.8363954505687, 1318.5253308808822, 1340.4571148684993, 30631.161246849006, 35475.67554130249, 35722.0, 35780.64219357773, 35830.215328195234, 54590.0, 60631.39368606434, 63644.68177659082, 63651.36348636454, 63758.68120659367, 474696.0, 482448.9387765334, 485505.0, 486177.0, 486903.0, 36865.815670922544, 43857.56142438662, 44848.55151448444, 47038.0, 47444.0, 50488.74755626198, 50689.493105068475, 50749.0, 50932.74533627308, 51274.0, 40576.166316049996, 42852.78573607112, 43228.56771432245, 43264.78367608142, 43836.34245486256, 42902.14195716131, 43052.13895722006, 43057.0, 43625.0, 43832.0, 43052.35421468618, 43402.13195736131, 43947.34078988754, 44014.339784902615, 44097.338539921286, 35193.0, 37934.8103259493, 38802.6119738799, 38845.0, 38901.22197555976, 28421.573676395285, 28758.29402829959, 29008.854955725088, 29019.85490072536, 29222.707772922], "value": 19761.082693674594, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.8400639744102, 399.96800255979485, 403.26404312130364, 409.44111288091756, 421.10305050242977, 13972.860271397309, 19510.21959121635, 20404.489887752796, 20424.0, 20540.589188216225, 16158.75761863573, 19340.0, 20211.0, 20391.490212744688, 20410.89794551029, 5649.265595472582, 5848.327442344143, 5851.356350801409, 5856.648601083927, 5859.384764599719, 5564.777408903659, 5696.746715722535, 5736.455036771521, 5787.826365209029, 5814.825555233349, 5676.233708449347, 5710.600257981943, 5722.141678748179, 5722.628029178111, 5722.885542289152, 5522.0, 5697.6296540724725, 5702.600817942745, 5704.800331988381, 5756.82729518111, 5154.613403994678, 5156.819511317086, 5160.767765450526, 5167.0957582422925, 5168.379794424655, 23453.765462345327, 24455.510889782105, 24726.876365618056, 24815.13147039845, 25049.624255635998, 2402.7957623602015, 2550.85970271635, 2600.180943002948, 2617.9869100654373, 2988.3126880817317, 9957.5021248938, 14755.409783608642, 15419.460318888898, 15572.29924653382, 15902.840971590253, 1904.8951608067289, 1924.1052910396734, 1958.7649482062243, 1976.3774411060613, 2009.8995050247574, 84751.57624211902, 87583.56208218982, 89321.0, 89658.99999999935, 92307.07692923119, 11431.542738290542, 11596.304221746723, 11664.94167529157, 11779.646610601696, 11912.583059592873, 16320.591985200464, 16325.26536305869, 16355.0, 16361.100139492204, 16362.345506179858, 8285.917140828515, 8456.746297611082, 8461.180846118114, 8613.353998450017, 8732.0, 8100.594970251464, 8168.305694016075, 8230.711925082593, 8280.254777069973, 8404.831903362021, 4190.55999120088, 4458.130664520388, 4516.480604730433, 4527.298268768346, 4606.585407313358, 2026.8277196438469, 2060.7939206079272, 2093.8324934005245, 2106.2206983416236, 2139.8074173324476, 879.8548239540532, 933.2767105493297, 945.0832692288469, 996.6860438961704, 1036.5698235232444, 18600.906995465208, 20661.380158595268, 20873.582528349347, 20970.895145524173, 21136.682949755766, 1036.6730584851402, 1063.691529456449, 1096.7696783675517, 1098.4288170151635, 1106.6679996001167, 10536.262461627612, 11569.0, 11650.825237621442, 11658.766824663458, 11684.824727629093, 477718.0, 491269.0, 501597.0, 502387.0, 505483.0, 89518.0, 92202.0, 93591.0, 94259.0, 94600.0, 959.4387283439127, 962.8363178259713, 983.1299300119302, 984.1683777208248, 985.4973963278779, 16330.346786128897, 17695.203715832562, 18315.450536483928, 18375.356862509747, 18651.813481865007, 41715.58284417117, 43809.78095109631, 43954.0, 44050.77974610106, 44563.77718111389, 56612.0, 57539.7123014399, 58081.0, 58416.4158358411, 59944.70027649834, 8200.343972482306, 8460.323174146177, 8739.12608739121, 8827.646894124164, 9251.722448326562, 10765.946170269099, 13249.735005300034, 13253.80119298192, 13343.933280333536, 13476.730465390441, 11512.079033677119, 11652.0, 11820.404245426756, 11932.164748467523, 12040.819387709367, 11550.364729939934, 11605.709857253636, 11801.055915526566, 11864.940675296568, 12103.697407564881, 11939.76120477603, 12009.699757506132, 12044.75910481768, 12319.076069295019, 12443.191192572454, 8665.43674661145, 9012.954935225544, 9079.409838360485, 9087.409318394282, 9270.304727145356, 9622.37454565451, 12283.38583070825, 12489.750204995667, 12555.0, 12670.683232918882], "value": 9512.350048060334, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1174.0666170394536, 1460.5545308680855, 1496.1995332497115, 1612.2261314569007, 1747.3709464592753, 8341.12418196089, 8800.867986980193, 8839.116088391158, 9140.0, 9165.587548560314, 8937.597808098635, 9060.592273347698, 9061.546922653857, 9064.320175986812, 9064.320175986812, 2059.927902523412, 2351.0360752091647, 2426.4297889995837, 2429.137656132074, 2450.3261603059123, 2417.2385698504913, 2418.5888398972215, 2419.987900060506, 2423.3578101803037, 2423.5274121546304, 2357.8231632627535, 2387.152560840894, 2391.330427480308, 2392.031227352916, 2395.2574701842395, 2267.501149747053, 2311.2257393773107, 2313.571989181998, 2317.351141680332, 2350.6003979323555, 2286.8627882326946, 2295.426143464134, 2300.1029598456616, 2302.2747834432103, 2303.124812571229, 9221.400608960395, 9241.306901982378, 9267.582958766872, 9275.67535136267, 9353.859692104625, 5166.870828229286, 5285.603579731535, 5294.391145018334, 5299.788008479656, 5307.177387504943, 3375.3586818504405, 3402.5576675032335, 3554.306910152521, 3560.2167523144867, 3562.9643703562892, 1120.618989543559, 1169.5380324771752, 1174.6769638349438, 1200.8138738495547, 1204.4579939027474, 12652.81020784689, 12737.745245095139, 12755.0, 12768.170068945488, 12798.872011279862, 4340.891477713082, 4475.932861007088, 4630.699004564693, 4633.72197668141, 4649.883752906205, 9856.063673950963, 9865.901340986498, 9877.160441362565, 9898.554565044591, 9898.80202395948, 5383.192521121863, 5386.48828361305, 5388.757505912244, 5395.460453954652, 5396.676199428047, 4136.276151673445, 4853.247746599282, 4921.704697718148, 5056.620753443506, 5057.089723849743, 3688.63113688629, 3780.9621903781162, 3842.269968705937, 3850.942235866464, 3925.4700615417123, 1143.891330323618, 1156.196443471779, 1165.609520810538, 1165.8426112474876, 1173.5716463490764, 877.6489404238356, 995.9452230127267, 1000.9949950250202, 1007.6171054999054, 1022.1669339488293, 5967.19442875215, 6003.909941350796, 6091.817245482643, 6149.046897730769, 6164.969175154096, 457.69334545854355, 460.39688008708697, 462.11966204380707, 1015.1878497202209, 1029.5264178477835, 3714.33142034431, 4049.716519843583, 4092.283850326181, 4211.515675697273, 4239.872803815891, 11274.54901803935, 11543.595974140864, 11626.244294120856, 11649.708757281136, 11672.766544669059, 11153.88461153878, 11340.546378144782, 11355.886441135648, 11359.034482068952, 11427.60003399877, 8224.876626850602, 8278.420510564203, 8300.418970672115, 8363.0, 8375.45559538628, 5719.685417302088, 5933.673647949404, 5982.551308651956, 6043.3352331244405, 6107.786227482017, 10128.594856205964, 10267.537960791633, 10345.63790267337, 10486.0, 10493.160547156056, 10530.736731581772, 10763.623273185402, 10823.51294191779, 10830.025297723243, 10868.0, 6243.406876346921, 7337.926620733724, 7544.094708634999, 7624.161342252237, 7756.88364674519, 8807.735767926972, 8985.101489850962, 8994.640214391351, 8995.010548839491, 9002.50486223264, 7633.274838890296, 7738.690452382067, 7923.484973476706, 8193.754187374388, 8205.220504052106, 7824.882626760717, 7950.602469876368, 7960.522368657899, 7977.162397948132, 7980.680772769024, 7286.562806231643, 7311.086114235721, 7388.667509961957, 7391.778246652609, 7397.26027397256, 5190.870228244324, 5242.18746094356, 5266.236395722675, 5286.603504737237, 5446.782128714807, 5357.651752636066, 5413.756380962787, 5428.91856622143, 5457.035467231873, 5470.507654311131], "value": 4663.273606252517, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.4377592440063, 341.9213580876398, 345.56113735555846, 352.79008989651146, 354.969827564657, 5215.478452154787, 5297.470252974701, 5304.787808487661, 5315.760790764415, 5318.6171595385185, 4993.675411098276, 5067.873303167423, 5081.898362032757, 5110.948890511094, 5268.8155914542995, 3998.7800670963084, 4042.676585873138, 4081.8979525511804, 4131.917361652765, 4169.958300416987, 4134.37984302354, 4138.52406973199, 4143.233501802176, 4146.212219678251, 4170.311898536753, 4032.6169013943777, 4047.919041619166, 4085.1318347206243, 4117.938230926524, 4152.335626299792, 4061.167460670585, 4093.2166127033606, 4096.9552764045275, 4101.528324242706, 4129.091599848029, 3628.129248980226, 3628.983884512341, 3642.562892452896, 3660.816959152032, 3700.5744339400776, 4876.268559716071, 5089.0, 5094.490550944913, 5120.3855537335385, 5132.640715149941, 4633.328167415706, 4660.16117098922, 4668.976655116737, 4674.789634466457, 4683.625309975194, 5440.401555828856, 5453.454654534555, 5453.536449401806, 5454.427285135043, 5465.0, 3852.364359880616, 3947.8618248361167, 3950.506186726659, 3954.358941039465, 3964.5638979712207, 5640.717964101778, 5645.0, 5650.463205995424, 5667.263255776723, 5691.203231547545, 4005.118873847749, 4007.8998025049023, 4022.356422972369, 4024.0, 4033.6369726724733, 4045.1505183911117, 4054.6958978076173, 4068.2880495913096, 4094.877153685394, 4106.377980697608, 4548.7725613719185, 4604.595598342537, 4613.700109492872, 4622.815087396534, 4648.2562789953445, 5405.270288511001, 5422.620416570802, 5428.75570599324, 5441.292631957899, 5443.891122177534, 1337.939792709311, 1385.7297826923652, 1386.7989141574415, 1390.7009992851586, 1400.096937475343, 951.9524023798782, 965.75856035991, 966.2415004221792, 978.2858513285199, 985.3398223190479, 5178.818741344035, 5203.0, 5209.306975233065, 5218.452062533379, 5236.16221404581, 5456.263404440429, 5563.748156664799, 5599.244102046253, 5611.971940140274, 5615.410381909841, 5164.579740571297, 5175.45657705943, 5183.611229157828, 5198.948010519922, 5210.030282738802, 2918.4600848843033, 2920.6495220573665, 2934.6184995950816, 2936.9265768355963, 2945.9410811784073, 5557.44425557441, 5638.323401191883, 5701.885962280731, 5718.79984200551, 5732.627379220337, 5535.0, 5592.860178495489, 5618.803341883015, 5620.381758006616, 5626.662400255916, 5602.215689803431, 5612.428520014402, 5621.7189140542805, 5627.043402621646, 5658.066419040812, 5254.632175747814, 5446.183072538993, 5487.396386397573, 5508.228847961369, 5514.228008078952, 5425.620206585501, 5437.238786569805, 5447.428020057995, 5468.4531546845, 5468.890622187613, 5519.420460851553, 5694.829155125353, 5708.771649133988, 5712.457316554921, 5728.742206600629, 4038.7980600969245, 4040.393940908887, 4054.736442131252, 4056.8985775355854, 4064.451299074587, 5445.972770136124, 5540.584456165884, 5543.0, 5564.666120032811, 5580.576952876991, 5216.191490319006, 5425.538829199562, 5438.755256013568, 5442.673439593637, 5464.3442786865835, 5458.972705136449, 5463.590230732791, 5465.535429488379, 5485.31219702335, 5487.338640759308, 5208.479152084761, 5291.444398338119, 5380.677159370451, 5447.836564903059, 5450.945490545044, 5478.534324582455, 5484.643498172606, 5484.860904247267, 5515.889682206413, 5517.668939863621, 5388.56891448691, 5395.757190926339, 5412.945870541244, 5419.83740487786, 5423.728813559227], "value": 4170.690576185691, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.7602203261907, 778.0740918307214, 796.5260669901406, 830.2569200565493, 835.2607941971354, 17001.14994250287, 17651.20569574369, 17684.0, 17686.911565442188, 17782.911085444586, 15664.921675391606, 15807.209639518036, 15843.287052082655, 15850.52448426549, 15854.28655710493, 641.8106658535743, 782.084960596101, 784.9607519624025, 803.9959800200991, 809.550699361854, 790.2018960849526, 792.1800936031209, 792.3740245206309, 792.5799326357011, 792.6670798264714, 857.1257317536109, 857.8284343131368, 858.8024754306513, 858.8926384201975, 859.0464584311409, 684.3122661724984, 825.0635528674937, 833.2584000239791, 841.1168273313058, 851.8211175653121, 625.558980918456, 625.5808608232495, 625.7278084033414, 626.1077963901422, 626.1765778001958, 14626.561203163923, 14667.119972801665, 14670.046446980912, 14671.77992330116, 14673.486427975076, 14941.0, 15040.774388384183, 15161.0, 15243.314050867737, 15392.384304627802, 12722.74554508914, 12865.935670321682, 12974.41615127312, 13912.860871391358, 14228.0, 4593.014271543159, 4639.814407423699, 4785.042991401733, 4789.616830653539, 4868.464468908417, 82297.0, 82515.97228471235, 82896.17103828945, 83415.16584834135, 84701.57649211776, 11524.078073754079, 11581.88418115825, 11589.362585057734, 11787.469563869647, 11834.763304733857, 21297.36107916765, 21320.466988325417, 21331.146754129662, 21346.466338341666, 21367.0, 8760.8685869712, 8799.824003519894, 8924.196822285896, 9003.549822508849, 9122.36143469964, 4809.21167410215, 4989.975050124726, 5031.169856973632, 5104.693718376835, 5182.6113041521285, 3401.098708842159, 3536.5225694531423, 3538.1685303954064, 3539.4159963605716, 3540.5751309842985, 606.9666168360694, 616.5006344860677, 617.4535536050628, 624.7126321891981, 631.3560168627973, 556.9972150139223, 642.7846671365145, 649.7953144759385, 649.8927676933253, 650.7722297196038, 9301.906980930104, 9947.452890091114, 10028.0, 10040.0, 10154.492275386201, 602.4668168670757, 608.6439432931696, 629.0281515059245, 641.887669657808, 673.7473447457204, 4418.447694038246, 4779.928301075487, 4796.472388037313, 4801.279808028754, 4823.397075365579, 80340.0, 132148.0, 133316.0, 136135.0, 136444.31777841048, 87549.56225218833, 89051.0, 111059.44470277597, 114744.0, 115755.42122289335, 81259.0, 85254.57372713096, 85829.14170858337, 92048.2722764368, 92870.07129928749, 9638.469884156439, 9702.175315098295, 9837.163841073303, 10281.948590257001, 10327.690169294932, 52424.213636796245, 53106.73446632742, 53992.46007539874, 54144.0, 55804.44195558155, 72092.27907720856, 72219.63890180516, 78407.60796195983, 80802.0, 82641.58679206566, 12459.93770031144, 12633.0, 12723.363831808187, 12857.871421285667, 12999.415026324028, 14122.364493598017, 14323.641908952359, 14361.497347592784, 14384.28078596045, 15590.298436570154, 14521.419143234574, 14625.85374146245, 15836.92081539585, 16113.113778742281, 16116.27476763525, 13920.51278205258, 14138.575842724735, 14190.432382704576, 14430.917681173743, 14518.056326338754, 13530.661733456742, 13868.791968120688, 14142.929285353508, 14256.714865702836, 14500.129992200502, 11245.0, 11946.880531194576, 11955.402229888297, 12026.27842329463, 12147.210431321935, 11454.541818327174, 11993.34036627959, 12093.75812483763, 12153.57462488844, 12660.936695316464], "value": 7776.22158604372, "unit": "score"}], "name": "dromaeo_css", "value": 7307.383731800324, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 111.0, 110.0, 112.0, 111.0, 113.0, 112.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 119.0, 178.0, 119.0, 129.0, 145.0, 117.0, 159.0, 121.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 162.0, 159.0, 159.0, 161.0, 161.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 110.0, 76.0, 75.0, 74.0, 75.0, 108.0, 74.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 126.0, 84.0, 84.0, 84.0, 119.0, 85.0, 84.0, 122.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 147.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 51.0, 51.0, 51.0, 53.0, 53.0, 52.0, 54.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 61.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 80.0, 83.0, 78.0, 77.0, 78.0, 78.0, 83.0, 77.0, 76.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 120.0, 122.0, 123.0, 119.0, 127.0, 131.0, 130.0, 138.0, 134.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 170.0, 163.0, 160.0, 167.0, 159.0, 161.0, 160.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 62.0, 63.0, 61.0, 62.0, 64.0, 63.0, 63.0, 62.0, 62.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1486.0, "alertThreshold": 2.0}]} 02:38:29 INFO - Return code: 0 02:38:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:38:29 INFO - # TBPL SUCCESS # 02:38:29 INFO - Running post-action listener: _resource_record_post_action 02:38:29 INFO - [mozharness: 2016-08-13 09:38:29.242969Z] Finished run-tests step (success) 02:38:29 INFO - Running post-run listener: _resource_record_post_run 02:38:29 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 420220928; Read time: 0; Write time: 255412 02:38:29 INFO - TinderboxPrint: CPU usage
12.9% 02:38:29 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 02:38:29 INFO - TinderboxPrint: I/O write bytes / time
420,220,928 / 255,412 02:38:29 INFO - TinderboxPrint: CPU idle
8,289.2 (87.0%) 02:38:29 INFO - TinderboxPrint: CPU user
1,188.8 (12.5%) 02:38:29 INFO - TinderboxPrint: Swap in / out
0 / 0 02:38:29 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 128483328; Read time: 0; Write time: 73428 02:38:29 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 290459648; Read time: 0; Write time: 181920 02:38:29 INFO - Running post-run listener: _upload_blobber_files 02:38:29 INFO - Blob upload gear active. 02:38:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:38:29 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 02:38:29 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:38:29 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:38:30 INFO - (blobuploader) - INFO - Open directory for files ... 02:38:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:38:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:38:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:30 INFO - (blobuploader) - INFO - Done attempting. 02:38:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:38:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:38:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:31 INFO - (blobuploader) - INFO - Done attempting. 02:38:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:38:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:38:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:36 INFO - (blobuploader) - INFO - Done attempting. 02:38:36 INFO - (blobuploader) - INFO - Iteration through files over. 02:38:36 INFO - Return code: 0 02:38:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:38:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:38:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb24d74319856f2759869cad171f32ed0c35565e479db598a2408904e27b0657c854258ba2f6c88ee22e36d884451369c1f92d3a7a58c1c1b4e91a90f470c326", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/75c5464b2d3da87503199d4effbd7880cf8e2199b555a4d52916253e03924897275b1338d1a8dbacc064c780e04d74d1e2a4133936a49ab92b603042ffeb4a36", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5b1fab20914916566c51e83dc6f9307d3774fdbe28ab30e19088cf0ee35b3628a90c79b5a363c8cbb7df7150459634884f9c0f1987b502280e54e5260a520e4"} 02:38:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:38:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:38:36 INFO - Contents: 02:38:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb24d74319856f2759869cad171f32ed0c35565e479db598a2408904e27b0657c854258ba2f6c88ee22e36d884451369c1f92d3a7a58c1c1b4e91a90f470c326", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/75c5464b2d3da87503199d4effbd7880cf8e2199b555a4d52916253e03924897275b1338d1a8dbacc064c780e04d74d1e2a4133936a49ab92b603042ffeb4a36", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5b1fab20914916566c51e83dc6f9307d3774fdbe28ab30e19088cf0ee35b3628a90c79b5a363c8cbb7df7150459634884f9c0f1987b502280e54e5260a520e4"} 02:38:36 INFO - Running post-run listener: copy_logs_to_upload_dir 02:38:36 INFO - Copying logs to upload dir... 02:38:36 INFO - mkdir: /builds/slave/test/build/upload/logs 02:38:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.272047 ========= master_lag: 0.29 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-13 02:38:36.096866) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-13 02:38:36.100605) ========= 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=bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb24d74319856f2759869cad171f32ed0c35565e479db598a2408904e27b0657c854258ba2f6c88ee22e36d884451369c1f92d3a7a58c1c1b4e91a90f470c326", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/75c5464b2d3da87503199d4effbd7880cf8e2199b555a4d52916253e03924897275b1338d1a8dbacc064c780e04d74d1e2a4133936a49ab92b603042ffeb4a36", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5b1fab20914916566c51e83dc6f9307d3774fdbe28ab30e19088cf0ee35b3628a90c79b5a363c8cbb7df7150459634884f9c0f1987b502280e54e5260a520e4"} build_url:https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011243 build_url: 'https://queue.taskcluster.net/v1/task/CgoxjBJ8Q0-ZMc5fZT7_YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb24d74319856f2759869cad171f32ed0c35565e479db598a2408904e27b0657c854258ba2f6c88ee22e36d884451369c1f92d3a7a58c1c1b4e91a90f470c326", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/75c5464b2d3da87503199d4effbd7880cf8e2199b555a4d52916253e03924897275b1338d1a8dbacc064c780e04d74d1e2a4133936a49ab92b603042ffeb4a36", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5b1fab20914916566c51e83dc6f9307d3774fdbe28ab30e19088cf0ee35b3628a90c79b5a363c8cbb7df7150459634884f9c0f1987b502280e54e5260a520e4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-13 02:38:36.129059) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 02:38:36.129401) ========= 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=bb9b54edd4a927dc735d112f000001d9-1471078868.365071-761317419 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 02:38:36.182272) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-13 02:38:36.182689) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-13 02:38:36.183062) ========= ========= Total master_lag: 0.42 =========