builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1472733564.34 results: success (0) buildid: 20160901023002 builduid: 6ae1593ef4eb482ba59858460d3d360d revision: d3bf9bbe73ba35c4257dcd10339f6ccecbb1fd5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.343903) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.344399) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.344691) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.373531) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.373815) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 05:39:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 87.3M=0s 2016-09-01 05:39:24 (87.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116598 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.504680) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.504993) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017778 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.554851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:24.555231) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d3bf9bbe73ba35c4257dcd10339f6ccecbb1fd5e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d3bf9bbe73ba35c4257dcd10339f6ccecbb1fd5e --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=14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 05:39:24,934 truncating revision to first 12 chars 2016-09-01 05:39:24,934 Setting DEBUG logging. 2016-09-01 05:39:24,934 attempt 1/10 2016-09-01 05:39:24,934 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d3bf9bbe73ba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 05:39:25,398 unpacking tar archive at: fx-team-d3bf9bbe73ba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.811058 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:25.379972) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:25.380380) ========= script_repo_revision: d3bf9bbe73ba35c4257dcd10339f6ccecbb1fd5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:25.380774) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:25.381056) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 05:39:25.390365) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-01 05:39:25.390680) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:39:25 INFO - MultiFileLogger online at 20160901 05:39:25 in /builds/slave/test 05:39:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:39:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:39:25 INFO - {'append_to_log': False, 05:39:25 INFO - 'base_work_dir': '/builds/slave/test', 05:39:25 INFO - 'blob_upload_branch': 'Fx-Team', 05:39:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:39:25 INFO - 'branch': 'Fx-Team', 05:39:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:39:25 INFO - 'config_files': ('talos/linux_config.py',), 05:39:25 INFO - 'default_actions': ('clobber', 05:39:25 INFO - 'read-buildbot-config', 05:39:25 INFO - 'download-and-extract', 05:39:25 INFO - 'populate-webroot', 05:39:25 INFO - 'create-virtualenv', 05:39:25 INFO - 'install', 05:39:25 INFO - 'run-tests'), 05:39:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:39:25 INFO - 'download_minidump_stackwalk': True, 05:39:25 INFO - 'download_symbols': 'ondemand', 05:39:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:39:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:39:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:39:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:39:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:39:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:39:25 INFO - 'installer_path': 'installer.exe', 05:39:25 INFO - 'log_level': 'info', 05:39:25 INFO - 'log_name': 'talos', 05:39:25 INFO - 'log_to_console': True, 05:39:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:39:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:39:25 INFO - 'opt_config_files': (), 05:39:25 INFO - 'pip_index': False, 05:39:25 INFO - 'sps_profile': False, 05:39:25 INFO - 'sps_profile_interval': 0, 05:39:25 INFO - 'suite': 'dromaeojs', 05:39:25 INFO - 'system_bits': '32', 05:39:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:39:25 INFO - 'title': 'talos-linux64-ix-036', 05:39:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:39:25 INFO - 'use_talos_json': True, 05:39:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:39:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:39:25 INFO - 'work_dir': 'build'} 05:39:25 INFO - [mozharness: 2016-09-01 12:39:25.865074Z] Running clobber step. 05:39:25 INFO - Running pre-action listener: _resource_record_pre_action 05:39:25 INFO - Running main action method: clobber 05:39:25 INFO - rmtree: /builds/slave/test/build 05:39:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:39:26 INFO - Running post-action listener: _resource_record_post_action 05:39:26 INFO - [mozharness: 2016-09-01 12:39:26.327410Z] Finished clobber step (success) 05:39:26 INFO - [mozharness: 2016-09-01 12:39:26.327511Z] Running read-buildbot-config step. 05:39:26 INFO - Running pre-action listener: _resource_record_pre_action 05:39:26 INFO - Running main action method: read_buildbot_config 05:39:26 INFO - Using buildbot properties: 05:39:26 INFO - { 05:39:26 INFO - "project": "", 05:39:26 INFO - "product": "firefox", 05:39:26 INFO - "script_repo_revision": "production", 05:39:26 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:39:26 INFO - "repository": "", 05:39:26 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:39:26 INFO - "buildid": "20160901023002", 05:39:26 INFO - "pgo_build": "True", 05:39:26 INFO - "basedir": "/builds/slave/test", 05:39:26 INFO - "buildnumber": 413, 05:39:26 INFO - "slavename": "talos-linux64-ix-036", 05:39:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:39:26 INFO - "platform": "ubuntu64_hw", 05:39:26 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:39:26 INFO - "branch": "fx-team", 05:39:26 INFO - "repo_path": "integration/fx-team", 05:39:26 INFO - "revision": "d3bf9bbe73ba35c4257dcd10339f6ccecbb1fd5e", 05:39:26 INFO - "stage_platform": "linux64-pgo", 05:39:26 INFO - "builduid": "6ae1593ef4eb482ba59858460d3d360d", 05:39:26 INFO - "slavebuilddir": "test" 05:39:26 INFO - } 05:39:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:39:26 INFO - Running post-action listener: _resource_record_post_action 05:39:26 INFO - [mozharness: 2016-09-01 12:39:26.329950Z] Finished read-buildbot-config step (success) 05:39:26 INFO - [mozharness: 2016-09-01 12:39:26.330040Z] Running download-and-extract step. 05:39:26 INFO - Running pre-action listener: _resource_record_pre_action 05:39:26 INFO - Running main action method: download_and_extract 05:39:26 INFO - mkdir: /builds/slave/test/build/tests 05:39: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')]} 05:39:26 INFO - trying https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:39:26 INFO - Downloading https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:27 INFO - Downloaded 1361 bytes. 05:39:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:39:27 INFO - Using the following test package requirements: 05:39:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:39:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:39:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:39:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:39:27 INFO - u'jsshell-linux-x86_64.zip'], 05:39:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:39:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:39:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:39:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:39:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:39:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:39:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:27 INFO - trying https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:39:27 INFO - Downloading https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:28 INFO - Downloaded 21905521 bytes. 05:39:28 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 05:39:32 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 05:39:32 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')]} 05:39:32 INFO - trying https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:39:32 INFO - Downloading https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:33 INFO - Downloaded 21905521 bytes. 05:39:33 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 05:39:37 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')]} 05:39:37 INFO - trying https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:39:37 INFO - Downloading https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:38 INFO - Downloaded 14413998 bytes. 05:39:38 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 05:39:39 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')]} 05:39:39 INFO - trying https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:39:39 INFO - Downloading https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/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 05:39:40 INFO - Downloaded 59737155 bytes. 05:39:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:39:40 INFO - mkdir: /builds/slave/test/properties 05:39:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:39:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:39:40 INFO - Contents: 05:39:40 INFO - build_url:https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:39:41 INFO - Running post-action listener: _resource_record_post_action 05:39:41 INFO - Running post-action listener: set_extra_try_arguments 05:39:41 INFO - [mozharness: 2016-09-01 12:39:41.598283Z] Finished download-and-extract step (success) 05:39:41 INFO - [mozharness: 2016-09-01 12:39:41.598509Z] Running populate-webroot step. 05:39:41 INFO - Running pre-action listener: _resource_record_pre_action 05:39:41 INFO - Running main action method: populate_webroot 05:39:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:39:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:39:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:39:41 INFO - '--tppagecycles', 05:39:41 INFO - '7'], 05:39:41 INFO - 'tests': ['tsvgm']}}, 05:39:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:39:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:39:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:39:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:39:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:39:41 INFO - 'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:39:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:39:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:39:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:39:41 INFO - 'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['damp', 'tps']}, 05:39:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:39:41 INFO - 'tests': ['damp', 'tps']}, 05:39:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['dromaeo_dom']}, 05:39:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:39:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['basic_compositor_video']}, 05:39:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:39:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['a11yr', 05:39:41 INFO - 'ts_paint', 05:39:41 INFO - 'tpaint', 05:39:41 INFO - 'sessionrestore', 05:39:41 INFO - 'sessionrestore_no_auto_restore', 05:39:41 INFO - 'tabpaint']}, 05:39:41 INFO - 'other-e10s': {'tests': ['a11yr', 05:39:41 INFO - 'ts_paint', 05:39:41 INFO - 'tpaint', 05:39:41 INFO - 'sessionrestore', 05:39:41 INFO - 'sessionrestore_no_auto_restore', 05:39:41 INFO - 'tabpaint']}, 05:39:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:39:41 INFO - 'ts_paint', 05:39:41 INFO - 'tpaint', 05:39:41 INFO - 'sessionrestore', 05:39:41 INFO - 'sessionrestore_no_auto_restore', 05:39:41 INFO - 'tabpaint']}, 05:39:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:39:41 INFO - 'ts_paint', 05:39:41 INFO - 'tpaint', 05:39:41 INFO - 'sessionrestore', 05:39:41 INFO - 'sessionrestore_no_auto_restore', 05:39:41 INFO - 'tabpaint']}, 05:39:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['a11yr', 05:39:41 INFO - 'ts_paint', 05:39:41 INFO - 'tpaint', 05:39:41 INFO - 'sessionrestore', 05:39:41 INFO - 'sessionrestore_no_auto_restore', 05:39:41 INFO - 'tabpaint']}, 05:39:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['a11yr', 05:39:41 INFO - 'ts_paint', 05:39:41 INFO - 'tpaint', 05:39:41 INFO - 'sessionrestore', 05:39:41 INFO - 'sessionrestore_no_auto_restore', 05:39:41 INFO - 'tabpaint']}, 05:39:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['tsvgx', 05:39:41 INFO - 'tsvgr_opacity', 05:39:41 INFO - 'tart', 05:39:41 INFO - 'tscrollx', 05:39:41 INFO - 'cart']}, 05:39:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:39:41 INFO - 'tsvgr_opacity', 05:39:41 INFO - 'tart', 05:39:41 INFO - 'tscrollx', 05:39:41 INFO - 'cart']}, 05:39:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:39:41 INFO - 'talos_options': ['--disable-e10s'], 05:39:41 INFO - 'tests': ['tp5o']}, 05:39:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:39:41 INFO - 'tests': ['tp5o']}, 05:39:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:39:41 INFO - 'talos_options': ['--disable-e10s', 05:39:41 INFO - '--xperf_path', 05:39:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:39:41 INFO - 'tests': ['tp5n']}, 05:39:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:39:41 INFO - 'talos_options': ['--xperf_path', 05:39:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:39:41 INFO - 'tests': ['tp5n']}}, 05:39:41 INFO - 'talos.zip': {'path': '', 05:39:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:39:41 INFO - Running post-action listener: _resource_record_post_action 05:39:41 INFO - [mozharness: 2016-09-01 12:39:41.613643Z] Finished populate-webroot step (success) 05:39:41 INFO - [mozharness: 2016-09-01 12:39:41.613732Z] Running create-virtualenv step. 05:39:41 INFO - Running pre-action listener: _resource_record_pre_action 05:39:41 INFO - Running main action method: create_virtualenv 05:39:41 INFO - Creating virtualenv /builds/slave/test/build/venv 05:39:41 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 05:39:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:39:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:39:41 INFO - Using real prefix '/usr' 05:39:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:39:42 INFO - Installing distribute.............................................................................................................................................................................................done. 05:39:44 INFO - Installing pip.................done. 05:39:44 INFO - Return code: 0 05:39:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:39:44 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')]} 05:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:44 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')]} 05:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:44 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:39:44 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 05:39:44 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 05:39:44 INFO - Using env: {'DISPLAY': ':0', 05:39:44 INFO - 'HOME': '/home/cltbld', 05:39:44 INFO - 'LANG': 'en_US.UTF-8', 05:39:44 INFO - 'LANGUAGE': 'en_US:en', 05:39:44 INFO - 'LOGNAME': 'cltbld', 05:39:44 INFO - 'MAIL': '/var/mail/cltbld', 05:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:44 INFO - 'MOZ_NO_REMOTE': '1', 05:39:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:44 INFO - 'NO_EM_RESTART': '1', 05:39:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:44 INFO - 'PWD': '/builds/slave/test', 05:39:44 INFO - 'SHELL': '/bin/bash', 05:39:44 INFO - 'SHLVL': '1', 05:39:44 INFO - 'TERM': 'linux', 05:39:44 INFO - 'TMOUT': '86400', 05:39:44 INFO - 'USER': 'cltbld', 05:39:44 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:44 INFO - '_': '/tools/buildbot/bin/python'} 05:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:44 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 05:39:44 INFO - Cleaning up... 05:39:44 INFO - Return code: 0 05:39:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:39:44 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')]} 05:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:44 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')]} 05:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:44 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:39:44 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 05:39:44 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 05:39:44 INFO - Using env: {'DISPLAY': ':0', 05:39:44 INFO - 'HOME': '/home/cltbld', 05:39:44 INFO - 'LANG': 'en_US.UTF-8', 05:39:44 INFO - 'LANGUAGE': 'en_US:en', 05:39:44 INFO - 'LOGNAME': 'cltbld', 05:39:44 INFO - 'MAIL': '/var/mail/cltbld', 05:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:44 INFO - 'MOZ_NO_REMOTE': '1', 05:39:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:44 INFO - 'NO_EM_RESTART': '1', 05:39:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:44 INFO - 'PWD': '/builds/slave/test', 05:39:44 INFO - 'SHELL': '/bin/bash', 05:39:44 INFO - 'SHLVL': '1', 05:39:44 INFO - 'TERM': 'linux', 05:39:44 INFO - 'TMOUT': '86400', 05:39:44 INFO - 'USER': 'cltbld', 05:39:44 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:44 INFO - '_': '/tools/buildbot/bin/python'} 05:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:44 INFO - Downloading/unpacking psutil>=3.1.1 05:39:44 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 05:39:44 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 05:39:44 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 05:39:44 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 05:39:44 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 05:39:44 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 05:39:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:39:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:39:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:39:46 INFO - Installing collected packages: psutil 05:39:46 INFO - Running setup.py install for psutil 05:39:46 INFO - building 'psutil._psutil_linux' extension 05:39:46 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 05:39:46 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 05:39:46 INFO - building 'psutil._psutil_posix' extension 05:39:46 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 05:39:46 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 05:39:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:39:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:39:47 INFO - Successfully installed psutil 05:39:47 INFO - Cleaning up... 05:39:47 INFO - Return code: 0 05:39:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:39:47 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')]} 05:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:47 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')]} 05:39:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:47 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:39:47 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 05:39:47 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 05:39:47 INFO - Using env: {'DISPLAY': ':0', 05:39:47 INFO - 'HOME': '/home/cltbld', 05:39:47 INFO - 'LANG': 'en_US.UTF-8', 05:39:47 INFO - 'LANGUAGE': 'en_US:en', 05:39:47 INFO - 'LOGNAME': 'cltbld', 05:39:47 INFO - 'MAIL': '/var/mail/cltbld', 05:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:47 INFO - 'MOZ_NO_REMOTE': '1', 05:39:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:47 INFO - 'NO_EM_RESTART': '1', 05:39:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:47 INFO - 'PWD': '/builds/slave/test', 05:39:47 INFO - 'SHELL': '/bin/bash', 05:39:47 INFO - 'SHLVL': '1', 05:39:47 INFO - 'TERM': 'linux', 05:39:47 INFO - 'TMOUT': '86400', 05:39:47 INFO - 'USER': 'cltbld', 05:39:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:47 INFO - '_': '/tools/buildbot/bin/python'} 05:39:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:39:47 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 05:39:47 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 05:39:47 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 05:39:47 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 05:39:47 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 05:39:47 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 05:39:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:39:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:39:49 INFO - Installing collected packages: mozsystemmonitor 05:39:49 INFO - Running setup.py install for mozsystemmonitor 05:39:49 INFO - Successfully installed mozsystemmonitor 05:39:49 INFO - Cleaning up... 05:39:49 INFO - Return code: 0 05:39:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:39:49 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')]} 05:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:49 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')]} 05:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:49 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:39:49 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 05:39:49 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 05:39:49 INFO - Using env: {'DISPLAY': ':0', 05:39:49 INFO - 'HOME': '/home/cltbld', 05:39:49 INFO - 'LANG': 'en_US.UTF-8', 05:39:49 INFO - 'LANGUAGE': 'en_US:en', 05:39:49 INFO - 'LOGNAME': 'cltbld', 05:39:49 INFO - 'MAIL': '/var/mail/cltbld', 05:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:49 INFO - 'MOZ_NO_REMOTE': '1', 05:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:49 INFO - 'NO_EM_RESTART': '1', 05:39:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:49 INFO - 'PWD': '/builds/slave/test', 05:39:49 INFO - 'SHELL': '/bin/bash', 05:39:49 INFO - 'SHLVL': '1', 05:39:49 INFO - 'TERM': 'linux', 05:39:49 INFO - 'TMOUT': '86400', 05:39:49 INFO - 'USER': 'cltbld', 05:39:49 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:49 INFO - '_': '/tools/buildbot/bin/python'} 05:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:49 INFO - Downloading/unpacking jsonschema==2.5.1 05:39:49 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 05:39:49 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 05:39:49 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 05:39:49 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 05:39:49 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 05:39:49 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 05:39:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:39:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:39:51 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 05:39:51 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 05:39:51 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 05:39:51 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 05:39:51 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 05:39:51 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 05:39:51 INFO - Downloading functools32-3.2.3-2.tar.gz 05:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:39:51 INFO - warning: no files found matching '*.txt' 05:39:51 INFO - no previously-included directories found matching 'build' 05:39:51 INFO - no previously-included directories found matching 'dist' 05:39:51 INFO - no previously-included directories found matching '.git*' 05:39:51 INFO - Installing collected packages: jsonschema, functools32 05:39:51 INFO - Running setup.py install for functools32 05:39:51 INFO - warning: no files found matching '*.txt' 05:39:51 INFO - no previously-included directories found matching 'build' 05:39:51 INFO - no previously-included directories found matching 'dist' 05:39:51 INFO - no previously-included directories found matching '.git*' 05:39:51 INFO - Successfully installed jsonschema functools32 05:39:51 INFO - Cleaning up... 05:39:51 INFO - Return code: 0 05:39:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:39: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')]} 05:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39: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')]} 05:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:51 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:39:51 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 05:39:51 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 05:39:52 INFO - Using env: {'DISPLAY': ':0', 05:39:52 INFO - 'HOME': '/home/cltbld', 05:39:52 INFO - 'LANG': 'en_US.UTF-8', 05:39:52 INFO - 'LANGUAGE': 'en_US:en', 05:39:52 INFO - 'LOGNAME': 'cltbld', 05:39:52 INFO - 'MAIL': '/var/mail/cltbld', 05:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:52 INFO - 'MOZ_NO_REMOTE': '1', 05:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:52 INFO - 'NO_EM_RESTART': '1', 05:39:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:52 INFO - 'PWD': '/builds/slave/test', 05:39:52 INFO - 'SHELL': '/bin/bash', 05:39:52 INFO - 'SHLVL': '1', 05:39:52 INFO - 'TERM': 'linux', 05:39:52 INFO - 'TMOUT': '86400', 05:39:52 INFO - 'USER': 'cltbld', 05:39:52 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:52 INFO - '_': '/tools/buildbot/bin/python'} 05:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:39:52 INFO - Cleaning up... 05:39:52 INFO - Return code: 0 05:39:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:52 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:39:52 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 05:39:52 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 05:39:52 INFO - Using env: {'DISPLAY': ':0', 05:39:52 INFO - 'HOME': '/home/cltbld', 05:39:52 INFO - 'LANG': 'en_US.UTF-8', 05:39:52 INFO - 'LANGUAGE': 'en_US:en', 05:39:52 INFO - 'LOGNAME': 'cltbld', 05:39:52 INFO - 'MAIL': '/var/mail/cltbld', 05:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:52 INFO - 'MOZ_NO_REMOTE': '1', 05:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:52 INFO - 'NO_EM_RESTART': '1', 05:39:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:52 INFO - 'PWD': '/builds/slave/test', 05:39:52 INFO - 'SHELL': '/bin/bash', 05:39:52 INFO - 'SHLVL': '1', 05:39:52 INFO - 'TERM': 'linux', 05:39:52 INFO - 'TMOUT': '86400', 05:39:52 INFO - 'USER': 'cltbld', 05:39:52 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:52 INFO - '_': '/tools/buildbot/bin/python'} 05:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:39:52 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 05:39:52 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 05:39:52 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 05:39:52 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 05:39:52 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 05:39:52 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 05:39:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:39:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:39:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:39:54 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 05:39:54 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 05:39:54 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 05:39:54 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 05:39:54 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 05:39:54 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 05:39:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:39:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:39:54 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 05:39:54 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 05:39:54 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 05:39:54 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 05:39:54 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 05:39:54 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 05:39:55 INFO - Downloading docopt-0.6.1.tar.gz 05:39:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:39:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:39:55 INFO - Running setup.py install for blobuploader 05:39:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:39:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:39:55 INFO - Running setup.py install for requests 05:39:55 INFO - Running setup.py install for docopt 05:39:55 INFO - Successfully installed blobuploader requests docopt 05:39:55 INFO - Cleaning up... 05:39:55 INFO - Return code: 0 05:39:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:39:55 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')]} 05:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:55 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')]} 05:39:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:55 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:39:55 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 05:39:55 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 05:39:55 INFO - Using env: {'DISPLAY': ':0', 05:39:55 INFO - 'HOME': '/home/cltbld', 05:39:55 INFO - 'LANG': 'en_US.UTF-8', 05:39:55 INFO - 'LANGUAGE': 'en_US:en', 05:39:55 INFO - 'LOGNAME': 'cltbld', 05:39:55 INFO - 'MAIL': '/var/mail/cltbld', 05:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:55 INFO - 'MOZ_NO_REMOTE': '1', 05:39:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:55 INFO - 'NO_EM_RESTART': '1', 05:39:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:55 INFO - 'PWD': '/builds/slave/test', 05:39:55 INFO - 'SHELL': '/bin/bash', 05:39:55 INFO - 'SHLVL': '1', 05:39:55 INFO - 'TERM': 'linux', 05:39:55 INFO - 'TMOUT': '86400', 05:39:55 INFO - 'USER': 'cltbld', 05:39:55 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:55 INFO - '_': '/tools/buildbot/bin/python'} 05:39:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:39:55 INFO - Running setup.py (path:/tmp/pip-JNR2rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:39:56 INFO - Running setup.py (path:/tmp/pip-MUZBEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:39:56 INFO - Running setup.py (path:/tmp/pip-EIgrwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:39:56 INFO - Running setup.py (path:/tmp/pip-K1T_lV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:39:56 INFO - Running setup.py (path:/tmp/pip-0HnDkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:39:56 INFO - Running setup.py (path:/tmp/pip-beoIOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:39:56 INFO - Running setup.py (path:/tmp/pip-tpJ4Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:39:56 INFO - Running setup.py (path:/tmp/pip-5WGLVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:39:56 INFO - Running setup.py (path:/tmp/pip-RDTzzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:39:56 INFO - Running setup.py (path:/tmp/pip-21KeNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:39:56 INFO - Running setup.py (path:/tmp/pip-KCn1Lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:39:56 INFO - Running setup.py (path:/tmp/pip-jV9bxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:39:56 INFO - Running setup.py (path:/tmp/pip-8UKCAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:39:56 INFO - Running setup.py (path:/tmp/pip-Fr4oqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:39:56 INFO - Running setup.py (path:/tmp/pip-pfuQN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:39:57 INFO - Running setup.py (path:/tmp/pip-G_Ii4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:39:57 INFO - Running setup.py (path:/tmp/pip-I0oWwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:39:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:39:57 INFO - Running setup.py install for manifestparser 05:39:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Running setup.py install for mozcrash 05:39:57 INFO - Running setup.py install for mozdebug 05:39:57 INFO - Running setup.py install for mozdevice 05:39:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Running setup.py install for mozfile 05:39:57 INFO - Running setup.py install for mozhttpd 05:39:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Running setup.py install for mozinfo 05:39:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Running setup.py install for mozInstall 05:39:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:39:57 INFO - Running setup.py install for mozleak 05:39:58 INFO - Running setup.py install for mozlog 05:39:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:39:58 INFO - Running setup.py install for moznetwork 05:39:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:39:58 INFO - Running setup.py install for mozprocess 05:39:58 INFO - Running setup.py install for mozprofile 05:39:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:39:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:39:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:39:58 INFO - Running setup.py install for mozrunner 05:39:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:39:58 INFO - Running setup.py install for mozscreenshot 05:39:58 INFO - Running setup.py install for moztest 05:39:58 INFO - Running setup.py install for mozversion 05:39:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:39:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:39:58 INFO - Cleaning up... 05:39:58 INFO - Return code: 0 05:39:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:39:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:39:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:39:58 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:39:58 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 05:39:58 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 05:39:58 INFO - Using env: {'DISPLAY': ':0', 05:39:58 INFO - 'HOME': '/home/cltbld', 05:39:58 INFO - 'LANG': 'en_US.UTF-8', 05:39:58 INFO - 'LANGUAGE': 'en_US:en', 05:39:58 INFO - 'LOGNAME': 'cltbld', 05:39:58 INFO - 'MAIL': '/var/mail/cltbld', 05:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:58 INFO - 'MOZ_NO_REMOTE': '1', 05:39:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:58 INFO - 'NO_EM_RESTART': '1', 05:39:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:58 INFO - 'PWD': '/builds/slave/test', 05:39:58 INFO - 'SHELL': '/bin/bash', 05:39:58 INFO - 'SHLVL': '1', 05:39:58 INFO - 'TERM': 'linux', 05:39:58 INFO - 'TMOUT': '86400', 05:39:58 INFO - 'USER': 'cltbld', 05:39:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:58 INFO - '_': '/tools/buildbot/bin/python'} 05:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:39:59 INFO - Running setup.py (path:/tmp/pip-TmE3aP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:39:59 INFO - Running setup.py (path:/tmp/pip-lYkcyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:39:59 INFO - Running setup.py (path:/tmp/pip-miiG_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:39:59 INFO - Running setup.py (path:/tmp/pip-NBsB0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:39:59 INFO - Running setup.py (path:/tmp/pip-zU3zaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:39:59 INFO - Running setup.py (path:/tmp/pip-u94jx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:39:59 INFO - Running setup.py (path:/tmp/pip-5RkkZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:39:59 INFO - Running setup.py (path:/tmp/pip-PQbrY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:39:59 INFO - Running setup.py (path:/tmp/pip-M7zSeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:39:59 INFO - Running setup.py (path:/tmp/pip-obVRZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:39:59 INFO - Running setup.py (path:/tmp/pip-xduP9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:39:59 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)) 05:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:00 INFO - Running setup.py (path:/tmp/pip-i6jCzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:00 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)) 05:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:00 INFO - Running setup.py (path:/tmp/pip-FjNS8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:00 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)) 05:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:00 INFO - Running setup.py (path:/tmp/pip-LCTWWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:00 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)) 05:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:00 INFO - Running setup.py (path:/tmp/pip-gh3Qrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:00 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)) 05:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:00 INFO - Running setup.py (path:/tmp/pip-mTGY8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:00 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)) 05:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:00 INFO - Running setup.py (path:/tmp/pip-4mgIGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:00 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)) 05:40:00 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)) 05:40:00 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)) 05:40:00 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)) 05:40:00 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)) 05:40:00 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)) 05:40:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:00 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 05:40:00 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 05:40:00 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 05:40:00 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 05:40:00 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 05:40:00 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 05:40:02 INFO - Downloading blessings-1.6.tar.gz 05:40:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:40:02 INFO - Installing collected packages: blessings 05:40:02 INFO - Running setup.py install for blessings 05:40:02 INFO - Successfully installed blessings 05:40:02 INFO - Cleaning up... 05:40:02 INFO - Return code: 0 05:40:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:40:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:02 INFO - Reading from file tmpfile_stdout 05:40:02 INFO - Current package versions: 05:40:02 INFO - argparse == 1.2.1 05:40:02 INFO - blessings == 1.6 05:40:02 INFO - blobuploader == 1.2.4 05:40:02 INFO - docopt == 0.6.1 05:40:02 INFO - functools32 == 3.2.3-2 05:40:02 INFO - jsonschema == 2.5.1 05:40:02 INFO - manifestparser == 1.1 05:40:02 INFO - mozInstall == 1.12 05:40:02 INFO - mozcrash == 0.17 05:40:02 INFO - mozdebug == 0.1 05:40:02 INFO - mozdevice == 0.48 05:40:02 INFO - mozfile == 1.2 05:40:02 INFO - mozhttpd == 0.7 05:40:02 INFO - mozinfo == 0.9 05:40:02 INFO - mozleak == 0.1 05:40:02 INFO - mozlog == 3.3 05:40:02 INFO - moznetwork == 0.27 05:40:02 INFO - mozprocess == 0.23 05:40:02 INFO - mozprofile == 0.28 05:40:02 INFO - mozrunner == 6.12 05:40:02 INFO - mozscreenshot == 0.1 05:40:02 INFO - mozsystemmonitor == 0.3 05:40:02 INFO - moztest == 0.7 05:40:02 INFO - mozversion == 1.4 05:40:02 INFO - psutil == 3.1.1 05:40:02 INFO - requests == 1.2.3 05:40:02 INFO - wsgiref == 0.1.2 05:40:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:02 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:40:02 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 05:40:02 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 05:40:02 INFO - Using env: {'DISPLAY': ':0', 05:40:02 INFO - 'HOME': '/home/cltbld', 05:40:02 INFO - 'LANG': 'en_US.UTF-8', 05:40:02 INFO - 'LANGUAGE': 'en_US:en', 05:40:02 INFO - 'LOGNAME': 'cltbld', 05:40:02 INFO - 'MAIL': '/var/mail/cltbld', 05:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:02 INFO - 'MOZ_NO_REMOTE': '1', 05:40:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:02 INFO - 'NO_EM_RESTART': '1', 05:40:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:02 INFO - 'PWD': '/builds/slave/test', 05:40:02 INFO - 'SHELL': '/bin/bash', 05:40:02 INFO - 'SHLVL': '1', 05:40:02 INFO - 'TERM': 'linux', 05:40:02 INFO - 'TMOUT': '86400', 05:40:02 INFO - 'USER': 'cltbld', 05:40:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:02 INFO - '_': '/tools/buildbot/bin/python'} 05:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 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)) 05:40:03 INFO - Cleaning up... 05:40:03 INFO - Return code: 0 05:40:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:03 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 0x7fc251adaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27df850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, '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': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', '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 05:40:03 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 05:40:03 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 05:40:03 INFO - Using env: {'DISPLAY': ':0', 05:40:03 INFO - 'HOME': '/home/cltbld', 05:40:03 INFO - 'LANG': 'en_US.UTF-8', 05:40:03 INFO - 'LANGUAGE': 'en_US:en', 05:40:03 INFO - 'LOGNAME': 'cltbld', 05:40:03 INFO - 'MAIL': '/var/mail/cltbld', 05:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:03 INFO - 'MOZ_NO_REMOTE': '1', 05:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:03 INFO - 'NO_EM_RESTART': '1', 05:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:03 INFO - 'PWD': '/builds/slave/test', 05:40:03 INFO - 'SHELL': '/bin/bash', 05:40:03 INFO - 'SHLVL': '1', 05:40:03 INFO - 'TERM': 'linux', 05:40:03 INFO - 'TMOUT': '86400', 05:40:03 INFO - 'USER': 'cltbld', 05:40:03 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:03 INFO - '_': '/tools/buildbot/bin/python'} 05:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:40:03 INFO - Cleaning up... 05:40:03 INFO - Return code: 0 05:40:03 INFO - Running post-action listener: _resource_record_post_action 05:40:03 INFO - Running post-action listener: _start_resource_monitoring 05:40:03 INFO - Starting resource monitoring. 05:40:03 INFO - [mozharness: 2016-09-01 12:40:03.270601Z] Finished create-virtualenv step (success) 05:40:03 INFO - [mozharness: 2016-09-01 12:40:03.271097Z] Running install step. 05:40:03 INFO - Running pre-action listener: _resource_record_pre_action 05:40:03 INFO - Running main action method: install 05:40:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:03 INFO - Reading from file tmpfile_stdout 05:40:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:40:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:40:03 INFO - Reading from file tmpfile_stdout 05:40:03 INFO - Output received: 05:40:03 INFO - Usage: mozinstall [options] installer 05:40:03 INFO - Options: 05:40:03 INFO - -h, --help show this help message and exit 05:40:03 INFO - -d DEST, --destination=DEST 05:40:03 INFO - Directory to install application into. [default: 05:40:03 INFO - "/builds/slave/test"] 05:40:03 INFO - --app=APP Application being installed. [default: firefox] 05:40:03 INFO - mkdir: /builds/slave/test/build/application 05:40:03 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'] 05:40:03 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 05:40:20 INFO - Reading from file tmpfile_stdout 05:40:20 INFO - Output received: 05:40:20 INFO - /builds/slave/test/build/application/firefox/firefox 05:40:20 INFO - Running post-action listener: _resource_record_post_action 05:40:20 INFO - [mozharness: 2016-09-01 12:40:20.581048Z] Finished install step (success) 05:40:20 INFO - [mozharness: 2016-09-01 12:40:20.581325Z] Running run-tests step. 05:40:20 INFO - Running pre-action listener: _resource_record_pre_action 05:40:20 INFO - Running main action method: run_tests 05:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:40:20 INFO - Python 2.7.3 05:40:20 INFO - Return code: 0 05:40:20 INFO - grabbing minidump binary from tooltool 05:40:20 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')]} 05:40:20 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 0x27da370>, '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 0x27dd8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ddd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:40:20 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 05:40:20 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 05:40:20 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 05:40:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:40:20 INFO - Return code: 0 05:40:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:40:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:40:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:40:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:40:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 05:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 05:40:20 INFO - Using env: {'DISPLAY': ':0', 05:40:20 INFO - 'HOME': '/home/cltbld', 05:40:20 INFO - 'LANG': 'en_US.UTF-8', 05:40:20 INFO - 'LANGUAGE': 'en_US:en', 05:40:20 INFO - 'LOGNAME': 'cltbld', 05:40:20 INFO - 'MAIL': '/var/mail/cltbld', 05:40:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:40:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:20 INFO - 'MOZ_NO_REMOTE': '1', 05:40:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:40:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:20 INFO - 'NO_EM_RESTART': '1', 05:40:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:20 INFO - 'PWD': '/builds/slave/test', 05:40:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:40:20 INFO - 'SHELL': '/bin/bash', 05:40:20 INFO - 'SHLVL': '1', 05:40:20 INFO - 'TERM': 'linux', 05:40:20 INFO - 'TMOUT': '86400', 05:40:20 INFO - 'USER': 'cltbld', 05:40:20 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139', 05:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:20 INFO - '_': '/tools/buildbot/bin/python'} 05:40:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 05:40:20 INFO - mozversion application_buildid: 20160901023002 05:40:20 INFO - mozversion application_changeset: d3bf9bbe73ba35c4257dcd10339f6ccecbb1fd5e 05:40:20 INFO - mozversion application_display_name: Nightly 05:40:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:40:20 INFO - mozversion application_name: Firefox 05:40:20 INFO - mozversion application_remotingname: firefox 05:40:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:40:20 INFO - mozversion application_vendor: Mozilla 05:40:20 INFO - mozversion application_version: 51.0a1 05:40:20 INFO - mozversion platform_buildid: 20160901023002 05:40:20 INFO - mozversion platform_changeset: d3bf9bbe73ba35c4257dcd10339f6ccecbb1fd5e 05:40:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:40:20 INFO - mozversion platform_version: 51.0a1 05:40:20 INFO - using testdate: 1472733620 05:40:20 INFO - actual date: 1472733620 05:40:20 INFO - starting webserver on 'localhost:53560' 05:40:20 INFO - SUITE-START | Running 2 tests 05:40:20 INFO - TEST-START | dromaeo_css 05:40:20 INFO - operating with platform_type : linux_ 05:40:20 INFO - Initialising browser for dromaeo_css test... 05:40:20 INFO - TEST-INFO | started process 20597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp677cm9/profile http://localhost:53560/getInfo.html) 05:40:25 INFO - PROCESS | 20597 | __metrics Screen width/height:1600/1200 05:40:25 INFO - PROCESS | 20597 | colorDepth:24 05:40:25 INFO - PROCESS | 20597 | Browser inner width/height: 1024/697 05:40:25 INFO - PROCESS | 20597 | __metrics 05:40:26 INFO - PROCESS | 20597 | 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" 05:40:26 INFO - PROCESS | 20597 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:40:26 INFO - PROCESS | 20597 | console.error: PushService: 05:40:26 INFO - PROCESS | 20597 | stateChangeProcessEnqueue: Error transitioning state 05:40:26 INFO - PROCESS | 20597 | UnknownError 05:40:27 INFO - TEST-INFO | 20597: exit 0 05:40:27 INFO - Browser initialized. 05:40:27 INFO - Running cycle 1/1 for dromaeo_css test... 05:40:27 INFO - TEST-INFO | started process 20679 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp677cm9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:40:39 INFO - PROCESS | 20679 | RSS: Main: 179970048 05:40:39 INFO - PROCESS | 20679 | 05:41:30 INFO - PROCESS | 20679 | 1472733690058 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] 05:43:40 INFO - PROCESS | 20679 | Cycle 1(1): loaded http://localhost:53560/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53560/tests/dromaeo/cssquery-ext.html) 05:43:41 INFO - PROCESS | 20679 | RSS: Main: 271339520 05:43:41 INFO - PROCESS | 20679 | 05:46:44 INFO - PROCESS | 20679 | Cycle 1(1): loaded http://localhost:53560/tests/dromaeo/cssquery-ext.html (next: http://localhost:53560/tests/dromaeo/cssquery-jquery.html) 05:46:44 INFO - PROCESS | 20679 | RSS: Main: 273084416 05:46:44 INFO - PROCESS | 20679 | 05:49:46 INFO - PROCESS | 20679 | Cycle 1(1): loaded http://localhost:53560/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53560/tests/dromaeo/cssquery-mootools.html) 05:49:47 INFO - PROCESS | 20679 | RSS: Main: 219058176 05:49:47 INFO - PROCESS | 20679 | 05:52:49 INFO - PROCESS | 20679 | Cycle 1(1): loaded http://localhost:53560/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53560/tests/dromaeo/cssquery-prototype.html) 05:52:49 INFO - PROCESS | 20679 | RSS: Main: 241803264 05:52:49 INFO - PROCESS | 20679 | 05:55:52 INFO - PROCESS | 20679 | Cycle 1(1): loaded http://localhost:53560/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53560/tests/dromaeo/cssquery-yui.html) 05:55:53 INFO - PROCESS | 20679 | RSS: Main: 219938816 05:55:53 INFO - PROCESS | 20679 | 05:58:55 INFO - PROCESS | 20679 | Cycle 1(1): loaded http://localhost:53560/tests/dromaeo/cssquery-yui.html (next: http://localhost:53560/tests/dromaeo/cssquery-dojo.html) 05:58:56 INFO - PROCESS | 20679 | RSS: Main: 271998976 05:58:56 INFO - PROCESS | 20679 | 05:58:56 INFO - PROCESS | 20679 | __start_tp_report 05:58:56 INFO - PROCESS | 20679 | _x_x_mozilla_page_load 05:58:56 INFO - PROCESS | 20679 | _x_x_mozilla_page_load_details 05:58:56 INFO - PROCESS | 20679 | |i|pagename|runs| 05:58:56 INFO - PROCESS | 20679 | |0;dojo.html;2936.1044881311204;2962;2992.11732538901;3037.07369252378;3048.085574327701;5187.377514698242;5197.7920883164625;5278.762455689493;5358.758855851486;5363.249145119681;5207.479252074791;5229.50319719627;5276.736163191844;5295.947040529593;5369.543588794957;4747.881302967437;4767.070421267854;4880.7071575705395;4918.581920536759;4937.210046392578;4877;4901.215805471125;4903.31697899148;4926.852194434173;4947.901041979158;4653.278741795028;4673.953260467386;4812.855614331558;4816.759162041884;4914.213725803872;4729.929051064237;4735.57379835813;4736.407949006374;4738.076075165344;4742.668013239075;4683.1336202802595;4695.060987802419;4707.552782485658;4711.175544279771;4716.127516409441;5085.821996230151;5127.1796512558185;5129.6409251352425;5130.846074617767;5134.460881607415;5466.699331536764;5478.287822583078;5481.287432633772;5482.780688772445;5523.143912693498;5572.721363931787;5591.02157122506;5608.186812912146;5622.803201887955;5699.173619825144;5269;5334.893302133935;5336.439673834269;5338.065838478251;5341.038613049688;5670.489655930986;5691.459311365414;5796.88406231873;5806.506446952057;5807.94192058074;5507.339119305629;5514.172874068921;5556.833295001156;5597.244372009808;5602.075657516546;5616.550675945915;5621.634593751393;5623.128415095667;5626.85932851682;5631.887362252732;5465.535429488458;5542.612017158841;5594.748236329375;5601.159826026129;5607.383187849333;5592.3009623797025;5652.491275785199;5656.123300888369;5690.971545142248;5725.198472213893;4373.934390984138;4378.737275763465;4383.452068491439;4384.802683879169;4432.135733531994;3829.885103446901;3853.8073096345634;3864.768113913174;3877.806109694504;3920.882373528799;5368.32895888014;5387.892242155135;5400.756965936543;5464.644798088111;5466.426025267369;5677.829665110054;5688.772449102073;5689.118186680988;5690.829275121753;5697.57268204878;5288.048151332797;5336.199570064444;5389.9191512127345;5408;5465.043617366945;4876.244182151772;4898.485659005824;4900.705957642553;4952.678075925053;4955.108080545537;5882.470577648032;5899.528037757055;5901.144334071536;5904.232449781501;5907.350191478848;5401.540869026019;5638.5489160867855;5656.43435656431;5706;5768.625039372428;5886.558508111823;5898.528117750656;5918.852028699144;5952.791652292149;5955.076963070558;5574.331080270394;5613.719314034201;5619.157126431068;5621.184928185472;5627.127795191752;5361.410244873139;5395.973020134874;5724.57065720081;5736.368999410145;5736.885262294814;5532.917006244829;5569.470900264469;5583.832485025456;5590.748416321194;5650.039493286234;5575.163725441216;5580.609357345109;5582.9720851397105;5587.944120558905;5589.245451863946;5313.149896016619;5524.033294173584;5534.280543529316;5537.197106419627;5597.972010140086;5495.890082198413;5556.194351819045;5564.4991950724625;5571.9721401392735;5642.605017648725;5416.83749487516;5454.918176227439;5457.863553411196;5459.181122831448;5467.234587157722 05:58:56 INFO - PROCESS | 20679 | |1;ext.html;547.7617236502122;572.5105035194908;574.3624576719842;616.4636766013566;634.4258446106276;32286.67713322866;46426;47279.76360118203;47328.52671473284;47400.52599474004;45754;46174.76912615432;46200.76899615506;46201.53798462015;46240.76879615597;8104;8924.509151996637;9075.047120052399;9084.591193396313;9086.86369704455;10428.791424171512;10761.83857242138;10771.83842242363;11065.557377704883;11079.944600277027;10922.945385273062;11008.339499630009;11024.118070554376;11027.61403350883;11028.558857645725;10834.729131771768;10896;10948.726281842937;10949.397783121925;10972.396518191455;10584.259101862872;10634.989675980769;10646.201534884915;10720.517576709066;10728.946355268174;42175.1564968702;42472.36291455631;42544;42548.14903701939;42565.78717106426;3360.462326027824;3535.5757309122814;3653.451982202664;3866.2074274773604;3890.513685789276;32524.837375813207;32679.83660081708;32758.508622370682;32916.177095572566;32945.34109317824;4307.78461076948;4427.22523558376;4531.456225252958;4589.839355622571;4599.44806623204;333145;335055;335110.32444837864;336559;337676;35113.47329790055;36007;58688.70655646695;59085.40914590885;59191.70404147952;38340.80829595834;38567;38672.80663596664;38709.419358709645;38717.225655486734;14735;15068.54794356171;15088.094714317176;15094.094354338775;15097.094174349575;10175.389476631277;11602.6519204424;11790.879866412677;12607.747845043232;12736.61790146297;8563.443376180529;8564.828703425897;8611.095834937241;8662.696805611773;8670.566471676391;3155.921101972469;3272.6236482804784;3378.44255697812;3400.8129552874334;3454.635419009477;1010.4493051287067;1011.4588695048114;1020.6325722739956;1022.3252653248969;1067.9359238445718;31690.524642130385;31709.365812683616;31991.200219994687;32236.677633223837;32398.838005810292;1059.2003037706636;1072.9892701073045;1075.1184029096223;1091.121647074113;1146.5585749486509;15867.920660396625;16650.3339866404;16651.6669666606;16716;16850.756244275515;551128;555066;555799;556050;557438;56687.71656141776;57036;57061.14408283879;57071;57112;1200.4237965776476;1331.2145833958011;1356.3286173344259;1360.340234986031;1374.3540535948089;30293;32398;32712.83643581767;32945.505817413235;33085.66914330826;53034.73482632562;54923.72538137284;54931.45068549264;58955.410445894995;59109.70445147747;451537;517486;521007;522440;525877;27715;28312.009079682113;28397;39964.40053399259;43449;44792.10415791731;45011.54988450074;45665.54334456746;46659.53340466688;46963.29555056609;39487.407688885265;39497.60502394939;40537;40579.59420405758;42014.48775439812;38582.42126368163;39221.41167882541;39786.60213397829;39865;40756.59243407647;38894.4165837507;38911.221775564896;39057;39221.803890980365;39249;33121.83439082886;36186.8190659045;36453.89575040832;36848.81575592105;36928.63071369252;27230.319242019108;27378.726212737616;27431.86284068567;27632.309192270353;27647.170584882486 05:58:56 INFO - PROCESS | 20679 | |2;jquery.html;361.87515307219;376.88505005973184;377.77711150421266;392.4368531157789;405.43846772220485;15463.149526776022;17031.744523832138;17897.194626241817;19537.413877583658;19994.900025499854;17661.91169044156;17695.203715832788;18520.72218916714;18979.145938432768;19007.809921900778;5665.603407761459;5730.512906402951;5938.435848594377;5948.762049518035;5957.374475680056;5764.798232061858;5780.942190578104;5808.215890854723;5812.883742325151;5852.912206316909;5626.634268772516;5770.134479828039;5770.653760774367;5772.624779389326;5809.854753631151;5740.7703691852275;5793.739281732311;5816.360200377955;5830.7959221427045;5847.473727364536;5002.199648056294;5118.564921981636;5161;5219.243209734605;5224.973875130638;23225.883870580707;23382.532349352918;23415.53168936612;23419;23617.055317787268;2303.5968705476475;2314.8148148148107;2386.367612582667;2565.9743402565923;2840.4035152617976;14220.431182752678;14606.488772893004;14860.256987150598;15121.621959451102;15361.46234881785;1889.914953827081;1893.6307420053095;1901.7147427885927;1985.9702104468445;1991.0243980449475;78829.60585197095;80044.19955800426;80944.59527702382;81413.59293203555;81472;10028.348157369748;10172.338797978107;10580.15358771312;10589.629362972259;10600.893991059991;14212.21832799206;14272.714545709028;14615.561533154023;14616.342264598119;14640.707185856436;7477.887831682529;7556.433267504959;7794.259545343183;7870.685172593032;7872.409569282212;7104.11198600175;7198.136223653195;7228;7282.3809976152625;7491.887621685679;6532.444742196966;6557.540972132;6592.923229182792;6615.17310336208;6624.536282460278;1827.8903265804095;1885.4060970794155;1915.1764741161155;1939.3987863761997;1944.4263942137104;869.682565863468;1005.5927349423495;1031.4017869635593;1086.760912599227;1089.4607169451172;19339.51651208703;20036.39890803278;20037.799622003593;20073.799262007193;20444.897775511028;977.1059480575194;984.7833476635129;995.785906030207;996.531630133836;1006.9496525173712;10425.739356515996;10544.574747808047;10626.256162068736;11146.331220126818;11375.431228438545;453651;498415;499140;506825;507335;87131.56434217915;90115;91114.54442727743;93751;93896;980;1033.3076838518211;1058.1587637827945;1066.6053560182627;1074.957001719938;15832.841671583137;17127.486175414757;17555.56111097233;17621.29514819444;17631.294748209926;39718.60281397149;41529.37705934353;41648.58351416447;41653;41803.10248808913;54323.72838135784;55458.72270638621;56016;57886.4211357881;58197.70901145467;5417.729113544386;5925.9407405925385;8196.54918979438;8524.36067294943;8889.64441422336;9468.479233642216;11438.142139339416;12142.453589588313;12573.559925402567;13313.400896959467;11242.94378528102;11470.770584588428;11630.360330181922;11667.474963626826;12209.450574723982;11181.273217241178;11394.94302528482;11553.075753939496;11655;11726;11481;11501.82747258775;11580;11690.824637630272;11705;8616.698415555677;8764.56177191125;8778.56107194625;8881.511516866634;8919.286457083548;11322.207445479067;11462.885371146182;11522.654320370402;11546.653600392001;11943.522259109886 05:58:56 INFO - PROCESS | 20679 | |3;mootools.html;1316.4405127820673;1459.1463993563764;1496.2743069611236;1634.7466142747878;1782.839544441;7991.440599158062;8379.706710265142;8403.075661677225;8570.528620925847;8575.185357391054;8348.540830254333;8451.87322190166;8456.534890581015;8456.95771521143;8464.576771161448;2326.2323433267024;2347.319277409549;2356.929292121239;2357.5520651076326;2359.3629719975597;2183.9890800545977;2225.688403623494;2229.1306390507716;2238.9552208955893;2239.5632851593946;2198.8460807743463;2219.4895174109965;2219.5893759654437;2233.05095334483;2234.1175235781857;2158.082814803718;2164.7402311722535;2170.4573856535867;2177.0638625391075;2179.6403593407067;2173.4240426287047;2178.1287485005964;2180.29140529327;2180.8473406861526;2182.6725991101302;8334.20825021622;8342.082370939192;8391;8451.492910425393;8568.614412351459;4862.36789217403;4991;5059.316992206079;5063.240513922941;5063.392392912837;3129.640091389474;3266.7713260071805;3306.1404034950847;3322.352141332441;3332.0836769888474;1051.5005372448102;1052.768390953989;1060.3160961180072;1079.5357996061691;1082.247837752762;11622.94188529052;11735.765284694344;11763.529458821637;11777.528898844035;11813.881861181364;4016.4376987221813;4157.792110394468;4248.9787551062045;4302.870913872589;4304.074623955802;9257.814843703223;9298.488583127857;9299.814003719888;9303.627854885865;9307.06929307064;4887.755612219375;4933.45731969483;4963.70217786934;4967.950320496749;4976;3914.706397020235;4133.338665813516;4240.427542281815;4245.108527209259;4579.38178345919;3597.4783656370205;3686.612905644922;3695.020819482839;3695.519582454264;3724.1992971510713;1095.7370231144469;1096.276457538021;1101.1136035491506;1112.4271000434783;1122.2537012886503;875.2866413872673;937.4937533731709;964.7781010367655;978.2711879649706;988.2983082011708;5774.191613174159;5843.211166492554;5944.286685597756;5948.881022379528;6115.724792384353;1027.6403258859339;1034.5654824973528;1038.9584416623402;1056.7992081504644;1058.9258751887294;4076.4089207065404;4170.103427763045;4180.3102488089735;4190.790460476964;4316.633086187647;10290.742731431625;10369.688909332732;10458.738531536772;10531.104856087166;10769.138468922467;10423.322484038514;10444.477776111164;10459.790804183875;10563;10569.154467642571;7527.096748390229;7884.250996155356;7886.960565197137;8031.477953932976;8093.1502192270145;5747.7413516391025;5820.359760426434;5847.649141051551;5977.252843394575;6060.363661815446;9490.905090949002;9512.476813775309;9524.333296669167;9613.615455381987;9647;9011.549422528979;9512.09635084666;9555.522223888916;9687.70936871895;9887;7359.153697324662;7478.70085196586;7566.735164269224;7732.188120247293;7741.303282704584;8119.147489513516;8174.632141553763;8229.79425514367;8249.092599814136;8289;7408.777736667908;7423.443241756998;7429.702811887681;7457.142428620778;7464.290892365434;7424.406047516078;7434.665440055319;7436.516626419266;7641.6561254742555;7669.348105411103;6689.397954184147;6710.295418980937;6745.865082698417;6766;6814.965925170342;5045.369328833895;5210.947890521198;5264.394594621514;5281.366236051698;5338.839834804962;5191.636585439134;5241.790328386975;5268.315119034501;5288.206768984529;5288.567259060875 05:58:56 INFO - PROCESS | 20679 | |4;prototype.html;314.5941735161643;333.6046784560296;334.4097667616659;335.70122590894107;337.92565635560175;5223.485189295105;5296.364436267653;5302.973485132574;5306.416294207644;5312.150055991042;4950.084234416636;5037.244413338001;5137.178051511759;5143.151380022292;5218.347706536683;4103.9179216415805;4108.157827645326;4110.075233072557;4123.814428350716;4148.958510414887;4115.621266875601;4124.422580838685;4126.29852925002;4130.628243458088;4143.19207754488;3892.630200130983;3898.9025274368228;4053.871717348386;4120.649744771698;4125.381192821086;4095.344744840811;4098.426220329156;4101.85643502476;4110.280700877364;4139.79301034947;3659.414493681025;3677.3748462761127;3681.5398075240596;3694.5751238607636;3700.8149592520267;5011.749412529395;5027.2207807789855;5027.2207807789855;5134.717590532519;5139;4640.60554852838;4649.209634362132;4658.697184682985;4660.18446771817;4673.696209746356;5346.289187460015;5364.892702145974;5383.734822316753;5417.729113544347;5432.239486471897;3719.981400093009;3921.725479216456;3985.668383995366;4027.7583344999393;4034.515858097047;5580.944190558083;5583.637063590894;5584.441555844423;5598.1602759586385;5618.836747852107;4058.9188216236103;4075.89810254746;4079.0618157823865;4079.653229475528;4101.118259574147;3988.1455123367773;4138.5654506276405;4140.523839758406;4142.772147531915;4156.605122513356;4695.802570344608;4704.858854234379;4705.60002399793;4712.410948631421;4721;5437.728113594305;5443.265159203457;5450.4549545045975;5526.198701188305;5536.197251398525;1394.4282844033899;1438.8848892088608;1454.632645313402;1457.4825936792497;1459.0880699562774;921.6774129054777;961.9807603848024;962.8459446488528;968.2496065549255;972.5477652891298;4459.620932220798;4661.510541393172;4669.229577119805;4710.740909250024;4777.307290442866;4864.586510146606;4881.973176630512;4886.877828054327;4899.951000490021;4919.2867034279825;4663;4677.64917631179;4688.46082700487;4719.4100737407825;4723;2555.833870798392;2562.6027965665353;2602.9718261287067;2615.5161295160456;2737.4935636907235;5647.915281270784;5654.9151762723595;5680.318361796528;5681.545476361881;5682.460166284196;5696.778256479894;5700.173474846124;5702.686352250583;5722.37053924068;5739.483446489836;5615.6630602164005;5643.351014633287;5711.077881496065;5718.828435146952;5767.826965191051;5520.861978450571;5523.834284971457;5526.590719366536;5577.330720313588;5591.552675786009;5511.696856672921;5526.944730552643;5534.667919924817;5541.916871246854;5561.916571251515;5465;5743.942560574341;5759.8560035999435;5767.509761670305;5790.594658373873;4074.5110586729784;4080.816363263601;4085.5097388313593;4096.795160241916;4118.732282401635;5197.7141257231215;5401.567874570103;5493.423190564933;5513.365962914316;5680.2047713321;5408.540274076748;5422.62041657096;5437.809676661298;5457.972710136424;5481.479259470344;5287.867803304948;5449.373322067854;5452.773126046608;5461;5496.450354964471;5355.892882142413;5371.060064488777;5377.3816011159215;5397.433269506802;5404.756785944721;5448.700321482356;5464.672119672832;5465;5471.562275018068;5474.2609747683555;5221.556167725786;5276.18219176013;5279.357243501971;5370.570354371563;5375.892482150257 05:58:56 INFO - PROCESS | 20679 | |5;yui.html;814.4665244265004;816.1185919207256;834.9791255218622;851.7487341234336;861.6553378648543;15675.60810979726;17220.82779172208;17362.21870015849;17502.91248543757;17510.299588016464;14729.852701472983;15038.774418383733;15169.620759481018;15183.013104148222;15184.240787960585;801.7274126796873;820.9055958564752;821.3716506872217;821.4742564758559;823.4153750836913;664.5481072870447;798.5049269452918;806.3508875355336;820.1060843680388;830.8089139497919;876.2420506262101;877.7542288159326;879.3009557401881;879.3844308983705;879.5338470610586;862.6980556805099;869.0353707384797;869.0787764969153;869.3479890082439;870.2733217763175;506.74662668665667;612.2499937576433;616.6515918506075;627.3444250757923;635.809257222836;15613.453529126426;15648.608784780357;15666.608334791608;15671.138087405188;15682.529524114294;13358.332083395791;13863.514776982756;13968.092074015156;14167.149971001772;14321.069130506483;13455.865441345559;13469.797953030713;13525.256110913895;13775.517856874962;13814.309284535733;4423.137488189772;4426.712263702849;4471.28459446487;4476.216662084154;4515.480719717241;84818.38649903375;86616.56691716565;87522;88487.55756221242;89463;11306.152038597138;11420.371879546705;11433.885661143282;11632.243904146204;11871.287722736663;21859.672104918438;21925.780742192692;21974.89012554927;22006;22040.779592204195;8211.425200235926;8230.670773169006;8443.915560844436;8499.235068843835;8765.211130998241;4720.740359280204;4731.597814185764;4742.976285118552;4774.832880849153;4975.701457912537;3226.6773322667545;3329.8335083245743;3331.666833316649;3334.9166270843425;3350.363430948112;557.2922388566498;562.8424041268452;623.9594426362272;657.3459407889181;658.134553062721;650.180772226988;711.548166914003;715.6386025057312;736.2219197408884;748.0649188514357;10019.903157769664;10315.535800888978;10374.792504149875;10916.56333746657;11042.566317088173;680.306087790451;720.5172534401963;730.8355619985567;757.2692351880462;782.385827125715;4836.298736683161;4904.975475122673;5037.874053148627;5098.490150984871;5283.709395983258;93427.53286233525;93970;94497;118673;121102;95117.52441237887;95781.52109239409;96436;97251;98666;54794;55996;81731;83099;86867.56566217128;9943.801123977624;10339.276250662382;10675.57297708083;10793.866644002266;10949.726256843642;36861.81569092245;40000.99997500086;47987;48158;49677.751611241714;43640.781796090814;45371.31943020923;45537;48548.27177592268;67271.66364168147;13228.14017088886;13266.933665331611;13441.731165376834;13468.595942121752;13593.45626174942;14897.553073407815;15129.47046853355;15145.848541514444;15828.335944282446;16009;10816.053110440196;13985.58043258704;14571.708565828412;14602;14875.628109297355;13524.661883452993;13850.930745346208;14199.503017394341;14477.493287734878;14709.264536773333;14704.926475367554;15042.6991460168;15613.531594052196;15651.608709782347;15666.216689165269;11178.608748693756;11798.174127810971;11804.173707840711;11804.70488237801;11973.221740586834;10322.483875806029;10460.372377657364;10675.679729608124;10715.946420268161;12037.796220377892 05:58:56 INFO - PROCESS | 20679 | __end_tp_report 05:58:56 INFO - PROCESS | 20679 | __start_cc_report 05:58:56 INFO - PROCESS | 20679 | _x_x_mozilla_cycle_collect,1072 05:58:56 INFO - PROCESS | 20679 | __end_cc_report 05:58:56 INFO - PROCESS | 20679 | __startTimestamp1472734736180__endTimestamp 05:58:56 INFO - PROCESS | 20679 | 05:58:56 INFO - PROCESS | 20679 | ------- Summary: start ------- 05:58:56 INFO - PROCESS | 20679 | Number of tests: 6 05:58:56 INFO - PROCESS | 20679 | 05:58:56 INFO - PROCESS | 20679 | [#0] dojo.html Cycles:165 Average:5249.67 Median:5465.54 stddev:602.75 (11.0%) stddev-sans-first:576.63 05:58:56 INFO - PROCESS | 20679 | Values: 2936.1 2962.0 2992.1 3037.1 3048.1 5187.4 5197.8 5278.8 5358.8 5363.2 5207.5 5229.5 5276.7 5295.9 5369.5 4747.9 4767.1 4880.7 4918.6 4937.2 4877.0 4901.2 4903.3 4926.9 4947.9 4653.3 4674.0 4812.9 4816.8 4914.2 4729.9 4735.6 4736.4 4738.1 4742.7 4683.1 4695.1 4707.6 4711.2 4716.1 5085.8 5127.2 5129.6 5130.8 5134.5 5466.7 5478.3 5481.3 5482.8 5523.1 5572.7 5591.0 5608.2 5622.8 5699.2 5269.0 5334.9 5336.4 5338.1 5341.0 5670.5 5691.5 5796.9 5806.5 5807.9 5507.3 5514.2 5556.8 5597.2 5602.1 5616.6 5621.6 5623.1 5626.9 5631.9 5465.5 5542.6 5594.7 5601.2 5607.4 5592.3 5652.5 5656.1 5691.0 5725.2 4373.9 4378.7 4383.5 4384.8 4432.1 3829.9 3853.8 3864.8 3877.8 3920.9 5368.3 5387.9 5400.8 5464.6 5466.4 5677.8 5688.8 5689.1 5690.8 5697.6 5288.0 5336.2 5389.9 5408.0 5465.0 4876.2 4898.5 4900.7 4952.7 4955.1 5882.5 5899.5 5901.1 5904.2 5907.4 5401.5 5638.5 5656.4 5706.0 5768.6 5886.6 5898.5 5918.9 5952.8 5955.1 5574.3 5613.7 5619.2 5621.2 5627.1 5361.4 5396.0 5724.6 5736.4 5736.9 5532.9 5569.5 5583.8 5590.7 5650.0 5575.2 5580.6 5583.0 5587.9 5589.2 5313.1 5524.0 5534.3 5537.2 5598.0 5495.9 5556.2 5564.5 5572.0 5642.6 5416.8 5454.9 5457.9 5459.2 5467.2 05:58:56 INFO - PROCESS | 20679 | 05:58:56 INFO - PROCESS | 20679 | [#1] ext.html Cycles:180 Average:61418.96 Median:20053.67 stddev:126660.98 (631.6%) stddev-sans-first:126933.84 05:58:56 INFO - PROCESS | 20679 | Values: 547.8 572.5 574.4 616.5 634.4 32286.7 46426.0 47279.8 47328.5 47400.5 45754.0 46174.8 46200.8 46201.5 46240.8 8104.0 8924.5 9075.0 9084.6 9086.9 10428.8 10761.8 10771.8 11065.6 11079.9 10922.9 11008.3 11024.1 11027.6 11028.6 10834.7 10896.0 10948.7 10949.4 10972.4 10584.3 10635.0 10646.2 10720.5 10728.9 42175.2 42472.4 42544.0 42548.1 42565.8 3360.5 3535.6 3653.5 3866.2 3890.5 32524.8 32679.8 32758.5 32916.2 32945.3 4307.8 4427.2 4531.5 4589.8 4599.4 333145.0 335055.0 335110.3 336559.0 337676.0 35113.5 36007.0 58688.7 59085.4 59191.7 38340.8 38567.0 38672.8 38709.4 38717.2 14735.0 15068.5 15088.1 15094.1 15097.1 10175.4 11602.7 11790.9 12607.7 12736.6 8563.4 8564.8 8611.1 8662.7 8670.6 3155.9 3272.6 3378.4 3400.8 3454.6 1010.4 1011.5 1020.6 1022.3 1067.9 31690.5 31709.4 31991.2 32236.7 32398.8 1059.2 1073.0 1075.1 1091.1 1146.6 15867.9 16650.3 16651.7 16716.0 16850.8 551128.0 555066.0 555799.0 556050.0 557438.0 56687.7 57036.0 57061.1 57071.0 57112.0 1200.4 1331.2 1356.3 1360.3 1374.4 30293.0 32398.0 32712.8 32945.5 33085.7 53034.7 54923.7 54931.5 58955.4 59109.7 451537.0 517486.0 521007.0 522440.0 525877.0 27715.0 28312.0 28397.0 39964.4 43449.0 44792.1 45011.5 45665.5 46659.5 46963.3 39487.4 39497.6 40537.0 40579.6 42014.5 38582.4 39221.4 39786.6 39865.0 40756.6 38894.4 38911.2 39057.0 39221.8 39249.0 33121.8 36186.8 36453.9 36848.8 36928.6 27230.3 27378.7 27431.9 27632.3 27647.2 05:58:56 INFO - PROCESS | 20679 | 05:58:56 INFO - PROCESS | 20679 | [#2] jquery.html Cycles:180 Average:28995.23 Median:2476.17 stddev:81305.63 (3283.5%) stddev-sans-first:81505.28 05:58:56 INFO - PROCESS | 20679 | Values: 361.9 376.9 377.8 392.4 405.4 15463.1 17031.7 17897.2 19537.4 19994.9 17661.9 17695.2 18520.7 18979.1 19007.8 5665.6 5730.5 5938.4 5948.8 5957.4 5764.8 5780.9 5808.2 5812.9 5852.9 5626.6 5770.1 5770.7 5772.6 5809.9 5740.8 5793.7 5816.4 5830.8 5847.5 5002.2 5118.6 5161.0 5219.2 5225.0 23225.9 23382.5 23415.5 23419.0 23617.1 2303.6 2314.8 2386.4 2566.0 2840.4 14220.4 14606.5 14860.3 15121.6 15361.5 1889.9 1893.6 1901.7 1986.0 1991.0 78829.6 80044.2 80944.6 81413.6 81472.0 10028.3 10172.3 10580.2 10589.6 10600.9 14212.2 14272.7 14615.6 14616.3 14640.7 7477.9 7556.4 7794.3 7870.7 7872.4 7104.1 7198.1 7228.0 7282.4 7491.9 6532.4 6557.5 6592.9 6615.2 6624.5 1827.9 1885.4 1915.2 1939.4 1944.4 869.7 1005.6 1031.4 1086.8 1089.5 19339.5 20036.4 20037.8 20073.8 20444.9 977.1 984.8 995.8 996.5 1006.9 10425.7 10544.6 10626.3 11146.3 11375.4 453651.0 498415.0 499140.0 506825.0 507335.0 87131.6 90115.0 91114.5 93751.0 93896.0 980.0 1033.3 1058.2 1066.6 1075.0 15832.8 17127.5 17555.6 17621.3 17631.3 39718.6 41529.4 41648.6 41653.0 41803.1 54323.7 55458.7 56016.0 57886.4 58197.7 5417.7 5925.9 8196.5 8524.4 8889.6 9468.5 11438.1 12142.5 12573.6 13313.4 11242.9 11470.8 11630.4 11667.5 12209.5 11181.3 11394.9 11553.1 11655.0 11726.0 11481.0 11501.8 11580.0 11690.8 11705.0 8616.7 8764.6 8778.6 8881.5 8919.3 11322.2 11462.9 11522.7 11546.7 11943.5 05:58:56 INFO - PROCESS | 20679 | 05:58:56 INFO - PROCESS | 20679 | [#3] mootools.html Cycles:180 Average:5566.04 Median:4983.50 stddev:3132.85 (62.9%) stddev-sans-first:3125.36 05:58:56 INFO - PROCESS | 20679 | Values: 1316.4 1459.1 1496.3 1634.7 1782.8 7991.4 8379.7 8403.1 8570.5 8575.2 8348.5 8451.9 8456.5 8457.0 8464.6 2326.2 2347.3 2356.9 2357.6 2359.4 2184.0 2225.7 2229.1 2239.0 2239.6 2198.8 2219.5 2219.6 2233.1 2234.1 2158.1 2164.7 2170.5 2177.1 2179.6 2173.4 2178.1 2180.3 2180.8 2182.7 8334.2 8342.1 8391.0 8451.5 8568.6 4862.4 4991.0 5059.3 5063.2 5063.4 3129.6 3266.8 3306.1 3322.4 3332.1 1051.5 1052.8 1060.3 1079.5 1082.2 11622.9 11735.8 11763.5 11777.5 11813.9 4016.4 4157.8 4249.0 4302.9 4304.1 9257.8 9298.5 9299.8 9303.6 9307.1 4887.8 4933.5 4963.7 4968.0 4976.0 3914.7 4133.3 4240.4 4245.1 4579.4 3597.5 3686.6 3695.0 3695.5 3724.2 1095.7 1096.3 1101.1 1112.4 1122.3 875.3 937.5 964.8 978.3 988.3 5774.2 5843.2 5944.3 5948.9 6115.7 1027.6 1034.6 1039.0 1056.8 1058.9 4076.4 4170.1 4180.3 4190.8 4316.6 10290.7 10369.7 10458.7 10531.1 10769.1 10423.3 10444.5 10459.8 10563.0 10569.2 7527.1 7884.3 7887.0 8031.5 8093.2 5747.7 5820.4 5847.6 5977.3 6060.4 9490.9 9512.5 9524.3 9613.6 9647.0 9011.5 9512.1 9555.5 9687.7 9887.0 7359.2 7478.7 7566.7 7732.2 7741.3 8119.1 8174.6 8229.8 8249.1 8289.0 7408.8 7423.4 7429.7 7457.1 7464.3 7424.4 7434.7 7436.5 7641.7 7669.3 6689.4 6710.3 6745.9 6766.0 6815.0 5045.4 5210.9 5264.4 5281.4 5338.8 5191.6 5241.8 5268.3 5288.2 5288.6 05:58:56 INFO - PROCESS | 20679 | 05:58:56 INFO - PROCESS | 20679 | [#4] prototype.html Cycles:180 Average:4541.21 Median:5135.95 stddev:1320.98 (25.7%) stddev-sans-first:1286.03 05:58:56 INFO - PROCESS | 20679 | Values: 314.6 333.6 334.4 335.7 337.9 5223.5 5296.4 5303.0 5306.4 5312.2 4950.1 5037.2 5137.2 5143.2 5218.3 4103.9 4108.2 4110.1 4123.8 4149.0 4115.6 4124.4 4126.3 4130.6 4143.2 3892.6 3898.9 4053.9 4120.6 4125.4 4095.3 4098.4 4101.9 4110.3 4139.8 3659.4 3677.4 3681.5 3694.6 3700.8 5011.7 5027.2 5027.2 5134.7 5139.0 4640.6 4649.2 4658.7 4660.2 4673.7 5346.3 5364.9 5383.7 5417.7 5432.2 3720.0 3921.7 3985.7 4027.8 4034.5 5580.9 5583.6 5584.4 5598.2 5618.8 4058.9 4075.9 4079.1 4079.7 4101.1 3988.1 4138.6 4140.5 4142.8 4156.6 4695.8 4704.9 4705.6 4712.4 4721.0 5437.7 5443.3 5450.5 5526.2 5536.2 1394.4 1438.9 1454.6 1457.5 1459.1 921.7 962.0 962.8 968.2 972.5 4459.6 4661.5 4669.2 4710.7 4777.3 4864.6 4882.0 4886.9 4900.0 4919.3 4663.0 4677.6 4688.5 4719.4 4723.0 2555.8 2562.6 2603.0 2615.5 2737.5 5647.9 5654.9 5680.3 5681.5 5682.5 5696.8 5700.2 5702.7 5722.4 5739.5 5615.7 5643.4 5711.1 5718.8 5767.8 5520.9 5523.8 5526.6 5577.3 5591.6 5511.7 5526.9 5534.7 5541.9 5561.9 5465.0 5743.9 5759.9 5767.5 5790.6 4074.5 4080.8 4085.5 4096.8 4118.7 5197.7 5401.6 5493.4 5513.4 5680.2 5408.5 5422.6 5437.8 5458.0 5481.5 5287.9 5449.4 5452.8 5461.0 5496.5 5355.9 5371.1 5377.4 5397.4 5404.8 5448.7 5464.7 5465.0 5471.6 5474.3 5221.6 5276.2 5279.4 5370.6 5375.9 05:58:56 INFO - PROCESS | 20679 | 05:58:56 INFO - PROCESS | 20679 | [#5] yui.html Cycles:180 Average:19801.23 Median:4449.00 stddev:27663.52 (621.8%) stddev-sans-first:27704.38 05:58:56 INFO - PROCESS | 20679 | Values: 814.5 816.1 835.0 851.7 861.7 15675.6 17220.8 17362.2 17502.9 17510.3 14729.9 15038.8 15169.6 15183.0 15184.2 801.7 820.9 821.4 821.5 823.4 664.5 798.5 806.4 820.1 830.8 876.2 877.8 879.3 879.4 879.5 862.7 869.0 869.1 869.3 870.3 506.7 612.2 616.7 627.3 635.8 15613.5 15648.6 15666.6 15671.1 15682.5 13358.3 13863.5 13968.1 14167.1 14321.1 13455.9 13469.8 13525.3 13775.5 13814.3 4423.1 4426.7 4471.3 4476.2 4515.5 84818.4 86616.6 87522.0 88487.6 89463.0 11306.2 11420.4 11433.9 11632.2 11871.3 21859.7 21925.8 21974.9 22006.0 22040.8 8211.4 8230.7 8443.9 8499.2 8765.2 4720.7 4731.6 4743.0 4774.8 4975.7 3226.7 3329.8 3331.7 3334.9 3350.4 557.3 562.8 624.0 657.3 658.1 650.2 711.5 715.6 736.2 748.1 10019.9 10315.5 10374.8 10916.6 11042.6 680.3 720.5 730.8 757.3 782.4 4836.3 4905.0 5037.9 5098.5 5283.7 93427.5 93970.0 94497.0 118673.0 121102.0 95117.5 95781.5 96436.0 97251.0 98666.0 54794.0 55996.0 81731.0 83099.0 86867.6 9943.8 10339.3 10675.6 10793.9 10949.7 36861.8 40001.0 47987.0 48158.0 49677.8 43640.8 45371.3 45537.0 48548.3 67271.7 13228.1 13266.9 13441.7 13468.6 13593.5 14897.6 15129.5 15145.8 15828.3 16009.0 10816.1 13985.6 14571.7 14602.0 14875.6 13524.7 13850.9 14199.5 14477.5 14709.3 14704.9 15042.7 15613.5 15651.6 15666.2 11178.6 11798.2 11804.2 11804.7 11973.2 10322.5 10460.4 10675.7 10715.9 12037.8 05:58:56 INFO - PROCESS | 20679 | -------- Summary: end -------- 05:58:56 INFO - PROCESS | 20679 | 05:58:57 INFO - TEST-INFO | 20679: exit 0 05:58:57 INFO - TEST-OK | dromaeo_css | took 1116307ms 05:58:57 INFO - TEST-START | kraken 05:58:57 INFO - operating with platform_type : linux_ 05:58:57 INFO - Initialising browser for kraken test... 05:58:57 INFO - TEST-INFO | started process 20987 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuZpdye/profile http://localhost:53560/getInfo.html) 05:59:01 INFO - PROCESS | 20987 | __metrics Screen width/height:1600/1200 05:59:01 INFO - PROCESS | 20987 | colorDepth:24 05:59:01 INFO - PROCESS | 20987 | Browser inner width/height: 1024/697 05:59:01 INFO - PROCESS | 20987 | __metrics 05:59:02 INFO - PROCESS | 20987 | 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" 05:59:02 INFO - PROCESS | 20987 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:59:02 INFO - PROCESS | 20987 | console.error: PushService: 05:59:02 INFO - PROCESS | 20987 | stateChangeProcessEnqueue: Error transitioning state 05:59:02 INFO - PROCESS | 20987 | UnknownError 05:59:03 INFO - TEST-INFO | 20987: exit 0 05:59:03 INFO - Browser initialized. 05:59:03 INFO - Running cycle 1/1 for kraken test... 05:59:03 INFO - TEST-INFO | started process 21068 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuZpdye/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:59:15 INFO - PROCESS | 21068 | RSS: Main: 180187136 05:59:15 INFO - PROCESS | 21068 | 05:59:18 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53560/tests/kraken/driver.html?testName=audio-beat-detection) 05:59:18 INFO - PROCESS | 21068 | RSS: Main: 234012672 05:59:18 INFO - PROCESS | 21068 | 05:59:21 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53560/tests/kraken/driver.html?testName=audio-dft) 05:59:22 INFO - PROCESS | 21068 | RSS: Main: 225374208 05:59:22 INFO - PROCESS | 21068 | 05:59:25 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53560/tests/kraken/driver.html?testName=audio-fft) 05:59:25 INFO - PROCESS | 21068 | RSS: Main: 302497792 05:59:25 INFO - PROCESS | 21068 | 05:59:28 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53560/tests/kraken/driver.html?testName=audio-oscillator) 05:59:28 INFO - PROCESS | 21068 | RSS: Main: 212684800 05:59:28 INFO - PROCESS | 21068 | 05:59:30 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53560/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:59:31 INFO - PROCESS | 21068 | RSS: Main: 214044672 05:59:31 INFO - PROCESS | 21068 | 05:59:34 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53560/tests/kraken/driver.html?testName=imaging-darkroom) 05:59:35 INFO - PROCESS | 21068 | RSS: Main: 252084224 05:59:35 INFO - PROCESS | 21068 | 05:59:38 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53560/tests/kraken/driver.html?testName=imaging-desaturate) 05:59:39 INFO - PROCESS | 21068 | RSS: Main: 251256832 05:59:39 INFO - PROCESS | 21068 | 05:59:41 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53560/tests/kraken/driver.html?testName=json-parse-financial) 05:59:42 INFO - PROCESS | 21068 | RSS: Main: 251281408 05:59:42 INFO - PROCESS | 21068 | 05:59:43 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53560/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:59:44 INFO - PROCESS | 21068 | RSS: Main: 213962752 05:59:44 INFO - PROCESS | 21068 | 05:59:46 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53560/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:59:47 INFO - PROCESS | 21068 | RSS: Main: 220266496 05:59:47 INFO - PROCESS | 21068 | 05:59:49 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53560/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:59:49 INFO - PROCESS | 21068 | RSS: Main: 221958144 05:59:49 INFO - PROCESS | 21068 | 05:59:52 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53560/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:59:53 INFO - PROCESS | 21068 | RSS: Main: 223842304 05:59:53 INFO - PROCESS | 21068 | 05:59:55 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53560/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:59:56 INFO - PROCESS | 21068 | RSS: Main: 221061120 05:59:56 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | Cycle 1(1): loaded http://localhost:53560/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53560/tests/kraken/driver.html?testName=ai-astar) 05:59:58 INFO - PROCESS | 21068 | RSS: Main: 272027648 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | __start_tp_report 05:59:58 INFO - PROCESS | 21068 | _x_x_mozilla_page_load 05:59:58 INFO - PROCESS | 21068 | _x_x_mozilla_page_load_details 05:59:58 INFO - PROCESS | 21068 | |i|pagename|runs| 05:59:58 INFO - PROCESS | 21068 | |0;ai-astar;112;111;129;113;111;113;112;113;110;111 05:59:58 INFO - PROCESS | 21068 | |1;audio-beat-detection;155;117;148;175;133;149;141;123;187;122 05:59:58 INFO - PROCESS | 21068 | |2;audio-dft;160;161;162;160;160;160;162;161;161;161 05:59:58 INFO - PROCESS | 21068 | |3;audio-fft;86;75;75;180;76;76;75;74;179;75 05:59:58 INFO - PROCESS | 21068 | |4;audio-oscillator;82;82;145;84;85;83;161;82;86;142 05:59:58 INFO - PROCESS | 21068 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;117;116;116 05:59:58 INFO - PROCESS | 21068 | |6;imaging-darkroom;146;147;146;146;146;146;146;146;146;147 05:59:58 INFO - PROCESS | 21068 | |7;imaging-desaturate;95;95;95;95;94;95;95;97;95;94 05:59:58 INFO - PROCESS | 21068 | |8;json-parse-financial;55;51;52;54;52;52;56;64;51;60 05:59:58 INFO - PROCESS | 21068 | |9;json-stringify-tinderbox;62;62;63;62;63;63;62;63;63;63 05:59:58 INFO - PROCESS | 21068 | |10;stanford-crypto-aes;81;81;80;87;86;80;88;82;79;79 05:59:58 INFO - PROCESS | 21068 | |11;stanford-crypto-ccm;137;123;124;131;137;127;125;123;163;133 05:59:58 INFO - PROCESS | 21068 | |12;stanford-crypto-pbkdf2;163;166;164;168;167;164;165;162;167;166 05:59:58 INFO - PROCESS | 21068 | |13;stanford-crypto-sha256-iterative;66;65;65;64;64;68;65;64;64;65 05:59:58 INFO - PROCESS | 21068 | __end_tp_report 05:59:58 INFO - PROCESS | 21068 | __start_cc_report 05:59:58 INFO - PROCESS | 21068 | _x_x_mozilla_cycle_collect,1670 05:59:58 INFO - PROCESS | 21068 | __end_cc_report 05:59:58 INFO - PROCESS | 21068 | __startTimestamp1472734798305__endTimestamp 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | ------- Summary: start ------- 05:59:58 INFO - PROCESS | 21068 | Number of tests: 14 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#0] ai-astar Cycles:10 Average:113.50 Median:112.50 stddev:5.54 (4.9%) stddev-sans-first:5.85 05:59:58 INFO - PROCESS | 21068 | Values: 112.0 111.0 129.0 113.0 111.0 113.0 112.0 113.0 110.0 111.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:148.50 stddev:22.96 (15.5%) stddev-sans-first:24.07 05:59:58 INFO - PROCESS | 21068 | Values: 155.0 117.0 148.0 175.0 133.0 149.0 141.0 123.0 187.0 122.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 05:59:58 INFO - PROCESS | 21068 | Values: 160.0 161.0 162.0 160.0 160.0 160.0 162.0 161.0 161.0 161.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#3] audio-fft Cycles:10 Average:97.10 Median:75.00 stddev:43.56 (58.1%) stddev-sans-first:46.02 05:59:58 INFO - PROCESS | 21068 | Values: 86.0 75.0 75.0 180.0 76.0 76.0 75.0 74.0 179.0 75.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:82.50 stddev:32.22 (39.1%) stddev-sans-first:33.25 05:59:58 INFO - PROCESS | 21068 | Values: 82.0 82.0 145.0 84.0 85.0 83.0 161.0 82.0 86.0 142.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 05:59:58 INFO - PROCESS | 21068 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 05:59:58 INFO - PROCESS | 21068 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 05:59:58 INFO - PROCESS | 21068 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 97.0 95.0 94.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.50 stddev:4.30 (7.9%) stddev-sans-first:4.56 05:59:58 INFO - PROCESS | 21068 | Values: 55.0 51.0 52.0 54.0 52.0 52.0 56.0 64.0 51.0 60.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 05:59:58 INFO - PROCESS | 21068 | Values: 62.0 62.0 63.0 62.0 63.0 63.0 62.0 63.0 63.0 63.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:81.50 stddev:3.40 (4.2%) stddev-sans-first:3.57 05:59:58 INFO - PROCESS | 21068 | Values: 81.0 81.0 80.0 87.0 86.0 80.0 88.0 82.0 79.0 79.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#11] stanford-crypto-ccm Cycles:10 Average:132.30 Median:132.00 stddev:12.07 (9.1%) stddev-sans-first:12.69 05:59:58 INFO - PROCESS | 21068 | Values: 137.0 123.0 124.0 131.0 137.0 127.0 125.0 123.0 163.0 133.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:166.00 stddev:1.93 (1.2%) stddev-sans-first:1.88 05:59:58 INFO - PROCESS | 21068 | Values: 163.0 166.0 164.0 168.0 167.0 164.0 165.0 162.0 167.0 166.0 05:59:58 INFO - PROCESS | 21068 | 05:59:58 INFO - PROCESS | 21068 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:1.25 (1.9%) stddev-sans-first:1.27 05:59:58 INFO - PROCESS | 21068 | Values: 66.0 65.0 65.0 64.0 64.0 68.0 65.0 64.0 64.0 65.0 05:59:58 INFO - PROCESS | 21068 | -------- Summary: end -------- 05:59:58 INFO - PROCESS | 21068 | 05:59:59 INFO - TEST-INFO | 21068: exit 0 05:59:59 INFO - TEST-OK | kraken | took 62327ms 05:59:59 INFO - SUITE-END | took 1178s 05:59:59 INFO - Completed test suite (00:19:39) 05:59:59 INFO - javascript benchmark 06:00:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2936.1044881311204, 2962.0, 2992.11732538901, 3037.07369252378, 3048.085574327701, 5187.377514698242, 5197.7920883164625, 5278.762455689493, 5358.758855851486, 5363.249145119681, 5207.479252074791, 5229.50319719627, 5276.736163191844, 5295.947040529593, 5369.543588794957, 4747.881302967437, 4767.070421267854, 4880.7071575705395, 4918.581920536759, 4937.210046392578, 4877.0, 4901.215805471125, 4903.31697899148, 4926.852194434173, 4947.901041979158, 4653.278741795028, 4673.953260467386, 4812.855614331558, 4816.759162041884, 4914.213725803872, 4729.929051064237, 4735.57379835813, 4736.407949006374, 4738.076075165344, 4742.668013239075, 4683.1336202802595, 4695.060987802419, 4707.552782485658, 4711.175544279771, 4716.127516409441, 5085.821996230151, 5127.1796512558185, 5129.6409251352425, 5130.846074617767, 5134.460881607415, 5466.699331536764, 5478.287822583078, 5481.287432633772, 5482.780688772445, 5523.143912693498, 5572.721363931787, 5591.02157122506, 5608.186812912146, 5622.803201887955, 5699.173619825144, 5269.0, 5334.893302133935, 5336.439673834269, 5338.065838478251, 5341.038613049688, 5670.489655930986, 5691.459311365414, 5796.88406231873, 5806.506446952057, 5807.94192058074, 5507.339119305629, 5514.172874068921, 5556.833295001156, 5597.244372009808, 5602.075657516546, 5616.550675945915, 5621.634593751393, 5623.128415095667, 5626.85932851682, 5631.887362252732, 5465.535429488458, 5542.612017158841, 5594.748236329375, 5601.159826026129, 5607.383187849333, 5592.3009623797025, 5652.491275785199, 5656.123300888369, 5690.971545142248, 5725.198472213893, 4373.934390984138, 4378.737275763465, 4383.452068491439, 4384.802683879169, 4432.135733531994, 3829.885103446901, 3853.8073096345634, 3864.768113913174, 3877.806109694504, 3920.882373528799, 5368.32895888014, 5387.892242155135, 5400.756965936543, 5464.644798088111, 5466.426025267369, 5677.829665110054, 5688.772449102073, 5689.118186680988, 5690.829275121753, 5697.57268204878, 5288.048151332797, 5336.199570064444, 5389.9191512127345, 5408.0, 5465.043617366945, 4876.244182151772, 4898.485659005824, 4900.705957642553, 4952.678075925053, 4955.108080545537, 5882.470577648032, 5899.528037757055, 5901.144334071536, 5904.232449781501, 5907.350191478848, 5401.540869026019, 5638.5489160867855, 5656.43435656431, 5706.0, 5768.625039372428, 5886.558508111823, 5898.528117750656, 5918.852028699144, 5952.791652292149, 5955.076963070558, 5574.331080270394, 5613.719314034201, 5619.157126431068, 5621.184928185472, 5627.127795191752, 5361.410244873139, 5395.973020134874, 5724.57065720081, 5736.368999410145, 5736.885262294814, 5532.917006244829, 5569.470900264469, 5583.832485025456, 5590.748416321194, 5650.039493286234, 5575.163725441216, 5580.609357345109, 5582.9720851397105, 5587.944120558905, 5589.245451863946, 5313.149896016619, 5524.033294173584, 5534.280543529316, 5537.197106419627, 5597.972010140086, 5495.890082198413, 5556.194351819045, 5564.4991950724625, 5571.9721401392735, 5642.605017648725, 5416.83749487516, 5454.918176227439, 5457.863553411196, 5459.181122831448, 5467.234587157722], "value": 5208.250144198685, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.7617236502122, 572.5105035194908, 574.3624576719842, 616.4636766013566, 634.4258446106276, 32286.67713322866, 46426.0, 47279.76360118203, 47328.52671473284, 47400.52599474004, 45754.0, 46174.76912615432, 46200.76899615506, 46201.53798462015, 46240.76879615597, 8104.0, 8924.509151996637, 9075.047120052399, 9084.591193396313, 9086.86369704455, 10428.791424171512, 10761.83857242138, 10771.83842242363, 11065.557377704883, 11079.944600277027, 10922.945385273062, 11008.339499630009, 11024.118070554376, 11027.61403350883, 11028.558857645725, 10834.729131771768, 10896.0, 10948.726281842937, 10949.397783121925, 10972.396518191455, 10584.259101862872, 10634.989675980769, 10646.201534884915, 10720.517576709066, 10728.946355268174, 42175.1564968702, 42472.36291455631, 42544.0, 42548.14903701939, 42565.78717106426, 3360.462326027824, 3535.5757309122814, 3653.451982202664, 3866.2074274773604, 3890.513685789276, 32524.837375813207, 32679.83660081708, 32758.508622370682, 32916.177095572566, 32945.34109317824, 4307.78461076948, 4427.22523558376, 4531.456225252958, 4589.839355622571, 4599.44806623204, 333145.0, 335055.0, 335110.32444837864, 336559.0, 337676.0, 35113.47329790055, 36007.0, 58688.70655646695, 59085.40914590885, 59191.70404147952, 38340.80829595834, 38567.0, 38672.80663596664, 38709.419358709645, 38717.225655486734, 14735.0, 15068.54794356171, 15088.094714317176, 15094.094354338775, 15097.094174349575, 10175.389476631277, 11602.6519204424, 11790.879866412677, 12607.747845043232, 12736.61790146297, 8563.443376180529, 8564.828703425897, 8611.095834937241, 8662.696805611773, 8670.566471676391, 3155.921101972469, 3272.6236482804784, 3378.44255697812, 3400.8129552874334, 3454.635419009477, 1010.4493051287067, 1011.4588695048114, 1020.6325722739956, 1022.3252653248969, 1067.9359238445718, 31690.524642130385, 31709.365812683616, 31991.200219994687, 32236.677633223837, 32398.838005810292, 1059.2003037706636, 1072.9892701073045, 1075.1184029096223, 1091.121647074113, 1146.5585749486509, 15867.920660396625, 16650.3339866404, 16651.6669666606, 16716.0, 16850.756244275515, 551128.0, 555066.0, 555799.0, 556050.0, 557438.0, 56687.71656141776, 57036.0, 57061.14408283879, 57071.0, 57112.0, 1200.4237965776476, 1331.2145833958011, 1356.3286173344259, 1360.340234986031, 1374.3540535948089, 30293.0, 32398.0, 32712.83643581767, 32945.505817413235, 33085.66914330826, 53034.73482632562, 54923.72538137284, 54931.45068549264, 58955.410445894995, 59109.70445147747, 451537.0, 517486.0, 521007.0, 522440.0, 525877.0, 27715.0, 28312.009079682113, 28397.0, 39964.40053399259, 43449.0, 44792.10415791731, 45011.54988450074, 45665.54334456746, 46659.53340466688, 46963.29555056609, 39487.407688885265, 39497.60502394939, 40537.0, 40579.59420405758, 42014.48775439812, 38582.42126368163, 39221.41167882541, 39786.60213397829, 39865.0, 40756.59243407647, 38894.4165837507, 38911.221775564896, 39057.0, 39221.803890980365, 39249.0, 33121.83439082886, 36186.8190659045, 36453.89575040832, 36848.81575592105, 36928.63071369252, 27230.319242019108, 27378.726212737616, 27431.86284068567, 27632.309192270353, 27647.170584882486], "value": 19423.13445846519, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.87515307219, 376.88505005973184, 377.77711150421266, 392.4368531157789, 405.43846772220485, 15463.149526776022, 17031.744523832138, 17897.194626241817, 19537.413877583658, 19994.900025499854, 17661.91169044156, 17695.203715832788, 18520.72218916714, 18979.145938432768, 19007.809921900778, 5665.603407761459, 5730.512906402951, 5938.435848594377, 5948.762049518035, 5957.374475680056, 5764.798232061858, 5780.942190578104, 5808.215890854723, 5812.883742325151, 5852.912206316909, 5626.634268772516, 5770.134479828039, 5770.653760774367, 5772.624779389326, 5809.854753631151, 5740.7703691852275, 5793.739281732311, 5816.360200377955, 5830.7959221427045, 5847.473727364536, 5002.199648056294, 5118.564921981636, 5161.0, 5219.243209734605, 5224.973875130638, 23225.883870580707, 23382.532349352918, 23415.53168936612, 23419.0, 23617.055317787268, 2303.5968705476475, 2314.8148148148107, 2386.367612582667, 2565.9743402565923, 2840.4035152617976, 14220.431182752678, 14606.488772893004, 14860.256987150598, 15121.621959451102, 15361.46234881785, 1889.914953827081, 1893.6307420053095, 1901.7147427885927, 1985.9702104468445, 1991.0243980449475, 78829.60585197095, 80044.19955800426, 80944.59527702382, 81413.59293203555, 81472.0, 10028.348157369748, 10172.338797978107, 10580.15358771312, 10589.629362972259, 10600.893991059991, 14212.21832799206, 14272.714545709028, 14615.561533154023, 14616.342264598119, 14640.707185856436, 7477.887831682529, 7556.433267504959, 7794.259545343183, 7870.685172593032, 7872.409569282212, 7104.11198600175, 7198.136223653195, 7228.0, 7282.3809976152625, 7491.887621685679, 6532.444742196966, 6557.540972132, 6592.923229182792, 6615.17310336208, 6624.536282460278, 1827.8903265804095, 1885.4060970794155, 1915.1764741161155, 1939.3987863761997, 1944.4263942137104, 869.682565863468, 1005.5927349423495, 1031.4017869635593, 1086.760912599227, 1089.4607169451172, 19339.51651208703, 20036.39890803278, 20037.799622003593, 20073.799262007193, 20444.897775511028, 977.1059480575194, 984.7833476635129, 995.785906030207, 996.531630133836, 1006.9496525173712, 10425.739356515996, 10544.574747808047, 10626.256162068736, 11146.331220126818, 11375.431228438545, 453651.0, 498415.0, 499140.0, 506825.0, 507335.0, 87131.56434217915, 90115.0, 91114.54442727743, 93751.0, 93896.0, 980.0, 1033.3076838518211, 1058.1587637827945, 1066.6053560182627, 1074.957001719938, 15832.841671583137, 17127.486175414757, 17555.56111097233, 17621.29514819444, 17631.294748209926, 39718.60281397149, 41529.37705934353, 41648.58351416447, 41653.0, 41803.10248808913, 54323.72838135784, 55458.72270638621, 56016.0, 57886.4211357881, 58197.70901145467, 5417.729113544386, 5925.9407405925385, 8196.54918979438, 8524.36067294943, 8889.64441422336, 9468.479233642216, 11438.142139339416, 12142.453589588313, 12573.559925402567, 13313.400896959467, 11242.94378528102, 11470.770584588428, 11630.360330181922, 11667.474963626826, 12209.450574723982, 11181.273217241178, 11394.94302528482, 11553.075753939496, 11655.0, 11726.0, 11481.0, 11501.82747258775, 11580.0, 11690.824637630272, 11705.0, 8616.698415555677, 8764.56177191125, 8778.56107194625, 8881.511516866634, 8919.286457083548, 11322.207445479067, 11462.885371146182, 11522.654320370402, 11546.653600392001, 11943.522259109886], "value": 9243.784107668165, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.4405127820673, 1459.1463993563764, 1496.2743069611236, 1634.7466142747878, 1782.839544441, 7991.440599158062, 8379.706710265142, 8403.075661677225, 8570.528620925847, 8575.185357391054, 8348.540830254333, 8451.87322190166, 8456.534890581015, 8456.95771521143, 8464.576771161448, 2326.2323433267024, 2347.319277409549, 2356.929292121239, 2357.5520651076326, 2359.3629719975597, 2183.9890800545977, 2225.688403623494, 2229.1306390507716, 2238.9552208955893, 2239.5632851593946, 2198.8460807743463, 2219.4895174109965, 2219.5893759654437, 2233.05095334483, 2234.1175235781857, 2158.082814803718, 2164.7402311722535, 2170.4573856535867, 2177.0638625391075, 2179.6403593407067, 2173.4240426287047, 2178.1287485005964, 2180.29140529327, 2180.8473406861526, 2182.6725991101302, 8334.20825021622, 8342.082370939192, 8391.0, 8451.492910425393, 8568.614412351459, 4862.36789217403, 4991.0, 5059.316992206079, 5063.240513922941, 5063.392392912837, 3129.640091389474, 3266.7713260071805, 3306.1404034950847, 3322.352141332441, 3332.0836769888474, 1051.5005372448102, 1052.768390953989, 1060.3160961180072, 1079.5357996061691, 1082.247837752762, 11622.94188529052, 11735.765284694344, 11763.529458821637, 11777.528898844035, 11813.881861181364, 4016.4376987221813, 4157.792110394468, 4248.9787551062045, 4302.870913872589, 4304.074623955802, 9257.814843703223, 9298.488583127857, 9299.814003719888, 9303.627854885865, 9307.06929307064, 4887.755612219375, 4933.45731969483, 4963.70217786934, 4967.950320496749, 4976.0, 3914.706397020235, 4133.338665813516, 4240.427542281815, 4245.108527209259, 4579.38178345919, 3597.4783656370205, 3686.612905644922, 3695.020819482839, 3695.519582454264, 3724.1992971510713, 1095.7370231144469, 1096.276457538021, 1101.1136035491506, 1112.4271000434783, 1122.2537012886503, 875.2866413872673, 937.4937533731709, 964.7781010367655, 978.2711879649706, 988.2983082011708, 5774.191613174159, 5843.211166492554, 5944.286685597756, 5948.881022379528, 6115.724792384353, 1027.6403258859339, 1034.5654824973528, 1038.9584416623402, 1056.7992081504644, 1058.9258751887294, 4076.4089207065404, 4170.103427763045, 4180.3102488089735, 4190.790460476964, 4316.633086187647, 10290.742731431625, 10369.688909332732, 10458.738531536772, 10531.104856087166, 10769.138468922467, 10423.322484038514, 10444.477776111164, 10459.790804183875, 10563.0, 10569.154467642571, 7527.096748390229, 7884.250996155356, 7886.960565197137, 8031.477953932976, 8093.1502192270145, 5747.7413516391025, 5820.359760426434, 5847.649141051551, 5977.252843394575, 6060.363661815446, 9490.905090949002, 9512.476813775309, 9524.333296669167, 9613.615455381987, 9647.0, 9011.549422528979, 9512.09635084666, 9555.522223888916, 9687.70936871895, 9887.0, 7359.153697324662, 7478.70085196586, 7566.735164269224, 7732.188120247293, 7741.303282704584, 8119.147489513516, 8174.632141553763, 8229.79425514367, 8249.092599814136, 8289.0, 7408.777736667908, 7423.443241756998, 7429.702811887681, 7457.142428620778, 7464.290892365434, 7424.406047516078, 7434.665440055319, 7436.516626419266, 7641.6561254742555, 7669.348105411103, 6689.397954184147, 6710.295418980937, 6745.865082698417, 6766.0, 6814.965925170342, 5045.369328833895, 5210.947890521198, 5264.394594621514, 5281.366236051698, 5338.839834804962, 5191.636585439134, 5241.790328386975, 5268.315119034501, 5288.206768984529, 5288.567259060875], "value": 4467.591607092087, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.5941735161643, 333.6046784560296, 334.4097667616659, 335.70122590894107, 337.92565635560175, 5223.485189295105, 5296.364436267653, 5302.973485132574, 5306.416294207644, 5312.150055991042, 4950.084234416636, 5037.244413338001, 5137.178051511759, 5143.151380022292, 5218.347706536683, 4103.9179216415805, 4108.157827645326, 4110.075233072557, 4123.814428350716, 4148.958510414887, 4115.621266875601, 4124.422580838685, 4126.29852925002, 4130.628243458088, 4143.19207754488, 3892.630200130983, 3898.9025274368228, 4053.871717348386, 4120.649744771698, 4125.381192821086, 4095.344744840811, 4098.426220329156, 4101.85643502476, 4110.280700877364, 4139.79301034947, 3659.414493681025, 3677.3748462761127, 3681.5398075240596, 3694.5751238607636, 3700.8149592520267, 5011.749412529395, 5027.2207807789855, 5027.2207807789855, 5134.717590532519, 5139.0, 4640.60554852838, 4649.209634362132, 4658.697184682985, 4660.18446771817, 4673.696209746356, 5346.289187460015, 5364.892702145974, 5383.734822316753, 5417.729113544347, 5432.239486471897, 3719.981400093009, 3921.725479216456, 3985.668383995366, 4027.7583344999393, 4034.515858097047, 5580.944190558083, 5583.637063590894, 5584.441555844423, 5598.1602759586385, 5618.836747852107, 4058.9188216236103, 4075.89810254746, 4079.0618157823865, 4079.653229475528, 4101.118259574147, 3988.1455123367773, 4138.5654506276405, 4140.523839758406, 4142.772147531915, 4156.605122513356, 4695.802570344608, 4704.858854234379, 4705.60002399793, 4712.410948631421, 4721.0, 5437.728113594305, 5443.265159203457, 5450.4549545045975, 5526.198701188305, 5536.197251398525, 1394.4282844033899, 1438.8848892088608, 1454.632645313402, 1457.4825936792497, 1459.0880699562774, 921.6774129054777, 961.9807603848024, 962.8459446488528, 968.2496065549255, 972.5477652891298, 4459.620932220798, 4661.510541393172, 4669.229577119805, 4710.740909250024, 4777.307290442866, 4864.586510146606, 4881.973176630512, 4886.877828054327, 4899.951000490021, 4919.2867034279825, 4663.0, 4677.64917631179, 4688.46082700487, 4719.4100737407825, 4723.0, 2555.833870798392, 2562.6027965665353, 2602.9718261287067, 2615.5161295160456, 2737.4935636907235, 5647.915281270784, 5654.9151762723595, 5680.318361796528, 5681.545476361881, 5682.460166284196, 5696.778256479894, 5700.173474846124, 5702.686352250583, 5722.37053924068, 5739.483446489836, 5615.6630602164005, 5643.351014633287, 5711.077881496065, 5718.828435146952, 5767.826965191051, 5520.861978450571, 5523.834284971457, 5526.590719366536, 5577.330720313588, 5591.552675786009, 5511.696856672921, 5526.944730552643, 5534.667919924817, 5541.916871246854, 5561.916571251515, 5465.0, 5743.942560574341, 5759.8560035999435, 5767.509761670305, 5790.594658373873, 4074.5110586729784, 4080.816363263601, 4085.5097388313593, 4096.795160241916, 4118.732282401635, 5197.7141257231215, 5401.567874570103, 5493.423190564933, 5513.365962914316, 5680.2047713321, 5408.540274076748, 5422.62041657096, 5437.809676661298, 5457.972710136424, 5481.479259470344, 5287.867803304948, 5449.373322067854, 5452.773126046608, 5461.0, 5496.450354964471, 5355.892882142413, 5371.060064488777, 5377.3816011159215, 5397.433269506802, 5404.756785944721, 5448.700321482356, 5464.672119672832, 5465.0, 5471.562275018068, 5474.2609747683555, 5221.556167725786, 5276.18219176013, 5279.357243501971, 5370.570354371563, 5375.892482150257], "value": 4125.693752580658, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.4665244265004, 816.1185919207256, 834.9791255218622, 851.7487341234336, 861.6553378648543, 15675.60810979726, 17220.82779172208, 17362.21870015849, 17502.91248543757, 17510.299588016464, 14729.852701472983, 15038.774418383733, 15169.620759481018, 15183.013104148222, 15184.240787960585, 801.7274126796873, 820.9055958564752, 821.3716506872217, 821.4742564758559, 823.4153750836913, 664.5481072870447, 798.5049269452918, 806.3508875355336, 820.1060843680388, 830.8089139497919, 876.2420506262101, 877.7542288159326, 879.3009557401881, 879.3844308983705, 879.5338470610586, 862.6980556805099, 869.0353707384797, 869.0787764969153, 869.3479890082439, 870.2733217763175, 506.74662668665667, 612.2499937576433, 616.6515918506075, 627.3444250757923, 635.809257222836, 15613.453529126426, 15648.608784780357, 15666.608334791608, 15671.138087405188, 15682.529524114294, 13358.332083395791, 13863.514776982756, 13968.092074015156, 14167.149971001772, 14321.069130506483, 13455.865441345559, 13469.797953030713, 13525.256110913895, 13775.517856874962, 13814.309284535733, 4423.137488189772, 4426.712263702849, 4471.28459446487, 4476.216662084154, 4515.480719717241, 84818.38649903375, 86616.56691716565, 87522.0, 88487.55756221242, 89463.0, 11306.152038597138, 11420.371879546705, 11433.885661143282, 11632.243904146204, 11871.287722736663, 21859.672104918438, 21925.780742192692, 21974.89012554927, 22006.0, 22040.779592204195, 8211.425200235926, 8230.670773169006, 8443.915560844436, 8499.235068843835, 8765.211130998241, 4720.740359280204, 4731.597814185764, 4742.976285118552, 4774.832880849153, 4975.701457912537, 3226.6773322667545, 3329.8335083245743, 3331.666833316649, 3334.9166270843425, 3350.363430948112, 557.2922388566498, 562.8424041268452, 623.9594426362272, 657.3459407889181, 658.134553062721, 650.180772226988, 711.548166914003, 715.6386025057312, 736.2219197408884, 748.0649188514357, 10019.903157769664, 10315.535800888978, 10374.792504149875, 10916.56333746657, 11042.566317088173, 680.306087790451, 720.5172534401963, 730.8355619985567, 757.2692351880462, 782.385827125715, 4836.298736683161, 4904.975475122673, 5037.874053148627, 5098.490150984871, 5283.709395983258, 93427.53286233525, 93970.0, 94497.0, 118673.0, 121102.0, 95117.52441237887, 95781.52109239409, 96436.0, 97251.0, 98666.0, 54794.0, 55996.0, 81731.0, 83099.0, 86867.56566217128, 9943.801123977624, 10339.276250662382, 10675.57297708083, 10793.866644002266, 10949.726256843642, 36861.81569092245, 40000.99997500086, 47987.0, 48158.0, 49677.751611241714, 43640.781796090814, 45371.31943020923, 45537.0, 48548.27177592268, 67271.66364168147, 13228.14017088886, 13266.933665331611, 13441.731165376834, 13468.595942121752, 13593.45626174942, 14897.553073407815, 15129.47046853355, 15145.848541514444, 15828.335944282446, 16009.0, 10816.053110440196, 13985.58043258704, 14571.708565828412, 14602.0, 14875.628109297355, 13524.661883452993, 13850.930745346208, 14199.503017394341, 14477.493287734878, 14709.264536773333, 14704.926475367554, 15042.6991460168, 15613.531594052196, 15651.608709782347, 15666.216689165269, 11178.608748693756, 11798.174127810971, 11804.173707840711, 11804.70488237801, 11973.221740586834, 10322.483875806029, 10460.372377657364, 10675.679729608124, 10715.946420268161, 12037.796220377892], "value": 7622.543760776159, "unit": "score"}], "name": "dromaeo_css", "value": 7130.111782588042, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 129.0, 113.0, 111.0, 113.0, 112.0, 113.0, 110.0, 111.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 117.0, 148.0, 175.0, 133.0, 149.0, 141.0, 123.0, 187.0, 122.0], "value": 145.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 160.0, 160.0, 160.0, 162.0, 161.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 75.0, 75.0, 180.0, 76.0, 76.0, 75.0, 74.0, 179.0, 75.0], "value": 97.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 145.0, 84.0, 85.0, 83.0, 161.0, 82.0, 86.0, 142.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 97.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 52.0, 54.0, 52.0, 52.0, 56.0, 64.0, 51.0, 60.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 87.0, 86.0, 80.0, 88.0, 82.0, 79.0, 79.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 123.0, 124.0, 131.0, 137.0, 127.0, 125.0, 123.0, 163.0, 133.0], "value": 132.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 164.0, 168.0, 167.0, 164.0, 165.0, 162.0, 167.0, 166.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 64.0, 64.0, 68.0, 65.0, 64.0, 64.0, 65.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1539.5, "alertThreshold": 2.0}]} 06:00:00 INFO - Return code: 0 06:00:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:00:00 INFO - # TBPL SUCCESS # 06:00:00 INFO - Running post-action listener: _resource_record_post_action 06:00:00 INFO - [mozharness: 2016-09-01 13:00:00.117936Z] Finished run-tests step (success) 06:00:00 INFO - Running post-run listener: _resource_record_post_run 06:00:00 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 418275328; Read time: 20; Write time: 338568 06:00:00 INFO - TinderboxPrint: CPU usage
13.1% 06:00:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 06:00:00 INFO - TinderboxPrint: I/O write bytes / time
418,275,328 / 338,568 06:00:00 INFO - TinderboxPrint: CPU idle
8,293.5 (86.9%) 06:00:00 INFO - TinderboxPrint: CPU user
1,198.1 (12.6%) 06:00:00 INFO - TinderboxPrint: Swap in / out
0 / 0 06:00:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 129728512; Read time: 0; Write time: 119280 06:00:00 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 288546816; Read time: 0; Write time: 219288 06:00:00 INFO - Running post-run listener: _upload_blobber_files 06:00:00 INFO - Blob upload gear active. 06:00:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:00:00 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 06:00:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:00:01 INFO - (blobuploader) - INFO - Open directory for files ... 06:00:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:00:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:00:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:01 INFO - (blobuploader) - INFO - Done attempting. 06:00:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:00:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:00:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:02 INFO - (blobuploader) - INFO - Done attempting. 06:00:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:00:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:00:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:07 INFO - (blobuploader) - INFO - Done attempting. 06:00:07 INFO - (blobuploader) - INFO - Iteration through files over. 06:00:07 INFO - Return code: 0 06:00:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:00:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:00:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc8f911111293ee3e98cc39ed028528e1049c6aa3938003df14e444fc70c6a1574d1bc40d55be4764e299d145c9f9e446f24e26e1772a127b25f6d28408bfe01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0eadb5f00502ff3ebeab06bf4a981c9a04e19a30c7670fa81b7c1f9821fdcc83853fd70cf9a80f943ecda770e5719418d05466c2a8091355da62b10758a7b70d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e43b6ac8d130d7519bb23e1d78d019ce4d54bc8767eb934ab4d988f26c2ceb5a7817b514f7012625319809efa8490613211d21efa251d774d4c3b21c2af4a05"} 06:00:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:00:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:00:07 INFO - Contents: 06:00:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc8f911111293ee3e98cc39ed028528e1049c6aa3938003df14e444fc70c6a1574d1bc40d55be4764e299d145c9f9e446f24e26e1772a127b25f6d28408bfe01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0eadb5f00502ff3ebeab06bf4a981c9a04e19a30c7670fa81b7c1f9821fdcc83853fd70cf9a80f943ecda770e5719418d05466c2a8091355da62b10758a7b70d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e43b6ac8d130d7519bb23e1d78d019ce4d54bc8767eb934ab4d988f26c2ceb5a7817b514f7012625319809efa8490613211d21efa251d774d4c3b21c2af4a05"} 06:00:07 INFO - Running post-run listener: copy_logs_to_upload_dir 06:00:07 INFO - Copying logs to upload dir... 06:00:07 INFO - mkdir: /builds/slave/test/build/upload/logs 06:00:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.659285 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-01 06:00:07.100428) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 06:00:07.107559) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc8f911111293ee3e98cc39ed028528e1049c6aa3938003df14e444fc70c6a1574d1bc40d55be4764e299d145c9f9e446f24e26e1772a127b25f6d28408bfe01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0eadb5f00502ff3ebeab06bf4a981c9a04e19a30c7670fa81b7c1f9821fdcc83853fd70cf9a80f943ecda770e5719418d05466c2a8091355da62b10758a7b70d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e43b6ac8d130d7519bb23e1d78d019ce4d54bc8767eb934ab4d988f26c2ceb5a7817b514f7012625319809efa8490613211d21efa251d774d4c3b21c2af4a05"} build_url:https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011209 build_url: 'https://queue.taskcluster.net/v1/task/SnBvS6JfT2uEi1sA6sbjXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc8f911111293ee3e98cc39ed028528e1049c6aa3938003df14e444fc70c6a1574d1bc40d55be4764e299d145c9f9e446f24e26e1772a127b25f6d28408bfe01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0eadb5f00502ff3ebeab06bf4a981c9a04e19a30c7670fa81b7c1f9821fdcc83853fd70cf9a80f943ecda770e5719418d05466c2a8091355da62b10758a7b70d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e43b6ac8d130d7519bb23e1d78d019ce4d54bc8767eb934ab4d988f26c2ceb5a7817b514f7012625319809efa8490613211d21efa251d774d4c3b21c2af4a05"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 06:00:07.132425) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:00:07.132746) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472732433.141918-1535815139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004397 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:00:07.215857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 06:00:07.218216) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 06:00:07.218508) ========= ========= Total master_lag: 0.20 =========