builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-001 starttime: 1471052502.73 results: success (0) buildid: 20160812164416 builduid: ec04254b99074f78a1973b312ec5fe1e revision: 60b349aec11bef8df2e9f6f89546b4eee0a01574 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:42.729725) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:42.730189) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:42.730477) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:42.747951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:42.748226) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-12 18:41:43-- 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% 17.3M=0.001s 2016-08-12 18:41:43 (17.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113298 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:42.875467) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:42.875791) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020729 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:42.925059) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 18:41:42.925411) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 60b349aec11bef8df2e9f6f89546b4eee0a01574 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 60b349aec11bef8df2e9f6f89546b4eee0a01574 --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=8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-12 18:41:43,296 truncating revision to first 12 chars 2016-08-12 18:41:43,296 Setting DEBUG logging. 2016-08-12 18:41:43,296 attempt 1/10 2016-08-12 18:41:43,296 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/60b349aec11b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 18:41:44,178 unpacking tar archive at: autoland-60b349aec11b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.283329 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 18:41:44.222881) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:44.223257) ========= script_repo_revision: 60b349aec11bef8df2e9f6f89546b4eee0a01574 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:44.223656) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:44.223916) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 18:41:44.234200) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-12 18:41:44.234565) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:41:44 INFO - MultiFileLogger online at 20160812 18:41:44 in /builds/slave/test 18:41:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 18:41:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:41:44 INFO - {'append_to_log': False, 18:41:44 INFO - 'base_work_dir': '/builds/slave/test', 18:41:44 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 18:41:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:41:44 INFO - 'branch': 'Autoland-Non-PGO', 18:41:44 INFO - 'buildbot_json_path': 'buildprops.json', 18:41:44 INFO - 'config_files': ('talos/linux_config.py',), 18:41:44 INFO - 'default_actions': ('clobber', 18:41:44 INFO - 'read-buildbot-config', 18:41:44 INFO - 'download-and-extract', 18:41:44 INFO - 'populate-webroot', 18:41:44 INFO - 'create-virtualenv', 18:41:44 INFO - 'install', 18:41:44 INFO - 'run-tests'), 18:41:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:41:44 INFO - 'download_minidump_stackwalk': True, 18:41:44 INFO - 'download_symbols': 'ondemand', 18:41:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:41:44 INFO - 'tooltool.py': '/tools/tooltool.py', 18:41:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:41:44 INFO - '/tools/misc-python/virtualenv.py')}, 18:41:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:41:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:41:44 INFO - 'installer_path': 'installer.exe', 18:41:44 INFO - 'log_level': 'info', 18:41:44 INFO - 'log_name': 'talos', 18:41:44 INFO - 'log_to_console': True, 18:41:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:41:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:41:44 INFO - 'opt_config_files': (), 18:41:44 INFO - 'pip_index': False, 18:41:44 INFO - 'sps_profile': False, 18:41:44 INFO - 'sps_profile_interval': 0, 18:41:44 INFO - 'suite': 'dromaeojs', 18:41:44 INFO - 'system_bits': '32', 18:41:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:41:44 INFO - 'title': 'talos-linux64-ix-001', 18:41:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:41:44 INFO - 'use_talos_json': True, 18:41:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:41:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:41:44 INFO - 'work_dir': 'build'} 18:41:44 INFO - [mozharness: 2016-08-13 01:41:44.706023Z] Running clobber step. 18:41:44 INFO - Running pre-action listener: _resource_record_pre_action 18:41:44 INFO - Running main action method: clobber 18:41:44 INFO - rmtree: /builds/slave/test/build 18:41:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:41:45 INFO - Running post-action listener: _resource_record_post_action 18:41:45 INFO - [mozharness: 2016-08-13 01:41:45.702680Z] Finished clobber step (success) 18:41:45 INFO - [mozharness: 2016-08-13 01:41:45.702781Z] Running read-buildbot-config step. 18:41:45 INFO - Running pre-action listener: _resource_record_pre_action 18:41:45 INFO - Running main action method: read_buildbot_config 18:41:45 INFO - Using buildbot properties: 18:41:45 INFO - { 18:41:45 INFO - "project": "", 18:41:45 INFO - "product": "firefox", 18:41:45 INFO - "script_repo_revision": "production", 18:41:45 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 18:41:45 INFO - "repository": "", 18:41:45 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 18:41:45 INFO - "buildid": "20160812164416", 18:41:45 INFO - "pgo_build": "False", 18:41:45 INFO - "basedir": "/builds/slave/test", 18:41:45 INFO - "buildnumber": 377, 18:41:45 INFO - "slavename": "talos-linux64-ix-001", 18:41:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:41:45 INFO - "platform": "ubuntu64_hw", 18:41:45 INFO - "scheduler": "tests-autoland-linux64-talos", 18:41:45 INFO - "branch": "autoland", 18:41:45 INFO - "repo_path": "integration/autoland", 18:41:45 INFO - "revision": "60b349aec11bef8df2e9f6f89546b4eee0a01574", 18:41:45 INFO - "stage_platform": "linux64", 18:41:45 INFO - "builduid": "ec04254b99074f78a1973b312ec5fe1e", 18:41:45 INFO - "slavebuilddir": "test" 18:41:45 INFO - } 18:41:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:41:45 INFO - Running post-action listener: _resource_record_post_action 18:41:45 INFO - [mozharness: 2016-08-13 01:41:45.705224Z] Finished read-buildbot-config step (success) 18:41:45 INFO - [mozharness: 2016-08-13 01:41:45.705313Z] Running download-and-extract step. 18:41:45 INFO - Running pre-action listener: _resource_record_pre_action 18:41:45 INFO - Running main action method: download_and_extract 18:41:45 INFO - mkdir: /builds/slave/test/build/tests 18:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:45 INFO - trying https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:41:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:46 INFO - Downloaded 1361 bytes. 18:41:46 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:41:46 INFO - Using the following test package requirements: 18:41:46 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:41:46 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:41:46 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:41:46 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:46 INFO - u'jsshell-linux-x86_64.zip'], 18:41:46 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:41:46 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:41:46 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:41:46 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:41:46 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:41:46 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:41:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:41:46 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')]} 18:41:46 INFO - trying https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:41:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:47 INFO - Downloaded 20265557 bytes. 18:41:47 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 18:41:51 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 18:41:51 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')]} 18:41:51 INFO - trying https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:41:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:52 INFO - Downloaded 20265557 bytes. 18:41:52 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 18:41:57 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')]} 18:41:57 INFO - trying https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:41:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:57 INFO - Downloaded 14413719 bytes. 18:41:57 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 18:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:58 INFO - trying https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:41:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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 18:41:59 INFO - Downloaded 55620629 bytes. 18:41:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:41:59 INFO - mkdir: /builds/slave/test/properties 18:41:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:41:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:41:59 INFO - Contents: 18:41:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:42:00 INFO - Running post-action listener: _resource_record_post_action 18:42:00 INFO - Running post-action listener: set_extra_try_arguments 18:42:00 INFO - [mozharness: 2016-08-13 01:42:00.032554Z] Finished download-and-extract step (success) 18:42:00 INFO - [mozharness: 2016-08-13 01:42:00.032779Z] Running populate-webroot step. 18:42:00 INFO - Running pre-action listener: _resource_record_pre_action 18:42:00 INFO - Running main action method: populate_webroot 18:42:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:42:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:42:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:42:00 INFO - '--tppagecycles', 18:42:00 INFO - '7'], 18:42:00 INFO - 'tests': ['tsvgm']}}, 18:42:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:42:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:42:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:42:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:42:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['damp', 'tps']}, 18:42:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'tests': ['damp', 'tps']}, 18:42:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['dromaeo_dom']}, 18:42:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:42:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['basic_compositor_video']}, 18:42:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:42:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other-e10s': {'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['a11yr', 18:42:00 INFO - 'ts_paint', 18:42:00 INFO - 'tpaint', 18:42:00 INFO - 'sessionrestore', 18:42:00 INFO - 'sessionrestore_no_auto_restore', 18:42:00 INFO - 'tabpaint']}, 18:42:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['tsvgx', 18:42:00 INFO - 'tsvgr_opacity', 18:42:00 INFO - 'tart', 18:42:00 INFO - 'tscrollx', 18:42:00 INFO - 'cart']}, 18:42:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:42:00 INFO - 'tsvgr_opacity', 18:42:00 INFO - 'tart', 18:42:00 INFO - 'tscrollx', 18:42:00 INFO - 'cart']}, 18:42:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--disable-e10s'], 18:42:00 INFO - 'tests': ['tp5o']}, 18:42:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'tests': ['tp5o']}, 18:42:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--disable-e10s', 18:42:00 INFO - '--xperf_path', 18:42:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:00 INFO - 'tests': ['tp5n']}, 18:42:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:00 INFO - 'talos_options': ['--xperf_path', 18:42:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:00 INFO - 'tests': ['tp5n']}}, 18:42:00 INFO - 'talos.zip': {'path': '', 18:42:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:42:00 INFO - Running post-action listener: _resource_record_post_action 18:42:00 INFO - [mozharness: 2016-08-13 01:42:00.048824Z] Finished populate-webroot step (success) 18:42:00 INFO - [mozharness: 2016-08-13 01:42:00.048931Z] Running create-virtualenv step. 18:42:00 INFO - Running pre-action listener: _resource_record_pre_action 18:42:00 INFO - Running main action method: create_virtualenv 18:42:00 INFO - Creating virtualenv /builds/slave/test/build/venv 18:42:00 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 18:42:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:42:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:42:00 INFO - Using real prefix '/usr' 18:42:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:42:00 INFO - Installing distribute.............................................................................................................................................................................................done. 18:42:02 INFO - Installing pip.................done. 18:42:02 INFO - Return code: 0 18:42:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:42:02 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')]} 18:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:02 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')]} 18:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:02 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:02 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 18:42:02 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 18:42:02 INFO - Using env: {'DISPLAY': ':0', 18:42:02 INFO - 'HOME': '/home/cltbld', 18:42:02 INFO - 'LANG': 'en_US.UTF-8', 18:42:02 INFO - 'LANGUAGE': 'en_US:en', 18:42:02 INFO - 'LOGNAME': 'cltbld', 18:42:02 INFO - 'MAIL': '/var/mail/cltbld', 18:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:02 INFO - 'MOZ_NO_REMOTE': '1', 18:42:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:02 INFO - 'NO_EM_RESTART': '1', 18:42:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:02 INFO - 'PWD': '/builds/slave/test', 18:42:02 INFO - 'SHELL': '/bin/bash', 18:42:02 INFO - 'SHLVL': '1', 18:42:02 INFO - 'TERM': 'linux', 18:42:02 INFO - 'TMOUT': '86400', 18:42:02 INFO - 'USER': 'cltbld', 18:42:02 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:02 INFO - '_': '/tools/buildbot/bin/python'} 18:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:02 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 18:42:02 INFO - Cleaning up... 18:42:02 INFO - Return code: 0 18:42:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:42:02 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')]} 18:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:02 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')]} 18:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:02 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:02 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 18:42:02 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 18:42:02 INFO - Using env: {'DISPLAY': ':0', 18:42:02 INFO - 'HOME': '/home/cltbld', 18:42:02 INFO - 'LANG': 'en_US.UTF-8', 18:42:02 INFO - 'LANGUAGE': 'en_US:en', 18:42:02 INFO - 'LOGNAME': 'cltbld', 18:42:02 INFO - 'MAIL': '/var/mail/cltbld', 18:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:02 INFO - 'MOZ_NO_REMOTE': '1', 18:42:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:02 INFO - 'NO_EM_RESTART': '1', 18:42:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:02 INFO - 'PWD': '/builds/slave/test', 18:42:02 INFO - 'SHELL': '/bin/bash', 18:42:02 INFO - 'SHLVL': '1', 18:42:02 INFO - 'TERM': 'linux', 18:42:02 INFO - 'TMOUT': '86400', 18:42:02 INFO - 'USER': 'cltbld', 18:42:02 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:02 INFO - '_': '/tools/buildbot/bin/python'} 18:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:03 INFO - Downloading/unpacking psutil>=3.1.1 18:42:03 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 18:42:03 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 18:42:03 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 18:42:03 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 18:42:03 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 18:42:03 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 18:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:42:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:05 INFO - Installing collected packages: psutil 18:42:05 INFO - Running setup.py install for psutil 18:42:05 INFO - building 'psutil._psutil_linux' extension 18:42:05 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 18:42:05 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 18:42:05 INFO - building 'psutil._psutil_posix' extension 18:42:05 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 18:42:05 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 18:42:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:05 INFO - Successfully installed psutil 18:42:05 INFO - Cleaning up... 18:42:05 INFO - Return code: 0 18:42:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:05 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:05 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 18:42:05 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 18:42:05 INFO - Using env: {'DISPLAY': ':0', 18:42:05 INFO - 'HOME': '/home/cltbld', 18:42:05 INFO - 'LANG': 'en_US.UTF-8', 18:42:05 INFO - 'LANGUAGE': 'en_US:en', 18:42:05 INFO - 'LOGNAME': 'cltbld', 18:42:05 INFO - 'MAIL': '/var/mail/cltbld', 18:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:05 INFO - 'MOZ_NO_REMOTE': '1', 18:42:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:05 INFO - 'NO_EM_RESTART': '1', 18:42:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:05 INFO - 'PWD': '/builds/slave/test', 18:42:05 INFO - 'SHELL': '/bin/bash', 18:42:05 INFO - 'SHLVL': '1', 18:42:05 INFO - 'TERM': 'linux', 18:42:05 INFO - 'TMOUT': '86400', 18:42:05 INFO - 'USER': 'cltbld', 18:42:05 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:05 INFO - '_': '/tools/buildbot/bin/python'} 18:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:42:05 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 18:42:05 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 18:42:05 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 18:42:05 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 18:42:05 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 18:42:05 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 18:42:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:42:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:42:07 INFO - Installing collected packages: mozsystemmonitor 18:42:07 INFO - Running setup.py install for mozsystemmonitor 18:42:07 INFO - Successfully installed mozsystemmonitor 18:42:07 INFO - Cleaning up... 18:42:07 INFO - Return code: 0 18:42:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:42:07 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')]} 18:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:07 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')]} 18:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:07 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:07 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 18:42:07 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 18:42:07 INFO - Using env: {'DISPLAY': ':0', 18:42:07 INFO - 'HOME': '/home/cltbld', 18:42:07 INFO - 'LANG': 'en_US.UTF-8', 18:42:07 INFO - 'LANGUAGE': 'en_US:en', 18:42:07 INFO - 'LOGNAME': 'cltbld', 18:42:07 INFO - 'MAIL': '/var/mail/cltbld', 18:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:07 INFO - 'MOZ_NO_REMOTE': '1', 18:42:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:07 INFO - 'NO_EM_RESTART': '1', 18:42:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:07 INFO - 'PWD': '/builds/slave/test', 18:42:07 INFO - 'SHELL': '/bin/bash', 18:42:07 INFO - 'SHLVL': '1', 18:42:07 INFO - 'TERM': 'linux', 18:42:07 INFO - 'TMOUT': '86400', 18:42:07 INFO - 'USER': 'cltbld', 18:42:07 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:07 INFO - '_': '/tools/buildbot/bin/python'} 18:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:08 INFO - Downloading/unpacking jsonschema==2.5.1 18:42:08 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 18:42:08 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 18:42:08 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 18:42:08 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 18:42:08 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 18:42:08 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 18:42:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:42:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:42:10 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 18:42:10 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 18:42:10 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 18:42:10 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 18:42:10 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 18:42:10 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 18:42:10 INFO - Downloading functools32-3.2.3-2.tar.gz 18:42:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:42:10 INFO - warning: no files found matching '*.txt' 18:42:10 INFO - no previously-included directories found matching 'build' 18:42:10 INFO - no previously-included directories found matching 'dist' 18:42:10 INFO - no previously-included directories found matching '.git*' 18:42:10 INFO - Installing collected packages: jsonschema, functools32 18:42:10 INFO - Running setup.py install for functools32 18:42:10 INFO - warning: no files found matching '*.txt' 18:42:10 INFO - no previously-included directories found matching 'build' 18:42:10 INFO - no previously-included directories found matching 'dist' 18:42:10 INFO - no previously-included directories found matching '.git*' 18:42:10 INFO - Successfully installed jsonschema functools32 18:42:10 INFO - Cleaning up... 18:42:10 INFO - Return code: 0 18:42:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:10 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:10 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 18:42:10 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 18:42:10 INFO - Using env: {'DISPLAY': ':0', 18:42:10 INFO - 'HOME': '/home/cltbld', 18:42:10 INFO - 'LANG': 'en_US.UTF-8', 18:42:10 INFO - 'LANGUAGE': 'en_US:en', 18:42:10 INFO - 'LOGNAME': 'cltbld', 18:42:10 INFO - 'MAIL': '/var/mail/cltbld', 18:42:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:10 INFO - 'MOZ_NO_REMOTE': '1', 18:42:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:10 INFO - 'NO_EM_RESTART': '1', 18:42:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:10 INFO - 'PWD': '/builds/slave/test', 18:42:10 INFO - 'SHELL': '/bin/bash', 18:42:10 INFO - 'SHLVL': '1', 18:42:10 INFO - 'TERM': 'linux', 18:42:10 INFO - 'TMOUT': '86400', 18:42:10 INFO - 'USER': 'cltbld', 18:42:10 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:10 INFO - '_': '/tools/buildbot/bin/python'} 18:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:42:10 INFO - Cleaning up... 18:42:10 INFO - Return code: 0 18:42:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:10 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:10 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 18:42:10 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 18:42:10 INFO - Using env: {'DISPLAY': ':0', 18:42:10 INFO - 'HOME': '/home/cltbld', 18:42:10 INFO - 'LANG': 'en_US.UTF-8', 18:42:10 INFO - 'LANGUAGE': 'en_US:en', 18:42:10 INFO - 'LOGNAME': 'cltbld', 18:42:10 INFO - 'MAIL': '/var/mail/cltbld', 18:42:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:10 INFO - 'MOZ_NO_REMOTE': '1', 18:42:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:10 INFO - 'NO_EM_RESTART': '1', 18:42:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:10 INFO - 'PWD': '/builds/slave/test', 18:42:10 INFO - 'SHELL': '/bin/bash', 18:42:10 INFO - 'SHLVL': '1', 18:42:10 INFO - 'TERM': 'linux', 18:42:10 INFO - 'TMOUT': '86400', 18:42:10 INFO - 'USER': 'cltbld', 18:42:10 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:10 INFO - '_': '/tools/buildbot/bin/python'} 18:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:10 INFO - Downloading/unpacking blobuploader==1.2.4 18:42:10 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 18:42:10 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 18:42:10 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 18:42:10 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 18:42:10 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 18:42:10 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 18:42:12 INFO - Downloading blobuploader-1.2.4.tar.gz 18:42:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:42:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:42:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:42:13 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 18:42:13 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 18:42:13 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 18:42:13 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 18:42:13 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 18:42:13 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 18:42:13 INFO - Downloading docopt-0.6.1.tar.gz 18:42:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:42:13 INFO - Installing collected packages: blobuploader, requests, docopt 18:42:13 INFO - Running setup.py install for blobuploader 18:42:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:42:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:42:13 INFO - Running setup.py install for requests 18:42:13 INFO - Running setup.py install for docopt 18:42:14 INFO - Successfully installed blobuploader requests docopt 18:42:14 INFO - Cleaning up... 18:42:14 INFO - Return code: 0 18:42:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:14 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:14 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 18:42:14 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 18:42:14 INFO - Using env: {'DISPLAY': ':0', 18:42:14 INFO - 'HOME': '/home/cltbld', 18:42:14 INFO - 'LANG': 'en_US.UTF-8', 18:42:14 INFO - 'LANGUAGE': 'en_US:en', 18:42:14 INFO - 'LOGNAME': 'cltbld', 18:42:14 INFO - 'MAIL': '/var/mail/cltbld', 18:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:14 INFO - 'MOZ_NO_REMOTE': '1', 18:42:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:14 INFO - 'NO_EM_RESTART': '1', 18:42:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:14 INFO - 'PWD': '/builds/slave/test', 18:42:14 INFO - 'SHELL': '/bin/bash', 18:42:14 INFO - 'SHLVL': '1', 18:42:14 INFO - 'TERM': 'linux', 18:42:14 INFO - 'TMOUT': '86400', 18:42:14 INFO - 'USER': 'cltbld', 18:42:14 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:14 INFO - '_': '/tools/buildbot/bin/python'} 18:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:14 INFO - Running setup.py (path:/tmp/pip-mPJpPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:14 INFO - Running setup.py (path:/tmp/pip-2qokkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:14 INFO - Running setup.py (path:/tmp/pip-Vr1d2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:14 INFO - Running setup.py (path:/tmp/pip-dc7fUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:14 INFO - Running setup.py (path:/tmp/pip-k6VSTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:14 INFO - Running setup.py (path:/tmp/pip-XxnMbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:14 INFO - Running setup.py (path:/tmp/pip-ZI5DB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:14 INFO - Running setup.py (path:/tmp/pip-1jiKBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:14 INFO - Running setup.py (path:/tmp/pip-UWvM8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:15 INFO - Running setup.py (path:/tmp/pip-SSQGzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:15 INFO - Running setup.py (path:/tmp/pip-M5Xapv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:15 INFO - Running setup.py (path:/tmp/pip-T_Hgfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:15 INFO - Running setup.py (path:/tmp/pip-tkVDsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:15 INFO - Running setup.py (path:/tmp/pip-4WG_rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:15 INFO - Running setup.py (path:/tmp/pip-wxP1Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:15 INFO - Running setup.py (path:/tmp/pip-a4aum3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:15 INFO - Running setup.py (path:/tmp/pip-Rs60Xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:42:15 INFO - Running setup.py install for manifestparser 18:42:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:42:15 INFO - Running setup.py install for mozcrash 18:42:15 INFO - Running setup.py install for mozdebug 18:42:15 INFO - Running setup.py install for mozdevice 18:42:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Running setup.py install for mozfile 18:42:16 INFO - Running setup.py install for mozhttpd 18:42:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Running setup.py install for mozinfo 18:42:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Running setup.py install for mozInstall 18:42:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Running setup.py install for mozleak 18:42:16 INFO - Running setup.py install for mozlog 18:42:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Running setup.py install for moznetwork 18:42:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Running setup.py install for mozprocess 18:42:16 INFO - Running setup.py install for mozprofile 18:42:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:42:16 INFO - Running setup.py install for mozrunner 18:42:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:42:17 INFO - Running setup.py install for mozscreenshot 18:42:17 INFO - Running setup.py install for moztest 18:42:17 INFO - Running setup.py install for mozversion 18:42:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:42:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:42:17 INFO - Cleaning up... 18:42:17 INFO - Return code: 0 18:42:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:17 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')]} 18:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:17 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')]} 18:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:17 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:17 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 18:42:17 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 18:42:17 INFO - Using env: {'DISPLAY': ':0', 18:42:17 INFO - 'HOME': '/home/cltbld', 18:42:17 INFO - 'LANG': 'en_US.UTF-8', 18:42:17 INFO - 'LANGUAGE': 'en_US:en', 18:42:17 INFO - 'LOGNAME': 'cltbld', 18:42:17 INFO - 'MAIL': '/var/mail/cltbld', 18:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:17 INFO - 'MOZ_NO_REMOTE': '1', 18:42:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:17 INFO - 'NO_EM_RESTART': '1', 18:42:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:17 INFO - 'PWD': '/builds/slave/test', 18:42:17 INFO - 'SHELL': '/bin/bash', 18:42:17 INFO - 'SHLVL': '1', 18:42:17 INFO - 'TERM': 'linux', 18:42:17 INFO - 'TMOUT': '86400', 18:42:17 INFO - 'USER': 'cltbld', 18:42:17 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:17 INFO - '_': '/tools/buildbot/bin/python'} 18:42:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:17 INFO - Running setup.py (path:/tmp/pip-6afuS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:17 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)) 18:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:17 INFO - Running setup.py (path:/tmp/pip-8wV_OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:17 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)) 18:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:17 INFO - Running setup.py (path:/tmp/pip-mOz0xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:17 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)) 18:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:17 INFO - Running setup.py (path:/tmp/pip-uC5gLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:17 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)) 18:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:17 INFO - Running setup.py (path:/tmp/pip-DYPqIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:18 INFO - Running setup.py (path:/tmp/pip-q8qDzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:18 INFO - Running setup.py (path:/tmp/pip-nGBt0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:18 INFO - Running setup.py (path:/tmp/pip-mMQOps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:18 INFO - Running setup.py (path:/tmp/pip-1pu7n9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:18 INFO - Running setup.py (path:/tmp/pip-jWk0d_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:18 INFO - Running setup.py (path:/tmp/pip-PGCggE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:18 INFO - Running setup.py (path:/tmp/pip-iIaRmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:18 INFO - Running setup.py (path:/tmp/pip-velkdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:18 INFO - Running setup.py (path:/tmp/pip-MtzFCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:18 INFO - Running setup.py (path:/tmp/pip-2FXC75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:18 INFO - Running setup.py (path:/tmp/pip-LxopkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:18 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)) 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:18 INFO - Running setup.py (path:/tmp/pip-Wbjqqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:18 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)) 18:42:18 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)) 18:42:18 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)) 18:42:18 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)) 18:42:18 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)) 18:42:18 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)) 18:42:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:20 INFO - Downloading blessings-1.6.tar.gz 18:42:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:42:20 INFO - Installing collected packages: blessings 18:42:20 INFO - Running setup.py install for blessings 18:42:20 INFO - Successfully installed blessings 18:42:20 INFO - Cleaning up... 18:42:21 INFO - Return code: 0 18:42:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:42:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:21 INFO - Reading from file tmpfile_stdout 18:42:21 INFO - Current package versions: 18:42:21 INFO - argparse == 1.2.1 18:42:21 INFO - blessings == 1.6 18:42:21 INFO - blobuploader == 1.2.4 18:42:21 INFO - docopt == 0.6.1 18:42:21 INFO - functools32 == 3.2.3-2 18:42:21 INFO - jsonschema == 2.5.1 18:42:21 INFO - manifestparser == 1.1 18:42:21 INFO - mozInstall == 1.12 18:42:21 INFO - mozcrash == 0.17 18:42:21 INFO - mozdebug == 0.1 18:42:21 INFO - mozdevice == 0.48 18:42:21 INFO - mozfile == 1.2 18:42:21 INFO - mozhttpd == 0.7 18:42:21 INFO - mozinfo == 0.9 18:42:21 INFO - mozleak == 0.1 18:42:21 INFO - mozlog == 3.3 18:42:21 INFO - moznetwork == 0.27 18:42:21 INFO - mozprocess == 0.23 18:42:21 INFO - mozprofile == 0.28 18:42:21 INFO - mozrunner == 6.12 18:42:21 INFO - mozscreenshot == 0.1 18:42:21 INFO - mozsystemmonitor == 0.3 18:42:21 INFO - moztest == 0.7 18:42:21 INFO - mozversion == 1.4 18:42:21 INFO - psutil == 3.1.1 18:42:21 INFO - requests == 1.2.3 18:42:21 INFO - wsgiref == 0.1.2 18:42:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:21 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 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:21 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 18:42:21 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 18:42:21 INFO - Using env: {'DISPLAY': ':0', 18:42:21 INFO - 'HOME': '/home/cltbld', 18:42:21 INFO - 'LANG': 'en_US.UTF-8', 18:42:21 INFO - 'LANGUAGE': 'en_US:en', 18:42:21 INFO - 'LOGNAME': 'cltbld', 18:42:21 INFO - 'MAIL': '/var/mail/cltbld', 18:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:21 INFO - 'MOZ_NO_REMOTE': '1', 18:42:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:21 INFO - 'NO_EM_RESTART': '1', 18:42:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:21 INFO - 'PWD': '/builds/slave/test', 18:42:21 INFO - 'SHELL': '/bin/bash', 18:42:21 INFO - 'SHLVL': '1', 18:42:21 INFO - 'TERM': 'linux', 18:42:21 INFO - 'TMOUT': '86400', 18:42:21 INFO - 'USER': 'cltbld', 18:42:21 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:21 INFO - '_': '/tools/buildbot/bin/python'} 18:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 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)) 18:42:21 INFO - Cleaning up... 18:42:21 INFO - Return code: 0 18:42:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f04d4f79f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, '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': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', '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 18:42:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:42:21 INFO - Using env: {'DISPLAY': ':0', 18:42:21 INFO - 'HOME': '/home/cltbld', 18:42:21 INFO - 'LANG': 'en_US.UTF-8', 18:42:21 INFO - 'LANGUAGE': 'en_US:en', 18:42:21 INFO - 'LOGNAME': 'cltbld', 18:42:21 INFO - 'MAIL': '/var/mail/cltbld', 18:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:21 INFO - 'MOZ_NO_REMOTE': '1', 18:42:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:21 INFO - 'NO_EM_RESTART': '1', 18:42:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:21 INFO - 'PWD': '/builds/slave/test', 18:42:21 INFO - 'SHELL': '/bin/bash', 18:42:21 INFO - 'SHLVL': '1', 18:42:21 INFO - 'TERM': 'linux', 18:42:21 INFO - 'TMOUT': '86400', 18:42:21 INFO - 'USER': 'cltbld', 18:42:21 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:21 INFO - '_': '/tools/buildbot/bin/python'} 18:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:42:21 INFO - Cleaning up... 18:42:21 INFO - Return code: 0 18:42:21 INFO - Running post-action listener: _resource_record_post_action 18:42:21 INFO - Running post-action listener: _start_resource_monitoring 18:42:21 INFO - Starting resource monitoring. 18:42:21 INFO - [mozharness: 2016-08-13 01:42:21.686598Z] Finished create-virtualenv step (success) 18:42:21 INFO - [mozharness: 2016-08-13 01:42:21.687114Z] Running install step. 18:42:21 INFO - Running pre-action listener: _resource_record_pre_action 18:42:21 INFO - Running main action method: install 18:42:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:21 INFO - Reading from file tmpfile_stdout 18:42:21 INFO - Detecting whether we're running mozinstall >=1.0... 18:42:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:42:21 INFO - Reading from file tmpfile_stdout 18:42:21 INFO - Output received: 18:42:21 INFO - Usage: mozinstall [options] installer 18:42:21 INFO - Options: 18:42:21 INFO - -h, --help show this help message and exit 18:42:21 INFO - -d DEST, --destination=DEST 18:42:21 INFO - Directory to install application into. [default: 18:42:21 INFO - "/builds/slave/test"] 18:42:21 INFO - --app=APP Application being installed. [default: firefox] 18:42:21 INFO - mkdir: /builds/slave/test/build/application 18:42:21 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'] 18:42:21 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 18:42:38 INFO - Reading from file tmpfile_stdout 18:42:38 INFO - Output received: 18:42:38 INFO - /builds/slave/test/build/application/firefox/firefox 18:42:38 INFO - Running post-action listener: _resource_record_post_action 18:42:38 INFO - [mozharness: 2016-08-13 01:42:38.003934Z] Finished install step (success) 18:42:38 INFO - [mozharness: 2016-08-13 01:42:38.004186Z] Running run-tests step. 18:42:38 INFO - Running pre-action listener: _resource_record_pre_action 18:42:38 INFO - Running main action method: run_tests 18:42:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:42:38 INFO - Python 2.7.3 18:42:38 INFO - Return code: 0 18:42:38 INFO - grabbing minidump binary from tooltool 18:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:38 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 0x27d9090>, '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 0x27dc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27dce40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:42:38 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 18:42:38 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 18:42:38 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 18:42:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:42:38 INFO - Return code: 0 18:42:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:42:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:42:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:42:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:42:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:42:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 18:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 18:42:38 INFO - Using env: {'DISPLAY': ':0', 18:42:38 INFO - 'HOME': '/home/cltbld', 18:42:38 INFO - 'LANG': 'en_US.UTF-8', 18:42:38 INFO - 'LANGUAGE': 'en_US:en', 18:42:38 INFO - 'LOGNAME': 'cltbld', 18:42:38 INFO - 'MAIL': '/var/mail/cltbld', 18:42:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:42:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:38 INFO - 'MOZ_NO_REMOTE': '1', 18:42:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:42:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:38 INFO - 'NO_EM_RESTART': '1', 18:42:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:38 INFO - 'PWD': '/builds/slave/test', 18:42:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:42:38 INFO - 'SHELL': '/bin/bash', 18:42:38 INFO - 'SHLVL': '1', 18:42:38 INFO - 'TERM': 'linux', 18:42:38 INFO - 'TMOUT': '86400', 18:42:38 INFO - 'USER': 'cltbld', 18:42:38 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775', 18:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:38 INFO - '_': '/tools/buildbot/bin/python'} 18:42:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 18:42:38 INFO - mozversion application_buildid: 20160812164416 18:42:38 INFO - mozversion application_changeset: 60b349aec11bef8df2e9f6f89546b4eee0a01574 18:42:38 INFO - mozversion application_display_name: Nightly 18:42:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:42:38 INFO - mozversion application_name: Firefox 18:42:38 INFO - mozversion application_remotingname: firefox 18:42:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:42:38 INFO - mozversion application_vendor: Mozilla 18:42:38 INFO - mozversion application_version: 51.0a1 18:42:38 INFO - mozversion platform_buildid: 20160812164416 18:42:38 INFO - mozversion platform_changeset: 60b349aec11bef8df2e9f6f89546b4eee0a01574 18:42:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:42:38 INFO - mozversion platform_version: 51.0a1 18:42:38 INFO - using testdate: 1471052558 18:42:38 INFO - actual date: 1471052558 18:42:38 INFO - starting webserver on 'localhost:52041' 18:42:38 INFO - SUITE-START | Running 2 tests 18:42:38 INFO - TEST-START | dromaeo_css 18:42:38 INFO - operating with platform_type : linux_ 18:42:38 INFO - Initialising browser for dromaeo_css test... 18:42:38 INFO - TEST-INFO | started process 3217 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdTveF9/profile http://localhost:52041/getInfo.html) 18:42:42 INFO - PROCESS | 3217 | __metrics Screen width/height:1600/1200 18:42:42 INFO - PROCESS | 3217 | colorDepth:24 18:42:42 INFO - PROCESS | 3217 | Browser inner width/height: 1024/697 18:42:42 INFO - PROCESS | 3217 | __metrics 18:42:43 INFO - PROCESS | 3217 | 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" 18:42:43 INFO - PROCESS | 3217 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:42:44 INFO - TEST-INFO | 3217: exit 0 18:42:44 INFO - Browser initialized. 18:42:44 INFO - Running cycle 1/1 for dromaeo_css test... 18:42:44 INFO - TEST-INFO | started process 3295 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdTveF9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:56 INFO - PROCESS | 3295 | RSS: Main: 156909568 18:42:56 INFO - PROCESS | 3295 | 18:43:47 INFO - PROCESS | 3295 | 1471052627887 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] 18:46:00 INFO - PROCESS | 3295 | Cycle 1(1): loaded http://localhost:52041/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52041/tests/dromaeo/cssquery-ext.html) 18:46:02 INFO - PROCESS | 3295 | RSS: Main: 240885760 18:46:02 INFO - PROCESS | 3295 | 18:49:04 INFO - PROCESS | 3295 | Cycle 1(1): loaded http://localhost:52041/tests/dromaeo/cssquery-ext.html (next: http://localhost:52041/tests/dromaeo/cssquery-jquery.html) 18:49:05 INFO - PROCESS | 3295 | RSS: Main: 246992896 18:49:05 INFO - PROCESS | 3295 | 18:52:07 INFO - PROCESS | 3295 | Cycle 1(1): loaded http://localhost:52041/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52041/tests/dromaeo/cssquery-mootools.html) 18:52:08 INFO - PROCESS | 3295 | RSS: Main: 228851712 18:52:08 INFO - PROCESS | 3295 | 18:55:10 INFO - PROCESS | 3295 | Cycle 1(1): loaded http://localhost:52041/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52041/tests/dromaeo/cssquery-prototype.html) 18:55:11 INFO - PROCESS | 3295 | RSS: Main: 240013312 18:55:11 INFO - PROCESS | 3295 | 18:58:14 INFO - PROCESS | 3295 | Cycle 1(1): loaded http://localhost:52041/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52041/tests/dromaeo/cssquery-yui.html) 18:58:14 INFO - PROCESS | 3295 | RSS: Main: 189566976 18:58:14 INFO - PROCESS | 3295 | 19:01:17 INFO - PROCESS | 3295 | Cycle 1(1): loaded http://localhost:52041/tests/dromaeo/cssquery-yui.html (next: http://localhost:52041/tests/dromaeo/cssquery-dojo.html) 19:01:18 INFO - PROCESS | 3295 | RSS: Main: 283529216 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - PROCESS | 3295 | __start_tp_report 19:01:18 INFO - PROCESS | 3295 | _x_x_mozilla_page_load 19:01:18 INFO - PROCESS | 3295 | _x_x_mozilla_page_load_details 19:01:18 INFO - PROCESS | 3295 | |i|pagename|runs| 19:01:18 INFO - PROCESS | 3295 | |0;dojo.html;2646.642703235063;2651.2709005023603;2666.9199924002273;2686.2344231893903;2688.704242533321;4669.906601867969;4707.76461176941;4747.8813029674275;4757.286407038946;4764.618830493561;4757.405324334458;4783.258594917794;4814.157522433569;4816.710997340162;4835.951640483594;4348.282533381988;4364.629006534448;4453.309736990772;4490.259107247301;4510.0979804039325;4495.730256184623;4520.97739511302;4530.184566777979;4540.091981603676;4553.066621342617;4301.397804307399;4335.111302183045;4346.913061738779;4360.127974405132;4404.537523560044;4352.194843953878;4354;4355.978220108911;4360.084382279725;4459.487158976694;4396.340548917656;4401.603855652975;4411.007523307262;4431.7119387240045;4440.844570440019;4670.929936050962;4706.011737535087;4791.2813078038225;4795;4847.248676455155;5094.337736094284;5146.588272938156;5186.429492755794;5186.507281808223;5190.429052804188;5159.845204643867;5181.818636347747;5193.584513238932;5210.531052205281;5214.921776173361;4695.366125573072;4704.411948506437;4719.197736384823;4809.2305231162845;4817.903641927177;5212.973935130376;5216.4522725114075;5243;5244.606654500928;5260.132078207053;4999.875003124951;4999.925001124986;5032.270320803463;5039.0929632666475;5043.268726034705;5022.773975171126;5069.721165335942;5084.059449001947;5106.642535022587;5113.565346945551;5123.359580052494;5133.230015497706;5134.922976155361;5150.072986862401;5154.355705536808;5091.236314552847;5109.080365534165;5111.718855462986;5115.846524604268;5165.845024649267;4211.978940105279;4246.787660617018;4252.914941701149;4360.280553708666;4414.940414300545;3735.6451137141926;3739.121306492972;3782.149016471272;3815.0271680721794;3837.3668344723364;4351.282038463618;4354.238008348526;4355.390245365651;4362.825486980485;4404.559544045634;4970.378702662168;5006.3992320921725;5079.034983353225;5090.160123579568;5124.308218390544;4326.2645350289895;4355.9981204323185;4367.803448844809;4374.518802931675;4429.689921705514;4585.70192937458;4596.287575425815;4597.057603191338;4601.631869450436;4630.282306242538;4646.024334889711;4804.807807687654;4808.927866081942;4809.206480930678;4834.830780922651;5035.16919708244;5123.257127716386;5127.743612819419;5193.0652482552;5210.1142805723875;4790.377250957354;4792.041591681747;4793.304970779287;4825.5898248649255;4828.782704778223;5024.346834911438;5029.044481548494;5029.4719054498755;5030.547250747451;5032.421271553818;4925.778339974781;5023;5067.2652465391575;5096.413912399973;5135.6405051646025;4997.125503036881;4997.200447928314;4997.550220480174;5001.8749531260555;5030.0694371541385;4997.425296090996;4999.825006124768;5004.624653150955;5010.674306170087;5043.747812609281;4708.246680531099;4803.279508073817;4826.541478559531;4835.105505481497;4958.082754690394;5002.849914502571;5004.549590536869;5009.699418034929;5010.398752149766;5024.924626130538;4709.0817290628;4886.096072226649;4888.071266459361;4897.608191344612;4906.484819094087 19:01:18 INFO - PROCESS | 3295 | |1;ext.html;549.6921723834652;561.4722161168504;563;567.6452217364148;568.2697733412566;28023.299417514547;38053.42919856201;39606.80196599013;39726.2054758905;43144.352834707504;39264.21471570574;39880;39895.401568976486;39924.80037599815;39980.800095999555;7195.244499327574;7614.08630964285;7743.9225607743765;7754.340881025119;7759.728409505668;7211.06256186695;7431.925680743205;7434.628268586576;9936.155426788731;10262.076413122817;9746;9783.510824458785;9801.460919649417;9804.656837010705;9807.65673201438;9907.752306192331;9969.102780749767;9984;9986.550605222783;9992.050755178247;9816.067473590068;9904.356216845954;9904.504774761304;9907.554160062742;9913.653022144263;36039.81980090083;36461.45307820385;36510.45234321487;36555.08612284688;36645.450318245246;2978.0321395546534;3043.269615292336;3203.7917535360125;3490.110021944388;3650.0509867434394;29024.129276121537;29163.70836291631;29306.70693293061;29593.260168495744;30701.38597228043;3498.510208570802;3541.2386336937684;3567.571891373052;3621.6559426854406;3683.1528748388023;248536;248651;249696;250017;251052;30835.22911927175;49428;49635.503644963086;49751.25373119406;49925.50074499209;31639.84180079131;33236.50145247823;33508;33526.49710254348;33544.83227583847;12174.695632609079;12323.260604363766;12328.383580820922;12334.938325308316;12347.3826308686;9697;9800.264980126376;9805.117539421486;9807.06832850878;9811.950940245253;7155.713771449084;7493.175750667422;7748.380129589732;7838.372930165573;7983.800404989922;3032.8938487152845;3079.1070589529236;3105.4099721052926;3108.7046730561015;3123.344097739455;842.8440738463404;861.9008813986471;906.4878343735768;914.6752902719572;957.2055194188806;26346.868265658548;27700.168994930184;28094.85952570265;28290.151295461168;28348.858255709;925.6482536636036;926.3700683535126;947.3415975896824;1014.4268488304084;1016.8983101689772;14134.36395362211;14798;14996.550103496913;15123.16822574769;15308.770368444482;360903;362568;363576;364026;365480;49362.75318623384;49768.25347619789;49913;49918;49985.25022124671;1064.5635289531256;1139.0602752729033;1164.831099490586;1178.63462326679;1181.9054475641929;27035.45929081368;28384.290392740346;29120.371064561707;29402.55896161602;29665.703342967157;40606.18787624172;49616;51524.742376287875;51656.22515662193;51686;375665;379089;379880;380259;380685;24474.388140296636;25065;25237.116700916114;26408.471830563667;38025.619743802206;39766.60233397745;39859;40072.19855602813;40119;40227.1954560913;35005;35046;35100.297994040484;35371.46942795911;35601.46597801087;35380.292394152486;35446.46830297496;35667.46498802468;35713.28573428465;35876.64123358733;34346.65653343534;34637.65362346344;34707.82646086753;35053;35544.82227588949;30138.84930575333;31689.683103168674;32203.677963220067;32333;32500.512492313108;24316.27051188467;24520.632190517514;24541.50916981686;24927;24977.500449990534 19:01:18 INFO - PROCESS | 3295 | |2;jquery.html;352.8359312919495;369.23199744531377;369.4624321612054;371.21858487883003;391.6494737210197;12531.498740050398;14232.71534569308;16328.918355408236;16414.343426262963;16579.17104144791;16294.356108509077;16840.157992100376;16992.490225293262;17186.14069296533;17211.569710757238;5001.249812528123;5179;5196.298499702549;5199.14214154664;5205;3715.925681486382;4903.141950158726;5106.234064890259;5186.247994040862;5187.714675692835;6065.211522502091;6159.3224745277985;6167.62994220346;6169.722362493676;6169.938300616981;5957.404259574051;5996.640201587918;6138.17134686816;6164.59930104544;6167.229096362977;5374.8925021499745;5381.865453363697;5389.272448219479;5391.083515802323;5392;19978.600427991358;20758.5848283035;20898.268560600307;20933.37199884006;21011.474713132142;1983.464464594566;2034.340618605622;2073.709680644711;2305.2853615379286;2308.3998160478395;13112;13158.342082895817;13415.731685366238;13873.375698093509;14080.366383512664;1789.2574581548643;1798.5953160538904;1802.6935420978334;1810.7555480010162;1829.0108339832393;63788;64667.353326466604;64920;65071.349286507;65076.67461662709;9379.296552758571;9600.32797704154;9932.602695892228;9962.501874906226;9994.850077248848;13293.13594616347;13300.401481933337;13301;13308.134971226838;13334.866651333363;6868.141482314711;6918.411934985583;7153.856922861513;7180.92819071813;7197.172325182566;6474.255460621995;6476.481881449567;6617.437517810944;6666.83332916681;6700.731970721214;5957.225560677084;6037.8188654339565;6073.969630151821;6077.20996270482;6146.938530614726;1712.571857035741;1777.3423833181805;1809.683305421546;1819.972700409495;1834.899080550556;347.9043263102667;379.1734019836727;380.853371451993;398.50187265917606;830.667732906842;9988.200943924468;10212.6425575106;10637.09584685295;10728.356298621951;11142.888571114347;388.85612323440506;397.6699339548168;401.48409294056995;409.4840500968741;417.6387424877459;5607.299087614048;5738.799142030009;5758.107493338539;5799.2750906136735;5946.205283516741;371578;373915;375180;377723;379387;70727.64636176787;72457;73948;80231.59884200542;81027.59486202532;898.3935843305873;913.9131782480521;944.9716508504756;945.7730144765206;947.2895328503622;15733.75188699022;15868.92065539711;16440.67118657596;16535.173241338125;16835.1582420876;38069.09928961015;38086;38460;38573;38854;49825.25262121144;50564;54183.45816541784;54889.432186907725;55737.442625573225;7648.158702542604;7671.2712292332135;7710.4217183710325;7858.174891636524;7959.005124359455;9434.48110353937;11593.594224202112;12194.451249693962;12290.508379664714;12860.485580577046;10167.44079075658;10519.368837869752;10638.627648032281;10829.834033193236;11138.554457821596;10020.248481364073;10061.195104391485;10484.790304194026;10805.567777288821;10887.618933337295;10562.102221311274;10732.248742588255;10974.615888443866;11105.944470277596;11417.771644567229;6530.281669016499;6822.249552549315;6931.202911665223;7497.325240728361;8086.989126359205;10363.896361036293;10624.256302058782;10648.520816563429;10826.783464330512;11079 19:01:18 INFO - PROCESS | 3295 | |3;mootools.html;1309.9410526526308;1381.772007618743;1427.785832125181;1657.8507934285913;1692.9238184281714;7368.115826100869;7956.045274567053;7978.48139870908;7987.08148562914;7987.92012079879;6713.697883595238;7446.069241344832;7675.884861727079;7682.347000504964;7869.92130078699;2196.06667166454;2206.3380985704307;2221.31139346803;2224.510607666311;2225.2879078694823;2066.7519897612233;2080.708700781892;2159.730033745782;2178.6078505868936;2180.647998241093;1880.5862710203733;2119.947001324964;2166.5341951480427;2177.6951226828255;2201.8018378345946;2191.7589065202815;2199.472126689599;2209.5801797658473;2211.1266049910355;2214.3910424633355;2133.0507923973837;2136.465883529118;2140.657494800824;2198.7801219878043;2233.6761169630313;7826.1391246962785;7913.208679132098;7914.456680947218;7935.404844636675;7941.404394670365;4305.827766889352;4413.271810151353;4413.514513403523;4417.182821178092;4420.84527041552;2783.6242107315456;2927.151126173407;2935.148806846012;2960.585518027478;2963.0443907021263;937.2174234514189;983.8130755156498;987.1115995603942;988;1012;10478.161747060292;10479.58081676732;10481.63314284004;10499.370037797757;10507.211959103022;3888.51393575803;4080;4195.97902010488;4240.342746874239;4315.438992930962;8894.777630559287;8899;8900.8664870027;8903.688370906988;8912.376133670581;4485.170243504962;4510.278355463176;4517.34498497716;4521.683482156218;4523;4019.477467929209;4069.4302797608357;4085.22380747657;4086.67306615476;4088.34586466164;3566.32239874423;3664.3770559005034;3710.8515659373325;3721.2557488501866;3744.9438258426144;1175.9294442333487;1196.0790191552455;1199.2910103103043;1206.7043574324346;1209.2563073709605;664.6012392564519;732.2311572848595;734.4858598980628;849.4818160921833;857.9571021448903;5174.456682048406;5206.167013277857;5302.522772950453;5381.2197231402015;5392.757325920343;818.0878320672526;828.5857071464268;851.6933903794629;867.8090820019586;887.6050157679771;3469.9479507807405;3499.4051011328133;3533.8763143290375;3579.3378225028455;3651.7626354286886;8972.461652300883;9180.127887850644;9387.483688397067;9415.387999780129;9770.120689138012;9719.076687714656;9758.21890761144;9777.506735782472;9782.364146330467;9804.705858824247;7106.253843346478;7132.251113633098;7361.411087113018;7434.479586429006;7442.292982166686;5276.788928442973;5287.180487024518;5313.468653134811;5433.782648694008;5593.8321850344555;8917.910820891708;8921.197092261727;8941.195292423714;8947.910520894968;9019.098090190928;8558.229759321443;8812.471251724917;8935.731928042169;8941.597628106621;9301.86047209306;6811.114555107804;6932.930670693228;6986.938853038468;7007;7009.509334346547;7630.656620451982;7704.499207551491;7705.113911899979;7705.1524332324525;7705.807354816175;6962.930370696228;7017.578945263301;7048.436125109876;7057.082579264663;7196.316349946746;6208.596441231305;6285.214348206474;6316.62100273985;6318.304986451395;6336.809895703136;6364.172657554488;6453;6498.870022599427;6516.934830651822;6573.474122070318;4539;4633.212353899912;4636.652251081115;4690.601298889633;4712.198926182626;4482.955170448384;4484.439445069366;4522.8643140705835;4535.523770004234;4773.11697335994 19:01:18 INFO - PROCESS | 3295 | |4;prototype.html;284.71244043516054;289.97100289971;297.3873819930943;302.3258134360375;303.33115480365797;4660.813567457306;4762.476127625962;4789.18583840747;4816.662833601649;4828.324034635154;4616.699914505555;4745.383100196978;4767.523247675231;4774.928376074362;4780.450248221456;3781.315266161029;3788.4317352397225;3789.7157713171487;3798.9050273743237;3806.5812760596314;3567.3935430976667;3607.8556857725657;3786.091338078855;3788.90527736806;3814.408766641161;3746.644068813472;3751.1934933989187;3761.2477504499075;3771.7359784815076;3779.168582911755;3763.172102137525;3766.9623303766884;3769.7738135711943;3774.4904437900805;3782.81085945703;3345.933081338384;3346.6318704942437;3347.263602007579;3354.3962086824363;3374.223928496435;4537.9773101134615;4612.630989520831;4628.81965098901;4653.325267836179;4686.929696054562;4232.555581663943;4234.618884300427;4236.568379612524;4239.618434340893;4241.575842415765;4927.457979622239;4931;4971.483697472269;4976.203807390836;4978.87552811179;3416.7437442191685;3563.625819288963;3594.8741794037346;3595.2989167112423;3611.6749492545796;5148.768305426227;5178.446466060167;5179.637425380225;5186.740662966874;5188.273641690166;3649.9635003650155;3650.5071815304595;3651.507046548735;3671.6511931366476;3673.7244706646575;3724.8510059597857;3748.5126933499014;3761.0785357587565;3761.2665530221893;3770.792606406674;3988.3419235826345;4141.420201171838;4167.708010516689;4188.246115699203;4282.764447955392;4991.301217829507;5001.8499445016705;5014.222795466709;5023.221400682827;5046.7476626168545;1208.2025862930423;1209.8124790657462;1248.5579156074748;1253.0602048463652;1268.2834198677717;835.8381849229576;838.4843321357444;852.6802449081595;854.4403415762686;860.3117505995303;4359.433273674465;4385.456435267537;4387.166438376698;4388.05656783793;4392.736435813861;5062.316587260746;5085.949140508547;5086.898262034739;5086.9745651271505;5089.8218562350885;4348.934765978513;4352.65067828974;4369.4101296325225;4376.606105450461;4380.6057454829215;2289.3933107726466;2406.9157579484636;2438.8292819502817;2457.2751038443344;2465.815063870217;4310.396544483712;4325.870223893288;4328.749050493726;4335.241332766753;4343.58833379153;4610.608098311681;4619.050711641566;4619.8450387403145;4626.375439315717;4697.718136911796;4153.752628484268;4163.354680024601;4261.659067274672;4271.935920961126;4324.308110702273;4511.819527219006;4516.9322460163785;4517.841875534472;4547.340635607752;4553.385292985405;4459.554044595515;4465.240909045535;4480.775961201992;4492.146492166478;4499.460064792246;4302.22559939214;4304;4332.978335108304;4503.752293623882;4886.144924638103;3682.6133256008807;3690.3910854709748;3691.224842782938;3692.4830523726164;3708.351038568294;5149.665271757323;5222.294990176278;5249.448807875118;5254.028004819121;5258.605604579749;4836.3072924476155;4852.514748525119;5010.87472813168;5044.344235249394;5050.166722490748;5027.974860125676;5040.873978150576;5044.672096313728;5050;5055.241713742821;4850.975745121252;4972.453030166606;4973.701577905337;4985.152524070844;4985.675931064469;5042.319286896369;5059.721715305694;5071.808125090527;5075.92386114216;5077;4935;4940.308356829974;4947.406311242674;4956.157453232887;4968.55283024515 19:01:18 INFO - PROCESS | 3295 | |5;yui.html;645.464264660332;675.1122274209414;695.7321431248968;734.3831181807284;751.556581616846;12621.305828179446;13752.724945501084;13774.931125344372;13828.585142445718;13868.86131138687;12213.328266945315;12348.691282717937;12356.62930112098;12376.443060062296;12428.440720167591;609.9420555047286;731.1847290271336;748.3938010211746;753.4763339479064;766.1572270502459;763.2825144364308;774.8256642255476;775.0312109862672;775.8719811231168;777.4363586399843;818.100089901107;818.1409520004001;818.9181081891822;819.0049090355217;819.1562690428868;645.6094063091808;749.1796482851269;771.8301973565863;810.2424233341815;814.0028465130227;584.8888711144868;584.95612829038;584.9795257166021;585.2333443189435;585.5520526797004;12970.610881673565;12974.156679815782;12984.091113622051;12989.285589292585;13016.349182540835;12276.140670153092;12750.744985100338;12900.741985160337;13224.471021159143;13378.264195469244;11685.591004314892;11787.469563869647;11932.821007684892;12134.332611706353;12558.183718058297;4058.898527536806;4064.878053658395;4097.7541347519245;4243.511996120424;4257.76582287974;61157;62436;63165;64514;64986.67506662436;10031.598736050622;10193.796124077626;10386.272960892817;10465.215108866865;10465.319754215952;18614.627707446045;18723.25106995705;18729.625407492047;18823.3411830588;18836.340728074727;6998.16022077354;7026.824329391806;7044.788656340318;7061.540999834994;7290.3438690518105;3725.0951421535274;3729.627037296303;3748.156664750409;3796.8101594920695;3863.7681739095183;2835.333696581302;2939.5590661400533;2946.175070980129;3233.5958005249345;3390.69483746464;563.650536667269;568.8947544704243;584.52653350786;584.5966283264534;596.0165726551138;603.4026313949247;609.5458883132095;626.4988009592308;626.5582764151262;635.196476457282;8663.436876603128;8663.523506207093;8664.8700269496;8868.215814901605;9047.502387368626;553.8492024175234;571.5856004396779;597.8624916269282;609.9939000610026;639.48840927258;3953.189596132786;3973.4635824163947;4151.813168407429;4253.128108737701;4281.571842815756;74017.62991185009;104066;108529.45735271274;114308;114492;89038;92805;93404.53297733467;94887.52556237175;95800.52099739456;78708;80299.59850200711;80609.59695201604;81578;82537.17462825295;7334.889976650461;7597.734079307198;7811.062672479339;7857.646405911633;7945.443818932619;47819.28271075867;47871.5212847881;50275.74862125666;50609;50839;68515.65742171257;71649.641751793;72062;72167.63916180596;75355.24644753702;10092.889782123813;10144.087032167141;10176.542055607664;10674.46627668629;10739.248252622241;11534.250273732516;11794.99742521861;11885.524579017088;11999.40002999864;12928.676783080498;10390.272680912263;10953.616623418142;10976.890231097586;11235.325880447199;11620.6513804586;10392.636257730943;11084.778304434027;11172;11198.21612487118;11303.095752339628;9945.502724863583;10620.787584248426;10910.345379277269;11152;11237.3257604544;9019.909800901907;9458.527073646152;9532.189763870148;9738.318317717692;9884.308098433328;9146.484015539474;9197.21823644971;9397.483138427178;9398.577064031999;9477.715668529956 19:01:18 INFO - PROCESS | 3295 | __end_tp_report 19:01:18 INFO - PROCESS | 3295 | __start_cc_report 19:01:18 INFO - PROCESS | 3295 | _x_x_mozilla_cycle_collect,974 19:01:18 INFO - PROCESS | 3295 | __end_cc_report 19:01:18 INFO - PROCESS | 3295 | __startTimestamp1471053678146__endTimestamp 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - PROCESS | 3295 | ------- Summary: start ------- 19:01:18 INFO - PROCESS | 3295 | Number of tests: 6 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - PROCESS | 3295 | [#0] dojo.html Cycles:165 Average:4719.64 Median:4817.90 stddev:496.57 (10.3%) stddev-sans-first:470.71 19:01:18 INFO - PROCESS | 3295 | Values: 2646.6 2651.3 2666.9 2686.2 2688.7 4669.9 4707.8 4747.9 4757.3 4764.6 4757.4 4783.3 4814.2 4816.7 4836.0 4348.3 4364.6 4453.3 4490.3 4510.1 4495.7 4521.0 4530.2 4540.1 4553.1 4301.4 4335.1 4346.9 4360.1 4404.5 4352.2 4354.0 4356.0 4360.1 4459.5 4396.3 4401.6 4411.0 4431.7 4440.8 4670.9 4706.0 4791.3 4795.0 4847.2 5094.3 5146.6 5186.4 5186.5 5190.4 5159.8 5181.8 5193.6 5210.5 5214.9 4695.4 4704.4 4719.2 4809.2 4817.9 5213.0 5216.5 5243.0 5244.6 5260.1 4999.9 4999.9 5032.3 5039.1 5043.3 5022.8 5069.7 5084.1 5106.6 5113.6 5123.4 5133.2 5134.9 5150.1 5154.4 5091.2 5109.1 5111.7 5115.8 5165.8 4212.0 4246.8 4252.9 4360.3 4414.9 3735.6 3739.1 3782.1 3815.0 3837.4 4351.3 4354.2 4355.4 4362.8 4404.6 4970.4 5006.4 5079.0 5090.2 5124.3 4326.3 4356.0 4367.8 4374.5 4429.7 4585.7 4596.3 4597.1 4601.6 4630.3 4646.0 4804.8 4808.9 4809.2 4834.8 5035.2 5123.3 5127.7 5193.1 5210.1 4790.4 4792.0 4793.3 4825.6 4828.8 5024.3 5029.0 5029.5 5030.5 5032.4 4925.8 5023.0 5067.3 5096.4 5135.6 4997.1 4997.2 4997.6 5001.9 5030.1 4997.4 4999.8 5004.6 5010.7 5043.7 4708.2 4803.3 4826.5 4835.1 4958.1 5002.8 5004.5 5009.7 5010.4 5024.9 4709.1 4886.1 4888.1 4897.6 4906.5 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - PROCESS | 3295 | [#1] ext.html Cycles:180 Average:47322.80 Median:363801.00 stddev:88615.85 (24.4%) stddev-sans-first:88794.85 19:01:18 INFO - PROCESS | 3295 | Values: 549.7 561.5 563.0 567.6 568.3 28023.3 38053.4 39606.8 39726.2 43144.4 39264.2 39880.0 39895.4 39924.8 39980.8 7195.2 7614.1 7743.9 7754.3 7759.7 7211.1 7431.9 7434.6 9936.2 10262.1 9746.0 9783.5 9801.5 9804.7 9807.7 9907.8 9969.1 9984.0 9986.6 9992.1 9816.1 9904.4 9904.5 9907.6 9913.7 36039.8 36461.5 36510.5 36555.1 36645.5 2978.0 3043.3 3203.8 3490.1 3650.1 29024.1 29163.7 29306.7 29593.3 30701.4 3498.5 3541.2 3567.6 3621.7 3683.2 248536.0 248651.0 249696.0 250017.0 251052.0 30835.2 49428.0 49635.5 49751.3 49925.5 31639.8 33236.5 33508.0 33526.5 33544.8 12174.7 12323.3 12328.4 12334.9 12347.4 9697.0 9800.3 9805.1 9807.1 9812.0 7155.7 7493.2 7748.4 7838.4 7983.8 3032.9 3079.1 3105.4 3108.7 3123.3 842.8 861.9 906.5 914.7 957.2 26346.9 27700.2 28094.9 28290.2 28348.9 925.6 926.4 947.3 1014.4 1016.9 14134.4 14798.0 14996.6 15123.2 15308.8 360903.0 362568.0 363576.0 364026.0 365480.0 49362.8 49768.3 49913.0 49918.0 49985.3 1064.6 1139.1 1164.8 1178.6 1181.9 27035.5 28384.3 29120.4 29402.6 29665.7 40606.2 49616.0 51524.7 51656.2 51686.0 375665.0 379089.0 379880.0 380259.0 380685.0 24474.4 25065.0 25237.1 26408.5 38025.6 39766.6 39859.0 40072.2 40119.0 40227.2 35005.0 35046.0 35100.3 35371.5 35601.5 35380.3 35446.5 35667.5 35713.3 35876.6 34346.7 34637.7 34707.8 35053.0 35544.8 30138.8 31689.7 32203.7 32333.0 32500.5 24316.3 24520.6 24541.5 24927.0 24977.5 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - PROCESS | 3295 | [#2] jquery.html Cycles:180 Average:23723.26 Median:19621.43 stddev:62049.49 (316.2%) stddev-sans-first:62198.74 19:01:18 INFO - PROCESS | 3295 | Values: 352.8 369.2 369.5 371.2 391.6 12531.5 14232.7 16328.9 16414.3 16579.2 16294.4 16840.2 16992.5 17186.1 17211.6 5001.2 5179.0 5196.3 5199.1 5205.0 3715.9 4903.1 5106.2 5186.2 5187.7 6065.2 6159.3 6167.6 6169.7 6169.9 5957.4 5996.6 6138.2 6164.6 6167.2 5374.9 5381.9 5389.3 5391.1 5392.0 19978.6 20758.6 20898.3 20933.4 21011.5 1983.5 2034.3 2073.7 2305.3 2308.4 13112.0 13158.3 13415.7 13873.4 14080.4 1789.3 1798.6 1802.7 1810.8 1829.0 63788.0 64667.4 64920.0 65071.3 65076.7 9379.3 9600.3 9932.6 9962.5 9994.9 13293.1 13300.4 13301.0 13308.1 13334.9 6868.1 6918.4 7153.9 7180.9 7197.2 6474.3 6476.5 6617.4 6666.8 6700.7 5957.2 6037.8 6074.0 6077.2 6146.9 1712.6 1777.3 1809.7 1820.0 1834.9 347.9 379.2 380.9 398.5 830.7 9988.2 10212.6 10637.1 10728.4 11142.9 388.9 397.7 401.5 409.5 417.6 5607.3 5738.8 5758.1 5799.3 5946.2 371578.0 373915.0 375180.0 377723.0 379387.0 70727.6 72457.0 73948.0 80231.6 81027.6 898.4 913.9 945.0 945.8 947.3 15733.8 15868.9 16440.7 16535.2 16835.2 38069.1 38086.0 38460.0 38573.0 38854.0 49825.3 50564.0 54183.5 54889.4 55737.4 7648.2 7671.3 7710.4 7858.2 7959.0 9434.5 11593.6 12194.5 12290.5 12860.5 10167.4 10519.4 10638.6 10829.8 11138.6 10020.2 10061.2 10484.8 10805.6 10887.6 10562.1 10732.2 10974.6 11105.9 11417.8 6530.3 6822.2 6931.2 7497.3 8087.0 10363.9 10624.3 10648.5 10826.8 11079.0 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - PROCESS | 3295 | [#3] mootools.html Cycles:180 Average:5128.57 Median:5308.00 stddev:2866.05 (54.0%) stddev-sans-first:2859.72 19:01:18 INFO - PROCESS | 3295 | Values: 1309.9 1381.8 1427.8 1657.9 1692.9 7368.1 7956.0 7978.5 7987.1 7987.9 6713.7 7446.1 7675.9 7682.3 7869.9 2196.1 2206.3 2221.3 2224.5 2225.3 2066.8 2080.7 2159.7 2178.6 2180.6 1880.6 2119.9 2166.5 2177.7 2201.8 2191.8 2199.5 2209.6 2211.1 2214.4 2133.1 2136.5 2140.7 2198.8 2233.7 7826.1 7913.2 7914.5 7935.4 7941.4 4305.8 4413.3 4413.5 4417.2 4420.8 2783.6 2927.2 2935.1 2960.6 2963.0 937.2 983.8 987.1 988.0 1012.0 10478.2 10479.6 10481.6 10499.4 10507.2 3888.5 4080.0 4196.0 4240.3 4315.4 8894.8 8899.0 8900.9 8903.7 8912.4 4485.2 4510.3 4517.3 4521.7 4523.0 4019.5 4069.4 4085.2 4086.7 4088.3 3566.3 3664.4 3710.9 3721.3 3744.9 1175.9 1196.1 1199.3 1206.7 1209.3 664.6 732.2 734.5 849.5 858.0 5174.5 5206.2 5302.5 5381.2 5392.8 818.1 828.6 851.7 867.8 887.6 3469.9 3499.4 3533.9 3579.3 3651.8 8972.5 9180.1 9387.5 9415.4 9770.1 9719.1 9758.2 9777.5 9782.4 9804.7 7106.3 7132.3 7361.4 7434.5 7442.3 5276.8 5287.2 5313.5 5433.8 5593.8 8917.9 8921.2 8941.2 8947.9 9019.1 8558.2 8812.5 8935.7 8941.6 9301.9 6811.1 6932.9 6986.9 7007.0 7009.5 7630.7 7704.5 7705.1 7705.2 7705.8 6962.9 7017.6 7048.4 7057.1 7196.3 6208.6 6285.2 6316.6 6318.3 6336.8 6364.2 6453.0 6498.9 6516.9 6573.5 4539.0 4633.2 4636.7 4690.6 4712.2 4483.0 4484.4 4522.9 4535.5 4773.1 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - PROCESS | 3295 | [#4] prototype.html Cycles:180 Average:4061.72 Median:4387.61 stddev:1170.48 (26.7%) stddev-sans-first:1138.91 19:01:18 INFO - PROCESS | 3295 | Values: 284.7 290.0 297.4 302.3 303.3 4660.8 4762.5 4789.2 4816.7 4828.3 4616.7 4745.4 4767.5 4774.9 4780.5 3781.3 3788.4 3789.7 3798.9 3806.6 3567.4 3607.9 3786.1 3788.9 3814.4 3746.6 3751.2 3761.2 3771.7 3779.2 3763.2 3767.0 3769.8 3774.5 3782.8 3345.9 3346.6 3347.3 3354.4 3374.2 4538.0 4612.6 4628.8 4653.3 4686.9 4232.6 4234.6 4236.6 4239.6 4241.6 4927.5 4931.0 4971.5 4976.2 4978.9 3416.7 3563.6 3594.9 3595.3 3611.7 5148.8 5178.4 5179.6 5186.7 5188.3 3650.0 3650.5 3651.5 3671.7 3673.7 3724.9 3748.5 3761.1 3761.3 3770.8 3988.3 4141.4 4167.7 4188.2 4282.8 4991.3 5001.8 5014.2 5023.2 5046.7 1208.2 1209.8 1248.6 1253.1 1268.3 835.8 838.5 852.7 854.4 860.3 4359.4 4385.5 4387.2 4388.1 4392.7 5062.3 5085.9 5086.9 5087.0 5089.8 4348.9 4352.7 4369.4 4376.6 4380.6 2289.4 2406.9 2438.8 2457.3 2465.8 4310.4 4325.9 4328.7 4335.2 4343.6 4610.6 4619.1 4619.8 4626.4 4697.7 4153.8 4163.4 4261.7 4271.9 4324.3 4511.8 4516.9 4517.8 4547.3 4553.4 4459.6 4465.2 4480.8 4492.1 4499.5 4302.2 4304.0 4333.0 4503.8 4886.1 3682.6 3690.4 3691.2 3692.5 3708.4 5149.7 5222.3 5249.4 5254.0 5258.6 4836.3 4852.5 5010.9 5044.3 5050.2 5028.0 5040.9 5044.7 5050.0 5055.2 4851.0 4972.5 4973.7 4985.2 4985.7 5042.3 5059.7 5071.8 5075.9 5077.0 4935.0 4940.3 4947.4 4956.2 4968.6 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - PROCESS | 3295 | [#5] yui.html Cycles:180 Average:18708.34 Median:570.24 stddev:27670.95 (4852.5%) stddev-sans-first:27715.33 19:01:18 INFO - PROCESS | 3295 | Values: 645.5 675.1 695.7 734.4 751.6 12621.3 13752.7 13774.9 13828.6 13868.9 12213.3 12348.7 12356.6 12376.4 12428.4 609.9 731.2 748.4 753.5 766.2 763.3 774.8 775.0 775.9 777.4 818.1 818.1 818.9 819.0 819.2 645.6 749.2 771.8 810.2 814.0 584.9 585.0 585.0 585.2 585.6 12970.6 12974.2 12984.1 12989.3 13016.3 12276.1 12750.7 12900.7 13224.5 13378.3 11685.6 11787.5 11932.8 12134.3 12558.2 4058.9 4064.9 4097.8 4243.5 4257.8 61157.0 62436.0 63165.0 64514.0 64986.7 10031.6 10193.8 10386.3 10465.2 10465.3 18614.6 18723.3 18729.6 18823.3 18836.3 6998.2 7026.8 7044.8 7061.5 7290.3 3725.1 3729.6 3748.2 3796.8 3863.8 2835.3 2939.6 2946.2 3233.6 3390.7 563.7 568.9 584.5 584.6 596.0 603.4 609.5 626.5 626.6 635.2 8663.4 8663.5 8664.9 8868.2 9047.5 553.8 571.6 597.9 610.0 639.5 3953.2 3973.5 4151.8 4253.1 4281.6 74017.6 104066.0 108529.5 114308.0 114492.0 89038.0 92805.0 93404.5 94887.5 95800.5 78708.0 80299.6 80609.6 81578.0 82537.2 7334.9 7597.7 7811.1 7857.6 7945.4 47819.3 47871.5 50275.7 50609.0 50839.0 68515.7 71649.6 72062.0 72167.6 75355.2 10092.9 10144.1 10176.5 10674.5 10739.2 11534.3 11795.0 11885.5 11999.4 12928.7 10390.3 10953.6 10976.9 11235.3 11620.7 10392.6 11084.8 11172.0 11198.2 11303.1 9945.5 10620.8 10910.3 11152.0 11237.3 9019.9 9458.5 9532.2 9738.3 9884.3 9146.5 9197.2 9397.5 9398.6 9477.7 19:01:18 INFO - PROCESS | 3295 | -------- Summary: end -------- 19:01:18 INFO - PROCESS | 3295 | 19:01:18 INFO - TEST-INFO | 3295: exit 0 19:01:18 INFO - TEST-OK | dromaeo_css | took 1120718ms 19:01:18 INFO - TEST-START | kraken 19:01:19 INFO - operating with platform_type : linux_ 19:01:19 INFO - Initialising browser for kraken test... 19:01:19 INFO - TEST-INFO | started process 3595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl1FOAi/profile http://localhost:52041/getInfo.html) 19:01:23 INFO - PROCESS | 3595 | __metrics Screen width/height:1600/1200 19:01:23 INFO - PROCESS | 3595 | colorDepth:24 19:01:23 INFO - PROCESS | 3595 | Browser inner width/height: 1024/697 19:01:23 INFO - PROCESS | 3595 | __metrics 19:01:24 INFO - PROCESS | 3595 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:01:24 INFO - PROCESS | 3595 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:01:25 INFO - TEST-INFO | 3595: exit 0 19:01:25 INFO - Browser initialized. 19:01:25 INFO - Running cycle 1/1 for kraken test... 19:01:25 INFO - TEST-INFO | started process 3676 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl1FOAi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:37 INFO - PROCESS | 3676 | RSS: Main: 157319168 19:01:37 INFO - PROCESS | 3676 | 19:01:39 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52041/tests/kraken/driver.html?testName=audio-beat-detection) 19:01:40 INFO - PROCESS | 3676 | RSS: Main: 204574720 19:01:40 INFO - PROCESS | 3676 | 19:01:43 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52041/tests/kraken/driver.html?testName=audio-dft) 19:01:44 INFO - PROCESS | 3676 | RSS: Main: 195588096 19:01:44 INFO - PROCESS | 3676 | 19:01:47 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52041/tests/kraken/driver.html?testName=audio-fft) 19:01:47 INFO - PROCESS | 3676 | RSS: Main: 277954560 19:01:47 INFO - PROCESS | 3676 | 19:01:50 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52041/tests/kraken/driver.html?testName=audio-oscillator) 19:01:50 INFO - PROCESS | 3676 | RSS: Main: 186810368 19:01:50 INFO - PROCESS | 3676 | 19:01:53 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52041/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:01:53 INFO - PROCESS | 3676 | RSS: Main: 191184896 19:01:53 INFO - PROCESS | 3676 | 19:01:56 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52041/tests/kraken/driver.html?testName=imaging-darkroom) 19:01:57 INFO - PROCESS | 3676 | RSS: Main: 227958784 19:01:57 INFO - PROCESS | 3676 | 19:02:00 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52041/tests/kraken/driver.html?testName=imaging-desaturate) 19:02:01 INFO - PROCESS | 3676 | RSS: Main: 221396992 19:02:01 INFO - PROCESS | 3676 | 19:02:04 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52041/tests/kraken/driver.html?testName=json-parse-financial) 19:02:05 INFO - PROCESS | 3676 | RSS: Main: 225501184 19:02:05 INFO - PROCESS | 3676 | 19:02:06 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52041/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:02:07 INFO - PROCESS | 3676 | RSS: Main: 188592128 19:02:07 INFO - PROCESS | 3676 | 19:02:09 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52041/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:02:09 INFO - PROCESS | 3676 | RSS: Main: 196595712 19:02:09 INFO - PROCESS | 3676 | 19:02:12 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52041/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:02:12 INFO - PROCESS | 3676 | RSS: Main: 193032192 19:02:12 INFO - PROCESS | 3676 | 19:02:15 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52041/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:02:16 INFO - PROCESS | 3676 | RSS: Main: 188653568 19:02:16 INFO - PROCESS | 3676 | 19:02:19 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52041/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:02:19 INFO - PROCESS | 3676 | RSS: Main: 192049152 19:02:19 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | Cycle 1(1): loaded http://localhost:52041/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52041/tests/kraken/driver.html?testName=ai-astar) 19:02:21 INFO - PROCESS | 3676 | RSS: Main: 242225152 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | __start_tp_report 19:02:21 INFO - PROCESS | 3676 | _x_x_mozilla_page_load 19:02:21 INFO - PROCESS | 3676 | _x_x_mozilla_page_load_details 19:02:21 INFO - PROCESS | 3676 | |i|pagename|runs| 19:02:21 INFO - PROCESS | 3676 | |0;ai-astar;105;116;114;115;108;113;114;115;114;114 19:02:21 INFO - PROCESS | 3676 | |1;audio-beat-detection;150;129;122;179;124;123;144;122;173;121 19:02:21 INFO - PROCESS | 3676 | |2;audio-dft;161;161;162;163;160;161;162;162;160;160 19:02:21 INFO - PROCESS | 3676 | |3;audio-fft;75;76;75;111;77;77;75;75;98;75 19:02:21 INFO - PROCESS | 3676 | |4;audio-oscillator;86;85;134;87;86;85;114;86;86;126 19:02:21 INFO - PROCESS | 3676 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;117;117;117 19:02:21 INFO - PROCESS | 3676 | |6;imaging-darkroom;146;147;146;146;147;147;146;146;146;146 19:02:21 INFO - PROCESS | 3676 | |7;imaging-desaturate;95;95;97;96;95;95;95;95;95;94 19:02:21 INFO - PROCESS | 3676 | |8;json-parse-financial;64;62;62;62;63;64;64;61;64;74 19:02:21 INFO - PROCESS | 3676 | |9;json-stringify-tinderbox;65;66;66;67;66;65;65;66;67;65 19:02:21 INFO - PROCESS | 3676 | |10;stanford-crypto-aes;93;83;89;83;83;83;86;83;83;82 19:02:21 INFO - PROCESS | 3676 | |11;stanford-crypto-ccm;135;155;132;146;134;141;143;135;148;136 19:02:21 INFO - PROCESS | 3676 | |12;stanford-crypto-pbkdf2;175;176;176;174;175;174;175;175;176;175 19:02:21 INFO - PROCESS | 3676 | |13;stanford-crypto-sha256-iterative;69;70;70;68;68;67;70;68;69;67 19:02:21 INFO - PROCESS | 3676 | __end_tp_report 19:02:21 INFO - PROCESS | 3676 | __start_cc_report 19:02:21 INFO - PROCESS | 3676 | _x_x_mozilla_cycle_collect,1978 19:02:21 INFO - PROCESS | 3676 | __end_cc_report 19:02:21 INFO - PROCESS | 3676 | __startTimestamp1471053741851__endTimestamp 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | ------- Summary: start ------- 19:02:21 INFO - PROCESS | 3676 | Number of tests: 14 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.49 (3.1%) stddev-sans-first:2.29 19:02:21 INFO - PROCESS | 3676 | Values: 105.0 116.0 114.0 115.0 108.0 113.0 114.0 115.0 114.0 114.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:136.50 stddev:22.07 (16.2%) stddev-sans-first:23.03 19:02:21 INFO - PROCESS | 3676 | Values: 150.0 129.0 122.0 179.0 124.0 123.0 144.0 122.0 173.0 121.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.03 (0.6%) stddev-sans-first:1.09 19:02:21 INFO - PROCESS | 3676 | Values: 161.0 161.0 162.0 163.0 160.0 161.0 162.0 162.0 160.0 160.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.50 stddev:12.58 (16.7%) stddev-sans-first:13.13 19:02:21 INFO - PROCESS | 3676 | Values: 75.0 76.0 75.0 111.0 77.0 77.0 75.0 75.0 98.0 75.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:86.00 stddev:19.35 (22.5%) stddev-sans-first:20.07 19:02:21 INFO - PROCESS | 3676 | Values: 86.0 85.0 134.0 87.0 86.0 85.0 114.0 86.0 86.0 126.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:02:21 INFO - PROCESS | 3676 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 19:02:21 INFO - PROCESS | 3676 | Values: 146.0 147.0 146.0 146.0 147.0 147.0 146.0 146.0 146.0 146.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 19:02:21 INFO - PROCESS | 3676 | Values: 95.0 95.0 97.0 96.0 95.0 95.0 95.0 95.0 95.0 94.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:3.68 (5.8%) stddev-sans-first:3.91 19:02:21 INFO - PROCESS | 3676 | Values: 64.0 62.0 62.0 62.0 63.0 64.0 64.0 61.0 64.0 74.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 19:02:21 INFO - PROCESS | 3676 | Values: 65.0 66.0 66.0 67.0 66.0 65.0 65.0 66.0 67.0 65.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:83.00 stddev:3.55 (4.3%) stddev-sans-first:2.20 19:02:21 INFO - PROCESS | 3676 | Values: 93.0 83.0 89.0 83.0 83.0 83.0 86.0 83.0 83.0 82.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:142.00 stddev:7.44 (5.2%) stddev-sans-first:7.62 19:02:21 INFO - PROCESS | 3676 | Values: 135.0 155.0 132.0 146.0 134.0 141.0 143.0 135.0 148.0 136.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:175.00 stddev:0.74 (0.4%) stddev-sans-first:0.78 19:02:21 INFO - PROCESS | 3676 | Values: 175.0 176.0 176.0 174.0 175.0 174.0 175.0 175.0 176.0 175.0 19:02:21 INFO - PROCESS | 3676 | 19:02:21 INFO - PROCESS | 3676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.17 (1.7%) stddev-sans-first:1.24 19:02:21 INFO - PROCESS | 3676 | Values: 69.0 70.0 70.0 68.0 68.0 67.0 70.0 68.0 69.0 67.0 19:02:21 INFO - PROCESS | 3676 | -------- Summary: end -------- 19:02:21 INFO - PROCESS | 3676 | 19:02:22 INFO - TEST-INFO | 3676: exit 0 19:02:23 INFO - TEST-OK | kraken | took 64003ms 19:02:23 INFO - SUITE-END | took 1184s 19:02:23 INFO - Completed test suite (00:19:44) 19:02:23 INFO - javascript benchmark 19:02:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2646.642703235063, 2651.2709005023603, 2666.9199924002273, 2686.2344231893903, 2688.704242533321, 4669.906601867969, 4707.76461176941, 4747.8813029674275, 4757.286407038946, 4764.618830493561, 4757.405324334458, 4783.258594917794, 4814.157522433569, 4816.710997340162, 4835.951640483594, 4348.282533381988, 4364.629006534448, 4453.309736990772, 4490.259107247301, 4510.0979804039325, 4495.730256184623, 4520.97739511302, 4530.184566777979, 4540.091981603676, 4553.066621342617, 4301.397804307399, 4335.111302183045, 4346.913061738779, 4360.127974405132, 4404.537523560044, 4352.194843953878, 4354.0, 4355.978220108911, 4360.084382279725, 4459.487158976694, 4396.340548917656, 4401.603855652975, 4411.007523307262, 4431.7119387240045, 4440.844570440019, 4670.929936050962, 4706.011737535087, 4791.2813078038225, 4795.0, 4847.248676455155, 5094.337736094284, 5146.588272938156, 5186.429492755794, 5186.507281808223, 5190.429052804188, 5159.845204643867, 5181.818636347747, 5193.584513238932, 5210.531052205281, 5214.921776173361, 4695.366125573072, 4704.411948506437, 4719.197736384823, 4809.2305231162845, 4817.903641927177, 5212.973935130376, 5216.4522725114075, 5243.0, 5244.606654500928, 5260.132078207053, 4999.875003124951, 4999.925001124986, 5032.270320803463, 5039.0929632666475, 5043.268726034705, 5022.773975171126, 5069.721165335942, 5084.059449001947, 5106.642535022587, 5113.565346945551, 5123.359580052494, 5133.230015497706, 5134.922976155361, 5150.072986862401, 5154.355705536808, 5091.236314552847, 5109.080365534165, 5111.718855462986, 5115.846524604268, 5165.845024649267, 4211.978940105279, 4246.787660617018, 4252.914941701149, 4360.280553708666, 4414.940414300545, 3735.6451137141926, 3739.121306492972, 3782.149016471272, 3815.0271680721794, 3837.3668344723364, 4351.282038463618, 4354.238008348526, 4355.390245365651, 4362.825486980485, 4404.559544045634, 4970.378702662168, 5006.3992320921725, 5079.034983353225, 5090.160123579568, 5124.308218390544, 4326.2645350289895, 4355.9981204323185, 4367.803448844809, 4374.518802931675, 4429.689921705514, 4585.70192937458, 4596.287575425815, 4597.057603191338, 4601.631869450436, 4630.282306242538, 4646.024334889711, 4804.807807687654, 4808.927866081942, 4809.206480930678, 4834.830780922651, 5035.16919708244, 5123.257127716386, 5127.743612819419, 5193.0652482552, 5210.1142805723875, 4790.377250957354, 4792.041591681747, 4793.304970779287, 4825.5898248649255, 4828.782704778223, 5024.346834911438, 5029.044481548494, 5029.4719054498755, 5030.547250747451, 5032.421271553818, 4925.778339974781, 5023.0, 5067.2652465391575, 5096.413912399973, 5135.6405051646025, 4997.125503036881, 4997.200447928314, 4997.550220480174, 5001.8749531260555, 5030.0694371541385, 4997.425296090996, 4999.825006124768, 5004.624653150955, 5010.674306170087, 5043.747812609281, 4708.246680531099, 4803.279508073817, 4826.541478559531, 4835.105505481497, 4958.082754690394, 5002.849914502571, 5004.549590536869, 5009.699418034929, 5010.398752149766, 5024.924626130538, 4709.0817290628, 4886.096072226649, 4888.071266459361, 4897.608191344612, 4906.484819094087], "value": 4687.838045169736, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.6921723834652, 561.4722161168504, 563.0, 567.6452217364148, 568.2697733412566, 28023.299417514547, 38053.42919856201, 39606.80196599013, 39726.2054758905, 43144.352834707504, 39264.21471570574, 39880.0, 39895.401568976486, 39924.80037599815, 39980.800095999555, 7195.244499327574, 7614.08630964285, 7743.9225607743765, 7754.340881025119, 7759.728409505668, 7211.06256186695, 7431.925680743205, 7434.628268586576, 9936.155426788731, 10262.076413122817, 9746.0, 9783.510824458785, 9801.460919649417, 9804.656837010705, 9807.65673201438, 9907.752306192331, 9969.102780749767, 9984.0, 9986.550605222783, 9992.050755178247, 9816.067473590068, 9904.356216845954, 9904.504774761304, 9907.554160062742, 9913.653022144263, 36039.81980090083, 36461.45307820385, 36510.45234321487, 36555.08612284688, 36645.450318245246, 2978.0321395546534, 3043.269615292336, 3203.7917535360125, 3490.110021944388, 3650.0509867434394, 29024.129276121537, 29163.70836291631, 29306.70693293061, 29593.260168495744, 30701.38597228043, 3498.510208570802, 3541.2386336937684, 3567.571891373052, 3621.6559426854406, 3683.1528748388023, 248536.0, 248651.0, 249696.0, 250017.0, 251052.0, 30835.22911927175, 49428.0, 49635.503644963086, 49751.25373119406, 49925.50074499209, 31639.84180079131, 33236.50145247823, 33508.0, 33526.49710254348, 33544.83227583847, 12174.695632609079, 12323.260604363766, 12328.383580820922, 12334.938325308316, 12347.3826308686, 9697.0, 9800.264980126376, 9805.117539421486, 9807.06832850878, 9811.950940245253, 7155.713771449084, 7493.175750667422, 7748.380129589732, 7838.372930165573, 7983.800404989922, 3032.8938487152845, 3079.1070589529236, 3105.4099721052926, 3108.7046730561015, 3123.344097739455, 842.8440738463404, 861.9008813986471, 906.4878343735768, 914.6752902719572, 957.2055194188806, 26346.868265658548, 27700.168994930184, 28094.85952570265, 28290.151295461168, 28348.858255709, 925.6482536636036, 926.3700683535126, 947.3415975896824, 1014.4268488304084, 1016.8983101689772, 14134.36395362211, 14798.0, 14996.550103496913, 15123.16822574769, 15308.770368444482, 360903.0, 362568.0, 363576.0, 364026.0, 365480.0, 49362.75318623384, 49768.25347619789, 49913.0, 49918.0, 49985.25022124671, 1064.5635289531256, 1139.0602752729033, 1164.831099490586, 1178.63462326679, 1181.9054475641929, 27035.45929081368, 28384.290392740346, 29120.371064561707, 29402.55896161602, 29665.703342967157, 40606.18787624172, 49616.0, 51524.742376287875, 51656.22515662193, 51686.0, 375665.0, 379089.0, 379880.0, 380259.0, 380685.0, 24474.388140296636, 25065.0, 25237.116700916114, 26408.471830563667, 38025.619743802206, 39766.60233397745, 39859.0, 40072.19855602813, 40119.0, 40227.1954560913, 35005.0, 35046.0, 35100.297994040484, 35371.46942795911, 35601.46597801087, 35380.292394152486, 35446.46830297496, 35667.46498802468, 35713.28573428465, 35876.64123358733, 34346.65653343534, 34637.65362346344, 34707.82646086753, 35053.0, 35544.82227588949, 30138.84930575333, 31689.683103168674, 32203.677963220067, 32333.0, 32500.512492313108, 24316.27051188467, 24520.632190517514, 24541.50916981686, 24927.0, 24977.500449990534], "value": 16717.638325892683, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.8359312919495, 369.23199744531377, 369.4624321612054, 371.21858487883003, 391.6494737210197, 12531.498740050398, 14232.71534569308, 16328.918355408236, 16414.343426262963, 16579.17104144791, 16294.356108509077, 16840.157992100376, 16992.490225293262, 17186.14069296533, 17211.569710757238, 5001.249812528123, 5179.0, 5196.298499702549, 5199.14214154664, 5205.0, 3715.925681486382, 4903.141950158726, 5106.234064890259, 5186.247994040862, 5187.714675692835, 6065.211522502091, 6159.3224745277985, 6167.62994220346, 6169.722362493676, 6169.938300616981, 5957.404259574051, 5996.640201587918, 6138.17134686816, 6164.59930104544, 6167.229096362977, 5374.8925021499745, 5381.865453363697, 5389.272448219479, 5391.083515802323, 5392.0, 19978.600427991358, 20758.5848283035, 20898.268560600307, 20933.37199884006, 21011.474713132142, 1983.464464594566, 2034.340618605622, 2073.709680644711, 2305.2853615379286, 2308.3998160478395, 13112.0, 13158.342082895817, 13415.731685366238, 13873.375698093509, 14080.366383512664, 1789.2574581548643, 1798.5953160538904, 1802.6935420978334, 1810.7555480010162, 1829.0108339832393, 63788.0, 64667.353326466604, 64920.0, 65071.349286507, 65076.67461662709, 9379.296552758571, 9600.32797704154, 9932.602695892228, 9962.501874906226, 9994.850077248848, 13293.13594616347, 13300.401481933337, 13301.0, 13308.134971226838, 13334.866651333363, 6868.141482314711, 6918.411934985583, 7153.856922861513, 7180.92819071813, 7197.172325182566, 6474.255460621995, 6476.481881449567, 6617.437517810944, 6666.83332916681, 6700.731970721214, 5957.225560677084, 6037.8188654339565, 6073.969630151821, 6077.20996270482, 6146.938530614726, 1712.571857035741, 1777.3423833181805, 1809.683305421546, 1819.972700409495, 1834.899080550556, 347.9043263102667, 379.1734019836727, 380.853371451993, 398.50187265917606, 830.667732906842, 9988.200943924468, 10212.6425575106, 10637.09584685295, 10728.356298621951, 11142.888571114347, 388.85612323440506, 397.6699339548168, 401.48409294056995, 409.4840500968741, 417.6387424877459, 5607.299087614048, 5738.799142030009, 5758.107493338539, 5799.2750906136735, 5946.205283516741, 371578.0, 373915.0, 375180.0, 377723.0, 379387.0, 70727.64636176787, 72457.0, 73948.0, 80231.59884200542, 81027.59486202532, 898.3935843305873, 913.9131782480521, 944.9716508504756, 945.7730144765206, 947.2895328503622, 15733.75188699022, 15868.92065539711, 16440.67118657596, 16535.173241338125, 16835.1582420876, 38069.09928961015, 38086.0, 38460.0, 38573.0, 38854.0, 49825.25262121144, 50564.0, 54183.45816541784, 54889.432186907725, 55737.442625573225, 7648.158702542604, 7671.2712292332135, 7710.4217183710325, 7858.174891636524, 7959.005124359455, 9434.48110353937, 11593.594224202112, 12194.451249693962, 12290.508379664714, 12860.485580577046, 10167.44079075658, 10519.368837869752, 10638.627648032281, 10829.834033193236, 11138.554457821596, 10020.248481364073, 10061.195104391485, 10484.790304194026, 10805.567777288821, 10887.618933337295, 10562.102221311274, 10732.248742588255, 10974.615888443866, 11105.944470277596, 11417.771644567229, 6530.281669016499, 6822.249552549315, 6931.202911665223, 7497.325240728361, 8086.989126359205, 10363.896361036293, 10624.256302058782, 10648.520816563429, 10826.783464330512, 11079.0], "value": 7881.363736061685, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.9410526526308, 1381.772007618743, 1427.785832125181, 1657.8507934285913, 1692.9238184281714, 7368.115826100869, 7956.045274567053, 7978.48139870908, 7987.08148562914, 7987.92012079879, 6713.697883595238, 7446.069241344832, 7675.884861727079, 7682.347000504964, 7869.92130078699, 2196.06667166454, 2206.3380985704307, 2221.31139346803, 2224.510607666311, 2225.2879078694823, 2066.7519897612233, 2080.708700781892, 2159.730033745782, 2178.6078505868936, 2180.647998241093, 1880.5862710203733, 2119.947001324964, 2166.5341951480427, 2177.6951226828255, 2201.8018378345946, 2191.7589065202815, 2199.472126689599, 2209.5801797658473, 2211.1266049910355, 2214.3910424633355, 2133.0507923973837, 2136.465883529118, 2140.657494800824, 2198.7801219878043, 2233.6761169630313, 7826.1391246962785, 7913.208679132098, 7914.456680947218, 7935.404844636675, 7941.404394670365, 4305.827766889352, 4413.271810151353, 4413.514513403523, 4417.182821178092, 4420.84527041552, 2783.6242107315456, 2927.151126173407, 2935.148806846012, 2960.585518027478, 2963.0443907021263, 937.2174234514189, 983.8130755156498, 987.1115995603942, 988.0, 1012.0, 10478.161747060292, 10479.58081676732, 10481.63314284004, 10499.370037797757, 10507.211959103022, 3888.51393575803, 4080.0, 4195.97902010488, 4240.342746874239, 4315.438992930962, 8894.777630559287, 8899.0, 8900.8664870027, 8903.688370906988, 8912.376133670581, 4485.170243504962, 4510.278355463176, 4517.34498497716, 4521.683482156218, 4523.0, 4019.477467929209, 4069.4302797608357, 4085.22380747657, 4086.67306615476, 4088.34586466164, 3566.32239874423, 3664.3770559005034, 3710.8515659373325, 3721.2557488501866, 3744.9438258426144, 1175.9294442333487, 1196.0790191552455, 1199.2910103103043, 1206.7043574324346, 1209.2563073709605, 664.6012392564519, 732.2311572848595, 734.4858598980628, 849.4818160921833, 857.9571021448903, 5174.456682048406, 5206.167013277857, 5302.522772950453, 5381.2197231402015, 5392.757325920343, 818.0878320672526, 828.5857071464268, 851.6933903794629, 867.8090820019586, 887.6050157679771, 3469.9479507807405, 3499.4051011328133, 3533.8763143290375, 3579.3378225028455, 3651.7626354286886, 8972.461652300883, 9180.127887850644, 9387.483688397067, 9415.387999780129, 9770.120689138012, 9719.076687714656, 9758.21890761144, 9777.506735782472, 9782.364146330467, 9804.705858824247, 7106.253843346478, 7132.251113633098, 7361.411087113018, 7434.479586429006, 7442.292982166686, 5276.788928442973, 5287.180487024518, 5313.468653134811, 5433.782648694008, 5593.8321850344555, 8917.910820891708, 8921.197092261727, 8941.195292423714, 8947.910520894968, 9019.098090190928, 8558.229759321443, 8812.471251724917, 8935.731928042169, 8941.597628106621, 9301.86047209306, 6811.114555107804, 6932.930670693228, 6986.938853038468, 7007.0, 7009.509334346547, 7630.656620451982, 7704.499207551491, 7705.113911899979, 7705.1524332324525, 7705.807354816175, 6962.930370696228, 7017.578945263301, 7048.436125109876, 7057.082579264663, 7196.316349946746, 6208.596441231305, 6285.214348206474, 6316.62100273985, 6318.304986451395, 6336.809895703136, 6364.172657554488, 6453.0, 6498.870022599427, 6516.934830651822, 6573.474122070318, 4539.0, 4633.212353899912, 4636.652251081115, 4690.601298889633, 4712.198926182626, 4482.955170448384, 4484.439445069366, 4522.8643140705835, 4535.523770004234, 4773.11697335994], "value": 4129.688225973806, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.71244043516054, 289.97100289971, 297.3873819930943, 302.3258134360375, 303.33115480365797, 4660.813567457306, 4762.476127625962, 4789.18583840747, 4816.662833601649, 4828.324034635154, 4616.699914505555, 4745.383100196978, 4767.523247675231, 4774.928376074362, 4780.450248221456, 3781.315266161029, 3788.4317352397225, 3789.7157713171487, 3798.9050273743237, 3806.5812760596314, 3567.3935430976667, 3607.8556857725657, 3786.091338078855, 3788.90527736806, 3814.408766641161, 3746.644068813472, 3751.1934933989187, 3761.2477504499075, 3771.7359784815076, 3779.168582911755, 3763.172102137525, 3766.9623303766884, 3769.7738135711943, 3774.4904437900805, 3782.81085945703, 3345.933081338384, 3346.6318704942437, 3347.263602007579, 3354.3962086824363, 3374.223928496435, 4537.9773101134615, 4612.630989520831, 4628.81965098901, 4653.325267836179, 4686.929696054562, 4232.555581663943, 4234.618884300427, 4236.568379612524, 4239.618434340893, 4241.575842415765, 4927.457979622239, 4931.0, 4971.483697472269, 4976.203807390836, 4978.87552811179, 3416.7437442191685, 3563.625819288963, 3594.8741794037346, 3595.2989167112423, 3611.6749492545796, 5148.768305426227, 5178.446466060167, 5179.637425380225, 5186.740662966874, 5188.273641690166, 3649.9635003650155, 3650.5071815304595, 3651.507046548735, 3671.6511931366476, 3673.7244706646575, 3724.8510059597857, 3748.5126933499014, 3761.0785357587565, 3761.2665530221893, 3770.792606406674, 3988.3419235826345, 4141.420201171838, 4167.708010516689, 4188.246115699203, 4282.764447955392, 4991.301217829507, 5001.8499445016705, 5014.222795466709, 5023.221400682827, 5046.7476626168545, 1208.2025862930423, 1209.8124790657462, 1248.5579156074748, 1253.0602048463652, 1268.2834198677717, 835.8381849229576, 838.4843321357444, 852.6802449081595, 854.4403415762686, 860.3117505995303, 4359.433273674465, 4385.456435267537, 4387.166438376698, 4388.05656783793, 4392.736435813861, 5062.316587260746, 5085.949140508547, 5086.898262034739, 5086.9745651271505, 5089.8218562350885, 4348.934765978513, 4352.65067828974, 4369.4101296325225, 4376.606105450461, 4380.6057454829215, 2289.3933107726466, 2406.9157579484636, 2438.8292819502817, 2457.2751038443344, 2465.815063870217, 4310.396544483712, 4325.870223893288, 4328.749050493726, 4335.241332766753, 4343.58833379153, 4610.608098311681, 4619.050711641566, 4619.8450387403145, 4626.375439315717, 4697.718136911796, 4153.752628484268, 4163.354680024601, 4261.659067274672, 4271.935920961126, 4324.308110702273, 4511.819527219006, 4516.9322460163785, 4517.841875534472, 4547.340635607752, 4553.385292985405, 4459.554044595515, 4465.240909045535, 4480.775961201992, 4492.146492166478, 4499.460064792246, 4302.22559939214, 4304.0, 4332.978335108304, 4503.752293623882, 4886.144924638103, 3682.6133256008807, 3690.3910854709748, 3691.224842782938, 3692.4830523726164, 3708.351038568294, 5149.665271757323, 5222.294990176278, 5249.448807875118, 5254.028004819121, 5258.605604579749, 4836.3072924476155, 4852.514748525119, 5010.87472813168, 5044.344235249394, 5050.166722490748, 5027.974860125676, 5040.873978150576, 5044.672096313728, 5050.0, 5055.241713742821, 4850.975745121252, 4972.453030166606, 4973.701577905337, 4985.152524070844, 4985.675931064469, 5042.319286896369, 5059.721715305694, 5071.808125090527, 5075.92386114216, 5077.0, 4935.0, 4940.308356829974, 4947.406311242674, 4956.157453232887, 4968.55283024515], "value": 3690.9253680519546, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.464264660332, 675.1122274209414, 695.7321431248968, 734.3831181807284, 751.556581616846, 12621.305828179446, 13752.724945501084, 13774.931125344372, 13828.585142445718, 13868.86131138687, 12213.328266945315, 12348.691282717937, 12356.62930112098, 12376.443060062296, 12428.440720167591, 609.9420555047286, 731.1847290271336, 748.3938010211746, 753.4763339479064, 766.1572270502459, 763.2825144364308, 774.8256642255476, 775.0312109862672, 775.8719811231168, 777.4363586399843, 818.100089901107, 818.1409520004001, 818.9181081891822, 819.0049090355217, 819.1562690428868, 645.6094063091808, 749.1796482851269, 771.8301973565863, 810.2424233341815, 814.0028465130227, 584.8888711144868, 584.95612829038, 584.9795257166021, 585.2333443189435, 585.5520526797004, 12970.610881673565, 12974.156679815782, 12984.091113622051, 12989.285589292585, 13016.349182540835, 12276.140670153092, 12750.744985100338, 12900.741985160337, 13224.471021159143, 13378.264195469244, 11685.591004314892, 11787.469563869647, 11932.821007684892, 12134.332611706353, 12558.183718058297, 4058.898527536806, 4064.878053658395, 4097.7541347519245, 4243.511996120424, 4257.76582287974, 61157.0, 62436.0, 63165.0, 64514.0, 64986.67506662436, 10031.598736050622, 10193.796124077626, 10386.272960892817, 10465.215108866865, 10465.319754215952, 18614.627707446045, 18723.25106995705, 18729.625407492047, 18823.3411830588, 18836.340728074727, 6998.16022077354, 7026.824329391806, 7044.788656340318, 7061.540999834994, 7290.3438690518105, 3725.0951421535274, 3729.627037296303, 3748.156664750409, 3796.8101594920695, 3863.7681739095183, 2835.333696581302, 2939.5590661400533, 2946.175070980129, 3233.5958005249345, 3390.69483746464, 563.650536667269, 568.8947544704243, 584.52653350786, 584.5966283264534, 596.0165726551138, 603.4026313949247, 609.5458883132095, 626.4988009592308, 626.5582764151262, 635.196476457282, 8663.436876603128, 8663.523506207093, 8664.8700269496, 8868.215814901605, 9047.502387368626, 553.8492024175234, 571.5856004396779, 597.8624916269282, 609.9939000610026, 639.48840927258, 3953.189596132786, 3973.4635824163947, 4151.813168407429, 4253.128108737701, 4281.571842815756, 74017.62991185009, 104066.0, 108529.45735271274, 114308.0, 114492.0, 89038.0, 92805.0, 93404.53297733467, 94887.52556237175, 95800.52099739456, 78708.0, 80299.59850200711, 80609.59695201604, 81578.0, 82537.17462825295, 7334.889976650461, 7597.734079307198, 7811.062672479339, 7857.646405911633, 7945.443818932619, 47819.28271075867, 47871.5212847881, 50275.74862125666, 50609.0, 50839.0, 68515.65742171257, 71649.641751793, 72062.0, 72167.63916180596, 75355.24644753702, 10092.889782123813, 10144.087032167141, 10176.542055607664, 10674.46627668629, 10739.248252622241, 11534.250273732516, 11794.99742521861, 11885.524579017088, 11999.40002999864, 12928.676783080498, 10390.272680912263, 10953.616623418142, 10976.890231097586, 11235.325880447199, 11620.6513804586, 10392.636257730943, 11084.778304434027, 11172.0, 11198.21612487118, 11303.095752339628, 9945.502724863583, 10620.787584248426, 10910.345379277269, 11152.0, 11237.3257604544, 9019.909800901907, 9458.527073646152, 9532.189763870148, 9738.318317717692, 9884.308098433328, 9146.484015539474, 9197.21823644971, 9397.483138427178, 9398.577064031999, 9477.715668529956], "value": 6675.979154049879, "unit": "score"}], "name": "dromaeo_css", "value": 6305.615098181673, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 114.0, 115.0, 108.0, 113.0, 114.0, 115.0, 114.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 129.0, 122.0, 179.0, 124.0, 123.0, 144.0, 122.0, 173.0, 121.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 163.0, 160.0, 161.0, 162.0, 162.0, 160.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 111.0, 77.0, 77.0, 75.0, 75.0, 98.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 134.0, 87.0, 86.0, 85.0, 114.0, 86.0, 86.0, 126.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 62.0, 63.0, 64.0, 64.0, 61.0, 64.0, 74.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 66.0, 65.0, 65.0, 66.0, 67.0, 65.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 83.0, 89.0, 83.0, 83.0, 83.0, 86.0, 83.0, 83.0, 82.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 155.0, 132.0, 146.0, 134.0, 141.0, 143.0, 135.0, 148.0, 136.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 176.0, 174.0, 175.0, 174.0, 175.0, 175.0, 176.0, 175.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 68.0, 68.0, 67.0, 70.0, 68.0, 69.0, 67.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1548.6999999999998, "alertThreshold": 2.0}]} 19:02:23 INFO - Return code: 0 19:02:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:02:23 INFO - # TBPL SUCCESS # 19:02:23 INFO - Running post-action listener: _resource_record_post_action 19:02:23 INFO - [mozharness: 2016-08-13 02:02:23.150931Z] Finished run-tests step (success) 19:02:23 INFO - Running post-run listener: _resource_record_post_run 19:02:23 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 397791232; Read time: 8; Write time: 306636 19:02:23 INFO - TinderboxPrint: CPU usage
13.0% 19:02:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 19:02:23 INFO - TinderboxPrint: I/O write bytes / time
397,791,232 / 306,636 19:02:23 INFO - TinderboxPrint: CPU idle
8,338.1 (87.0%) 19:02:23 INFO - TinderboxPrint: CPU user
1,203.4 (12.6%) 19:02:23 INFO - TinderboxPrint: Swap in / out
0 / 0 19:02:23 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 115965952; Read time: 0; Write time: 121512 19:02:23 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 281825280; Read time: 0; Write time: 185124 19:02:23 INFO - Running post-run listener: _upload_blobber_files 19:02:23 INFO - Blob upload gear active. 19:02:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:02:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:02:23 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:02:23 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:02:24 INFO - (blobuploader) - INFO - Open directory for files ... 19:02:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:02:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:02:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:30 INFO - (blobuploader) - INFO - Done attempting. 19:02:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:02:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:02:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:31 INFO - (blobuploader) - INFO - Done attempting. 19:02:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:02:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:02:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:32 INFO - (blobuploader) - INFO - Done attempting. 19:02:32 INFO - (blobuploader) - INFO - Iteration through files over. 19:02:32 INFO - Return code: 0 19:02:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:02:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:02:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/293a7488e0cefe84fbf364157ed745dbf9f90564db7ea3a74b2fdcaba5e2ec46c2cbc49bb958838fdfac1d74ea80487cde6f8caa17c5e16c0e74f986fe63cf92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/becd159f2b8e6fdfe318d15e48405f736295e714d7e52338b81edd81d2b7ee6b9edc983e00e191c02de8b6ffb77a9a1a0b0a73d2198a89bd5839d2fd303f202d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ff236111351ef71602d66111e85138da8ecb4226efaaafbbd8c7aafe14fd412cca534d49c92af55f0a10e079aa7e4a86b2c89c215927bcc7b072107d7fe9b74"} 19:02:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:02:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:02:32 INFO - Contents: 19:02:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/293a7488e0cefe84fbf364157ed745dbf9f90564db7ea3a74b2fdcaba5e2ec46c2cbc49bb958838fdfac1d74ea80487cde6f8caa17c5e16c0e74f986fe63cf92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/becd159f2b8e6fdfe318d15e48405f736295e714d7e52338b81edd81d2b7ee6b9edc983e00e191c02de8b6ffb77a9a1a0b0a73d2198a89bd5839d2fd303f202d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ff236111351ef71602d66111e85138da8ecb4226efaaafbbd8c7aafe14fd412cca534d49c92af55f0a10e079aa7e4a86b2c89c215927bcc7b072107d7fe9b74"} 19:02:32 INFO - Running post-run listener: copy_logs_to_upload_dir 19:02:32 INFO - Copying logs to upload dir... 19:02:32 INFO - mkdir: /builds/slave/test/build/upload/logs 19:02:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.537589 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-12 19:02:31.793740) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 19:02:31.797615) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/293a7488e0cefe84fbf364157ed745dbf9f90564db7ea3a74b2fdcaba5e2ec46c2cbc49bb958838fdfac1d74ea80487cde6f8caa17c5e16c0e74f986fe63cf92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/becd159f2b8e6fdfe318d15e48405f736295e714d7e52338b81edd81d2b7ee6b9edc983e00e191c02de8b6ffb77a9a1a0b0a73d2198a89bd5839d2fd303f202d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ff236111351ef71602d66111e85138da8ecb4226efaaafbbd8c7aafe14fd412cca534d49c92af55f0a10e079aa7e4a86b2c89c215927bcc7b072107d7fe9b74"} build_url:https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/Hrk6vIPyQlGGSIpOK9JcrA/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/Autoland-Non-PGO/sha512/293a7488e0cefe84fbf364157ed745dbf9f90564db7ea3a74b2fdcaba5e2ec46c2cbc49bb958838fdfac1d74ea80487cde6f8caa17c5e16c0e74f986fe63cf92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/becd159f2b8e6fdfe318d15e48405f736295e714d7e52338b81edd81d2b7ee6b9edc983e00e191c02de8b6ffb77a9a1a0b0a73d2198a89bd5839d2fd303f202d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ff236111351ef71602d66111e85138da8ecb4226efaaafbbd8c7aafe14fd412cca534d49c92af55f0a10e079aa7e4a86b2c89c215927bcc7b072107d7fe9b74"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 19:02:31.822945) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:02:31.823310) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471052502.240421-639402775 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004482 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:02:31.909708) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:02:31.910428) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:02:31.911031) ========= ========= Total master_lag: 0.17 =========