builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-022 starttime: 1470929507.95 results: success (0) buildid: 20160811071121 builduid: 243e6cb392c94c598b1b0f67bb2f7132 revision: 74b1d84ac6aa4c279cdb84fd213dd1f6e6ec6c70 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:47.952464) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:47.952940) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:47.953316) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:48.010070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:48.010382) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-11 08:31:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.0M=0s 2016-08-11 08:31:48 (50.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123764 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:48.170169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:48.170557) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020682 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:48.230230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 08:31:48.233668) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 74b1d84ac6aa4c279cdb84fd213dd1f6e6ec6c70 --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 74b1d84ac6aa4c279cdb84fd213dd1f6e6ec6c70 --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=9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-11 08:31:48,605 truncating revision to first 12 chars 2016-08-11 08:31:48,605 Setting DEBUG logging. 2016-08-11 08:31:48,605 attempt 1/10 2016-08-11 08:31:48,605 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/74b1d84ac6aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-11 08:31:49,708 unpacking tar archive at: autoland-74b1d84ac6aa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.468589 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 08:31:49.716493) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:49.716874) ========= script_repo_revision: 74b1d84ac6aa4c279cdb84fd213dd1f6e6ec6c70 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:49.717437) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:49.717756) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 08:31:49.728107) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-08-11 08:31:49.728439) ========= /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=9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:31:50 INFO - MultiFileLogger online at 20160811 08:31:50 in /builds/slave/test 08:31:50 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 08:31:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:31:50 INFO - {'append_to_log': False, 08:31:50 INFO - 'base_work_dir': '/builds/slave/test', 08:31:50 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 08:31:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:31:50 INFO - 'branch': 'Autoland-Non-PGO', 08:31:50 INFO - 'buildbot_json_path': 'buildprops.json', 08:31:50 INFO - 'config_files': ('talos/linux_config.py',), 08:31:50 INFO - 'default_actions': ('clobber', 08:31:50 INFO - 'read-buildbot-config', 08:31:50 INFO - 'download-and-extract', 08:31:50 INFO - 'populate-webroot', 08:31:50 INFO - 'create-virtualenv', 08:31:50 INFO - 'install', 08:31:50 INFO - 'run-tests'), 08:31:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:31:50 INFO - 'download_minidump_stackwalk': True, 08:31:50 INFO - 'download_symbols': 'ondemand', 08:31:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:31:50 INFO - 'tooltool.py': '/tools/tooltool.py', 08:31:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:31:50 INFO - '/tools/misc-python/virtualenv.py')}, 08:31:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:31:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:31:50 INFO - 'installer_path': 'installer.exe', 08:31:50 INFO - 'log_level': 'info', 08:31:50 INFO - 'log_name': 'talos', 08:31:50 INFO - 'log_to_console': True, 08:31:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:31:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:31:50 INFO - 'opt_config_files': (), 08:31:50 INFO - 'pip_index': False, 08:31:50 INFO - 'sps_profile': False, 08:31:50 INFO - 'sps_profile_interval': 0, 08:31:50 INFO - 'suite': 'dromaeojs', 08:31:50 INFO - 'system_bits': '32', 08:31:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:31:50 INFO - 'title': 'talos-linux64-ix-022', 08:31:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:31:50 INFO - 'use_talos_json': True, 08:31:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:31:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:31:50 INFO - 'work_dir': 'build'} 08:31:50 INFO - [mozharness: 2016-08-11 15:31:50.196174Z] Running clobber step. 08:31:50 INFO - Running pre-action listener: _resource_record_pre_action 08:31:50 INFO - Running main action method: clobber 08:31:50 INFO - rmtree: /builds/slave/test/build 08:31:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:31:50 INFO - Running post-action listener: _resource_record_post_action 08:31:50 INFO - [mozharness: 2016-08-11 15:31:50.662252Z] Finished clobber step (success) 08:31:50 INFO - [mozharness: 2016-08-11 15:31:50.662352Z] Running read-buildbot-config step. 08:31:50 INFO - Running pre-action listener: _resource_record_pre_action 08:31:50 INFO - Running main action method: read_buildbot_config 08:31:50 INFO - Using buildbot properties: 08:31:50 INFO - { 08:31:50 INFO - "project": "", 08:31:50 INFO - "product": "firefox", 08:31:50 INFO - "script_repo_revision": "production", 08:31:50 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 08:31:50 INFO - "repository": "", 08:31:50 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 08:31:50 INFO - "buildid": "20160811071121", 08:31:50 INFO - "pgo_build": "False", 08:31:50 INFO - "basedir": "/builds/slave/test", 08:31:50 INFO - "buildnumber": 259, 08:31:50 INFO - "slavename": "talos-linux64-ix-022", 08:31:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:31:50 INFO - "platform": "ubuntu64_hw", 08:31:50 INFO - "scheduler": "tests-autoland-linux64-talos", 08:31:50 INFO - "branch": "autoland", 08:31:50 INFO - "repo_path": "integration/autoland", 08:31:50 INFO - "revision": "74b1d84ac6aa4c279cdb84fd213dd1f6e6ec6c70", 08:31:50 INFO - "stage_platform": "linux64", 08:31:50 INFO - "builduid": "243e6cb392c94c598b1b0f67bb2f7132", 08:31:50 INFO - "slavebuilddir": "test" 08:31:50 INFO - } 08:31:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:31:50 INFO - Running post-action listener: _resource_record_post_action 08:31:50 INFO - [mozharness: 2016-08-11 15:31:50.664768Z] Finished read-buildbot-config step (success) 08:31:50 INFO - [mozharness: 2016-08-11 15:31:50.664857Z] Running download-and-extract step. 08:31:50 INFO - Running pre-action listener: _resource_record_pre_action 08:31:50 INFO - Running main action method: download_and_extract 08:31:50 INFO - mkdir: /builds/slave/test/build/tests 08:31:50 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')]} 08:31:50 INFO - trying https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:31:50 INFO - Downloading https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:31:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:31:51 INFO - Downloaded 1361 bytes. 08:31:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:31:51 INFO - Using the following test package requirements: 08:31:51 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:31:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:31:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:31:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:51 INFO - u'jsshell-linux-x86_64.zip'], 08:31:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:31:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:31:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:31:51 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:31:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:31:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:31:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:31: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')]} 08:31:51 INFO - trying https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:31:51 INFO - Downloading https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:31:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:31:52 INFO - Downloaded 20262327 bytes. 08:31: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 08:31:56 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 08:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:56 INFO - trying https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:31:56 INFO - Downloading https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:31:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:31:57 INFO - Downloaded 20262327 bytes. 08:31:57 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 08:32: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')]} 08:32:02 INFO - trying https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:32:02 INFO - Downloading https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:32:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:32:03 INFO - Downloaded 14413719 bytes. 08:32:03 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 08:32:03 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')]} 08:32:03 INFO - trying https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:32:03 INFO - Downloading https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:32:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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 08:32:08 INFO - Downloaded 55627357 bytes. 08:32:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:32:08 INFO - mkdir: /builds/slave/test/properties 08:32:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:32:08 INFO - Writing to file /builds/slave/test/properties/build_url 08:32:08 INFO - Contents: 08:32:08 INFO - build_url:https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:32:08 INFO - Running post-action listener: _resource_record_post_action 08:32:08 INFO - Running post-action listener: set_extra_try_arguments 08:32:08 INFO - [mozharness: 2016-08-11 15:32:08.802565Z] Finished download-and-extract step (success) 08:32:08 INFO - [mozharness: 2016-08-11 15:32:08.802792Z] Running populate-webroot step. 08:32:08 INFO - Running pre-action listener: _resource_record_pre_action 08:32:08 INFO - Running main action method: populate_webroot 08:32:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:32:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:32:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:32:08 INFO - '--tppagecycles', 08:32:08 INFO - '7'], 08:32:08 INFO - 'tests': ['tsvgm']}}, 08:32:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:32:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:32:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:32:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:32:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:08 INFO - 'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:08 INFO - 'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['damp', 'tps']}, 08:32:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:08 INFO - 'tests': ['damp', 'tps']}, 08:32:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['dromaeo_dom']}, 08:32:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:32:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['basic_compositor_video']}, 08:32:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:32:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['a11yr', 08:32:08 INFO - 'ts_paint', 08:32:08 INFO - 'tpaint', 08:32:08 INFO - 'sessionrestore', 08:32:08 INFO - 'sessionrestore_no_auto_restore', 08:32:08 INFO - 'tabpaint']}, 08:32:08 INFO - 'other-e10s': {'tests': ['a11yr', 08:32:08 INFO - 'ts_paint', 08:32:08 INFO - 'tpaint', 08:32:08 INFO - 'sessionrestore', 08:32:08 INFO - 'sessionrestore_no_auto_restore', 08:32:08 INFO - 'tabpaint']}, 08:32:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:32:08 INFO - 'ts_paint', 08:32:08 INFO - 'tpaint', 08:32:08 INFO - 'sessionrestore', 08:32:08 INFO - 'sessionrestore_no_auto_restore', 08:32:08 INFO - 'tabpaint']}, 08:32:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:32:08 INFO - 'ts_paint', 08:32:08 INFO - 'tpaint', 08:32:08 INFO - 'sessionrestore', 08:32:08 INFO - 'sessionrestore_no_auto_restore', 08:32:08 INFO - 'tabpaint']}, 08:32:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['a11yr', 08:32:08 INFO - 'ts_paint', 08:32:08 INFO - 'tpaint', 08:32:08 INFO - 'sessionrestore', 08:32:08 INFO - 'sessionrestore_no_auto_restore', 08:32:08 INFO - 'tabpaint']}, 08:32:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['a11yr', 08:32:08 INFO - 'ts_paint', 08:32:08 INFO - 'tpaint', 08:32:08 INFO - 'sessionrestore', 08:32:08 INFO - 'sessionrestore_no_auto_restore', 08:32:08 INFO - 'tabpaint']}, 08:32:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['tsvgx', 08:32:08 INFO - 'tsvgr_opacity', 08:32:08 INFO - 'tart', 08:32:08 INFO - 'tscrollx', 08:32:08 INFO - 'cart']}, 08:32:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:32:08 INFO - 'tsvgr_opacity', 08:32:08 INFO - 'tart', 08:32:08 INFO - 'tscrollx', 08:32:08 INFO - 'cart']}, 08:32:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:08 INFO - 'talos_options': ['--disable-e10s'], 08:32:08 INFO - 'tests': ['tp5o']}, 08:32:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:08 INFO - 'tests': ['tp5o']}, 08:32:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:08 INFO - 'talos_options': ['--disable-e10s', 08:32:08 INFO - '--xperf_path', 08:32:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:08 INFO - 'tests': ['tp5n']}, 08:32:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:08 INFO - 'talos_options': ['--xperf_path', 08:32:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:08 INFO - 'tests': ['tp5n']}}, 08:32:08 INFO - 'talos.zip': {'path': '', 08:32:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:32:08 INFO - Running post-action listener: _resource_record_post_action 08:32:08 INFO - [mozharness: 2016-08-11 15:32:08.822775Z] Finished populate-webroot step (success) 08:32:08 INFO - [mozharness: 2016-08-11 15:32:08.822862Z] Running create-virtualenv step. 08:32:08 INFO - Running pre-action listener: _resource_record_pre_action 08:32:08 INFO - Running main action method: create_virtualenv 08:32:08 INFO - Creating virtualenv /builds/slave/test/build/venv 08:32:08 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 08:32:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:32:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:32:08 INFO - Using real prefix '/usr' 08:32:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:32:09 INFO - Installing distribute.............................................................................................................................................................................................done. 08:32:11 INFO - Installing pip.................done. 08:32:11 INFO - Return code: 0 08:32:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:11 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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:11 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 08:32:11 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 08:32:11 INFO - Using env: {'DISPLAY': ':0', 08:32:11 INFO - 'HOME': '/home/cltbld', 08:32:11 INFO - 'LANG': 'en_US.UTF-8', 08:32:11 INFO - 'LANGUAGE': 'en_US:en', 08:32:11 INFO - 'LOGNAME': 'cltbld', 08:32:11 INFO - 'MAIL': '/var/mail/cltbld', 08:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:11 INFO - 'MOZ_NO_REMOTE': '1', 08:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:11 INFO - 'NO_EM_RESTART': '1', 08:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:11 INFO - 'PWD': '/builds/slave/test', 08:32:11 INFO - 'SHELL': '/bin/bash', 08:32:11 INFO - 'SHLVL': '1', 08:32:11 INFO - 'TERM': 'linux', 08:32:11 INFO - 'TMOUT': '86400', 08:32:11 INFO - 'USER': 'cltbld', 08:32:11 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:11 INFO - '_': '/tools/buildbot/bin/python'} 08:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:11 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 08:32:11 INFO - Cleaning up... 08:32:11 INFO - Return code: 0 08:32:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:11 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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:11 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 08:32:11 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 08:32:11 INFO - Using env: {'DISPLAY': ':0', 08:32:11 INFO - 'HOME': '/home/cltbld', 08:32:11 INFO - 'LANG': 'en_US.UTF-8', 08:32:11 INFO - 'LANGUAGE': 'en_US:en', 08:32:11 INFO - 'LOGNAME': 'cltbld', 08:32:11 INFO - 'MAIL': '/var/mail/cltbld', 08:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:11 INFO - 'MOZ_NO_REMOTE': '1', 08:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:11 INFO - 'NO_EM_RESTART': '1', 08:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:11 INFO - 'PWD': '/builds/slave/test', 08:32:11 INFO - 'SHELL': '/bin/bash', 08:32:11 INFO - 'SHLVL': '1', 08:32:11 INFO - 'TERM': 'linux', 08:32:11 INFO - 'TMOUT': '86400', 08:32:11 INFO - 'USER': 'cltbld', 08:32:11 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:11 INFO - '_': '/tools/buildbot/bin/python'} 08:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:11 INFO - Downloading/unpacking psutil>=3.1.1 08:32:11 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 08:32:11 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 08:32:11 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 08:32:11 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 08:32:11 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 08:32:11 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 08:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:32:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:13 INFO - Installing collected packages: psutil 08:32:13 INFO - Running setup.py install for psutil 08:32:13 INFO - building 'psutil._psutil_linux' extension 08:32:13 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 08:32:14 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 08:32:14 INFO - building 'psutil._psutil_posix' extension 08:32:14 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 08:32:14 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 08:32:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:14 INFO - Successfully installed psutil 08:32:14 INFO - Cleaning up... 08:32:14 INFO - Return code: 0 08:32:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:14 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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:14 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 08:32:14 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 08:32:14 INFO - Using env: {'DISPLAY': ':0', 08:32:14 INFO - 'HOME': '/home/cltbld', 08:32:14 INFO - 'LANG': 'en_US.UTF-8', 08:32:14 INFO - 'LANGUAGE': 'en_US:en', 08:32:14 INFO - 'LOGNAME': 'cltbld', 08:32:14 INFO - 'MAIL': '/var/mail/cltbld', 08:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:14 INFO - 'MOZ_NO_REMOTE': '1', 08:32:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:14 INFO - 'NO_EM_RESTART': '1', 08:32:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:14 INFO - 'PWD': '/builds/slave/test', 08:32:14 INFO - 'SHELL': '/bin/bash', 08:32:14 INFO - 'SHLVL': '1', 08:32:14 INFO - 'TERM': 'linux', 08:32:14 INFO - 'TMOUT': '86400', 08:32:14 INFO - 'USER': 'cltbld', 08:32:14 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:14 INFO - '_': '/tools/buildbot/bin/python'} 08:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:32:14 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 08:32:14 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 08:32:14 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 08:32:14 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 08:32:14 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 08:32:14 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 08:32:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:32:16 INFO - Installing collected packages: mozsystemmonitor 08:32:16 INFO - Running setup.py install for mozsystemmonitor 08:32:16 INFO - Successfully installed mozsystemmonitor 08:32:16 INFO - Cleaning up... 08:32:16 INFO - Return code: 0 08:32:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:32:16 INFO - Using env: {'DISPLAY': ':0', 08:32:16 INFO - 'HOME': '/home/cltbld', 08:32:16 INFO - 'LANG': 'en_US.UTF-8', 08:32:16 INFO - 'LANGUAGE': 'en_US:en', 08:32:16 INFO - 'LOGNAME': 'cltbld', 08:32:16 INFO - 'MAIL': '/var/mail/cltbld', 08:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:16 INFO - 'MOZ_NO_REMOTE': '1', 08:32:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:16 INFO - 'NO_EM_RESTART': '1', 08:32:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:16 INFO - 'PWD': '/builds/slave/test', 08:32:16 INFO - 'SHELL': '/bin/bash', 08:32:16 INFO - 'SHLVL': '1', 08:32:16 INFO - 'TERM': 'linux', 08:32:16 INFO - 'TMOUT': '86400', 08:32:16 INFO - 'USER': 'cltbld', 08:32:16 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:16 INFO - '_': '/tools/buildbot/bin/python'} 08:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:16 INFO - Downloading/unpacking jsonschema==2.5.1 08:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:32:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:32:18 INFO - warning: no files found matching '*.txt' 08:32:18 INFO - no previously-included directories found matching 'build' 08:32:18 INFO - no previously-included directories found matching 'dist' 08:32:18 INFO - no previously-included directories found matching '.git*' 08:32:18 INFO - Installing collected packages: jsonschema, functools32 08:32:19 INFO - Running setup.py install for functools32 08:32:19 INFO - warning: no files found matching '*.txt' 08:32:19 INFO - no previously-included directories found matching 'build' 08:32:19 INFO - no previously-included directories found matching 'dist' 08:32:19 INFO - no previously-included directories found matching '.git*' 08:32:19 INFO - Successfully installed jsonschema functools32 08:32:19 INFO - Cleaning up... 08:32:19 INFO - Return code: 0 08:32:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:32:19 INFO - Using env: {'DISPLAY': ':0', 08:32:19 INFO - 'HOME': '/home/cltbld', 08:32:19 INFO - 'LANG': 'en_US.UTF-8', 08:32:19 INFO - 'LANGUAGE': 'en_US:en', 08:32:19 INFO - 'LOGNAME': 'cltbld', 08:32:19 INFO - 'MAIL': '/var/mail/cltbld', 08:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:19 INFO - 'MOZ_NO_REMOTE': '1', 08:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:19 INFO - 'NO_EM_RESTART': '1', 08:32:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:19 INFO - 'PWD': '/builds/slave/test', 08:32:19 INFO - 'SHELL': '/bin/bash', 08:32:19 INFO - 'SHLVL': '1', 08:32:19 INFO - 'TERM': 'linux', 08:32:19 INFO - 'TMOUT': '86400', 08:32:19 INFO - 'USER': 'cltbld', 08:32:19 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:19 INFO - '_': '/tools/buildbot/bin/python'} 08:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:32:19 INFO - Cleaning up... 08:32:19 INFO - Return code: 0 08:32:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:32:19 INFO - Using env: {'DISPLAY': ':0', 08:32:19 INFO - 'HOME': '/home/cltbld', 08:32:19 INFO - 'LANG': 'en_US.UTF-8', 08:32:19 INFO - 'LANGUAGE': 'en_US:en', 08:32:19 INFO - 'LOGNAME': 'cltbld', 08:32:19 INFO - 'MAIL': '/var/mail/cltbld', 08:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:19 INFO - 'MOZ_NO_REMOTE': '1', 08:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:19 INFO - 'NO_EM_RESTART': '1', 08:32:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:19 INFO - 'PWD': '/builds/slave/test', 08:32:19 INFO - 'SHELL': '/bin/bash', 08:32:19 INFO - 'SHLVL': '1', 08:32:19 INFO - 'TERM': 'linux', 08:32:19 INFO - 'TMOUT': '86400', 08:32:19 INFO - 'USER': 'cltbld', 08:32:19 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:19 INFO - '_': '/tools/buildbot/bin/python'} 08:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:19 INFO - Downloading/unpacking blobuploader==1.2.4 08:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:21 INFO - Downloading blobuploader-1.2.4.tar.gz 08:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:32:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:32:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:22 INFO - Downloading docopt-0.6.1.tar.gz 08:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:32:22 INFO - Installing collected packages: blobuploader, requests, docopt 08:32:22 INFO - Running setup.py install for blobuploader 08:32:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:32:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:32:22 INFO - Running setup.py install for requests 08:32:22 INFO - Running setup.py install for docopt 08:32:22 INFO - Successfully installed blobuploader requests docopt 08:32:22 INFO - Cleaning up... 08:32:22 INFO - Return code: 0 08:32:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:22 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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:22 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 08:32:22 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 08:32:22 INFO - Using env: {'DISPLAY': ':0', 08:32:22 INFO - 'HOME': '/home/cltbld', 08:32:22 INFO - 'LANG': 'en_US.UTF-8', 08:32:22 INFO - 'LANGUAGE': 'en_US:en', 08:32:22 INFO - 'LOGNAME': 'cltbld', 08:32:22 INFO - 'MAIL': '/var/mail/cltbld', 08:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:22 INFO - 'MOZ_NO_REMOTE': '1', 08:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:22 INFO - 'NO_EM_RESTART': '1', 08:32:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:22 INFO - 'PWD': '/builds/slave/test', 08:32:22 INFO - 'SHELL': '/bin/bash', 08:32:22 INFO - 'SHLVL': '1', 08:32:22 INFO - 'TERM': 'linux', 08:32:22 INFO - 'TMOUT': '86400', 08:32:22 INFO - 'USER': 'cltbld', 08:32:22 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:22 INFO - '_': '/tools/buildbot/bin/python'} 08:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:23 INFO - Running setup.py (path:/tmp/pip-QmCA1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:23 INFO - Running setup.py (path:/tmp/pip-kR6W4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:23 INFO - Running setup.py (path:/tmp/pip-BRntq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:23 INFO - Running setup.py (path:/tmp/pip-9luf7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:23 INFO - Running setup.py (path:/tmp/pip-gBVTA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:23 INFO - Running setup.py (path:/tmp/pip-OU6MAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:23 INFO - Running setup.py (path:/tmp/pip-k5GhrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:23 INFO - Running setup.py (path:/tmp/pip-r0zBF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:23 INFO - Running setup.py (path:/tmp/pip-t3lXbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:23 INFO - Running setup.py (path:/tmp/pip-w9AMCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:23 INFO - Running setup.py (path:/tmp/pip-V1lK5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:23 INFO - Running setup.py (path:/tmp/pip-69xMDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:23 INFO - Running setup.py (path:/tmp/pip-aUzsQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:24 INFO - Running setup.py (path:/tmp/pip-W2Qy7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:24 INFO - Running setup.py (path:/tmp/pip-lhrkcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:24 INFO - Running setup.py (path:/tmp/pip-KARa_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:24 INFO - Running setup.py (path:/tmp/pip-TH9QRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:32:24 INFO - Running setup.py install for manifestparser 08:32:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:32:24 INFO - Running setup.py install for mozcrash 08:32:24 INFO - Running setup.py install for mozdebug 08:32:24 INFO - Running setup.py install for mozdevice 08:32:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:32:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:32:24 INFO - Running setup.py install for mozfile 08:32:24 INFO - Running setup.py install for mozhttpd 08:32:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:32:24 INFO - Running setup.py install for mozinfo 08:32:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:32:24 INFO - Running setup.py install for mozInstall 08:32:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Running setup.py install for mozleak 08:32:25 INFO - Running setup.py install for mozlog 08:32:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Running setup.py install for moznetwork 08:32:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Running setup.py install for mozprocess 08:32:25 INFO - Running setup.py install for mozprofile 08:32:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Running setup.py install for mozrunner 08:32:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:32:25 INFO - Running setup.py install for mozscreenshot 08:32:25 INFO - Running setup.py install for moztest 08:32:25 INFO - Running setup.py install for mozversion 08:32:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:32:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:32:26 INFO - Cleaning up... 08:32:26 INFO - Return code: 0 08:32:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:26 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')]} 08:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:26 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')]} 08:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:26 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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:26 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 08:32:26 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 08:32:26 INFO - Using env: {'DISPLAY': ':0', 08:32:26 INFO - 'HOME': '/home/cltbld', 08:32:26 INFO - 'LANG': 'en_US.UTF-8', 08:32:26 INFO - 'LANGUAGE': 'en_US:en', 08:32:26 INFO - 'LOGNAME': 'cltbld', 08:32:26 INFO - 'MAIL': '/var/mail/cltbld', 08:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:26 INFO - 'MOZ_NO_REMOTE': '1', 08:32:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:26 INFO - 'NO_EM_RESTART': '1', 08:32:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:26 INFO - 'PWD': '/builds/slave/test', 08:32:26 INFO - 'SHELL': '/bin/bash', 08:32:26 INFO - 'SHLVL': '1', 08:32:26 INFO - 'TERM': 'linux', 08:32:26 INFO - 'TMOUT': '86400', 08:32:26 INFO - 'USER': 'cltbld', 08:32:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:26 INFO - '_': '/tools/buildbot/bin/python'} 08:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:26 INFO - Running setup.py (path:/tmp/pip-kowSUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:26 INFO - Running setup.py (path:/tmp/pip-ZgLi9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:26 INFO - Running setup.py (path:/tmp/pip-wkvFL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:26 INFO - Running setup.py (path:/tmp/pip-JS7zlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:26 INFO - Running setup.py (path:/tmp/pip-cek9Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:26 INFO - Running setup.py (path:/tmp/pip-FBUNS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:26 INFO - Running setup.py (path:/tmp/pip-iWeAUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:26 INFO - Running setup.py (path:/tmp/pip-J9HER7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:26 INFO - Running setup.py (path:/tmp/pip-Hz4qB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:26 INFO - Running setup.py (path:/tmp/pip-tRMx3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:26 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)) 08:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:26 INFO - Running setup.py (path:/tmp/pip-SJPOUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:27 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)) 08:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:27 INFO - Running setup.py (path:/tmp/pip-z_Y1J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:27 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)) 08:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:27 INFO - Running setup.py (path:/tmp/pip-_DjbBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:27 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)) 08:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:27 INFO - Running setup.py (path:/tmp/pip-8zl9Uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:27 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)) 08:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:27 INFO - Running setup.py (path:/tmp/pip-4UcYtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:27 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)) 08:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:27 INFO - Running setup.py (path:/tmp/pip-CBdS7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:27 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)) 08:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:27 INFO - Running setup.py (path:/tmp/pip-Gggqej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:27 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)) 08:32:27 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)) 08:32:27 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)) 08:32:27 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)) 08:32:27 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)) 08:32:27 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)) 08:32:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:29 INFO - Downloading blessings-1.6.tar.gz 08:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:32:29 INFO - Installing collected packages: blessings 08:32:29 INFO - Running setup.py install for blessings 08:32:29 INFO - Successfully installed blessings 08:32:29 INFO - Cleaning up... 08:32:29 INFO - Return code: 0 08:32:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:32:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:29 INFO - Reading from file tmpfile_stdout 08:32:29 INFO - Current package versions: 08:32:29 INFO - argparse == 1.2.1 08:32:29 INFO - blessings == 1.6 08:32:29 INFO - blobuploader == 1.2.4 08:32:29 INFO - docopt == 0.6.1 08:32:29 INFO - functools32 == 3.2.3-2 08:32:29 INFO - jsonschema == 2.5.1 08:32:29 INFO - manifestparser == 1.1 08:32:29 INFO - mozInstall == 1.12 08:32:29 INFO - mozcrash == 0.17 08:32:29 INFO - mozdebug == 0.1 08:32:29 INFO - mozdevice == 0.48 08:32:29 INFO - mozfile == 1.2 08:32:29 INFO - mozhttpd == 0.7 08:32:29 INFO - mozinfo == 0.9 08:32:29 INFO - mozleak == 0.1 08:32:29 INFO - mozlog == 3.3 08:32:29 INFO - moznetwork == 0.27 08:32:29 INFO - mozprocess == 0.23 08:32:29 INFO - mozprofile == 0.28 08:32:29 INFO - mozrunner == 6.12 08:32:29 INFO - mozscreenshot == 0.1 08:32:29 INFO - mozsystemmonitor == 0.3 08:32:29 INFO - moztest == 0.7 08:32:29 INFO - mozversion == 1.4 08:32:29 INFO - psutil == 3.1.1 08:32:29 INFO - requests == 1.2.3 08:32:29 INFO - wsgiref == 0.1.2 08:32:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:29 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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:29 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 08:32:29 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 08:32:29 INFO - Using env: {'DISPLAY': ':0', 08:32:29 INFO - 'HOME': '/home/cltbld', 08:32:29 INFO - 'LANG': 'en_US.UTF-8', 08:32:29 INFO - 'LANGUAGE': 'en_US:en', 08:32:29 INFO - 'LOGNAME': 'cltbld', 08:32:29 INFO - 'MAIL': '/var/mail/cltbld', 08:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:29 INFO - 'MOZ_NO_REMOTE': '1', 08:32:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:29 INFO - 'NO_EM_RESTART': '1', 08:32:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:29 INFO - 'PWD': '/builds/slave/test', 08:32:29 INFO - 'SHELL': '/bin/bash', 08:32:29 INFO - 'SHLVL': '1', 08:32:29 INFO - 'TERM': 'linux', 08:32:29 INFO - 'TMOUT': '86400', 08:32:29 INFO - 'USER': 'cltbld', 08:32:29 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:29 INFO - '_': '/tools/buildbot/bin/python'} 08:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 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)) 08:32:30 INFO - Cleaning up... 08:32:30 INFO - Return code: 0 08:32:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:32:30 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')]} 08:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:30 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')]} 08:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:30 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 0x7f8697762f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', '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 08:32:30 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 08:32:30 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 08:32:30 INFO - Using env: {'DISPLAY': ':0', 08:32:30 INFO - 'HOME': '/home/cltbld', 08:32:30 INFO - 'LANG': 'en_US.UTF-8', 08:32:30 INFO - 'LANGUAGE': 'en_US:en', 08:32:30 INFO - 'LOGNAME': 'cltbld', 08:32:30 INFO - 'MAIL': '/var/mail/cltbld', 08:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:30 INFO - 'MOZ_NO_REMOTE': '1', 08:32:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:30 INFO - 'NO_EM_RESTART': '1', 08:32:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:30 INFO - 'PWD': '/builds/slave/test', 08:32:30 INFO - 'SHELL': '/bin/bash', 08:32:30 INFO - 'SHLVL': '1', 08:32:30 INFO - 'TERM': 'linux', 08:32:30 INFO - 'TMOUT': '86400', 08:32:30 INFO - 'USER': 'cltbld', 08:32:30 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:30 INFO - '_': '/tools/buildbot/bin/python'} 08:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:32:30 INFO - Cleaning up... 08:32:30 INFO - Return code: 0 08:32:30 INFO - Running post-action listener: _resource_record_post_action 08:32:30 INFO - Running post-action listener: _start_resource_monitoring 08:32:30 INFO - Starting resource monitoring. 08:32:30 INFO - [mozharness: 2016-08-11 15:32:30.319204Z] Finished create-virtualenv step (success) 08:32:30 INFO - [mozharness: 2016-08-11 15:32:30.319704Z] Running install step. 08:32:30 INFO - Running pre-action listener: _resource_record_pre_action 08:32:30 INFO - Running main action method: install 08:32:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:30 INFO - Reading from file tmpfile_stdout 08:32:30 INFO - Detecting whether we're running mozinstall >=1.0... 08:32:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:32:30 INFO - Reading from file tmpfile_stdout 08:32:30 INFO - Output received: 08:32:30 INFO - Usage: mozinstall [options] installer 08:32:30 INFO - Options: 08:32:30 INFO - -h, --help show this help message and exit 08:32:30 INFO - -d DEST, --destination=DEST 08:32:30 INFO - Directory to install application into. [default: 08:32:30 INFO - "/builds/slave/test"] 08:32:30 INFO - --app=APP Application being installed. [default: firefox] 08:32:30 INFO - mkdir: /builds/slave/test/build/application 08:32:30 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'] 08:32:30 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 08:32:46 INFO - Reading from file tmpfile_stdout 08:32:46 INFO - Output received: 08:32:46 INFO - /builds/slave/test/build/application/firefox/firefox 08:32:46 INFO - Running post-action listener: _resource_record_post_action 08:32:46 INFO - [mozharness: 2016-08-11 15:32:46.667650Z] Finished install step (success) 08:32:46 INFO - [mozharness: 2016-08-11 15:32:46.667899Z] Running run-tests step. 08:32:46 INFO - Running pre-action listener: _resource_record_pre_action 08:32:46 INFO - Running main action method: run_tests 08:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:32:46 INFO - Python 2.7.3 08:32:46 INFO - Return code: 0 08:32:46 INFO - grabbing minidump binary from tooltool 08:32: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')]} 08:32:46 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 0xd82090>, '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 0xd859b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd85e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:32:46 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 08:32:46 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 08:32:46 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 08:32:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:32:46 INFO - Return code: 0 08:32:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:32:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:32:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:32:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:32:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:32:46 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/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 08:32:46 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/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 08:32:46 INFO - Using env: {'DISPLAY': ':0', 08:32:46 INFO - 'HOME': '/home/cltbld', 08:32:46 INFO - 'LANG': 'en_US.UTF-8', 08:32:46 INFO - 'LANGUAGE': 'en_US:en', 08:32:46 INFO - 'LOGNAME': 'cltbld', 08:32:46 INFO - 'MAIL': '/var/mail/cltbld', 08:32:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:32:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:46 INFO - 'MOZ_NO_REMOTE': '1', 08:32:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:46 INFO - 'NO_EM_RESTART': '1', 08:32:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:46 INFO - 'PWD': '/builds/slave/test', 08:32:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:32:46 INFO - 'SHELL': '/bin/bash', 08:32:46 INFO - 'SHLVL': '1', 08:32:46 INFO - 'TERM': 'linux', 08:32:46 INFO - 'TMOUT': '86400', 08:32:46 INFO - 'USER': 'cltbld', 08:32:46 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018', 08:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:46 INFO - '_': '/tools/buildbot/bin/python'} 08:32:46 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/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 08:32:46 INFO - mozversion application_buildid: 20160811071121 08:32:46 INFO - mozversion application_changeset: 74b1d84ac6aa4c279cdb84fd213dd1f6e6ec6c70 08:32:46 INFO - mozversion application_display_name: Nightly 08:32:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:32:46 INFO - mozversion application_name: Firefox 08:32:46 INFO - mozversion application_remotingname: firefox 08:32:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:32:46 INFO - mozversion application_vendor: Mozilla 08:32:46 INFO - mozversion application_version: 51.0a1 08:32:46 INFO - mozversion platform_buildid: 20160811071121 08:32:46 INFO - mozversion platform_changeset: 74b1d84ac6aa4c279cdb84fd213dd1f6e6ec6c70 08:32:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:32:46 INFO - mozversion platform_version: 51.0a1 08:32:46 INFO - using testdate: 1470929566 08:32:46 INFO - actual date: 1470929566 08:32:46 INFO - starting webserver on 'localhost:60869' 08:32:46 INFO - SUITE-START | Running 2 tests 08:32:46 INFO - TEST-START | dromaeo_css 08:32:46 INFO - operating with platform_type : linux_ 08:32:46 INFO - Initialising browser for dromaeo_css test... 08:32:46 INFO - TEST-INFO | started process 25378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg2HpXz/profile http://localhost:60869/getInfo.html) 08:32:51 INFO - PROCESS | 25378 | __metrics Screen width/height:1600/1200 08:32:51 INFO - PROCESS | 25378 | colorDepth:24 08:32:51 INFO - PROCESS | 25378 | Browser inner width/height: 1024/697 08:32:51 INFO - PROCESS | 25378 | __metrics 08:32:52 INFO - PROCESS | 25378 | 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" 08:32:52 INFO - PROCESS | 25378 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:32:53 INFO - TEST-INFO | 25378: exit 0 08:32:53 INFO - Browser initialized. 08:32:53 INFO - Running cycle 1/1 for dromaeo_css test... 08:32:53 INFO - TEST-INFO | started process 25457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg2HpXz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:05 INFO - PROCESS | 25457 | RSS: Main: 156631040 08:33:05 INFO - PROCESS | 25457 | 08:33:56 INFO - PROCESS | 25457 | 1470929636170 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] 08:36:09 INFO - PROCESS | 25457 | Cycle 1(1): loaded http://localhost:60869/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60869/tests/dromaeo/cssquery-ext.html) 08:36:10 INFO - PROCESS | 25457 | RSS: Main: 238989312 08:36:10 INFO - PROCESS | 25457 | 08:39:13 INFO - PROCESS | 25457 | Cycle 1(1): loaded http://localhost:60869/tests/dromaeo/cssquery-ext.html (next: http://localhost:60869/tests/dromaeo/cssquery-jquery.html) 08:39:14 INFO - PROCESS | 25457 | RSS: Main: 238018560 08:39:14 INFO - PROCESS | 25457 | 08:42:16 INFO - PROCESS | 25457 | Cycle 1(1): loaded http://localhost:60869/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60869/tests/dromaeo/cssquery-mootools.html) 08:42:16 INFO - PROCESS | 25457 | RSS: Main: 218873856 08:42:16 INFO - PROCESS | 25457 | 08:45:19 INFO - PROCESS | 25457 | Cycle 1(1): loaded http://localhost:60869/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60869/tests/dromaeo/cssquery-prototype.html) 08:45:19 INFO - PROCESS | 25457 | RSS: Main: 188420096 08:45:19 INFO - PROCESS | 25457 | 08:48:22 INFO - PROCESS | 25457 | Cycle 1(1): loaded http://localhost:60869/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60869/tests/dromaeo/cssquery-yui.html) 08:48:23 INFO - PROCESS | 25457 | RSS: Main: 189259776 08:48:23 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | Cycle 1(1): loaded http://localhost:60869/tests/dromaeo/cssquery-yui.html (next: http://localhost:60869/tests/dromaeo/cssquery-dojo.html) 08:51:26 INFO - PROCESS | 25457 | RSS: Main: 281186304 08:51:26 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | __start_tp_report 08:51:26 INFO - PROCESS | 25457 | _x_x_mozilla_page_load 08:51:26 INFO - PROCESS | 25457 | _x_x_mozilla_page_load_details 08:51:26 INFO - PROCESS | 25457 | |i|pagename|runs| 08:51:26 INFO - PROCESS | 25457 | |0;dojo.html;2646.4442467081913;2657.402084530982;2673.0644274503925;2698.217516920093;2703.3376822678447;4617.653675974307;4697.1075495655805;4786.042791441712;4929.926051109236;4932.82735104271;4825.734584597846;4859.878503037426;4892.339534162887;4908.312836202935;4911.852644420665;4359.04101097758;4366.148601022802;4461.821527138911;4526.7283962962165;4566.429196350457;4470.329450582422;4516.051629157864;4525.932111018337;4536.070105628338;4550.431196100488;4271.337942618868;4358.585934336233;4372;4421;4434.157510073076;4391.9780401098105;4408.9859332353735;4427.667924905645;4429.911401771947;4448.755318457484;4425.070735145591;4477.753723545204;4484.686071974963;4494.303382975611;4521.7739113044545;4574.817007319703;4919.311296418504;4930.827421040246;4971.204607262821;4977.203647416432;5070.3915530136255;5111.258867464234;5121.078205922932;5201;5201.08980928336;5164.509371609691;5197.376314842205;5201.583873290166;5205.6876587404495;5227.555657769094;4655.464621568563;4791.688540244873;4861.73260470674;4869.829555965524;4872.366592342973;5296.25852380667;5333;5360.705161216092;5380.085385484399;5381.354237491526;5149.845504634867;5152.948470515247;5153.072446959583;5160.045391602565;5165.819196328111;4901.313816065754;5070.949290507047;5245.659032162897;5256.6057545684225;5262.97368513155;5039.168537191323;5139.820106296262;5143.3827940646615;5145.4854514549;5151.896962060738;4969.304297398367;5076.060928728197;5078.263651770472;5079.822206222839;5123.820666276662;4317.870463886088;4325.264705000158;4331.068820203608;4332.935005974913;4380.649548036149;3709.8145092745253;3731.2910546995986;3733.3093377724663;3816;3854;4547.499775024745;4548.067646132535;4589.10512450069;4590.334401511829;4590.839320623762;5224.111900976767;5257.8159764408065;5259.421463638997;5262.394824595144;5324.627276090637;4368.978155109267;4378.10248898975;4538.568835960578;4549.40857688505;4549.818007279671;4573.817047318136;4604.286335617984;4604.286335617984;4646.303054541846;4653.837115700934;4827;4828.155072862235;4835.347228124158;4840.467548569725;4884.584810291165;5024.6231532634465;5049.469805670498;5141.948580514297;5152.793888244429;5200.401953775364;4803.279508073677;4817.98822247332;4832.323474713613;4867.537583929521;4884.3650325457465;5051.292819005415;5052.444231134646;5062;5068.417132029716;5112.156494178419;4935.777889995031;5104.489551044866;5117.1045067113855;5151.201563757623;5175.353080864892;5034.899302014012;5084.923726144037;5086.21163719624;5112.693238405707;5126.2054381570915;5077.7461126944245;5081.974590127025;5083.517065878736;5092.694438333712;5123.641345105807;4742.051589682146;4765.070811191785;4976;4987.60099192071;5034.219695947134;4847.515248475124;4886.755662216946;4921.827736029365;4949.554540091409;4963.205887058053;4811.398575178103;4812.5187481253;4822.855314340575;4826.686265392738;4852.636052296162 08:51:26 INFO - PROCESS | 25457 | |1;ext.html;551.9457835534131;559.9104143337065;560.0087844515208;562.9662220266782;578.661483032426;39138;41233.587664123384;41394.79302603483;41452;45146;41854.95362615936;42362.364564531556;42363.78818105905;42432.57567424325;42492.15015699684;7458.552486850779;7779.533228006309;7932.20677932205;7944.642491087915;7947.08608510023;7518.849623007536;7571.242875712413;7587;9947.452890091043;10317.432541210268;10238;10310.072093511582;10320.174386049099;10324.483775811217;10326.845097323547;10163.949180254089;10209.234307426972;10215.336003159846;10229.079382855505;10229.795404091876;10012.59949602015;10087.596496140146;10090.899091009069;10093.798124037552;10095.848562271573;39802.60197398018;40052.198956021006;40075.19849603021;40146.59853401487;40216.19567608632;3010.307629245264;3089.428455735696;3268.6241082275606;3645.052286405527;3728.8694895678523;29306.267343316587;29526.55710164349;30117.849410753024;31931.201719956956;33742.493862592084;3165.572647692555;3172.1752344390393;3227.3222623249144;3228.9031329060167;3295.2585668224456;270287;274884;275435;376393.99999999726;379802;53450;53736;53813.192802107216;53824;53853.192202117;33561.16097097592;34822.77658351217;57001.71499142561;57154;57180.14229786557;14892.255387230594;15027.924860375628;15088.698226035418;15097.54707358759;15115.848841511444;11667;11721.765564688829;11761.88238117625;11774.764504709858;11805.055595552336;16747.665046699243;17990.190441430168;18724.812751872578;18867.811321886606;19417.417477475698;6863.842632389057;7012.158540975013;7076.858462830714;7136.001919462558;7301.08736407949;821.8685010398308;897.6499165325529;900.3157600223877;967.6613185385058;1013.5844303835539;27057;28602.713972860005;28853.278668033046;29144.562831557545;29222.561661575095;975.8194733974237;989.44096585429;1016.2490899816252;1018.6282007067515;1062.18211976779;14868.776968345483;15333.156676382683;15393.538193854203;15561.84438155604;15568.138090264343;364513;371607;371779;372892;374043;51067.744661276454;51431.485685143416;51478.74260628748;51593;51660.48339516557;1060.2472244706191;1137.2380505061628;1174.9823752643717;1199.1545960098285;1218.0864351736197;28101.297467562657;29406.41187176202;29741.702582974758;30070;30685;42063.94840129021;42681.57318426776;42701.35947960721;42732.14535709331;42752.78623606862;297492;299922;300076;300691;301254;25593.23220303394;26622.7337726628;26750.46499069969;27856.8607156971;27913.302167445978;42073.78963105165;42857.78571107124;43395.13209735851;43478.13043739044;43829.46596869094;36205;36208.27583448369;36600.81699591591;36768.81615591905;37301;36706.02019640408;36725.26549469049;37015;37332;37901.620983789806;36630;37179;37289.254214916094;37451.625483744814;37721.81139094287;31020.934557014887;33178.50232246566;33534;33852.66147338495;34014.48978265279;25529.744702552736;25895.352616184748;26011.83172218437;26081.790770582054;26284 08:51:26 INFO - PROCESS | 25457 | |2;jquery.html;335.39461272403304;362.3550080856077;368.3608938491717;372.14035577815247;389.36339085595046;13052.869471305285;16506;16762.916185419086;16820.747688784646;17769.555761105978;15709.371625135009;15971.281292341844;17012.914935425335;17865.19606617702;17954.910225448886;4978.402591689002;4992.77532511036;5108.208227724708;5128.358955130609;5132.563732082759;3770.3213421584105;5036.848894533152;5119.56483698886;5584.748686309126;5854.648721076728;5888.116782482619;5894.970525147368;5904.852378690524;5906.202662640531;5919.141724449953;5159.303494028296;5402.78388864445;5420.7018613976215;5517.062099443064;5890.941090589082;5330.307060082203;5342.465753424665;5343.759530821122;5348.090824559834;5360.812371567015;12020.579279725254;12184.39078046103;12241.387930603434;12373.195742276814;12455.501779928793;1844.7970723220435;1956.6673665476903;2060.0250395295343;2156.266869264442;2269.54609078185;8842.646294148226;9286.024967378464;9447.90552094484;10483.68548943533;12695.238285702888;1649.5876030992251;1705.7441383792407;1726.5683579105223;1755.5172327609882;1809.6380723855282;61487;61789.38210617926;62779.6861015692;67840.6607966957;69423.65288173578;10044.397336159707;10165;10375.533101010473;10436.947815260875;11020.559177632806;13675.111117777313;13689.931550342384;13691.041627086208;13694.93152534231;13706;6968.825779355557;7050.576965381991;7117.82205444868;7179.569225846466;7213.278672132849;6554.901676474857;6562.737490500326;6761.729530818712;6793.422559082534;6880.346367095118;4269.338252570857;4324.762138082436;4362.345648152803;4397.670174736908;4537.478190008125;1753.8947663140252;1769.177332540375;1782.5900042990186;1815.482587462567;1850.1951651031845;821.0311832038252;827.4580150001758;867.7049803066861;872.7948932000975;877.2762470961483;17055.232514536874;17952;18043.548911277325;18149.364772233173;18305.806180870113;856.4176360075085;863.1282404771226;865.1002956924854;886.6276164011131;909.1045320359441;9546.713598592054;10003;10166.186705063577;10173.389596624227;10173.593056277667;347701;352166;380270;386051;388692;47884.760576197594;62568.74862502724;70440.64779676069;83600;84275.57862210649;871.6513394642067;897.9191872731485;915.8717779510873;918.1919910478736;919.1038737231146;15693.921530392732;15887.364505420117;16301.347946082024;16377.918110409848;16446.67106657884;40173.59826401698;40381.192376151725;40595;40687.79656101701;40986.590134098275;49734.502654974436;49960.500394995586;54183.45816541942;54869.45130548644;55520.72239638776;7673.040869891263;7707.34487568563;7714.575698336421;7862.213778622092;8109.229623185788;12074.39628018613;12125.514979401078;12262.509499620272;12483.687907802014;12639.747205055663;10364.378137311785;10678.786424271315;11006.504707288352;11007.559697612005;11195.160362972647;10548.841767373342;10569.365838049742;10631.989960953697;10752.354858708502;10794.892051079389;10025.147862431775;10404.219683523917;10511.211659125443;10857.945710271399;10871.347719136877;7548.886766698394;7711.1517733048195;7953.920460795318;7956.602169891598;8050.194980501903;8304.128066553167;10416.843747343644;10854.782904342026;10877.401742904216;10883.619073332395 08:51:26 INFO - PROCESS | 25457 | |3;mootools.html;1260.0486632592392;1395.3372148229591;1439.2228196773742;1496.3042185383795;1700.1499250374804;6332.556721029524;7276.890846637298;7493.363064139541;7574;7615.847683046336;7286.489945703803;7429.294217049385;7434.03357563516;7671.769846904588;7677.3090421862025;2120.3426937649338;2165.523584811339;2166.11189412341;2167.111484291441;2167.674848772681;2122.8620139690947;2156.0944403350554;2189.4964158243615;2194.550117225973;2196.242296407737;2108.7996640319143;2138.0699395762817;2138.9893050534724;2147.0552956699003;2210.6794514795347;2127.6808478728235;2133.978660213409;2135.39141344716;2139.283340081067;2140.518383363746;2038.6738121900573;2091.006771783406;2092.654711972523;2093.591749608827;2112.852100352976;7565.886511702329;7721.498102623312;7729.922700772977;7731;7746.30283274508;4297.35539669049;4431.468223813131;4434.06884554244;4481.619062379702;4485.2599321111975;2897.27568107973;2971.3463038131576;3014.894478693257;3038.3163788147713;3046.7562594992346;949.3022628368213;954.8758661373977;958.2717134977397;959.9808003839884;973.8928717841031;10172.186225102048;10403.11573516252;10443.060124588748;10447.111995480393;10453.320534165256;4167;4170.332746760504;4230.788460576959;4269.615734583902;4325.76208308546;8325.583720813935;8420.115887831813;8421.4947103174;8426.662933482592;8430.578471076422;4504.842330518416;4507.864764057083;4518.096380723869;4518.141553104965;4522.525134860858;3352;3730.869419570302;4126.66986641068;4178.811953462102;4180.372944058355;3286.9342613147605;3414.9658503415144;3420.6066302375048;3497.370473314777;3501.3172431376147;960.1166926427563;982.5873133284038;987.3976874106789;987.827130252203;997.217184510156;738.9408847292203;747.1818358896999;749.6663984526833;752.236479972828;772.9149793522708;5135.845924622267;5219.608529360313;5409.377921538995;5492.780288788404;5573.832785016456;368.02289920261745;377.8167588719469;379.5142217961014;401.94573732546314;414.4653397117684;3469.8029179932882;3510.684038436553;3517.1558825881602;3537.9292414151573;3583.552055993001;9296.256299496024;9582;9609.711708648752;9620.230381569458;9655.468949207861;9521.809563808685;9523.57143928525;9524.619015239312;9544.33189676716;9552.092551207625;7241;7261.41908647307;7275.272472752682;7391.371733402721;7397.482176247719;5097.490250974873;5198.402183748917;5202.609804264771;5287.418383977682;5288.4182739897815;8595.45064206241;8621.956890215508;8660.523671198145;8731.476111433334;8753.168448997336;8821.029686734582;8886.111388861062;8942.73171804847;8992;9006.549672516217;6209.223847019122;6258.155149054819;6273.780417685359;6276.6547839869245;6278.466330361788;6432.774852880127;6586.934130658824;6675.499337549801;6869.519133660596;7068.787936361918;6320.1467801848;6346.746130154742;6370.93629063722;6373.808785736435;6438.774642887476;6106.419890110433;6172.567920245539;6254.906176407449;6279.780207692709;6424.51816113784;6095.420935011167;6147.631142131487;6271.874562508633;6291.559590828781;6509.707063182263;4594.609458195957;4735.550122738335;4776;4825.131476334293;4858.562729354375;4537.296719008559;4636.907261854676;4738.715677059249;4802.783874725716;4909.1899836528 08:51:26 INFO - PROCESS | 25457 | |4;prototype.html;283.9886404543818;288.8599029470707;290.12237980110166;297.08497826693787;297.84958595909075;4863.2705094235835;4872.001239745852;4891.315215869777;4895.632827537931;4897.110704684235;4795.448523419807;4798.280257961307;4803.207470767319;4824.541668541492;4906.435759887614;3632.400653892111;3738.3084129436006;3740.8690695825653;3770.830312635924;3792.2036372361836;3773.245350929811;3789.450529673196;3799.7150213733944;3800.5629352624387;3801.0497375656087;3730.813459327023;3751.2872554214614;3761.13493896404;3764.228333191689;3829.5021647185827;3583.94624080639;3600.4419315006216;3764.6235376462273;3777.6033516480647;3791.374423220165;3310.7185889199204;3319.186799234179;3328.284418849959;3335.5163501292177;3344.749143814199;4720.716756994591;4723.952760472385;4729.59798417135;4732.621390288768;4762.738049407281;4161.500619925598;4292.270314046619;4292.399064131024;4304.827806887721;4373.212821692094;5047.3438453001245;5058.595312375001;5084.034033533654;5087.186050231945;5107.4892510749;3633.291508155911;3662.0661731258624;3665.157013886794;3667.6149004354425;3676.448532720087;5053.8736531587;5253.684778913239;5354.812581559627;5356.491133342326;5363.6245462817615;3685.6867166291167;3718.572364178154;3720.925581488409;3735.1035751419463;3735.3089678409583;3752.3996160614715;3762.5484941806612;3763.6048214937587;3770.170562476251;3826.100866296418;4230.2174097792;4252.74483530983;4253.255680255943;4258.957410425918;4263.168682107022;4831.75150992233;5013.498650134958;5041.873953151201;5049.469805670426;5053.444121146671;1283.4994352202648;1297.8189847238966;1301.4598941439385;1308.613958882132;1310.1156719214682;794.4875555266821;815.7022686719305;829.5354601423222;829.5648528046513;833.7790485521342;4460.397846290709;4519.209138400766;4532.575072336284;4552.795124219418;4578.198815207392;5216.478352164829;5221.895562088813;5257.737113144404;5259.47405259471;5296.708681022581;4396.441069241034;4541.751832842764;4542.568455996675;4545.227311357077;4548.74981876;2715.090444701047;2751.5872619106894;2790.7069757675154;2819.5629677400034;2821.7742580593504;4974.731443481961;4981.526754958273;5017.448080711119;5019.347484826949;5073.416557095908;5172.431032586413;5334.35949205146;5334.946650533445;5343.305370301836;5350.438203988525;4908.312836202863;4915.26271059344;4925.532074452921;4941.579965702884;4947.183714687037;5187.662801917863;5211.514718305265;5225.5819534437915;5237.764300606405;5333.653312534673;4957.380327459067;5101.642884998015;5136.767175877837;5145.4563630911325;5161.66449180802;4915;4942.481039490802;4946.35697359341;4948.678335908155;4954.578860796729;3742.3825068863334;3759.830807613609;3768.1333293342136;3774.1319496516503;3821.579626241056;4967.652264341606;5187.429382767817;5190.195519694454;5202.514315408761;5225.216217567243;5016.473270306711;5037.445880953165;5052.1411360069615;5057.747112644427;5061.417936937152;4966.180580204225;5027.195648696337;5093;5100.9489905101955;5175.4565770593545;4801.975990120027;4921.351347298551;4943.467525067241;4956.628252881121;4989.775460104376;4884.218525036119;4931.630127740362;5081.212412076134;5086.64393492452;5087.592992560512;4953.578945789648;4959.181735013683;4980;4995.175795993766;4998.2002879539095 08:51:26 INFO - PROCESS | 25457 | |5;yui.html;614.7540983606557;616.0509453366033;625.7966160997676;710.5594531390539;738.6897503048717;12571.182873113241;13740.58778236652;13756.10585311954;13770.24263665498;13803.240821754813;13501.662458438544;13625.454981800742;13637.249951252677;13659.043866929318;13671.863281367183;627.3569591169042;758.4198088462331;771.2403282766485;788.1212448120361;792.18405042806;784.7096574267529;786.1509569664748;786.7600381883523;786.838698066895;787.1027029186703;837.3845223760532;838.4256784102909;838.7106448275366;838.8238469921293;839.0602525171786;656.9277379488252;668.9197296324423;675.790504943469;675.8107729835654;830.5141492227054;576.435093608262;592.6918002638604;593.5874567175814;598.4195330529379;599.4065874783976;13176.60470185896;13263.801042984363;13269.274994250782;13309.201447913156;13318.334083295797;12138.271703697807;12511.311877846714;12516;12869.871301286961;13102.934485327512;11584.131190160771;11705.892939270927;11746.29522228669;11790.469428875722;11830.526778928832;4005.319095753729;4041.9191616167805;4046.473958385391;4217.578242175789;4235.57644235577;61542.384576154116;61798.69100654468;65094.67452662754;65565;66258.3374166257;9842.409455432697;10211.234157438223;10275.640352587623;10365.222608304408;10419.687409377731;19040.52398690016;19043.42869713911;19056.85658860473;19059.80940190608;19061.428157155307;6417.165768450165;6610.636414997125;6634.568753031038;6770.898436523456;6850.828729281709;3512.086857417039;3602.5136606558303;3706.9073273167846;3713.628637136265;3726.571444283888;2596.792256619466;2678.049292501173;2681.410089780245;2682.369643133862;2683.6511253537305;486.74932409808974;490.1911845454957;496.3200415430883;497.9253112033152;504.3166509379811;430.47482071872264;492.58376671712347;527.8680329917521;539.981100661475;550.8870681510281;7548.207438218908;7879.684812607432;7892.802679933047;8062.758117256374;8072.636731347104;494.4659767451144;515.2657463115046;520.9762816066448;554.8917960997567;556.8552176434156;3574.1422058705725;3671.7980511071614;3927.410888366711;3944.5660977292473;4017.216642754691;93715.53142234382;95569;96140.51929740307;97473;97815;45330;45626.77186614046;71914.64042679753;85414.57292713497;87311.56344218238;64450.6777466119;65010.67494662497;67470.32529674738;69032;70080.29919700738;7642.4650274480255;8414.621342039745;8832;8900.688475903313;9040.502772347583;37317.81341093277;40158.79920600378;40540.1891962165;41762.1647567053;43525.3471197926;39841.80079099702;40315.1936961265;40520.594794052864;57866.42133578779;59396.703016484644;9703.17523010552;9714.522796686799;9953.353032052893;10082.344647597884;10973.847745986557;12662.620121396372;12831.871681283441;12872.35638218104;13044;14240.857591423954;12051.51793928233;12173.452194651085;12572.811407829073;12878.613641590768;12890.935545322214;11038.889611104107;11744.060475162138;11747.29516229029;11749.47127379253;12554.686132846751;11956.880431195577;12422.875771242172;12911.5480958166;13073;13091.41088651032;10015.393735895314;10165.390076595428;10220.642277520252;10525.842112368167;10537.315074520131;10214.795704085727;10443.216758742972;10482;10482.68551943443;10655.786884262116 08:51:26 INFO - PROCESS | 25457 | __end_tp_report 08:51:26 INFO - PROCESS | 25457 | __start_cc_report 08:51:26 INFO - PROCESS | 25457 | _x_x_mozilla_cycle_collect,1026 08:51:26 INFO - PROCESS | 25457 | __end_cc_report 08:51:26 INFO - PROCESS | 25457 | __startTimestamp1470930686819__endTimestamp 08:51:26 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | ------- Summary: start ------- 08:51:26 INFO - PROCESS | 25457 | Number of tests: 6 08:51:26 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | [#0] dojo.html Cycles:165 Average:4766.36 Median:4884.37 stddev:505.65 (10.4%) stddev-sans-first:479.07 08:51:26 INFO - PROCESS | 25457 | Values: 2646.4 2657.4 2673.1 2698.2 2703.3 4617.7 4697.1 4786.0 4929.9 4932.8 4825.7 4859.9 4892.3 4908.3 4911.9 4359.0 4366.1 4461.8 4526.7 4566.4 4470.3 4516.1 4525.9 4536.1 4550.4 4271.3 4358.6 4372.0 4421.0 4434.2 4392.0 4409.0 4427.7 4429.9 4448.8 4425.1 4477.8 4484.7 4494.3 4521.8 4574.8 4919.3 4930.8 4971.2 4977.2 5070.4 5111.3 5121.1 5201.0 5201.1 5164.5 5197.4 5201.6 5205.7 5227.6 4655.5 4791.7 4861.7 4869.8 4872.4 5296.3 5333.0 5360.7 5380.1 5381.4 5149.8 5152.9 5153.1 5160.0 5165.8 4901.3 5070.9 5245.7 5256.6 5263.0 5039.2 5139.8 5143.4 5145.5 5151.9 4969.3 5076.1 5078.3 5079.8 5123.8 4317.9 4325.3 4331.1 4332.9 4380.6 3709.8 3731.3 3733.3 3816.0 3854.0 4547.5 4548.1 4589.1 4590.3 4590.8 5224.1 5257.8 5259.4 5262.4 5324.6 4369.0 4378.1 4538.6 4549.4 4549.8 4573.8 4604.3 4604.3 4646.3 4653.8 4827.0 4828.2 4835.3 4840.5 4884.6 5024.6 5049.5 5141.9 5152.8 5200.4 4803.3 4818.0 4832.3 4867.5 4884.4 5051.3 5052.4 5062.0 5068.4 5112.2 4935.8 5104.5 5117.1 5151.2 5175.4 5034.9 5084.9 5086.2 5112.7 5126.2 5077.7 5082.0 5083.5 5092.7 5123.6 4742.1 4765.1 4976.0 4987.6 5034.2 4847.5 4886.8 4921.8 4949.6 4963.2 4811.4 4812.5 4822.9 4826.7 4852.6 08:51:26 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | [#1] ext.html Cycles:180 Average:48888.77 Median:33933.58 stddev:87018.80 (256.4%) stddev-sans-first:87187.23 08:51:26 INFO - PROCESS | 25457 | Values: 551.9 559.9 560.0 563.0 578.7 39138.0 41233.6 41394.8 41452.0 45146.0 41855.0 42362.4 42363.8 42432.6 42492.2 7458.6 7779.5 7932.2 7944.6 7947.1 7518.8 7571.2 7587.0 9947.5 10317.4 10238.0 10310.1 10320.2 10324.5 10326.8 10163.9 10209.2 10215.3 10229.1 10229.8 10012.6 10087.6 10090.9 10093.8 10095.8 39802.6 40052.2 40075.2 40146.6 40216.2 3010.3 3089.4 3268.6 3645.1 3728.9 29306.3 29526.6 30117.8 31931.2 33742.5 3165.6 3172.2 3227.3 3228.9 3295.3 270287.0 274884.0 275435.0 376394.0 379802.0 53450.0 53736.0 53813.2 53824.0 53853.2 33561.2 34822.8 57001.7 57154.0 57180.1 14892.3 15027.9 15088.7 15097.5 15115.8 11667.0 11721.8 11761.9 11774.8 11805.1 16747.7 17990.2 18724.8 18867.8 19417.4 6863.8 7012.2 7076.9 7136.0 7301.1 821.9 897.6 900.3 967.7 1013.6 27057.0 28602.7 28853.3 29144.6 29222.6 975.8 989.4 1016.2 1018.6 1062.2 14868.8 15333.2 15393.5 15561.8 15568.1 364513.0 371607.0 371779.0 372892.0 374043.0 51067.7 51431.5 51478.7 51593.0 51660.5 1060.2 1137.2 1175.0 1199.2 1218.1 28101.3 29406.4 29741.7 30070.0 30685.0 42063.9 42681.6 42701.4 42732.1 42752.8 297492.0 299922.0 300076.0 300691.0 301254.0 25593.2 26622.7 26750.5 27856.9 27913.3 42073.8 42857.8 43395.1 43478.1 43829.5 36205.0 36208.3 36600.8 36768.8 37301.0 36706.0 36725.3 37015.0 37332.0 37901.6 36630.0 37179.0 37289.3 37451.6 37721.8 31020.9 33178.5 33534.0 33852.7 34014.5 25529.7 25895.4 26011.8 26081.8 26284.0 08:51:26 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | [#2] jquery.html Cycles:180 Average:23510.77 Median:20281.48 stddev:61293.91 (302.2%) stddev-sans-first:61441.16 08:51:26 INFO - PROCESS | 25457 | Values: 335.4 362.4 368.4 372.1 389.4 13052.9 16506.0 16762.9 16820.7 17769.6 15709.4 15971.3 17012.9 17865.2 17954.9 4978.4 4992.8 5108.2 5128.4 5132.6 3770.3 5036.8 5119.6 5584.7 5854.6 5888.1 5895.0 5904.9 5906.2 5919.1 5159.3 5402.8 5420.7 5517.1 5890.9 5330.3 5342.5 5343.8 5348.1 5360.8 12020.6 12184.4 12241.4 12373.2 12455.5 1844.8 1956.7 2060.0 2156.3 2269.5 8842.6 9286.0 9447.9 10483.7 12695.2 1649.6 1705.7 1726.6 1755.5 1809.6 61487.0 61789.4 62779.7 67840.7 69423.7 10044.4 10165.0 10375.5 10436.9 11020.6 13675.1 13689.9 13691.0 13694.9 13706.0 6968.8 7050.6 7117.8 7179.6 7213.3 6554.9 6562.7 6761.7 6793.4 6880.3 4269.3 4324.8 4362.3 4397.7 4537.5 1753.9 1769.2 1782.6 1815.5 1850.2 821.0 827.5 867.7 872.8 877.3 17055.2 17952.0 18043.5 18149.4 18305.8 856.4 863.1 865.1 886.6 909.1 9546.7 10003.0 10166.2 10173.4 10173.6 347701.0 352166.0 380270.0 386051.0 388692.0 47884.8 62568.7 70440.6 83600.0 84275.6 871.7 897.9 915.9 918.2 919.1 15693.9 15887.4 16301.3 16377.9 16446.7 40173.6 40381.2 40595.0 40687.8 40986.6 49734.5 49960.5 54183.5 54869.5 55520.7 7673.0 7707.3 7714.6 7862.2 8109.2 12074.4 12125.5 12262.5 12483.7 12639.7 10364.4 10678.8 11006.5 11007.6 11195.2 10548.8 10569.4 10632.0 10752.4 10794.9 10025.1 10404.2 10511.2 10857.9 10871.3 7548.9 7711.2 7953.9 7956.6 8050.2 8304.1 10416.8 10854.8 10877.4 10883.6 08:51:26 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | [#3] mootools.html Cycles:180 Average:4984.64 Median:5287.92 stddev:2804.19 (53.0%) stddev-sans-first:2798.08 08:51:26 INFO - PROCESS | 25457 | Values: 1260.0 1395.3 1439.2 1496.3 1700.1 6332.6 7276.9 7493.4 7574.0 7615.8 7286.5 7429.3 7434.0 7671.8 7677.3 2120.3 2165.5 2166.1 2167.1 2167.7 2122.9 2156.1 2189.5 2194.6 2196.2 2108.8 2138.1 2139.0 2147.1 2210.7 2127.7 2134.0 2135.4 2139.3 2140.5 2038.7 2091.0 2092.7 2093.6 2112.9 7565.9 7721.5 7729.9 7731.0 7746.3 4297.4 4431.5 4434.1 4481.6 4485.3 2897.3 2971.3 3014.9 3038.3 3046.8 949.3 954.9 958.3 960.0 973.9 10172.2 10403.1 10443.1 10447.1 10453.3 4167.0 4170.3 4230.8 4269.6 4325.8 8325.6 8420.1 8421.5 8426.7 8430.6 4504.8 4507.9 4518.1 4518.1 4522.5 3352.0 3730.9 4126.7 4178.8 4180.4 3286.9 3415.0 3420.6 3497.4 3501.3 960.1 982.6 987.4 987.8 997.2 738.9 747.2 749.7 752.2 772.9 5135.8 5219.6 5409.4 5492.8 5573.8 368.0 377.8 379.5 401.9 414.5 3469.8 3510.7 3517.2 3537.9 3583.6 9296.3 9582.0 9609.7 9620.2 9655.5 9521.8 9523.6 9524.6 9544.3 9552.1 7241.0 7261.4 7275.3 7391.4 7397.5 5097.5 5198.4 5202.6 5287.4 5288.4 8595.5 8622.0 8660.5 8731.5 8753.2 8821.0 8886.1 8942.7 8992.0 9006.5 6209.2 6258.2 6273.8 6276.7 6278.5 6432.8 6586.9 6675.5 6869.5 7068.8 6320.1 6346.7 6370.9 6373.8 6438.8 6106.4 6172.6 6254.9 6279.8 6424.5 6095.4 6147.6 6271.9 6291.6 6509.7 4594.6 4735.6 4776.0 4825.1 4858.6 4537.3 4636.9 4738.7 4802.8 4909.2 08:51:26 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | [#4] prototype.html Cycles:180 Average:4216.33 Median:4847.51 stddev:1225.06 (25.3%) stddev-sans-first:1192.41 08:51:26 INFO - PROCESS | 25457 | Values: 284.0 288.9 290.1 297.1 297.8 4863.3 4872.0 4891.3 4895.6 4897.1 4795.4 4798.3 4803.2 4824.5 4906.4 3632.4 3738.3 3740.9 3770.8 3792.2 3773.2 3789.5 3799.7 3800.6 3801.0 3730.8 3751.3 3761.1 3764.2 3829.5 3583.9 3600.4 3764.6 3777.6 3791.4 3310.7 3319.2 3328.3 3335.5 3344.7 4720.7 4724.0 4729.6 4732.6 4762.7 4161.5 4292.3 4292.4 4304.8 4373.2 5047.3 5058.6 5084.0 5087.2 5107.5 3633.3 3662.1 3665.2 3667.6 3676.4 5053.9 5253.7 5354.8 5356.5 5363.6 3685.7 3718.6 3720.9 3735.1 3735.3 3752.4 3762.5 3763.6 3770.2 3826.1 4230.2 4252.7 4253.3 4259.0 4263.2 4831.8 5013.5 5041.9 5049.5 5053.4 1283.5 1297.8 1301.5 1308.6 1310.1 794.5 815.7 829.5 829.6 833.8 4460.4 4519.2 4532.6 4552.8 4578.2 5216.5 5221.9 5257.7 5259.5 5296.7 4396.4 4541.8 4542.6 4545.2 4548.7 2715.1 2751.6 2790.7 2819.6 2821.8 4974.7 4981.5 5017.4 5019.3 5073.4 5172.4 5334.4 5334.9 5343.3 5350.4 4908.3 4915.3 4925.5 4941.6 4947.2 5187.7 5211.5 5225.6 5237.8 5333.7 4957.4 5101.6 5136.8 5145.5 5161.7 4915.0 4942.5 4946.4 4948.7 4954.6 3742.4 3759.8 3768.1 3774.1 3821.6 4967.7 5187.4 5190.2 5202.5 5225.2 5016.5 5037.4 5052.1 5057.7 5061.4 4966.2 5027.2 5093.0 5100.9 5175.5 4802.0 4921.4 4943.5 4956.6 4989.8 4884.2 4931.6 5081.2 5086.6 5087.6 4953.6 4959.2 4980.0 4995.2 4998.2 08:51:26 INFO - PROCESS | 25457 | 08:51:26 INFO - PROCESS | 25457 | [#5] yui.html Cycles:180 Average:16693.98 Median:22989.87 stddev:23148.00 (100.7%) stddev-sans-first:23181.45 08:51:26 INFO - PROCESS | 25457 | Values: 614.8 616.1 625.8 710.6 738.7 12571.2 13740.6 13756.1 13770.2 13803.2 13501.7 13625.5 13637.2 13659.0 13671.9 627.4 758.4 771.2 788.1 792.2 784.7 786.2 786.8 786.8 787.1 837.4 838.4 838.7 838.8 839.1 656.9 668.9 675.8 675.8 830.5 576.4 592.7 593.6 598.4 599.4 13176.6 13263.8 13269.3 13309.2 13318.3 12138.3 12511.3 12516.0 12869.9 13102.9 11584.1 11705.9 11746.3 11790.5 11830.5 4005.3 4041.9 4046.5 4217.6 4235.6 61542.4 61798.7 65094.7 65565.0 66258.3 9842.4 10211.2 10275.6 10365.2 10419.7 19040.5 19043.4 19056.9 19059.8 19061.4 6417.2 6610.6 6634.6 6770.9 6850.8 3512.1 3602.5 3706.9 3713.6 3726.6 2596.8 2678.0 2681.4 2682.4 2683.7 486.7 490.2 496.3 497.9 504.3 430.5 492.6 527.9 540.0 550.9 7548.2 7879.7 7892.8 8062.8 8072.6 494.5 515.3 521.0 554.9 556.9 3574.1 3671.8 3927.4 3944.6 4017.2 93715.5 95569.0 96140.5 97473.0 97815.0 45330.0 45626.8 71914.6 85414.6 87311.6 64450.7 65010.7 67470.3 69032.0 70080.3 7642.5 8414.6 8832.0 8900.7 9040.5 37317.8 40158.8 40540.2 41762.2 43525.3 39841.8 40315.2 40520.6 57866.4 59396.7 9703.2 9714.5 9953.4 10082.3 10973.8 12662.6 12831.9 12872.4 13044.0 14240.9 12051.5 12173.5 12572.8 12878.6 12890.9 11038.9 11744.1 11747.3 11749.5 12554.7 11956.9 12422.9 12911.5 13073.0 13091.4 10015.4 10165.4 10220.6 10525.8 10537.3 10214.8 10443.2 10482.0 10482.7 10655.8 08:51:26 INFO - PROCESS | 25457 | -------- Summary: end -------- 08:51:26 INFO - PROCESS | 25457 | 08:51:27 INFO - TEST-INFO | 25457: exit 0 08:51:27 INFO - TEST-OK | dromaeo_css | took 1120693ms 08:51:27 INFO - TEST-START | kraken 08:51:27 INFO - operating with platform_type : linux_ 08:51:27 INFO - Initialising browser for kraken test... 08:51:27 INFO - TEST-INFO | started process 25761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV0MHRH/profile http://localhost:60869/getInfo.html) 08:51:32 INFO - PROCESS | 25761 | __metrics Screen width/height:1600/1200 08:51:32 INFO - PROCESS | 25761 | colorDepth:24 08:51:32 INFO - PROCESS | 25761 | Browser inner width/height: 1024/697 08:51:32 INFO - PROCESS | 25761 | __metrics 08:51:33 INFO - PROCESS | 25761 | 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" 08:51:33 INFO - PROCESS | 25761 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:51:34 INFO - TEST-INFO | 25761: exit 0 08:51:34 INFO - Browser initialized. 08:51:34 INFO - Running cycle 1/1 for kraken test... 08:51:34 INFO - TEST-INFO | started process 25844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV0MHRH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:46 INFO - PROCESS | 25844 | RSS: Main: 157331456 08:51:46 INFO - PROCESS | 25844 | 08:51:48 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60869/tests/kraken/driver.html?testName=audio-beat-detection) 08:51:49 INFO - PROCESS | 25844 | RSS: Main: 213069824 08:51:49 INFO - PROCESS | 25844 | 08:51:52 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60869/tests/kraken/driver.html?testName=audio-dft) 08:51:53 INFO - PROCESS | 25844 | RSS: Main: 191733760 08:51:53 INFO - PROCESS | 25844 | 08:51:56 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60869/tests/kraken/driver.html?testName=audio-fft) 08:51:57 INFO - PROCESS | 25844 | RSS: Main: 272822272 08:51:57 INFO - PROCESS | 25844 | 08:51:59 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60869/tests/kraken/driver.html?testName=audio-oscillator) 08:51:59 INFO - PROCESS | 25844 | RSS: Main: 180174848 08:51:59 INFO - PROCESS | 25844 | 08:52:02 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60869/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:52:02 INFO - PROCESS | 25844 | RSS: Main: 183996416 08:52:02 INFO - PROCESS | 25844 | 08:52:05 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60869/tests/kraken/driver.html?testName=imaging-darkroom) 08:52:06 INFO - PROCESS | 25844 | RSS: Main: 220905472 08:52:06 INFO - PROCESS | 25844 | 08:52:09 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60869/tests/kraken/driver.html?testName=imaging-desaturate) 08:52:10 INFO - PROCESS | 25844 | RSS: Main: 220614656 08:52:10 INFO - PROCESS | 25844 | 08:52:13 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60869/tests/kraken/driver.html?testName=json-parse-financial) 08:52:14 INFO - PROCESS | 25844 | RSS: Main: 220221440 08:52:14 INFO - PROCESS | 25844 | 08:52:15 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60869/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:52:16 INFO - PROCESS | 25844 | RSS: Main: 184795136 08:52:16 INFO - PROCESS | 25844 | 08:52:18 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60869/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:52:18 INFO - PROCESS | 25844 | RSS: Main: 189235200 08:52:18 INFO - PROCESS | 25844 | 08:52:21 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60869/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:52:21 INFO - PROCESS | 25844 | RSS: Main: 194203648 08:52:21 INFO - PROCESS | 25844 | 08:52:24 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60869/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:52:25 INFO - PROCESS | 25844 | RSS: Main: 189710336 08:52:25 INFO - PROCESS | 25844 | 08:52:28 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60869/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:52:28 INFO - PROCESS | 25844 | RSS: Main: 192573440 08:52:28 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | Cycle 1(1): loaded http://localhost:60869/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60869/tests/kraken/driver.html?testName=ai-astar) 08:52:30 INFO - PROCESS | 25844 | RSS: Main: 243191808 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | __start_tp_report 08:52:30 INFO - PROCESS | 25844 | _x_x_mozilla_page_load 08:52:30 INFO - PROCESS | 25844 | _x_x_mozilla_page_load_details 08:52:30 INFO - PROCESS | 25844 | |i|pagename|runs| 08:52:30 INFO - PROCESS | 25844 | |0;ai-astar;104;116;113;113;113;113;112;113;115;115 08:52:30 INFO - PROCESS | 25844 | |1;audio-beat-detection;150;122;125;195;126;123;154;122;182;121 08:52:30 INFO - PROCESS | 25844 | |2;audio-dft;165;164;162;161;162;161;161;162;160;161 08:52:30 INFO - PROCESS | 25844 | |3;audio-fft;75;75;76;107;76;78;75;75;107;75 08:52:30 INFO - PROCESS | 25844 | |4;audio-oscillator;85;94;127;86;85;85;131;86;86;120 08:52:30 INFO - PROCESS | 25844 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;116;117;116 08:52:30 INFO - PROCESS | 25844 | |6;imaging-darkroom;147;147;146;146;145;146;146;146;146;146 08:52:30 INFO - PROCESS | 25844 | |7;imaging-desaturate;95;95;95;95;96;94;95;95;95;96 08:52:30 INFO - PROCESS | 25844 | |8;json-parse-financial;65;64;62;63;63;65;66;63;63;64 08:52:30 INFO - PROCESS | 25844 | |9;json-stringify-tinderbox;65;66;65;66;66;65;67;66;66;66 08:52:30 INFO - PROCESS | 25844 | |10;stanford-crypto-aes;81;89;80;88;80;82;79;80;78;84 08:52:30 INFO - PROCESS | 25844 | |11;stanford-crypto-ccm;131;132;134;130;128;128;139;130;155;139 08:52:30 INFO - PROCESS | 25844 | |12;stanford-crypto-pbkdf2;168;167;174;168;168;165;168;167;171;167 08:52:30 INFO - PROCESS | 25844 | |13;stanford-crypto-sha256-iterative;67;76;67;67;76;66;67;66;70;69 08:52:30 INFO - PROCESS | 25844 | __end_tp_report 08:52:30 INFO - PROCESS | 25844 | __start_cc_report 08:52:30 INFO - PROCESS | 25844 | _x_x_mozilla_cycle_collect,2005 08:52:30 INFO - PROCESS | 25844 | __end_cc_report 08:52:30 INFO - PROCESS | 25844 | __startTimestamp1470930750783__endTimestamp 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | ------- Summary: start ------- 08:52:30 INFO - PROCESS | 25844 | Number of tests: 14 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:3.30 (2.9%) stddev-sans-first:1.32 08:52:30 INFO - PROCESS | 25844 | Values: 104.0 116.0 113.0 113.0 113.0 113.0 112.0 113.0 115.0 115.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:138.00 stddev:27.41 (19.9%) stddev-sans-first:28.92 08:52:30 INFO - PROCESS | 25844 | Values: 150.0 122.0 125.0 195.0 126.0 123.0 154.0 122.0 182.0 121.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.52 (0.9%) stddev-sans-first:1.13 08:52:30 INFO - PROCESS | 25844 | Values: 165.0 164.0 162.0 161.0 162.0 161.0 161.0 162.0 160.0 161.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.00 stddev:13.26 (17.7%) stddev-sans-first:13.83 08:52:30 INFO - PROCESS | 25844 | Values: 75.0 75.0 76.0 107.0 76.0 78.0 75.0 75.0 107.0 75.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#4] audio-oscillator Cycles:10 Average:98.50 Median:85.50 stddev:19.34 (22.6%) stddev-sans-first:19.89 08:52:30 INFO - PROCESS | 25844 | Values: 85.0 94.0 127.0 86.0 85.0 85.0 131.0 86.0 86.0 120.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 08:52:30 INFO - PROCESS | 25844 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 08:52:30 INFO - PROCESS | 25844 | Values: 147.0 147.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:52:30 INFO - PROCESS | 25844 | Values: 95.0 95.0 95.0 95.0 96.0 94.0 95.0 95.0 95.0 96.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:1.23 (1.9%) stddev-sans-first:1.22 08:52:30 INFO - PROCESS | 25844 | Values: 65.0 64.0 62.0 63.0 63.0 65.0 66.0 63.0 63.0 64.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 08:52:30 INFO - PROCESS | 25844 | Values: 65.0 66.0 65.0 66.0 66.0 65.0 67.0 66.0 66.0 66.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:81.50 stddev:3.75 (4.6%) stddev-sans-first:3.96 08:52:30 INFO - PROCESS | 25844 | Values: 81.0 89.0 80.0 88.0 80.0 82.0 79.0 80.0 78.0 84.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#11] stanford-crypto-ccm Cycles:10 Average:134.60 Median:133.00 stddev:8.19 (6.2%) stddev-sans-first:8.59 08:52:30 INFO - PROCESS | 25844 | Values: 131.0 132.0 134.0 130.0 128.0 128.0 139.0 130.0 155.0 139.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:168.00 stddev:2.50 (1.5%) stddev-sans-first:2.65 08:52:30 INFO - PROCESS | 25844 | Values: 168.0 167.0 174.0 168.0 168.0 165.0 168.0 167.0 171.0 167.0 08:52:30 INFO - PROCESS | 25844 | 08:52:30 INFO - PROCESS | 25844 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:68.00 stddev:3.84 (5.7%) stddev-sans-first:4.00 08:52:30 INFO - PROCESS | 25844 | Values: 67.0 76.0 67.0 67.0 76.0 66.0 67.0 66.0 70.0 69.0 08:52:30 INFO - PROCESS | 25844 | -------- Summary: end -------- 08:52:30 INFO - PROCESS | 25844 | 08:52:31 INFO - TEST-INFO | 25844: exit 0 08:52:31 INFO - TEST-OK | kraken | took 64256ms 08:52:31 INFO - SUITE-END | took 1184s 08:52:31 INFO - Completed test suite (00:19:44) 08:52:31 INFO - javascript benchmark 08:52:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2646.4442467081913, 2657.402084530982, 2673.0644274503925, 2698.217516920093, 2703.3376822678447, 4617.653675974307, 4697.1075495655805, 4786.042791441712, 4929.926051109236, 4932.82735104271, 4825.734584597846, 4859.878503037426, 4892.339534162887, 4908.312836202935, 4911.852644420665, 4359.04101097758, 4366.148601022802, 4461.821527138911, 4526.7283962962165, 4566.429196350457, 4470.329450582422, 4516.051629157864, 4525.932111018337, 4536.070105628338, 4550.431196100488, 4271.337942618868, 4358.585934336233, 4372.0, 4421.0, 4434.157510073076, 4391.9780401098105, 4408.9859332353735, 4427.667924905645, 4429.911401771947, 4448.755318457484, 4425.070735145591, 4477.753723545204, 4484.686071974963, 4494.303382975611, 4521.7739113044545, 4574.817007319703, 4919.311296418504, 4930.827421040246, 4971.204607262821, 4977.203647416432, 5070.3915530136255, 5111.258867464234, 5121.078205922932, 5201.0, 5201.08980928336, 5164.509371609691, 5197.376314842205, 5201.583873290166, 5205.6876587404495, 5227.555657769094, 4655.464621568563, 4791.688540244873, 4861.73260470674, 4869.829555965524, 4872.366592342973, 5296.25852380667, 5333.0, 5360.705161216092, 5380.085385484399, 5381.354237491526, 5149.845504634867, 5152.948470515247, 5153.072446959583, 5160.045391602565, 5165.819196328111, 4901.313816065754, 5070.949290507047, 5245.659032162897, 5256.6057545684225, 5262.97368513155, 5039.168537191323, 5139.820106296262, 5143.3827940646615, 5145.4854514549, 5151.896962060738, 4969.304297398367, 5076.060928728197, 5078.263651770472, 5079.822206222839, 5123.820666276662, 4317.870463886088, 4325.264705000158, 4331.068820203608, 4332.935005974913, 4380.649548036149, 3709.8145092745253, 3731.2910546995986, 3733.3093377724663, 3816.0, 3854.0, 4547.499775024745, 4548.067646132535, 4589.10512450069, 4590.334401511829, 4590.839320623762, 5224.111900976767, 5257.8159764408065, 5259.421463638997, 5262.394824595144, 5324.627276090637, 4368.978155109267, 4378.10248898975, 4538.568835960578, 4549.40857688505, 4549.818007279671, 4573.817047318136, 4604.286335617984, 4604.286335617984, 4646.303054541846, 4653.837115700934, 4827.0, 4828.155072862235, 4835.347228124158, 4840.467548569725, 4884.584810291165, 5024.6231532634465, 5049.469805670498, 5141.948580514297, 5152.793888244429, 5200.401953775364, 4803.279508073677, 4817.98822247332, 4832.323474713613, 4867.537583929521, 4884.3650325457465, 5051.292819005415, 5052.444231134646, 5062.0, 5068.417132029716, 5112.156494178419, 4935.777889995031, 5104.489551044866, 5117.1045067113855, 5151.201563757623, 5175.353080864892, 5034.899302014012, 5084.923726144037, 5086.21163719624, 5112.693238405707, 5126.2054381570915, 5077.7461126944245, 5081.974590127025, 5083.517065878736, 5092.694438333712, 5123.641345105807, 4742.051589682146, 4765.070811191785, 4976.0, 4987.60099192071, 5034.219695947134, 4847.515248475124, 4886.755662216946, 4921.827736029365, 4949.554540091409, 4963.205887058053, 4811.398575178103, 4812.5187481253, 4822.855314340575, 4826.686265392738, 4852.636052296162], "value": 4733.785933825664, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.9457835534131, 559.9104143337065, 560.0087844515208, 562.9662220266782, 578.661483032426, 39138.0, 41233.587664123384, 41394.79302603483, 41452.0, 45146.0, 41854.95362615936, 42362.364564531556, 42363.78818105905, 42432.57567424325, 42492.15015699684, 7458.552486850779, 7779.533228006309, 7932.20677932205, 7944.642491087915, 7947.08608510023, 7518.849623007536, 7571.242875712413, 7587.0, 9947.452890091043, 10317.432541210268, 10238.0, 10310.072093511582, 10320.174386049099, 10324.483775811217, 10326.845097323547, 10163.949180254089, 10209.234307426972, 10215.336003159846, 10229.079382855505, 10229.795404091876, 10012.59949602015, 10087.596496140146, 10090.899091009069, 10093.798124037552, 10095.848562271573, 39802.60197398018, 40052.198956021006, 40075.19849603021, 40146.59853401487, 40216.19567608632, 3010.307629245264, 3089.428455735696, 3268.6241082275606, 3645.052286405527, 3728.8694895678523, 29306.267343316587, 29526.55710164349, 30117.849410753024, 31931.201719956956, 33742.493862592084, 3165.572647692555, 3172.1752344390393, 3227.3222623249144, 3228.9031329060167, 3295.2585668224456, 270287.0, 274884.0, 275435.0, 376393.99999999726, 379802.0, 53450.0, 53736.0, 53813.192802107216, 53824.0, 53853.192202117, 33561.16097097592, 34822.77658351217, 57001.71499142561, 57154.0, 57180.14229786557, 14892.255387230594, 15027.924860375628, 15088.698226035418, 15097.54707358759, 15115.848841511444, 11667.0, 11721.765564688829, 11761.88238117625, 11774.764504709858, 11805.055595552336, 16747.665046699243, 17990.190441430168, 18724.812751872578, 18867.811321886606, 19417.417477475698, 6863.842632389057, 7012.158540975013, 7076.858462830714, 7136.001919462558, 7301.08736407949, 821.8685010398308, 897.6499165325529, 900.3157600223877, 967.6613185385058, 1013.5844303835539, 27057.0, 28602.713972860005, 28853.278668033046, 29144.562831557545, 29222.561661575095, 975.8194733974237, 989.44096585429, 1016.2490899816252, 1018.6282007067515, 1062.18211976779, 14868.776968345483, 15333.156676382683, 15393.538193854203, 15561.84438155604, 15568.138090264343, 364513.0, 371607.0, 371779.0, 372892.0, 374043.0, 51067.744661276454, 51431.485685143416, 51478.74260628748, 51593.0, 51660.48339516557, 1060.2472244706191, 1137.2380505061628, 1174.9823752643717, 1199.1545960098285, 1218.0864351736197, 28101.297467562657, 29406.41187176202, 29741.702582974758, 30070.0, 30685.0, 42063.94840129021, 42681.57318426776, 42701.35947960721, 42732.14535709331, 42752.78623606862, 297492.0, 299922.0, 300076.0, 300691.0, 301254.0, 25593.23220303394, 26622.7337726628, 26750.46499069969, 27856.8607156971, 27913.302167445978, 42073.78963105165, 42857.78571107124, 43395.13209735851, 43478.13043739044, 43829.46596869094, 36205.0, 36208.27583448369, 36600.81699591591, 36768.81615591905, 37301.0, 36706.02019640408, 36725.26549469049, 37015.0, 37332.0, 37901.620983789806, 36630.0, 37179.0, 37289.254214916094, 37451.625483744814, 37721.81139094287, 31020.934557014887, 33178.50232246566, 33534.0, 33852.66147338495, 34014.48978265279, 25529.744702552736, 25895.352616184748, 26011.83172218437, 26081.790770582054, 26284.0], "value": 18341.960313336986, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.39461272403304, 362.3550080856077, 368.3608938491717, 372.14035577815247, 389.36339085595046, 13052.869471305285, 16506.0, 16762.916185419086, 16820.747688784646, 17769.555761105978, 15709.371625135009, 15971.281292341844, 17012.914935425335, 17865.19606617702, 17954.910225448886, 4978.402591689002, 4992.77532511036, 5108.208227724708, 5128.358955130609, 5132.563732082759, 3770.3213421584105, 5036.848894533152, 5119.56483698886, 5584.748686309126, 5854.648721076728, 5888.116782482619, 5894.970525147368, 5904.852378690524, 5906.202662640531, 5919.141724449953, 5159.303494028296, 5402.78388864445, 5420.7018613976215, 5517.062099443064, 5890.941090589082, 5330.307060082203, 5342.465753424665, 5343.759530821122, 5348.090824559834, 5360.812371567015, 12020.579279725254, 12184.39078046103, 12241.387930603434, 12373.195742276814, 12455.501779928793, 1844.7970723220435, 1956.6673665476903, 2060.0250395295343, 2156.266869264442, 2269.54609078185, 8842.646294148226, 9286.024967378464, 9447.90552094484, 10483.68548943533, 12695.238285702888, 1649.5876030992251, 1705.7441383792407, 1726.5683579105223, 1755.5172327609882, 1809.6380723855282, 61487.0, 61789.38210617926, 62779.6861015692, 67840.6607966957, 69423.65288173578, 10044.397336159707, 10165.0, 10375.533101010473, 10436.947815260875, 11020.559177632806, 13675.111117777313, 13689.931550342384, 13691.041627086208, 13694.93152534231, 13706.0, 6968.825779355557, 7050.576965381991, 7117.82205444868, 7179.569225846466, 7213.278672132849, 6554.901676474857, 6562.737490500326, 6761.729530818712, 6793.422559082534, 6880.346367095118, 4269.338252570857, 4324.762138082436, 4362.345648152803, 4397.670174736908, 4537.478190008125, 1753.8947663140252, 1769.177332540375, 1782.5900042990186, 1815.482587462567, 1850.1951651031845, 821.0311832038252, 827.4580150001758, 867.7049803066861, 872.7948932000975, 877.2762470961483, 17055.232514536874, 17952.0, 18043.548911277325, 18149.364772233173, 18305.806180870113, 856.4176360075085, 863.1282404771226, 865.1002956924854, 886.6276164011131, 909.1045320359441, 9546.713598592054, 10003.0, 10166.186705063577, 10173.389596624227, 10173.593056277667, 347701.0, 352166.0, 380270.0, 386051.0, 388692.0, 47884.760576197594, 62568.74862502724, 70440.64779676069, 83600.0, 84275.57862210649, 871.6513394642067, 897.9191872731485, 915.8717779510873, 918.1919910478736, 919.1038737231146, 15693.921530392732, 15887.364505420117, 16301.347946082024, 16377.918110409848, 16446.67106657884, 40173.59826401698, 40381.192376151725, 40595.0, 40687.79656101701, 40986.590134098275, 49734.502654974436, 49960.500394995586, 54183.45816541942, 54869.45130548644, 55520.72239638776, 7673.040869891263, 7707.34487568563, 7714.575698336421, 7862.213778622092, 8109.229623185788, 12074.39628018613, 12125.514979401078, 12262.509499620272, 12483.687907802014, 12639.747205055663, 10364.378137311785, 10678.786424271315, 11006.504707288352, 11007.559697612005, 11195.160362972647, 10548.841767373342, 10569.365838049742, 10631.989960953697, 10752.354858708502, 10794.892051079389, 10025.147862431775, 10404.219683523917, 10511.211659125443, 10857.945710271399, 10871.347719136877, 7548.886766698394, 7711.1517733048195, 7953.920460795318, 7956.602169891598, 8050.194980501903, 8304.128066553167, 10416.843747343644, 10854.782904342026, 10877.401742904216, 10883.619073332395], "value": 8172.7412162807195, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1260.0486632592392, 1395.3372148229591, 1439.2228196773742, 1496.3042185383795, 1700.1499250374804, 6332.556721029524, 7276.890846637298, 7493.363064139541, 7574.0, 7615.847683046336, 7286.489945703803, 7429.294217049385, 7434.03357563516, 7671.769846904588, 7677.3090421862025, 2120.3426937649338, 2165.523584811339, 2166.11189412341, 2167.111484291441, 2167.674848772681, 2122.8620139690947, 2156.0944403350554, 2189.4964158243615, 2194.550117225973, 2196.242296407737, 2108.7996640319143, 2138.0699395762817, 2138.9893050534724, 2147.0552956699003, 2210.6794514795347, 2127.6808478728235, 2133.978660213409, 2135.39141344716, 2139.283340081067, 2140.518383363746, 2038.6738121900573, 2091.006771783406, 2092.654711972523, 2093.591749608827, 2112.852100352976, 7565.886511702329, 7721.498102623312, 7729.922700772977, 7731.0, 7746.30283274508, 4297.35539669049, 4431.468223813131, 4434.06884554244, 4481.619062379702, 4485.2599321111975, 2897.27568107973, 2971.3463038131576, 3014.894478693257, 3038.3163788147713, 3046.7562594992346, 949.3022628368213, 954.8758661373977, 958.2717134977397, 959.9808003839884, 973.8928717841031, 10172.186225102048, 10403.11573516252, 10443.060124588748, 10447.111995480393, 10453.320534165256, 4167.0, 4170.332746760504, 4230.788460576959, 4269.615734583902, 4325.76208308546, 8325.583720813935, 8420.115887831813, 8421.4947103174, 8426.662933482592, 8430.578471076422, 4504.842330518416, 4507.864764057083, 4518.096380723869, 4518.141553104965, 4522.525134860858, 3352.0, 3730.869419570302, 4126.66986641068, 4178.811953462102, 4180.372944058355, 3286.9342613147605, 3414.9658503415144, 3420.6066302375048, 3497.370473314777, 3501.3172431376147, 960.1166926427563, 982.5873133284038, 987.3976874106789, 987.827130252203, 997.217184510156, 738.9408847292203, 747.1818358896999, 749.6663984526833, 752.236479972828, 772.9149793522708, 5135.845924622267, 5219.608529360313, 5409.377921538995, 5492.780288788404, 5573.832785016456, 368.02289920261745, 377.8167588719469, 379.5142217961014, 401.94573732546314, 414.4653397117684, 3469.8029179932882, 3510.684038436553, 3517.1558825881602, 3537.9292414151573, 3583.552055993001, 9296.256299496024, 9582.0, 9609.711708648752, 9620.230381569458, 9655.468949207861, 9521.809563808685, 9523.57143928525, 9524.619015239312, 9544.33189676716, 9552.092551207625, 7241.0, 7261.41908647307, 7275.272472752682, 7391.371733402721, 7397.482176247719, 5097.490250974873, 5198.402183748917, 5202.609804264771, 5287.418383977682, 5288.4182739897815, 8595.45064206241, 8621.956890215508, 8660.523671198145, 8731.476111433334, 8753.168448997336, 8821.029686734582, 8886.111388861062, 8942.73171804847, 8992.0, 9006.549672516217, 6209.223847019122, 6258.155149054819, 6273.780417685359, 6276.6547839869245, 6278.466330361788, 6432.774852880127, 6586.934130658824, 6675.499337549801, 6869.519133660596, 7068.787936361918, 6320.1467801848, 6346.746130154742, 6370.93629063722, 6373.808785736435, 6438.774642887476, 6106.419890110433, 6172.567920245539, 6254.906176407449, 6279.780207692709, 6424.51816113784, 6095.420935011167, 6147.631142131487, 6271.874562508633, 6291.559590828781, 6509.707063182263, 4594.609458195957, 4735.550122738335, 4776.0, 4825.131476334293, 4858.562729354375, 4537.296719008559, 4636.907261854676, 4738.715677059249, 4802.783874725716, 4909.1899836528], "value": 3938.1405578854033, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.9886404543818, 288.8599029470707, 290.12237980110166, 297.08497826693787, 297.84958595909075, 4863.2705094235835, 4872.001239745852, 4891.315215869777, 4895.632827537931, 4897.110704684235, 4795.448523419807, 4798.280257961307, 4803.207470767319, 4824.541668541492, 4906.435759887614, 3632.400653892111, 3738.3084129436006, 3740.8690695825653, 3770.830312635924, 3792.2036372361836, 3773.245350929811, 3789.450529673196, 3799.7150213733944, 3800.5629352624387, 3801.0497375656087, 3730.813459327023, 3751.2872554214614, 3761.13493896404, 3764.228333191689, 3829.5021647185827, 3583.94624080639, 3600.4419315006216, 3764.6235376462273, 3777.6033516480647, 3791.374423220165, 3310.7185889199204, 3319.186799234179, 3328.284418849959, 3335.5163501292177, 3344.749143814199, 4720.716756994591, 4723.952760472385, 4729.59798417135, 4732.621390288768, 4762.738049407281, 4161.500619925598, 4292.270314046619, 4292.399064131024, 4304.827806887721, 4373.212821692094, 5047.3438453001245, 5058.595312375001, 5084.034033533654, 5087.186050231945, 5107.4892510749, 3633.291508155911, 3662.0661731258624, 3665.157013886794, 3667.6149004354425, 3676.448532720087, 5053.8736531587, 5253.684778913239, 5354.812581559627, 5356.491133342326, 5363.6245462817615, 3685.6867166291167, 3718.572364178154, 3720.925581488409, 3735.1035751419463, 3735.3089678409583, 3752.3996160614715, 3762.5484941806612, 3763.6048214937587, 3770.170562476251, 3826.100866296418, 4230.2174097792, 4252.74483530983, 4253.255680255943, 4258.957410425918, 4263.168682107022, 4831.75150992233, 5013.498650134958, 5041.873953151201, 5049.469805670426, 5053.444121146671, 1283.4994352202648, 1297.8189847238966, 1301.4598941439385, 1308.613958882132, 1310.1156719214682, 794.4875555266821, 815.7022686719305, 829.5354601423222, 829.5648528046513, 833.7790485521342, 4460.397846290709, 4519.209138400766, 4532.575072336284, 4552.795124219418, 4578.198815207392, 5216.478352164829, 5221.895562088813, 5257.737113144404, 5259.47405259471, 5296.708681022581, 4396.441069241034, 4541.751832842764, 4542.568455996675, 4545.227311357077, 4548.74981876, 2715.090444701047, 2751.5872619106894, 2790.7069757675154, 2819.5629677400034, 2821.7742580593504, 4974.731443481961, 4981.526754958273, 5017.448080711119, 5019.347484826949, 5073.416557095908, 5172.431032586413, 5334.35949205146, 5334.946650533445, 5343.305370301836, 5350.438203988525, 4908.312836202863, 4915.26271059344, 4925.532074452921, 4941.579965702884, 4947.183714687037, 5187.662801917863, 5211.514718305265, 5225.5819534437915, 5237.764300606405, 5333.653312534673, 4957.380327459067, 5101.642884998015, 5136.767175877837, 5145.4563630911325, 5161.66449180802, 4915.0, 4942.481039490802, 4946.35697359341, 4948.678335908155, 4954.578860796729, 3742.3825068863334, 3759.830807613609, 3768.1333293342136, 3774.1319496516503, 3821.579626241056, 4967.652264341606, 5187.429382767817, 5190.195519694454, 5202.514315408761, 5225.216217567243, 5016.473270306711, 5037.445880953165, 5052.1411360069615, 5057.747112644427, 5061.417936937152, 4966.180580204225, 5027.195648696337, 5093.0, 5100.9489905101955, 5175.4565770593545, 4801.975990120027, 4921.351347298551, 4943.467525067241, 4956.628252881121, 4989.775460104376, 4884.218525036119, 4931.630127740362, 5081.212412076134, 5086.64393492452, 5087.592992560512, 4953.578945789648, 4959.181735013683, 4980.0, 4995.175795993766, 4998.2002879539095], "value": 3820.8552511482744, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.7540983606557, 616.0509453366033, 625.7966160997676, 710.5594531390539, 738.6897503048717, 12571.182873113241, 13740.58778236652, 13756.10585311954, 13770.24263665498, 13803.240821754813, 13501.662458438544, 13625.454981800742, 13637.249951252677, 13659.043866929318, 13671.863281367183, 627.3569591169042, 758.4198088462331, 771.2403282766485, 788.1212448120361, 792.18405042806, 784.7096574267529, 786.1509569664748, 786.7600381883523, 786.838698066895, 787.1027029186703, 837.3845223760532, 838.4256784102909, 838.7106448275366, 838.8238469921293, 839.0602525171786, 656.9277379488252, 668.9197296324423, 675.790504943469, 675.8107729835654, 830.5141492227054, 576.435093608262, 592.6918002638604, 593.5874567175814, 598.4195330529379, 599.4065874783976, 13176.60470185896, 13263.801042984363, 13269.274994250782, 13309.201447913156, 13318.334083295797, 12138.271703697807, 12511.311877846714, 12516.0, 12869.871301286961, 13102.934485327512, 11584.131190160771, 11705.892939270927, 11746.29522228669, 11790.469428875722, 11830.526778928832, 4005.319095753729, 4041.9191616167805, 4046.473958385391, 4217.578242175789, 4235.57644235577, 61542.384576154116, 61798.69100654468, 65094.67452662754, 65565.0, 66258.3374166257, 9842.409455432697, 10211.234157438223, 10275.640352587623, 10365.222608304408, 10419.687409377731, 19040.52398690016, 19043.42869713911, 19056.85658860473, 19059.80940190608, 19061.428157155307, 6417.165768450165, 6610.636414997125, 6634.568753031038, 6770.898436523456, 6850.828729281709, 3512.086857417039, 3602.5136606558303, 3706.9073273167846, 3713.628637136265, 3726.571444283888, 2596.792256619466, 2678.049292501173, 2681.410089780245, 2682.369643133862, 2683.6511253537305, 486.74932409808974, 490.1911845454957, 496.3200415430883, 497.9253112033152, 504.3166509379811, 430.47482071872264, 492.58376671712347, 527.8680329917521, 539.981100661475, 550.8870681510281, 7548.207438218908, 7879.684812607432, 7892.802679933047, 8062.758117256374, 8072.636731347104, 494.4659767451144, 515.2657463115046, 520.9762816066448, 554.8917960997567, 556.8552176434156, 3574.1422058705725, 3671.7980511071614, 3927.410888366711, 3944.5660977292473, 4017.216642754691, 93715.53142234382, 95569.0, 96140.51929740307, 97473.0, 97815.0, 45330.0, 45626.77186614046, 71914.64042679753, 85414.57292713497, 87311.56344218238, 64450.6777466119, 65010.67494662497, 67470.32529674738, 69032.0, 70080.29919700738, 7642.4650274480255, 8414.621342039745, 8832.0, 8900.688475903313, 9040.502772347583, 37317.81341093277, 40158.79920600378, 40540.1891962165, 41762.1647567053, 43525.3471197926, 39841.80079099702, 40315.1936961265, 40520.594794052864, 57866.42133578779, 59396.703016484644, 9703.17523010552, 9714.522796686799, 9953.353032052893, 10082.344647597884, 10973.847745986557, 12662.620121396372, 12831.871681283441, 12872.35638218104, 13044.0, 14240.857591423954, 12051.51793928233, 12173.452194651085, 12572.811407829073, 12878.613641590768, 12890.935545322214, 11038.889611104107, 11744.060475162138, 11747.29516229029, 11749.47127379253, 12554.686132846751, 11956.880431195577, 12422.875771242172, 12911.5480958166, 13073.0, 13091.41088651032, 10015.393735895314, 10165.390076595428, 10220.642277520252, 10525.842112368167, 10537.315074520131, 10214.795704085727, 10443.216758742972, 10482.0, 10482.68551943443, 10655.786884262116], "value": 6432.863192766301, "unit": "score"}], "name": "dromaeo_css", "value": 6399.634560612609, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 115.0, 115.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 122.0, 125.0, 195.0, 126.0, 123.0, 154.0, 122.0, 182.0, 121.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 162.0, 161.0, 162.0, 161.0, 161.0, 162.0, 160.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 107.0, 76.0, 78.0, 75.0, 75.0, 107.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 94.0, 127.0, 86.0, 85.0, 85.0, 131.0, 86.0, 86.0, 120.0], "value": 98.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 62.0, 63.0, 63.0, 65.0, 66.0, 63.0, 63.0, 64.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 67.0, 66.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 89.0, 80.0, 88.0, 80.0, 82.0, 79.0, 80.0, 78.0, 84.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 134.0, 130.0, 128.0, 128.0, 139.0, 130.0, 155.0, 139.0], "value": 134.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 174.0, 168.0, 168.0, 165.0, 168.0, 167.0, 171.0, 167.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 67.0, 67.0, 76.0, 66.0, 67.0, 66.0, 70.0, 69.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1538.5999999999997, "alertThreshold": 2.0}]} 08:52:32 INFO - Return code: 0 08:52:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:52:32 INFO - # TBPL SUCCESS # 08:52:32 INFO - Running post-action listener: _resource_record_post_action 08:52:32 INFO - [mozharness: 2016-08-11 15:52:32.112090Z] Finished run-tests step (success) 08:52:32 INFO - Running post-run listener: _resource_record_post_run 08:52:32 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396521472; Read time: 12; Write time: 287344 08:52:32 INFO - TinderboxPrint: CPU usage
13.0% 08:52:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:52:32 INFO - TinderboxPrint: I/O write bytes / time
396,521,472 / 287,344 08:52:32 INFO - TinderboxPrint: CPU idle
8,340.8 (87.0%) 08:52:32 INFO - TinderboxPrint: CPU user
1,200.7 (12.5%) 08:52:32 INFO - TinderboxPrint: Swap in / out
0 / 0 08:52:32 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 113393664; Read time: 0; Write time: 62244 08:52:32 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 283127808; Read time: 0; Write time: 225100 08:52:32 INFO - Running post-run listener: _upload_blobber_files 08:52:32 INFO - Blob upload gear active. 08:52:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:52:32 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 08:52:32 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'] 08:52:32 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 08:52:33 INFO - (blobuploader) - INFO - Open directory for files ... 08:52:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:52:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:52:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:33 INFO - (blobuploader) - INFO - Done attempting. 08:52:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:52:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:52:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:39 INFO - (blobuploader) - INFO - Done attempting. 08:52:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:52:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:52:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:40 INFO - (blobuploader) - INFO - Done attempting. 08:52:40 INFO - (blobuploader) - INFO - Iteration through files over. 08:52:40 INFO - Return code: 0 08:52:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:52:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:52:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ae769e3917d3145779e2c8d0492dd60ef08e39ca55a502d21e85cee4829f10f530b1df05ee796dc7b42c5ea6ae78cd2952cdba0f60b75cab586805f17abcaca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fc6d9e35862f46f86bf9f3c76c91ca42bf72ae9d979a8054a7977319a49daf7a1fc212a744af0f5d49334e862eff049c3318ab7180a200fd4b22dc1f0929022f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d9a83786c364c99740118b8eba366036ed0596d1a0c7dfb3a8190add8dfd108e470af00e126a4aa91b92d1756f446fae5fee2292a2f0794c8a35801a99b46d1d"} 08:52:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:52:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:52:40 INFO - Contents: 08:52:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ae769e3917d3145779e2c8d0492dd60ef08e39ca55a502d21e85cee4829f10f530b1df05ee796dc7b42c5ea6ae78cd2952cdba0f60b75cab586805f17abcaca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fc6d9e35862f46f86bf9f3c76c91ca42bf72ae9d979a8054a7977319a49daf7a1fc212a744af0f5d49334e862eff049c3318ab7180a200fd4b22dc1f0929022f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d9a83786c364c99740118b8eba366036ed0596d1a0c7dfb3a8190add8dfd108e470af00e126a4aa91b92d1756f446fae5fee2292a2f0794c8a35801a99b46d1d"} 08:52:40 INFO - Running post-run listener: copy_logs_to_upload_dir 08:52:40 INFO - Copying logs to upload dir... 08:52:40 INFO - mkdir: /builds/slave/test/build/upload/logs 08:52:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.299879 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-08-11 08:52:40.298291) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:40.305840) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018 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/3ae769e3917d3145779e2c8d0492dd60ef08e39ca55a502d21e85cee4829f10f530b1df05ee796dc7b42c5ea6ae78cd2952cdba0f60b75cab586805f17abcaca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fc6d9e35862f46f86bf9f3c76c91ca42bf72ae9d979a8054a7977319a49daf7a1fc212a744af0f5d49334e862eff049c3318ab7180a200fd4b22dc1f0929022f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d9a83786c364c99740118b8eba366036ed0596d1a0c7dfb3a8190add8dfd108e470af00e126a4aa91b92d1756f446fae5fee2292a2f0794c8a35801a99b46d1d"} build_url:https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/T3FT6fo0TaybY8fngi_Wow/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/3ae769e3917d3145779e2c8d0492dd60ef08e39ca55a502d21e85cee4829f10f530b1df05ee796dc7b42c5ea6ae78cd2952cdba0f60b75cab586805f17abcaca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fc6d9e35862f46f86bf9f3c76c91ca42bf72ae9d979a8054a7977319a49daf7a1fc212a744af0f5d49334e862eff049c3318ab7180a200fd4b22dc1f0929022f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d9a83786c364c99740118b8eba366036ed0596d1a0c7dfb3a8190add8dfd108e470af00e126a4aa91b92d1756f446fae5fee2292a2f0794c8a35801a99b46d1d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:40.333884) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:40.334276) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1470927701.154058-1306462018 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:40.384164) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 08:52:40.387507) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 08:52:40.387799) ========= ========= Total master_lag: 0.42 =========