builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-038 starttime: 1480302593.5 results: success (0) buildid: 20161127184057 builduid: e1b454a675c745b78de74372b7b011d4 revision: fa40a7b4f05f6066572872bd0ddc7a7243a862f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:53.502000) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:53.502356) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:53.502626) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:53.520067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:53.520335) ========= 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=8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-27 19:09:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 51.2M=0s 2016-11-27 19:09:53 (51.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127679 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:53.661245) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:53.661525) ========= 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=8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017745 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:53.710820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-27 19:09:53.711173) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev fa40a7b4f05f6066572872bd0ddc7a7243a862f4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev fa40a7b4f05f6066572872bd0ddc7a7243a862f4 --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=8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-27 19:09:54,063 truncating revision to first 12 chars 2016-11-27 19:09:54,063 Setting DEBUG logging. 2016-11-27 19:09:54,063 attempt 1/10 2016-11-27 19:09:54,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/fa40a7b4f05f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-27 19:09:54,772 unpacking tar archive at: graphics-fa40a7b4f05f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.009689 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-27 19:09:54.734386) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:54.734700) ========= script_repo_revision: fa40a7b4f05f6066572872bd0ddc7a7243a862f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:54.735102) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:54.735413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 19:09:54.744862) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-11-27 19:09:54.745149) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:09:55 INFO - MultiFileLogger online at 20161127 19:09:55 in /builds/slave/test 19:09:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 19:09:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:09:55 INFO - {'append_to_log': False, 19:09:55 INFO - 'base_work_dir': '/builds/slave/test', 19:09:55 INFO - 'blob_upload_branch': 'Graphics', 19:09:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:09:55 INFO - 'branch': 'Graphics', 19:09:55 INFO - 'buildbot_json_path': 'buildprops.json', 19:09:55 INFO - 'config_files': ('talos/linux_config.py',), 19:09:55 INFO - 'default_actions': ('clobber', 19:09:55 INFO - 'read-buildbot-config', 19:09:55 INFO - 'download-and-extract', 19:09:55 INFO - 'populate-webroot', 19:09:55 INFO - 'create-virtualenv', 19:09:55 INFO - 'install', 19:09:55 INFO - 'run-tests'), 19:09:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:09:55 INFO - 'download_minidump_stackwalk': True, 19:09:55 INFO - 'download_symbols': 'ondemand', 19:09:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:09:55 INFO - 'tooltool.py': '/tools/tooltool.py', 19:09:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:09:55 INFO - '/tools/misc-python/virtualenv.py')}, 19:09:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:09:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:09:55 INFO - 'installer_path': 'installer.exe', 19:09:55 INFO - 'log_level': 'info', 19:09:55 INFO - 'log_name': 'talos', 19:09:55 INFO - 'log_to_console': True, 19:09:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:09:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:09:55 INFO - 'opt_config_files': (), 19:09:55 INFO - 'pip_index': False, 19:09:55 INFO - 'sps_profile': False, 19:09:55 INFO - 'sps_profile_interval': 0, 19:09:55 INFO - 'suite': 'dromaeojs', 19:09:55 INFO - 'system_bits': '32', 19:09:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:09:55 INFO - 'title': 'talos-linux64-ix-038', 19:09:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:09:55 INFO - 'use_talos_json': True, 19:09:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:09:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:09:55 INFO - 'work_dir': 'build'} 19:09:55 INFO - [mozharness: 2016-11-28 03:09:55.204161Z] Running clobber step. 19:09:55 INFO - Running pre-action listener: _resource_record_pre_action 19:09:55 INFO - Running main action method: clobber 19:09:55 INFO - rmtree: /builds/slave/test/build 19:09:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:09:56 INFO - Running post-action listener: _resource_record_post_action 19:09:56 INFO - [mozharness: 2016-11-28 03:09:56.179349Z] Finished clobber step (success) 19:09:56 INFO - [mozharness: 2016-11-28 03:09:56.179448Z] Running read-buildbot-config step. 19:09:56 INFO - Running pre-action listener: _resource_record_pre_action 19:09:56 INFO - Running main action method: read_buildbot_config 19:09:56 INFO - Using buildbot properties: 19:09:56 INFO - { 19:09:56 INFO - "project": "", 19:09:56 INFO - "product": "firefox", 19:09:56 INFO - "script_repo_revision": "default", 19:09:56 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 19:09:56 INFO - "repository": "", 19:09:56 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 19:09:56 INFO - "buildid": "20161127184057", 19:09:56 INFO - "pgo_build": "False", 19:09:56 INFO - "basedir": "/builds/slave/test", 19:09:56 INFO - "buildnumber": 9, 19:09:56 INFO - "slavename": "talos-linux64-ix-038", 19:09:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:09:56 INFO - "platform": "ubuntu64_hw", 19:09:56 INFO - "scheduler": "tests-graphics-linux64-talos", 19:09:56 INFO - "branch": "graphics", 19:09:56 INFO - "repo_path": "projects/graphics", 19:09:56 INFO - "revision": "fa40a7b4f05f6066572872bd0ddc7a7243a862f4", 19:09:56 INFO - "stage_platform": "linux64", 19:09:56 INFO - "builduid": "e1b454a675c745b78de74372b7b011d4", 19:09:56 INFO - "slavebuilddir": "test" 19:09:56 INFO - } 19:09:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 19:09:56 INFO - Running post-action listener: _resource_record_post_action 19:09:56 INFO - [mozharness: 2016-11-28 03:09:56.181861Z] Finished read-buildbot-config step (success) 19:09:56 INFO - [mozharness: 2016-11-28 03:09:56.181949Z] Running download-and-extract step. 19:09:56 INFO - Running pre-action listener: _resource_record_pre_action 19:09:56 INFO - Running main action method: download_and_extract 19:09:56 INFO - mkdir: /builds/slave/test/build/tests 19:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:56 INFO - trying https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:09:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:09:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:09:56 INFO - Downloaded 1361 bytes. 19:09:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:09:56 INFO - Using the following test package requirements: 19:09:56 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:09:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:09:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:56 INFO - u'jsshell-linux-x86_64.zip'], 19:09:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:09:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:09:56 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:09:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:09:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:09:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:09:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:09:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:09:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:09:57 INFO - Http code: 200 19:09:57 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 19:09:57 INFO - x-amz-cf-id: PLUbHTi41xiFDBQa04K8r0C2mlhyeA7ne3axuVZo31fnyaKqLW5E4g== 19:09:57 INFO - x-amz-version-id: vO6we.c8ZIb4y_9rhwgFG4.hc7qYYUM1 19:09:57 INFO - Expected file size: 20202451 19:09:57 INFO - Obtained file size: 20202451 19:10:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:10:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:10:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:10:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:10:02 INFO - Http code: 200 19:10:02 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 19:10:02 INFO - x-amz-cf-id: Jb2ZHjWdpIp9i0Dah26eLpp69G6LB8L7xQZt6epmmJWTn0nkCobPzA== 19:10:02 INFO - x-amz-version-id: vO6we.c8ZIb4y_9rhwgFG4.hc7qYYUM1 19:10:03 INFO - Expected file size: 20202451 19:10:03 INFO - Obtained file size: 20202451 19:10:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 19:10:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:10:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:10:07 INFO - Http code: 200 19:10:07 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 19:10:07 INFO - x-amz-cf-id: lkvFMdDxec7Mq4bH_8hL2h9ArpT6mXgBKg7O4AbzUI6UePs6FquKEQ== 19:10:07 INFO - x-amz-version-id: ht__89nOWXtCmLVCRLE5JjYb6ftuhJym 19:10:08 INFO - Expected file size: 14409506 19:10:08 INFO - Obtained file size: 14409506 19:10:09 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')]} 19:10:09 INFO - trying https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:10:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:10:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:10:12 INFO - Downloaded 55603878 bytes. 19:10:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:10:12 INFO - mkdir: /builds/slave/test/properties 19:10:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:10:12 INFO - Writing to file /builds/slave/test/properties/build_url 19:10:12 INFO - Contents: 19:10:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:10:13 INFO - Running post-action listener: _resource_record_post_action 19:10:13 INFO - Running post-action listener: set_extra_try_arguments 19:10:13 INFO - [mozharness: 2016-11-28 03:10:13.401021Z] Finished download-and-extract step (success) 19:10:13 INFO - [mozharness: 2016-11-28 03:10:13.401249Z] Running populate-webroot step. 19:10:13 INFO - Running pre-action listener: _resource_record_pre_action 19:10:13 INFO - Running main action method: populate_webroot 19:10:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:10:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:10:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:10:13 INFO - '--tppagecycles', 19:10:13 INFO - '7'], 19:10:13 INFO - 'tests': ['tsvgm']}}, 19:10:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:10:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:10:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:10:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:10:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:10:13 INFO - 'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:10:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:10:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:10:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:10:13 INFO - 'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['damp', 'tps']}, 19:10:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:10:13 INFO - 'tests': ['damp', 'tps']}, 19:10:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['dromaeo_dom']}, 19:10:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:10:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['basic_compositor_video']}, 19:10:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:10:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['a11yr', 19:10:13 INFO - 'ts_paint', 19:10:13 INFO - 'tpaint', 19:10:13 INFO - 'sessionrestore', 19:10:13 INFO - 'sessionrestore_no_auto_restore', 19:10:13 INFO - 'tabpaint']}, 19:10:13 INFO - 'other-e10s': {'tests': ['a11yr', 19:10:13 INFO - 'ts_paint', 19:10:13 INFO - 'tpaint', 19:10:13 INFO - 'sessionrestore', 19:10:13 INFO - 'sessionrestore_no_auto_restore', 19:10:13 INFO - 'tabpaint']}, 19:10:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:10:13 INFO - 'ts_paint', 19:10:13 INFO - 'tpaint', 19:10:13 INFO - 'sessionrestore', 19:10:13 INFO - 'sessionrestore_no_auto_restore', 19:10:13 INFO - 'tabpaint']}, 19:10:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:10:13 INFO - 'ts_paint', 19:10:13 INFO - 'tpaint', 19:10:13 INFO - 'sessionrestore', 19:10:13 INFO - 'sessionrestore_no_auto_restore', 19:10:13 INFO - 'tabpaint']}, 19:10:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['a11yr', 19:10:13 INFO - 'ts_paint', 19:10:13 INFO - 'tpaint', 19:10:13 INFO - 'sessionrestore', 19:10:13 INFO - 'sessionrestore_no_auto_restore', 19:10:13 INFO - 'tabpaint']}, 19:10:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['a11yr', 19:10:13 INFO - 'ts_paint', 19:10:13 INFO - 'tpaint', 19:10:13 INFO - 'sessionrestore', 19:10:13 INFO - 'sessionrestore_no_auto_restore', 19:10:13 INFO - 'tabpaint']}, 19:10:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['tsvgx', 19:10:13 INFO - 'tsvgr_opacity', 19:10:13 INFO - 'tart', 19:10:13 INFO - 'tscrollx', 19:10:13 INFO - 'cart']}, 19:10:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:10:13 INFO - 'tsvgr_opacity', 19:10:13 INFO - 'tart', 19:10:13 INFO - 'tscrollx', 19:10:13 INFO - 'cart']}, 19:10:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:10:13 INFO - 'talos_options': ['--disable-e10s'], 19:10:13 INFO - 'tests': ['tp5o']}, 19:10:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:10:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:10:13 INFO - 'talos_options': ['--disable-e10s', 19:10:13 INFO - '--xperf_path', 19:10:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:10:13 INFO - 'tests': ['tp5n']}, 19:10:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:10:13 INFO - 'talos_options': ['--xperf_path', 19:10:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:10:13 INFO - 'tests': ['tp5n']}}, 19:10:13 INFO - 'talos.zip': {'path': '', 19:10:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:10:13 INFO - Running post-action listener: _resource_record_post_action 19:10:13 INFO - [mozharness: 2016-11-28 03:10:13.417861Z] Finished populate-webroot step (success) 19:10:13 INFO - [mozharness: 2016-11-28 03:10:13.417949Z] Running create-virtualenv step. 19:10:13 INFO - Running pre-action listener: _resource_record_pre_action 19:10:13 INFO - Running main action method: create_virtualenv 19:10:13 INFO - Creating virtualenv /builds/slave/test/build/venv 19:10:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:10:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:10:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:10:13 INFO - Using real prefix '/usr' 19:10:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:10:14 INFO - Installing distribute.............................................................................................................................................................................................done. 19:10:16 INFO - Installing pip.................done. 19:10:16 INFO - Return code: 0 19:10:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:10:16 INFO - Reading from file tmpfile_stdout 19:10:16 INFO - Output received: 19:10:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:10:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:10:16 INFO - Using env: {'DISPLAY': ':0', 19:10:16 INFO - 'HOME': '/home/cltbld', 19:10:16 INFO - 'LANG': 'en_US.UTF-8', 19:10:16 INFO - 'LANGUAGE': 'en_US:en', 19:10:16 INFO - 'LOGNAME': 'cltbld', 19:10:16 INFO - 'MAIL': '/var/mail/cltbld', 19:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:16 INFO - 'MOZ_NO_REMOTE': '1', 19:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:16 INFO - 'NO_EM_RESTART': '1', 19:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:16 INFO - 'PWD': '/builds/slave/test', 19:10:16 INFO - 'SHELL': '/bin/bash', 19:10:16 INFO - 'SHLVL': '1', 19:10:16 INFO - 'TERM': 'linux', 19:10:16 INFO - 'TMOUT': '86400', 19:10:16 INFO - 'USER': 'cltbld', 19:10:16 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:16 INFO - '_': '/tools/buildbot/bin/python'} 19:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:10:16 INFO - Cleaning up... 19:10:16 INFO - Return code: 0 19:10:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:10:16 INFO - Using env: {'DISPLAY': ':0', 19:10:16 INFO - 'HOME': '/home/cltbld', 19:10:16 INFO - 'LANG': 'en_US.UTF-8', 19:10:16 INFO - 'LANGUAGE': 'en_US:en', 19:10:16 INFO - 'LOGNAME': 'cltbld', 19:10:16 INFO - 'MAIL': '/var/mail/cltbld', 19:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:16 INFO - 'MOZ_NO_REMOTE': '1', 19:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:16 INFO - 'NO_EM_RESTART': '1', 19:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:16 INFO - 'PWD': '/builds/slave/test', 19:10:16 INFO - 'SHELL': '/bin/bash', 19:10:16 INFO - 'SHLVL': '1', 19:10:16 INFO - 'TERM': 'linux', 19:10:16 INFO - 'TMOUT': '86400', 19:10:16 INFO - 'USER': 'cltbld', 19:10:16 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:16 INFO - '_': '/tools/buildbot/bin/python'} 19:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:16 INFO - Downloading/unpacking psutil>=3.1.1 19:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:10:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:10:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:10:18 INFO - Installing collected packages: psutil 19:10:18 INFO - Running setup.py install for psutil 19:10:18 INFO - building 'psutil._psutil_linux' extension 19:10:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:10:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:10:18 INFO - building 'psutil._psutil_posix' extension 19:10:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:10:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:10:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:10:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:10:19 INFO - Successfully installed psutil 19:10:19 INFO - Cleaning up... 19:10:19 INFO - Return code: 0 19:10:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:10:19 INFO - Using env: {'DISPLAY': ':0', 19:10:19 INFO - 'HOME': '/home/cltbld', 19:10:19 INFO - 'LANG': 'en_US.UTF-8', 19:10:19 INFO - 'LANGUAGE': 'en_US:en', 19:10:19 INFO - 'LOGNAME': 'cltbld', 19:10:19 INFO - 'MAIL': '/var/mail/cltbld', 19:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:19 INFO - 'MOZ_NO_REMOTE': '1', 19:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:19 INFO - 'NO_EM_RESTART': '1', 19:10:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:19 INFO - 'PWD': '/builds/slave/test', 19:10:19 INFO - 'SHELL': '/bin/bash', 19:10:19 INFO - 'SHLVL': '1', 19:10:19 INFO - 'TERM': 'linux', 19:10:19 INFO - 'TMOUT': '86400', 19:10:19 INFO - 'USER': 'cltbld', 19:10:19 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:19 INFO - '_': '/tools/buildbot/bin/python'} 19:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:10:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:10:21 INFO - Installing collected packages: mozsystemmonitor 19:10:21 INFO - Running setup.py install for mozsystemmonitor 19:10:21 INFO - Successfully installed mozsystemmonitor 19:10:21 INFO - Cleaning up... 19:10:21 INFO - Return code: 0 19:10:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:10:21 INFO - Using env: {'DISPLAY': ':0', 19:10:21 INFO - 'HOME': '/home/cltbld', 19:10:21 INFO - 'LANG': 'en_US.UTF-8', 19:10:21 INFO - 'LANGUAGE': 'en_US:en', 19:10:21 INFO - 'LOGNAME': 'cltbld', 19:10:21 INFO - 'MAIL': '/var/mail/cltbld', 19:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:21 INFO - 'MOZ_NO_REMOTE': '1', 19:10:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:21 INFO - 'NO_EM_RESTART': '1', 19:10:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:21 INFO - 'PWD': '/builds/slave/test', 19:10:21 INFO - 'SHELL': '/bin/bash', 19:10:21 INFO - 'SHLVL': '1', 19:10:21 INFO - 'TERM': 'linux', 19:10:21 INFO - 'TMOUT': '86400', 19:10:21 INFO - 'USER': 'cltbld', 19:10:21 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:21 INFO - '_': '/tools/buildbot/bin/python'} 19:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:21 INFO - Downloading/unpacking jsonschema==2.5.1 19:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:10:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:23 INFO - Downloading functools32-3.2.3-2.tar.gz 19:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:10:24 INFO - warning: no files found matching '*.txt' 19:10:24 INFO - no previously-included directories found matching 'build' 19:10:24 INFO - no previously-included directories found matching 'dist' 19:10:24 INFO - no previously-included directories found matching '.git*' 19:10:24 INFO - Installing collected packages: jsonschema, functools32 19:10:24 INFO - Running setup.py install for functools32 19:10:24 INFO - warning: no files found matching '*.txt' 19:10:24 INFO - no previously-included directories found matching 'build' 19:10:24 INFO - no previously-included directories found matching 'dist' 19:10:24 INFO - no previously-included directories found matching '.git*' 19:10:24 INFO - Successfully installed jsonschema functools32 19:10:24 INFO - Cleaning up... 19:10:24 INFO - Return code: 0 19:10:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:10:24 INFO - Using env: {'DISPLAY': ':0', 19:10:24 INFO - 'HOME': '/home/cltbld', 19:10:24 INFO - 'LANG': 'en_US.UTF-8', 19:10:24 INFO - 'LANGUAGE': 'en_US:en', 19:10:24 INFO - 'LOGNAME': 'cltbld', 19:10:24 INFO - 'MAIL': '/var/mail/cltbld', 19:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:24 INFO - 'MOZ_NO_REMOTE': '1', 19:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:24 INFO - 'NO_EM_RESTART': '1', 19:10:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:24 INFO - 'PWD': '/builds/slave/test', 19:10:24 INFO - 'SHELL': '/bin/bash', 19:10:24 INFO - 'SHLVL': '1', 19:10:24 INFO - 'TERM': 'linux', 19:10:24 INFO - 'TMOUT': '86400', 19:10:24 INFO - 'USER': 'cltbld', 19:10:24 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:24 INFO - '_': '/tools/buildbot/bin/python'} 19:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:10:24 INFO - Cleaning up... 19:10:24 INFO - Return code: 0 19:10:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:10:24 INFO - Using env: {'DISPLAY': ':0', 19:10:24 INFO - 'HOME': '/home/cltbld', 19:10:24 INFO - 'LANG': 'en_US.UTF-8', 19:10:24 INFO - 'LANGUAGE': 'en_US:en', 19:10:24 INFO - 'LOGNAME': 'cltbld', 19:10:24 INFO - 'MAIL': '/var/mail/cltbld', 19:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:24 INFO - 'MOZ_NO_REMOTE': '1', 19:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:24 INFO - 'NO_EM_RESTART': '1', 19:10:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:24 INFO - 'PWD': '/builds/slave/test', 19:10:24 INFO - 'SHELL': '/bin/bash', 19:10:24 INFO - 'SHLVL': '1', 19:10:24 INFO - 'TERM': 'linux', 19:10:24 INFO - 'TMOUT': '86400', 19:10:24 INFO - 'USER': 'cltbld', 19:10:24 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:24 INFO - '_': '/tools/buildbot/bin/python'} 19:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:24 INFO - Downloading/unpacking blobuploader==1.2.4 19:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:26 INFO - Downloading blobuploader-1.2.4.tar.gz 19:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:10:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:10:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:27 INFO - Downloading docopt-0.6.1.tar.gz 19:10:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:10:27 INFO - Installing collected packages: blobuploader, requests, docopt 19:10:27 INFO - Running setup.py install for blobuploader 19:10:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:10:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:10:27 INFO - Running setup.py install for requests 19:10:27 INFO - Running setup.py install for docopt 19:10:27 INFO - Successfully installed blobuploader requests docopt 19:10:27 INFO - Cleaning up... 19:10:27 INFO - Return code: 0 19:10:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:10: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')]} 19:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10: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')]} 19:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:10:28 INFO - Using env: {'DISPLAY': ':0', 19:10:28 INFO - 'HOME': '/home/cltbld', 19:10:28 INFO - 'LANG': 'en_US.UTF-8', 19:10:28 INFO - 'LANGUAGE': 'en_US:en', 19:10:28 INFO - 'LOGNAME': 'cltbld', 19:10:28 INFO - 'MAIL': '/var/mail/cltbld', 19:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:28 INFO - 'MOZ_NO_REMOTE': '1', 19:10:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:28 INFO - 'NO_EM_RESTART': '1', 19:10:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:28 INFO - 'PWD': '/builds/slave/test', 19:10:28 INFO - 'SHELL': '/bin/bash', 19:10:28 INFO - 'SHLVL': '1', 19:10:28 INFO - 'TERM': 'linux', 19:10:28 INFO - 'TMOUT': '86400', 19:10:28 INFO - 'USER': 'cltbld', 19:10:28 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:28 INFO - '_': '/tools/buildbot/bin/python'} 19:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:10:28 INFO - Running setup.py (path:/tmp/pip-BfySYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:10:28 INFO - Running setup.py (path:/tmp/pip-btHWXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:10:28 INFO - Running setup.py (path:/tmp/pip-jrv0bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:10:28 INFO - Running setup.py (path:/tmp/pip-8jIb27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:10:28 INFO - Running setup.py (path:/tmp/pip-XAZnbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:10:28 INFO - Running setup.py (path:/tmp/pip-Il4Cz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:10:28 INFO - Running setup.py (path:/tmp/pip-bmgs7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:10:28 INFO - Running setup.py (path:/tmp/pip-8DmyXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:10:28 INFO - Running setup.py (path:/tmp/pip-WjgjlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:10:28 INFO - Running setup.py (path:/tmp/pip-3N4p9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:10:28 INFO - Running setup.py (path:/tmp/pip-nXpIK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:10:29 INFO - Running setup.py (path:/tmp/pip-ooyVzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:10:29 INFO - Running setup.py (path:/tmp/pip-mZ8C5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:10:29 INFO - Running setup.py (path:/tmp/pip-RlIGg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:10:29 INFO - Running setup.py (path:/tmp/pip-S8oqlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:10:29 INFO - Running setup.py (path:/tmp/pip-5TriV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:10:29 INFO - Running setup.py (path:/tmp/pip-lMxcnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:10:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:10:29 INFO - Running setup.py install for manifestparser 19:10:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:10:29 INFO - Running setup.py install for mozcrash 19:10:29 INFO - Running setup.py install for mozdebug 19:10:29 INFO - Running setup.py install for mozdevice 19:10:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:10:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:10:29 INFO - Running setup.py install for mozfile 19:10:29 INFO - Running setup.py install for mozhttpd 19:10:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Running setup.py install for mozinfo 19:10:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Running setup.py install for mozInstall 19:10:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Running setup.py install for mozleak 19:10:30 INFO - Running setup.py install for mozlog 19:10:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Running setup.py install for moznetwork 19:10:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Running setup.py install for mozprocess 19:10:30 INFO - Running setup.py install for mozprofile 19:10:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Running setup.py install for mozrunner 19:10:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:10:30 INFO - Running setup.py install for mozscreenshot 19:10:31 INFO - Running setup.py install for moztest 19:10:31 INFO - Running setup.py install for mozversion 19:10:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:10:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:10:31 INFO - Cleaning up... 19:10:31 INFO - Return code: 0 19:10:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:10:31 INFO - Using env: {'DISPLAY': ':0', 19:10:31 INFO - 'HOME': '/home/cltbld', 19:10:31 INFO - 'LANG': 'en_US.UTF-8', 19:10:31 INFO - 'LANGUAGE': 'en_US:en', 19:10:31 INFO - 'LOGNAME': 'cltbld', 19:10:31 INFO - 'MAIL': '/var/mail/cltbld', 19:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:31 INFO - 'MOZ_NO_REMOTE': '1', 19:10:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:31 INFO - 'NO_EM_RESTART': '1', 19:10:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:31 INFO - 'PWD': '/builds/slave/test', 19:10:31 INFO - 'SHELL': '/bin/bash', 19:10:31 INFO - 'SHLVL': '1', 19:10:31 INFO - 'TERM': 'linux', 19:10:31 INFO - 'TMOUT': '86400', 19:10:31 INFO - 'USER': 'cltbld', 19:10:31 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:31 INFO - '_': '/tools/buildbot/bin/python'} 19:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:10:31 INFO - Running setup.py (path:/tmp/pip-Fo8iv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:10:31 INFO - Running setup.py (path:/tmp/pip-eM3wBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:10:31 INFO - Running setup.py (path:/tmp/pip-5xruBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:10:31 INFO - Running setup.py (path:/tmp/pip-LqwKWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:10:31 INFO - Running setup.py (path:/tmp/pip-zfb95P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:10:31 INFO - Running setup.py (path:/tmp/pip-6v9q9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:10:31 INFO - Running setup.py (path:/tmp/pip-4CupZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:10:31 INFO - Running setup.py (path:/tmp/pip-9cMRim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:10:32 INFO - Running setup.py (path:/tmp/pip-zJRpYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:10:32 INFO - Running setup.py (path:/tmp/pip-qrBLxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:10:32 INFO - Running setup.py (path:/tmp/pip-VRTdo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:10:32 INFO - Running setup.py (path:/tmp/pip-xJq8jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:10:32 INFO - Running setup.py (path:/tmp/pip-hW3CeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:10:32 INFO - Running setup.py (path:/tmp/pip-ljRi9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:10:32 INFO - Running setup.py (path:/tmp/pip-ydfuSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:10:32 INFO - Running setup.py (path:/tmp/pip-_OHzGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:10:32 INFO - Running setup.py (path:/tmp/pip-Ml7cRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:10:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:34 INFO - Downloading blessings-1.6.tar.gz 19:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:10:34 INFO - Installing collected packages: blessings 19:10:34 INFO - Running setup.py install for blessings 19:10:34 INFO - Successfully installed blessings 19:10:34 INFO - Cleaning up... 19:10:34 INFO - Return code: 0 19:10:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:10:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:10:35 INFO - Reading from file tmpfile_stdout 19:10:35 INFO - Current package versions: 19:10:35 INFO - argparse == 1.2.1 19:10:35 INFO - blessings == 1.6 19:10:35 INFO - blobuploader == 1.2.4 19:10:35 INFO - docopt == 0.6.1 19:10:35 INFO - functools32 == 3.2.3-2 19:10:35 INFO - jsonschema == 2.5.1 19:10:35 INFO - manifestparser == 1.1 19:10:35 INFO - mozInstall == 1.12 19:10:35 INFO - mozcrash == 1.0 19:10:35 INFO - mozdebug == 0.1 19:10:35 INFO - mozdevice == 0.49 19:10:35 INFO - mozfile == 1.2 19:10:35 INFO - mozhttpd == 0.7 19:10:35 INFO - mozinfo == 0.9 19:10:35 INFO - mozleak == 0.1 19:10:35 INFO - mozlog == 3.4 19:10:35 INFO - moznetwork == 0.27 19:10:35 INFO - mozprocess == 0.23 19:10:35 INFO - mozprofile == 0.28 19:10:35 INFO - mozrunner == 6.13 19:10:35 INFO - mozscreenshot == 0.1 19:10:35 INFO - mozsystemmonitor == 0.3 19:10:35 INFO - moztest == 0.7 19:10:35 INFO - mozversion == 1.4 19:10:35 INFO - psutil == 3.1.1 19:10:35 INFO - requests == 1.2.3 19:10:35 INFO - wsgiref == 0.1.2 19:10:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:10:35 INFO - Using env: {'DISPLAY': ':0', 19:10:35 INFO - 'HOME': '/home/cltbld', 19:10:35 INFO - 'LANG': 'en_US.UTF-8', 19:10:35 INFO - 'LANGUAGE': 'en_US:en', 19:10:35 INFO - 'LOGNAME': 'cltbld', 19:10:35 INFO - 'MAIL': '/var/mail/cltbld', 19:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:35 INFO - 'MOZ_NO_REMOTE': '1', 19:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:35 INFO - 'NO_EM_RESTART': '1', 19:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:35 INFO - 'PWD': '/builds/slave/test', 19:10:35 INFO - 'SHELL': '/bin/bash', 19:10:35 INFO - 'SHLVL': '1', 19:10:35 INFO - 'TERM': 'linux', 19:10:35 INFO - 'TMOUT': '86400', 19:10:35 INFO - 'USER': 'cltbld', 19:10:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:35 INFO - '_': '/tools/buildbot/bin/python'} 19:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:10:35 INFO - Cleaning up... 19:10:35 INFO - Return code: 0 19:10:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5977c06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, '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': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', '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 19:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:10:35 INFO - Using env: {'DISPLAY': ':0', 19:10:35 INFO - 'HOME': '/home/cltbld', 19:10:35 INFO - 'LANG': 'en_US.UTF-8', 19:10:35 INFO - 'LANGUAGE': 'en_US:en', 19:10:35 INFO - 'LOGNAME': 'cltbld', 19:10:35 INFO - 'MAIL': '/var/mail/cltbld', 19:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:35 INFO - 'MOZ_NO_REMOTE': '1', 19:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:35 INFO - 'NO_EM_RESTART': '1', 19:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:35 INFO - 'PWD': '/builds/slave/test', 19:10:35 INFO - 'SHELL': '/bin/bash', 19:10:35 INFO - 'SHLVL': '1', 19:10:35 INFO - 'TERM': 'linux', 19:10:35 INFO - 'TMOUT': '86400', 19:10:35 INFO - 'USER': 'cltbld', 19:10:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:35 INFO - '_': '/tools/buildbot/bin/python'} 19:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:10:35 INFO - Cleaning up... 19:10:35 INFO - Return code: 0 19:10:35 INFO - Running post-action listener: _resource_record_post_action 19:10:35 INFO - Running post-action listener: _start_resource_monitoring 19:10:35 INFO - Starting resource monitoring. 19:10:35 INFO - [mozharness: 2016-11-28 03:10:35.612804Z] Finished create-virtualenv step (success) 19:10:35 INFO - [mozharness: 2016-11-28 03:10:35.613319Z] Running install step. 19:10:35 INFO - Running pre-action listener: _resource_record_pre_action 19:10:35 INFO - Running main action method: install 19:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:10:35 INFO - Reading from file tmpfile_stdout 19:10:35 INFO - Detecting whether we're running mozinstall >=1.0... 19:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:10:35 INFO - Reading from file tmpfile_stdout 19:10:35 INFO - Output received: 19:10:35 INFO - Usage: mozinstall [options] installer 19:10:35 INFO - Options: 19:10:35 INFO - -h, --help show this help message and exit 19:10:35 INFO - -d DEST, --destination=DEST 19:10:35 INFO - Directory to install application into. [default: 19:10:35 INFO - "/builds/slave/test"] 19:10:35 INFO - --app=APP Application being installed. [default: firefox] 19:10:35 INFO - mkdir: /builds/slave/test/build/application 19:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:10:52 INFO - Reading from file tmpfile_stdout 19:10:52 INFO - Output received: 19:10:52 INFO - /builds/slave/test/build/application/firefox/firefox 19:10:52 INFO - Running post-action listener: _resource_record_post_action 19:10:52 INFO - [mozharness: 2016-11-28 03:10:52.282092Z] Finished install step (success) 19:10:52 INFO - [mozharness: 2016-11-28 03:10:52.282344Z] Running run-tests step. 19:10:52 INFO - Running pre-action listener: _resource_record_pre_action 19:10:52 INFO - Running main action method: run_tests 19:10:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:10:52 INFO - Python 2.7.3 19:10:52 INFO - Return code: 0 19:10:52 INFO - grabbing minidump binary from tooltool 19:10: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')]} 19:10:52 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8fc0>, '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 0x29a9ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29aa350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:10:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:10:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:10:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:10:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:10:52 INFO - Return code: 0 19:10:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:10:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:10:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:10:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:10:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:10:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 19:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 19:10:52 INFO - Using env: {'DISPLAY': ':0', 19:10:52 INFO - 'HOME': '/home/cltbld', 19:10:52 INFO - 'LANG': 'en_US.UTF-8', 19:10:52 INFO - 'LANGUAGE': 'en_US:en', 19:10:52 INFO - 'LOGNAME': 'cltbld', 19:10:52 INFO - 'MAIL': '/var/mail/cltbld', 19:10:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:10:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:52 INFO - 'MOZ_NO_REMOTE': '1', 19:10:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:10:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:52 INFO - 'NO_EM_RESTART': '1', 19:10:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:52 INFO - 'PWD': '/builds/slave/test', 19:10:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:10:52 INFO - 'SHELL': '/bin/bash', 19:10:52 INFO - 'SHLVL': '1', 19:10:52 INFO - 'TERM': 'linux', 19:10:52 INFO - 'TMOUT': '86400', 19:10:52 INFO - 'USER': 'cltbld', 19:10:52 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914', 19:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:52 INFO - '_': '/tools/buildbot/bin/python'} 19:10:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 19:10:52 INFO - mozversion application_buildid: 20161127184057 19:10:52 INFO - mozversion application_changeset: fa40a7b4f05f6066572872bd0ddc7a7243a862f4 19:10:52 INFO - mozversion application_display_name: Nightly 19:10:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:10:52 INFO - mozversion application_name: Firefox 19:10:52 INFO - mozversion application_remotingname: firefox 19:10:52 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 19:10:52 INFO - mozversion application_vendor: Mozilla 19:10:52 INFO - mozversion application_version: 53.0a1 19:10:52 INFO - mozversion platform_buildid: 20161127184057 19:10:52 INFO - mozversion platform_changeset: fa40a7b4f05f6066572872bd0ddc7a7243a862f4 19:10:52 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 19:10:52 INFO - mozversion platform_version: 53.0a1 19:10:52 INFO - using testdate: 1480302652 19:10:52 INFO - actual date: 1480302652 19:10:52 INFO - starting webserver on 'localhost:47391' 19:10:52 INFO - SUITE-START | Running 2 tests 19:10:52 INFO - TEST-START | dromaeo_css 19:10:52 INFO - operating with platform_type : linux_ 19:10:52 INFO - Initialising browser for dromaeo_css test... 19:10:52 INFO - TEST-INFO | started process 12503 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6ytbZ2/profile http://localhost:47391/getInfo.html) 19:10:57 INFO - PROCESS | 12503 | __metrics Screen width/height:1600/1200 19:10:57 INFO - PROCESS | 12503 | colorDepth:24 19:10:57 INFO - PROCESS | 12503 | Browser inner width/height: 1024/697 19:10:57 INFO - PROCESS | 12503 | __metrics 19:10:58 INFO - PROCESS | 12503 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:10:58 INFO - PROCESS | 12503 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:10:59 INFO - TEST-INFO | 12503: exit 0 19:10:59 INFO - Browser initialized. 19:10:59 INFO - Running cycle 1/1 for dromaeo_css test... 19:10:59 INFO - TEST-INFO | started process 12582 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6ytbZ2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:11:11 INFO - PROCESS | 12582 | RSS: Main: 173711360 19:11:11 INFO - PROCESS | 12582 | 19:12:02 INFO - PROCESS | 12582 | 1480302722825 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] 19:14:17 INFO - PROCESS | 12582 | Cycle 1(1): loaded http://localhost:47391/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47391/tests/dromaeo/cssquery-ext.html) 19:14:18 INFO - PROCESS | 12582 | RSS: Main: 305885184 19:14:18 INFO - PROCESS | 12582 | 19:17:21 INFO - PROCESS | 12582 | Cycle 1(1): loaded http://localhost:47391/tests/dromaeo/cssquery-ext.html (next: http://localhost:47391/tests/dromaeo/cssquery-jquery.html) 19:17:22 INFO - PROCESS | 12582 | RSS: Main: 290828288 19:17:22 INFO - PROCESS | 12582 | 19:20:24 INFO - PROCESS | 12582 | Cycle 1(1): loaded http://localhost:47391/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47391/tests/dromaeo/cssquery-mootools.html) 19:20:25 INFO - PROCESS | 12582 | RSS: Main: 224608256 19:20:25 INFO - PROCESS | 12582 | 19:23:27 INFO - PROCESS | 12582 | Cycle 1(1): loaded http://localhost:47391/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47391/tests/dromaeo/cssquery-prototype.html) 19:23:28 INFO - PROCESS | 12582 | RSS: Main: 222486528 19:23:28 INFO - PROCESS | 12582 | 19:26:31 INFO - PROCESS | 12582 | Cycle 1(1): loaded http://localhost:47391/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47391/tests/dromaeo/cssquery-yui.html) 19:26:31 INFO - PROCESS | 12582 | RSS: Main: 223735808 19:26:31 INFO - PROCESS | 12582 | 19:29:34 INFO - PROCESS | 12582 | Cycle 1(1): loaded http://localhost:47391/tests/dromaeo/cssquery-yui.html (next: http://localhost:47391/tests/dromaeo/cssquery-dojo.html) 19:29:35 INFO - PROCESS | 12582 | RSS: Main: 316076032 19:29:35 INFO - PROCESS | 12582 | 19:29:35 INFO - PROCESS | 12582 | __start_tp_report 19:29:35 INFO - PROCESS | 12582 | _x_x_mozilla_page_load 19:29:35 INFO - PROCESS | 12582 | _x_x_mozilla_page_load_details 19:29:35 INFO - PROCESS | 12582 | |i|pagename|runs| 19:29:35 INFO - PROCESS | 12582 | |0;dojo.html;2589.702184248811;2617.9214623561274;2645.2857728413323;2651.681798184218;2659.6808382994045;4530.614897733697;4613.123506533765;4682.4849266580695;4692.061587682464;4692.953070469294;4621.12198682251;4684.718916864982;4723.692959957601;4777.04459108178;4781.019890922361;4265.253580623394;4290.334998075304;4332.176886391591;4363.301871700529;4441.045175287312;3966.9537072186354;4293.935590966138;4355.869323920288;4394.47266328041;4434.246178149706;4200.075983283673;4244.830206791725;4361.498427680825;4406.845760398371;4440.844570440036;4286.935695964563;4287.035417031174;4326.394304797331;4352.586504282088;4401.801918913625;4329.978350108261;4331.826726930919;4341.435613370273;4381.846635367747;4387.605115539617;4708.505606911259;4723.787429565644;4731.455882573514;4737.786799594027;4747.0505898820375;4892.584130348924;4945.258211268302;4949.010197960386;5008.499150084962;5079.390473143209;5059.1905295152965;5114.897702045975;5114.897702045975;5141.485851414866;5162.354705661793;4762.071396077767;4765.1660959331975;4768.213244814636;4769.070031343855;4820.421549414093;4967.453580106186;5023.623228257895;5211.296474975829;5212.035773381936;5273.1035723927025;4874.2201247800185;4877.219644856806;4913.434954980152;4921.941782516776;4979.800807967641;5008.198688209869;5021.623378246646;5025.723585202776;5025.773840177201;5073.315102461121;4925.7783399747095;4935.851924442272;4962.032403681247;4978.253262010655;5026.271190677331;4787.8563643090765;4837.201861692782;4837.6613637045775;4889.975550122226;4893.4617192108835;4275.0594869128745;4301.376300436419;4319.97840010798;4369.69412141147;4373.890652733707;3757.248550289953;3758.267137908136;3761.4545890845675;3796.6962642988497;3816.9236615268096;4366.781660916941;4367.737935723867;4369.541198174209;4403.9779801100785;4409.36064270679;4830.154722923475;4878.341423907798;4884.902301953941;4952.603791696655;5066;4214.620684138381;4261.97869010653;4264.658827293809;4265.3815196796295;4268.445102136702;4443.355713421536;4474.507804141542;4486.506484286726;4488.483824360241;4491.461024676994;4635.0498147878925;4635.304704294383;4639.8144074237325;4641.582257596832;4642.2340313847835;4938.16051271292;4959.603231741524;4959.7272150032095;4960.206366981266;4961.627877909244;4649.116667833101;4661.394018777537;4664.253719404879;4664.953350466452;4665.883352916068;4880.267959806058;4888.2178851383605;4893.412790465167;4898.33872427232;4936.629752768628;5093.083245015784;5095.643304968617;5096.490350964874;5097.1844504879045;5149.0474262261605;4682.648801339845;4717.551832575899;4935.235038569028;4944.802207911644;4988.6757360771435;4820.035992801384;4857.708537487762;4860.659753817199;4902.975485122552;4948.579370753526;4817.494163112823;4818.71087734737;4821.4696383397095;4821.927671084866;4857.927131092966;4722;4911.140550403594;4920.212765957573;4929.704217746947;4971.875703107451;4657.441107067174;4698.248280275139;4716.764161791828;4718.504557021601;4723.881902952454 19:29:35 INFO - PROCESS | 12582 | |1;ext.html;551.4265164229201;575.4878158438987;578.4562511239435;581.5434883616362;594.8304733151053;27676.308092297677;30485.237869053257;37986.24027519451;39080.60919390805;39370.803145984304;39139.804300978525;39369.40945885814;39413.21173576534;39444.40833387501;39475.60524394755;7521.586312752796;7661.387089032864;7665.655045522964;7667.923320766777;7668.65491052904;7359.484836061478;7575.621218939059;9607.183389411908;9856.704298871009;9882.061204185591;9902.306838521306;9951.402915825038;9959.950200248988;9965.501724913762;9968.102870741632;9756.902430975706;9806.607735690564;9817.656382026595;9824.410535367864;9824.705258842247;9464.479453630047;9543.570539325747;9545.379550329206;9545.90454095464;9546.713598592054;37024;37265;37313.62686373129;37326.81336593327;37386.62613373859;2937.8971735989353;3124.047165614487;3289.5394644749754;3639.836207370675;3722.088088418328;27457.725422745716;27468.450630987467;27980.720192798017;27992.300192495146;28072.298192545146;3489.3893568625645;3492.1967947372245;3499.877504287363;3537.4870643756512;3650.671439570451;167234.16382918006;169281;169287;169566;169939.99999999878;51432;51747.48252517502;51852;51905.2214216787;52083.73958130261;55294.72352638291;55314.72342638261;55317.44682553203;55367.16949245684;55516;13670.93164534171;14145.858541414455;14171.716565668628;14192.219427931357;14192.22597175353;9542.284328675378;9563.378380405251;9584.66453674118;9599.376040557481;10025.699229023141;14034.157950523002;18880.24479020824;18881.433556993314;18928.43214703561;19506.974243586003;6693.832654183587;6791.762288319984;6856.828579285558;7121.394681452031;7126.501144919909;755.3768141283463;821.6549049399266;861.7457849934159;892.8973168085753;911.4303560274828;26175.73824261733;28194.85902570515;28269.28939464358;28749.71250287512;30889.69110308868;934.9579268932914;960.9231261499064;978.0317485689117;1002.063071028576;1014.7970405918699;14000.859991399955;14977.700445991019;14992.775108373384;15060.924695376452;15061.322240499203;381654;383855;383998;385198;634387;43247.567524324986;47633;48893.266601001014;48912;55076;1155.1855941561348;1156.3871148291419;1167.2821214952744;1169.5964892112208;1199.3163896578867;26993.325166870985;27859.442811143257;28219.858900705363;28926.566101508073;29046.12861614155;42742.14515709606;43562.564374355854;43584.564154359316;43639;43664.56335436605;341481;343116;343496;344591;345398;25120.61354171351;25441.872790635927;25468.872655636602;25475.87262063678;25483.74516254888;41548.3767743478;43150.78424607857;43304.566954330054;47142.22862171087;47538;34348.82825585856;35059.47410788891;35187;35649.82175089108;35761.28477430385;35566;35714;36073.278534429686;36345.636543635286;36385.454218187275;34774.82612586921;34897.82551087228;35143.47284790679;35500.11249718675;35607.46588801118;30659.846700766353;32442.675573243967;32813.34373312568;32998.34003319968;33153.83423082869;24569.754302456746;24967.87516062408;25182.013272471995;25184.24447266585;25252.74747252504 19:29:35 INFO - PROCESS | 12582 | |2;jquery.html;332.307139613905;358.26019270207024;360.7618971478823;363.736291188888;383.7352226963395;12399.504019839196;13327.26700031499;16144;16598.75101873473;17354.00857802706;15013;15262.389504419838;15419.614509637264;16276.43032493863;16453.67092658146;3962.742421742592;4772.093302272566;4988.426330971948;4992.525710057539;4995.525425084629;4891;4975.8258460953875;4976.452590215092;4978.477259887696;4983.975080124595;4930.482299358569;4932.630052746041;4933.580645645125;4939.703617782927;4942.505749425065;4821.590164835993;4998.8500344989525;5019.347484826949;5022.447530771612;5024.9748751256375;4510.143072816154;4527.818887244506;4535.229011068093;4541.34150548172;4546.909061818778;19460.80539194604;20132.49668758278;20193.19227230909;20487.590248194952;20638.793612063986;1855.7958624551288;1870.5417172792752;1932.6424611446928;2135.6155891939443;2233.8101261392803;12018.4591693374;12761.61715148547;12931;13132.641967463804;13303.467861285537;1663.376233912283;1669.5492217101314;1686.5530634381898;1722.767426397433;1733.904635245061;64791.352086479004;66299.66850165767;66624;67581.66209168923;68055.65972170107;9013.684521041732;9044.81910361789;9289.584707975975;9376.5311734413;9643.649889015544;12800.61598152057;12810.743785124245;12820.551280705316;12837.037222208372;12837.871621283854;6599.637019963852;6632.237292711304;6745.696443659949;6944.409725173316;6950.721971121098;6391.872162556816;6436.67816609177;6458.451031662361;6652.933470665232;6656.500762442836;5915.526757859361;5920.111983202468;5929;5950.404959504015;6005.879882402328;1641.564985278902;1737.6090379664474;1743.429741974405;1744.6597913406763;1749.3702267183803;783.8902553642495;810.4205493072482;837.4472847920343;848.1645579104577;881.4345970549016;16370.67258654844;16807.83192168087;17169.7424538632;17617.471475855746;17774.46676599704;826.2728798657143;872.2062922740275;877;903.5391950105362;918.4076270805293;9432.481213533321;10051.949740251399;10173.44046077473;10182.23633227511;10192.082712555757;366775.81750646007;369885;373702;373795;373835;64071;64737.67631161814;64981;65317.346826531124;65616.67191664106;887.7558671365426;892.3173772064376;912.0378001208655;918.7060140754894;918.93108016899;15071.397144114113;15116.54650360491;15433.305501252245;15738.527844164693;15792.21038948071;33662.49506257457;33895.11891489251;33927.66072339245;35126.824365878005;35484.997903899246;44278;44477.777611111735;44729;44895;46231.53768462272;6406.903896441464;6579.638119903451;6686.531942764154;7159.8926016110845;7541.7360392386;10745.785084298426;11243.212975091665;11462.312261264351;11561.595344162914;11902.166848320534;9557.187639050759;9789.167920726817;10011.199104071511;10293.3823970562;10331.276810623183;4973.129702302011;7125.82185445368;9901.455419951972;10132.290739648153;10348.896511034793;10087;10239.795204096024;10285.382877027403;10614.469276535989;10749.62376316825;7362.77911662651;7405.852092925606;7527.548347099191;7532.585707786125;7555.395568354408;9790.75523111928;10155.136813370946;10297.330673506198;10370.792584148425;10534 19:29:35 INFO - PROCESS | 12582 | |3;mootools.html;1138.299945533497;1289.4390939941122;1320.518010926012;1335.7862741961287;1621.602707336702;5829.096490044045;6589.736410543578;6833.077534532837;6837.965810170955;6982.965085174567;6672.799816005516;6732.158480189976;6735.158105236846;6739.0902228199175;6740.494462915276;1734.0549400576654;1794.3271273272524;1993.5016245938516;2022.3629556689668;2025.1089520610883;1972.891490967996;2035.908384122718;2037.735094437721;2055.599158164159;2064.9896750516227;1992.9601407971832;2007.8393728501758;2020.2626041494896;2021.2016253579827;2023.271622216001;2021.4036859126593;2061.505238742706;2064.1743302678897;2064.4322811226884;2067.204126411335;1974.5754662747433;1977.3079422202259;1979.1291831594197;1990.920363185471;1994.601079784049;6644.069830223772;6815.727370905158;6821.727130914807;6858.279880612564;6903.827404314882;4146.730462519927;4243.342281946303;4264.808083636212;4267.466566679165;4267.893302667459;2692.8249663771794;2737.868684961091;2780.805343625947;2783.73554512321;2833.6032955386263;824.6206744897354;858.0689951402735;869.3740506835069;872.5898827551041;879.0330636300083;8828.558572071435;8831.337649676236;8841.513716745576;8886.60010299538;8934.910650893473;3768.8894219236768;3917.0990672145567;3925.156091440354;3928.705347098979;3962.32640451124;7263.527870688388;7326.487145899842;7332.670029848669;7333.633318334062;7335.083114610674;4220.957790422118;4226.978865105716;4227.3447615619625;4233.407322974786;4238.660907127422;3799.2591444668024;3908.4332771748504;3922.0783115967724;3927.096767743435;3931.980340098281;2948.513495273256;2974.8810047597854;3001.9099427017222;3023.576699262105;3041.7870749047356;902.3052249767642;928.4522131942186;935.2004036548789;936.7283487788601;941.5198248893114;681.1655721740827;731.4806487393904;815.9428839981263;849.0363437498484;852.2244757270854;4782.139214941344;4876.804927802919;4878.121938051185;4901.877453063702;5036;370.54793152354085;384.8133655177237;393.34311699461966;397.97811120388076;398.2771270144678;3194.8242846643657;3412.3004784019217;3457.896263112111;3490.528778614905;3494.5631796025496;7466.178720340758;7873.133955264916;7875.054993400829;8055.838883222303;8058.35533157346;7979.4015448841565;7983.441159118806;7984.6806127754235;7993.800154996055;7997.840043199103;6114.174586430864;6247.625142491556;6278.30938596754;6284.560080794392;6287.2769631492965;4763.7141771493825;4767.809287628456;4982.501749825134;4996.15065438869;5108.616853736059;7122.501424900414;7226.530275532074;7590.696372145052;7599.088109426904;7604.505707129019;6775.1531058617675;7489.662965166472;7769.805754855947;7804.9609751950875;7813.1796161402235;5448.56411487088;5801.709914504342;5961.850953726192;6007;6148.262208535004;6654.73381064752;6660.966695166493;6668.066470694203;6742.4943129266485;6745.628990405378;5981.0729336952845;5991.2510936132985;6000.759969601167;6039.094135879477;6047.213862197887;5853.1805547222575;5884.941150588439;5901.232839730807;5901.350851406256;6027.126066720213;5625.353084395183;5711.171880077449;5718.971405142947;5722.914156287742;5726.685032323262;4173.853915113078;4351.825926962886;4399.626031787334;4409.823607055682;4442.2892337225885;4221.472315960505;4232.259354612869;4274.551172126882;4376.584224498668;4376.606105450524 19:29:35 INFO - PROCESS | 12582 | |4;prototype.html;290.45249704307383;290.9010936281664;292.96484421869366;297.05388338143007;298.68190377248214;4808.975955120224;4826.155422801014;4830.492798256185;4848.515148485149;4859.6841205321625;4782.091402633498;4784.746372466721;4793.931883594493;4799.208130658446;4809.8797530061765;3578.2843431313713;3656.6526180012856;3683.5211422515035;3749.1564398010364;3778.848846046155;3743.7084205948904;3755.6619904208615;3770.7744982880536;3771.076086358832;3783.829727662248;3727.850885964558;3728.1611637381643;3732.216234590739;3735.6077611850774;3772.566154892181;3573.178169021127;3665.138692407282;3697;3724.925501489955;3767.830447629863;3297.258116923697;3299.6040475142895;3330.7668463207583;3332.150301673082;3335.1328654549748;4637.258038713823;4647.070585882837;4656.301554766778;4658.743769092698;4668.159731248374;3966.682665386762;4166.7708276044805;4267.466566679165;4270.91458170838;4276.978615106936;4921.532454416824;4928.9156385595115;4943.134951383529;4943.431505376891;4946.258061290835;3506.3162683276773;3507.018034950218;3508.438649816043;3510.1224693826543;3526.1360966563097;4907.153850766546;5154.845354639367;5195.856911479506;5202.557782588447;5204.505571970657;3537.911552211215;3570.4465807799834;3576.1238496567985;3594.0655429588496;3610.6750392464805;3628.9637103628625;3646.5624125105155;3646.690031347365;3646.908827279339;3651.9634803652157;4174.248635245624;4174.791260436966;4179.185058913483;4180.832766689359;4181.790910454526;4659.394278743742;4896.902061958741;4901.632377571696;4944.431390390079;4952.628552858478;1242.577523641966;1256.7369793357618;1256.7800634888858;1256.8750967883875;1265.5064524835323;817.6811043692965;859.7893516088474;874.1782724239195;879.1735768377705;890.2610833008588;4118.670506359484;4208.305629571087;4426.539242050116;4440.977795111004;4449.421575195204;5148.124818780741;5155.9742201288755;5173.63784535079;5201.375834899761;5210.336748610049;4452.5547445255215;4454.814807412716;4458.375827384169;4468.530804265571;4473.114840927648;2557.347876291571;2661.4544018476554;2665.680488158374;2667.906158475016;2670.626112344273;4658.020341354911;4669.556392142741;4695.812167513262;4702.31816386622;4708.529147085333;4914.483979182206;5129.769160387791;5138.3833939927445;5146.819861304836;5181.393767931953;4694.183489906029;4718.386609740781;4724.929126063112;4725.692829966041;4749.3588365570895;4967.379077615298;4990.276409920614;4998.175301075427;5000.6249531284575;5018;4655.231886738786;4856.163630731682;4881.9023619528125;4896.387951506062;4927.359443272495;4676.672632915799;4680.648951328732;4681.391419115494;4696.436427628706;4712.222483290218;3465.774724642991;3490.1449144959647;3490.3193877193703;3493.5633045869267;3505.947410788789;4945.579625731853;4988.782235458182;5019.447860735243;5020.774065167149;5040.513048650583;4711.693739906895;4775.832845850378;4858.61529464101;4876.70794726478;4879.95120048795;4845.612351011981;4852.320675105559;4867.488913664014;4876.171050921281;4889.535494128052;4793.472718001087;4808.663393562557;4813.95186048135;4815.566599006106;4820.1805693031565;4753.691010084333;4925.408950925912;5043.218301163325;5070.6957582545165;5090;4949.356583644248;4972.627052970969;4977.701337919736;4989.101961646938;4992.600591952562 19:29:35 INFO - PROCESS | 12582 | |5;yui.html;644.4940721533595;648.7534736800015;657.3951964192945;726.8037629839945;734.0747122715976;11320.207585469021;12226.205296655711;12229.38853057348;12240.143189976694;12250.693732656688;11830.526778928854;11958.461869215884;12025.158238923279;12038.398080095982;12053.035757139429;602.9788957386493;621.0280910375271;732.6776218463872;734.1740541890373;757.8673732096888;770.9498882572643;774.7365895595497;775.0544335910181;775.151209425678;775.678093591159;810.623060276971;810.7405630198339;811.407672399149;811.975640730779;812.252727490707;631.2929518938772;649.1042361541066;767.8349154931716;791.956442395668;799.6001999000441;583.7373182068044;584.6638183044766;584.8596336879161;585.0376131264089;585.6105689716334;11934.641960741192;11940.40297985106;11964.581239656658;11966.940165299206;11968.102392320523;11957.641270761891;12055.517779288817;12225.083118766039;12260.632181034583;12442.315672638002;10907.563697452093;10942.726431839188;10988.450577471174;11184.105271578253;11343.886561134366;3944.881653550398;4013.1572369802084;4066.2680717470844;4069.674426045909;4099.487564054493;45378.31932521015;45822;46181.53818461806;46568.30147547789;47454.76272618649;9397.295202859812;9606.27952903535;9627.614895404246;9794.706158815246;9813.509324533745;17350.3059877606;17374.305027798746;17377.304907803547;17378.217980190922;17393.565160870825;6269.185005949197;6436.292007879129;6541.313162117909;6616.900746488806;6623.3376662333385;3200.071576624288;3260.4457242268873;3410.1645096951406;3464.549608550872;3520.15516276097;2652.124798816386;2704.1211606228053;2762.7513523782955;2766.0595397564925;2766.1286694691107;454.9226631472658;456.15155810193016;456.50469240874025;465.6879890473391;470.18892410591457;448.8070129844133;491.25329499161074;542.7665157875393;542.9375621803464;549.2420459765498;6985.301469852974;7481.625918704043;7531.096268447822;7573.240750440209;7614.961925190339;420.0988878854839;511.5856156513236;523.7355135656469;582.2721598002497;629.7795771479946;3575.856965721394;3693.132113953219;3844.2119365529998;3873.302805495038;3893.4354518594646;63385;63477.68261158665;65129.674351628884;66639;67753.32246677569;35280.470792938126;37743;47645.76177119092;55680.721596391755;56990;50967.49032509702;51059;51130.488695113316;51545.48454515407;53044.93910121776;7282.708691652275;7347.889781653173;7459.179490255959;7576.393888489018;7829.921700783148;34748.82625586856;35451;36700.81649591735;36847.26305473929;37823.43264850974;29301.85349073241;35372.646273536935;49168.508314917824;50624.240636389746;52547.474525254256;7813;8115.918840811516;8768.561571921251;8797.516136612292;8982.416142950688;10090.545925433225;10153.136983356497;10432.113270372103;10603.840942385716;10748.086412654699;10259.897401025893;10558.736031598964;10941.890581094087;11246.212765106691;11861.5848445304;10891.292066015683;11233.887661123284;11291.604793832177;11381.943090284496;11597.536098555964;10491.318064325795;11082.889171108185;11149.609763658233;11522.465466513806;11985.400729963292;8450.619722112642;8693.130686931509;8738.519381434082;9053.230799960158;9299.76750581241;9261.768455788659;9296.860547091934;9447.716568502956;9563.521823908637;9574.377665451724 19:29:35 INFO - PROCESS | 12582 | __end_tp_report 19:29:35 INFO - PROCESS | 12582 | __start_cc_report 19:29:35 INFO - PROCESS | 12582 | _x_x_mozilla_cycle_collect,1454 19:29:35 INFO - PROCESS | 12582 | __end_cc_report 19:29:35 INFO - PROCESS | 12582 | __startTimestamp1480303775240__endTimestamp 19:29:35 INFO - PROCESS | 12582 | 19:29:35 INFO - PROCESS | 12582 | ------- Summary: start ------- 19:29:35 INFO - PROCESS | 12582 | Number of tests: 6 19:29:35 INFO - PROCESS | 12582 | 19:29:35 INFO - PROCESS | 12582 | [#0] dojo.html Cycles:165 Average:4627.03 Median:4723.88 stddev:474.09 (10.0%) stddev-sans-first:447.79 19:29:35 INFO - PROCESS | 12582 | Values: 2589.7 2617.9 2645.3 2651.7 2659.7 4530.6 4613.1 4682.5 4692.1 4693.0 4621.1 4684.7 4723.7 4777.0 4781.0 4265.3 4290.3 4332.2 4363.3 4441.0 3967.0 4293.9 4355.9 4394.5 4434.2 4200.1 4244.8 4361.5 4406.8 4440.8 4286.9 4287.0 4326.4 4352.6 4401.8 4330.0 4331.8 4341.4 4381.8 4387.6 4708.5 4723.8 4731.5 4737.8 4747.1 4892.6 4945.3 4949.0 5008.5 5079.4 5059.2 5114.9 5114.9 5141.5 5162.4 4762.1 4765.2 4768.2 4769.1 4820.4 4967.5 5023.6 5211.3 5212.0 5273.1 4874.2 4877.2 4913.4 4921.9 4979.8 5008.2 5021.6 5025.7 5025.8 5073.3 4925.8 4935.9 4962.0 4978.3 5026.3 4787.9 4837.2 4837.7 4890.0 4893.5 4275.1 4301.4 4320.0 4369.7 4373.9 3757.2 3758.3 3761.5 3796.7 3816.9 4366.8 4367.7 4369.5 4404.0 4409.4 4830.2 4878.3 4884.9 4952.6 5066.0 4214.6 4262.0 4264.7 4265.4 4268.4 4443.4 4474.5 4486.5 4488.5 4491.5 4635.0 4635.3 4639.8 4641.6 4642.2 4938.2 4959.6 4959.7 4960.2 4961.6 4649.1 4661.4 4664.3 4665.0 4665.9 4880.3 4888.2 4893.4 4898.3 4936.6 5093.1 5095.6 5096.5 5097.2 5149.0 4682.6 4717.6 4935.2 4944.8 4988.7 4820.0 4857.7 4860.7 4903.0 4948.6 4817.5 4818.7 4821.5 4821.9 4857.9 4722.0 4911.1 4920.2 4929.7 4971.9 4657.4 4698.2 4716.8 4718.5 4723.9 19:29:35 INFO - PROCESS | 12582 | 19:29:35 INFO - PROCESS | 12582 | [#1] ext.html Cycles:180 Average:47094.31 Median:20493.54 stddev:90171.54 (440.0%) stddev-sans-first:90356.78 19:29:35 INFO - PROCESS | 12582 | Values: 551.4 575.5 578.5 581.5 594.8 27676.3 30485.2 37986.2 39080.6 39370.8 39139.8 39369.4 39413.2 39444.4 39475.6 7521.6 7661.4 7665.7 7667.9 7668.7 7359.5 7575.6 9607.2 9856.7 9882.1 9902.3 9951.4 9960.0 9965.5 9968.1 9756.9 9806.6 9817.7 9824.4 9824.7 9464.5 9543.6 9545.4 9545.9 9546.7 37024.0 37265.0 37313.6 37326.8 37386.6 2937.9 3124.0 3289.5 3639.8 3722.1 27457.7 27468.5 27980.7 27992.3 28072.3 3489.4 3492.2 3499.9 3537.5 3650.7 167234.2 169281.0 169287.0 169566.0 169940.0 51432.0 51747.5 51852.0 51905.2 52083.7 55294.7 55314.7 55317.4 55367.2 55516.0 13670.9 14145.9 14171.7 14192.2 14192.2 9542.3 9563.4 9584.7 9599.4 10025.7 14034.2 18880.2 18881.4 18928.4 19507.0 6693.8 6791.8 6856.8 7121.4 7126.5 755.4 821.7 861.7 892.9 911.4 26175.7 28194.9 28269.3 28749.7 30889.7 935.0 960.9 978.0 1002.1 1014.8 14000.9 14977.7 14992.8 15060.9 15061.3 381654.0 383855.0 383998.0 385198.0 634387.0 43247.6 47633.0 48893.3 48912.0 55076.0 1155.2 1156.4 1167.3 1169.6 1199.3 26993.3 27859.4 28219.9 28926.6 29046.1 42742.1 43562.6 43584.6 43639.0 43664.6 341481.0 343116.0 343496.0 344591.0 345398.0 25120.6 25441.9 25468.9 25475.9 25483.7 41548.4 43150.8 43304.6 47142.2 47538.0 34348.8 35059.5 35187.0 35649.8 35761.3 35566.0 35714.0 36073.3 36345.6 36385.5 34774.8 34897.8 35143.5 35500.1 35607.5 30659.8 32442.7 32813.3 32998.3 33153.8 24569.8 24967.9 25182.0 25184.2 25252.7 19:29:35 INFO - PROCESS | 12582 | 19:29:35 INFO - PROCESS | 12582 | [#2] jquery.html Cycles:180 Average:22992.73 Median:44603.39 stddev:61121.00 (137.0%) stddev-sans-first:61268.78 19:29:35 INFO - PROCESS | 12582 | Values: 332.3 358.3 360.8 363.7 383.7 12399.5 13327.3 16144.0 16598.8 17354.0 15013.0 15262.4 15419.6 16276.4 16453.7 3962.7 4772.1 4988.4 4992.5 4995.5 4891.0 4975.8 4976.5 4978.5 4984.0 4930.5 4932.6 4933.6 4939.7 4942.5 4821.6 4998.9 5019.3 5022.4 5025.0 4510.1 4527.8 4535.2 4541.3 4546.9 19460.8 20132.5 20193.2 20487.6 20638.8 1855.8 1870.5 1932.6 2135.6 2233.8 12018.5 12761.6 12931.0 13132.6 13303.5 1663.4 1669.5 1686.6 1722.8 1733.9 64791.4 66299.7 66624.0 67581.7 68055.7 9013.7 9044.8 9289.6 9376.5 9643.6 12800.6 12810.7 12820.6 12837.0 12837.9 6599.6 6632.2 6745.7 6944.4 6950.7 6391.9 6436.7 6458.5 6652.9 6656.5 5915.5 5920.1 5929.0 5950.4 6005.9 1641.6 1737.6 1743.4 1744.7 1749.4 783.9 810.4 837.4 848.2 881.4 16370.7 16807.8 17169.7 17617.5 17774.5 826.3 872.2 877.0 903.5 918.4 9432.5 10051.9 10173.4 10182.2 10192.1 366775.8 369885.0 373702.0 373795.0 373835.0 64071.0 64737.7 64981.0 65317.3 65616.7 887.8 892.3 912.0 918.7 918.9 15071.4 15116.5 15433.3 15738.5 15792.2 33662.5 33895.1 33927.7 35126.8 35485.0 44278.0 44477.8 44729.0 44895.0 46231.5 6406.9 6579.6 6686.5 7159.9 7541.7 10745.8 11243.2 11462.3 11561.6 11902.2 9557.2 9789.2 10011.2 10293.4 10331.3 4973.1 7125.8 9901.5 10132.3 10348.9 10087.0 10239.8 10285.4 10614.5 10749.6 7362.8 7405.9 7527.5 7532.6 7555.4 9790.8 10155.1 10297.3 10370.8 10534.0 19:29:35 INFO - PROCESS | 12582 | 19:29:35 INFO - PROCESS | 12582 | [#3] mootools.html Cycles:180 Average:4494.30 Median:5536.96 stddev:2391.29 (43.2%) stddev-sans-first:2384.69 19:29:35 INFO - PROCESS | 12582 | Values: 1138.3 1289.4 1320.5 1335.8 1621.6 5829.1 6589.7 6833.1 6838.0 6983.0 6672.8 6732.2 6735.2 6739.1 6740.5 1734.1 1794.3 1993.5 2022.4 2025.1 1972.9 2035.9 2037.7 2055.6 2065.0 1993.0 2007.8 2020.3 2021.2 2023.3 2021.4 2061.5 2064.2 2064.4 2067.2 1974.6 1977.3 1979.1 1990.9 1994.6 6644.1 6815.7 6821.7 6858.3 6903.8 4146.7 4243.3 4264.8 4267.5 4267.9 2692.8 2737.9 2780.8 2783.7 2833.6 824.6 858.1 869.4 872.6 879.0 8828.6 8831.3 8841.5 8886.6 8934.9 3768.9 3917.1 3925.2 3928.7 3962.3 7263.5 7326.5 7332.7 7333.6 7335.1 4221.0 4227.0 4227.3 4233.4 4238.7 3799.3 3908.4 3922.1 3927.1 3932.0 2948.5 2974.9 3001.9 3023.6 3041.8 902.3 928.5 935.2 936.7 941.5 681.2 731.5 815.9 849.0 852.2 4782.1 4876.8 4878.1 4901.9 5036.0 370.5 384.8 393.3 398.0 398.3 3194.8 3412.3 3457.9 3490.5 3494.6 7466.2 7873.1 7875.1 8055.8 8058.4 7979.4 7983.4 7984.7 7993.8 7997.8 6114.2 6247.6 6278.3 6284.6 6287.3 4763.7 4767.8 4982.5 4996.2 5108.6 7122.5 7226.5 7590.7 7599.1 7604.5 6775.2 7489.7 7769.8 7805.0 7813.2 5448.6 5801.7 5961.9 6007.0 6148.3 6654.7 6661.0 6668.1 6742.5 6745.6 5981.1 5991.3 6000.8 6039.1 6047.2 5853.2 5884.9 5901.2 5901.4 6027.1 5625.4 5711.2 5719.0 5722.9 5726.7 4173.9 4351.8 4399.6 4409.8 4442.3 4221.5 4232.3 4274.6 4376.6 4376.6 19:29:35 INFO - PROCESS | 12582 | 19:29:35 INFO - PROCESS | 12582 | [#4] prototype.html Cycles:180 Average:4098.08 Median:4681.02 stddev:1183.20 (25.3%) stddev-sans-first:1151.49 19:29:35 INFO - PROCESS | 12582 | Values: 290.5 290.9 293.0 297.1 298.7 4809.0 4826.2 4830.5 4848.5 4859.7 4782.1 4784.7 4793.9 4799.2 4809.9 3578.3 3656.7 3683.5 3749.2 3778.8 3743.7 3755.7 3770.8 3771.1 3783.8 3727.9 3728.2 3732.2 3735.6 3772.6 3573.2 3665.1 3697.0 3724.9 3767.8 3297.3 3299.6 3330.8 3332.2 3335.1 4637.3 4647.1 4656.3 4658.7 4668.2 3966.7 4166.8 4267.5 4270.9 4277.0 4921.5 4928.9 4943.1 4943.4 4946.3 3506.3 3507.0 3508.4 3510.1 3526.1 4907.2 5154.8 5195.9 5202.6 5204.5 3537.9 3570.4 3576.1 3594.1 3610.7 3629.0 3646.6 3646.7 3646.9 3652.0 4174.2 4174.8 4179.2 4180.8 4181.8 4659.4 4896.9 4901.6 4944.4 4952.6 1242.6 1256.7 1256.8 1256.9 1265.5 817.7 859.8 874.2 879.2 890.3 4118.7 4208.3 4426.5 4441.0 4449.4 5148.1 5156.0 5173.6 5201.4 5210.3 4452.6 4454.8 4458.4 4468.5 4473.1 2557.3 2661.5 2665.7 2667.9 2670.6 4658.0 4669.6 4695.8 4702.3 4708.5 4914.5 5129.8 5138.4 5146.8 5181.4 4694.2 4718.4 4724.9 4725.7 4749.4 4967.4 4990.3 4998.2 5000.6 5018.0 4655.2 4856.2 4881.9 4896.4 4927.4 4676.7 4680.6 4681.4 4696.4 4712.2 3465.8 3490.1 3490.3 3493.6 3505.9 4945.6 4988.8 5019.4 5020.8 5040.5 4711.7 4775.8 4858.6 4876.7 4880.0 4845.6 4852.3 4867.5 4876.2 4889.5 4793.5 4808.7 4814.0 4815.6 4820.2 4753.7 4925.4 5043.2 5070.7 5090.0 4949.4 4972.6 4977.7 4989.1 4992.6 19:29:35 INFO - PROCESS | 12582 | 19:29:35 INFO - PROCESS | 12582 | [#5] yui.html Cycles:180 Average:13543.66 Median:47550.26 stddev:16743.71 (35.2%) stddev-sans-first:16762.66 19:29:35 INFO - PROCESS | 12582 | Values: 644.5 648.8 657.4 726.8 734.1 11320.2 12226.2 12229.4 12240.1 12250.7 11830.5 11958.5 12025.2 12038.4 12053.0 603.0 621.0 732.7 734.2 757.9 770.9 774.7 775.1 775.2 775.7 810.6 810.7 811.4 812.0 812.3 631.3 649.1 767.8 792.0 799.6 583.7 584.7 584.9 585.0 585.6 11934.6 11940.4 11964.6 11966.9 11968.1 11957.6 12055.5 12225.1 12260.6 12442.3 10907.6 10942.7 10988.5 11184.1 11343.9 3944.9 4013.2 4066.3 4069.7 4099.5 45378.3 45822.0 46181.5 46568.3 47454.8 9397.3 9606.3 9627.6 9794.7 9813.5 17350.3 17374.3 17377.3 17378.2 17393.6 6269.2 6436.3 6541.3 6616.9 6623.3 3200.1 3260.4 3410.2 3464.5 3520.2 2652.1 2704.1 2762.8 2766.1 2766.1 454.9 456.2 456.5 465.7 470.2 448.8 491.3 542.8 542.9 549.2 6985.3 7481.6 7531.1 7573.2 7615.0 420.1 511.6 523.7 582.3 629.8 3575.9 3693.1 3844.2 3873.3 3893.4 63385.0 63477.7 65129.7 66639.0 67753.3 35280.5 37743.0 47645.8 55680.7 56990.0 50967.5 51059.0 51130.5 51545.5 53044.9 7282.7 7347.9 7459.2 7576.4 7829.9 34748.8 35451.0 36700.8 36847.3 37823.4 29301.9 35372.6 49168.5 50624.2 52547.5 7813.0 8115.9 8768.6 8797.5 8982.4 10090.5 10153.1 10432.1 10603.8 10748.1 10259.9 10558.7 10941.9 11246.2 11861.6 10891.3 11233.9 11291.6 11381.9 11597.5 10491.3 11082.9 11149.6 11522.5 11985.4 8450.6 8693.1 8738.5 9053.2 9299.8 9261.8 9296.9 9447.7 9563.5 9574.4 19:29:35 INFO - PROCESS | 12582 | -------- Summary: end -------- 19:29:35 INFO - PROCESS | 12582 | 19:29:36 INFO - TEST-INFO | 12582: exit 0 19:29:36 INFO - TEST-OK | dromaeo_css | took 1123738ms 19:29:36 INFO - TEST-START | kraken 19:29:36 INFO - operating with platform_type : linux_ 19:29:36 INFO - Initialising browser for kraken test... 19:29:36 INFO - TEST-INFO | started process 12889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUbfnVS/profile http://localhost:47391/getInfo.html) 19:29:40 INFO - PROCESS | 12889 | __metrics Screen width/height:1600/1200 19:29:40 INFO - PROCESS | 12889 | colorDepth:24 19:29:40 INFO - PROCESS | 12889 | Browser inner width/height: 1024/697 19:29:40 INFO - PROCESS | 12889 | __metrics 19:29:41 INFO - PROCESS | 12889 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:29:41 INFO - PROCESS | 12889 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:29:42 INFO - PROCESS | 12889 | console.error: PushService: 19:29:42 INFO - PROCESS | 12889 | stateChangeProcessEnqueue: Error transitioning state 19:29:42 INFO - PROCESS | 12889 | UnknownError 19:29:43 INFO - TEST-INFO | 12889: exit 0 19:29:43 INFO - Browser initialized. 19:29:43 INFO - Running cycle 1/1 for kraken test... 19:29:43 INFO - TEST-INFO | started process 12972 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUbfnVS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:29:55 INFO - PROCESS | 12972 | RSS: Main: 174252032 19:29:55 INFO - PROCESS | 12972 | 19:29:58 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47391/tests/kraken/driver.html?testName=audio-beat-detection) 19:29:59 INFO - PROCESS | 12972 | RSS: Main: 239071232 19:29:59 INFO - PROCESS | 12972 | 19:30:01 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47391/tests/kraken/driver.html?testName=audio-dft) 19:30:02 INFO - PROCESS | 12972 | RSS: Main: 218324992 19:30:02 INFO - PROCESS | 12972 | 19:30:05 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47391/tests/kraken/driver.html?testName=audio-fft) 19:30:06 INFO - PROCESS | 12972 | RSS: Main: 299937792 19:30:06 INFO - PROCESS | 12972 | 19:30:08 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47391/tests/kraken/driver.html?testName=audio-oscillator) 19:30:09 INFO - PROCESS | 12972 | RSS: Main: 210878464 19:30:09 INFO - PROCESS | 12972 | 19:30:11 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47391/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:30:11 INFO - PROCESS | 12972 | RSS: Main: 214016000 19:30:11 INFO - PROCESS | 12972 | 19:30:15 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47391/tests/kraken/driver.html?testName=imaging-darkroom) 19:30:15 INFO - PROCESS | 12972 | RSS: Main: 253444096 19:30:15 INFO - PROCESS | 12972 | 19:30:19 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47391/tests/kraken/driver.html?testName=imaging-desaturate) 19:30:19 INFO - PROCESS | 12972 | RSS: Main: 253390848 19:30:19 INFO - PROCESS | 12972 | 19:30:22 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47391/tests/kraken/driver.html?testName=json-parse-financial) 19:30:23 INFO - PROCESS | 12972 | RSS: Main: 252719104 19:30:23 INFO - PROCESS | 12972 | 19:30:24 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47391/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:30:25 INFO - PROCESS | 12972 | RSS: Main: 214249472 19:30:25 INFO - PROCESS | 12972 | 19:30:27 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47391/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:30:28 INFO - PROCESS | 12972 | RSS: Main: 222593024 19:30:28 INFO - PROCESS | 12972 | 19:30:30 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47391/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:30:30 INFO - PROCESS | 12972 | RSS: Main: 230117376 19:30:30 INFO - PROCESS | 12972 | 19:30:33 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:30:34 INFO - PROCESS | 12972 | RSS: Main: 239333376 19:30:34 INFO - PROCESS | 12972 | 19:30:37 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:30:38 INFO - PROCESS | 12972 | RSS: Main: 235773952 19:30:38 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | Cycle 1(1): loaded http://localhost:47391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47391/tests/kraken/driver.html?testName=ai-astar) 19:30:40 INFO - PROCESS | 12972 | RSS: Main: 287105024 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | __start_tp_report 19:30:40 INFO - PROCESS | 12972 | _x_x_mozilla_page_load 19:30:40 INFO - PROCESS | 12972 | _x_x_mozilla_page_load_details 19:30:40 INFO - PROCESS | 12972 | |i|pagename|runs| 19:30:40 INFO - PROCESS | 12972 | |0;ai-astar;111;114;114;114;113;115;114;115;114;115 19:30:40 INFO - PROCESS | 12972 | |1;audio-beat-detection;162;130;128;165;134;130;127;127;155;131 19:30:40 INFO - PROCESS | 12972 | |2;audio-dft;161;161;162;161;161;161;161;161;161;161 19:30:40 INFO - PROCESS | 12972 | |3;audio-fft;76;77;79;112;78;78;77;79;91;76 19:30:40 INFO - PROCESS | 12972 | |4;audio-oscillator;83;84;108;85;85;84;103;85;83;107 19:30:40 INFO - PROCESS | 12972 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;117;116 19:30:40 INFO - PROCESS | 12972 | |6;imaging-darkroom;146;147;146;146;146;146;146;146;147;146 19:30:40 INFO - PROCESS | 12972 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;95;95 19:30:40 INFO - PROCESS | 12972 | |8;json-parse-financial;63;63;62;63;64;71;65;64;64;65 19:30:40 INFO - PROCESS | 12972 | |9;json-stringify-tinderbox;66;66;66;65;66;65;65;65;64;70 19:30:40 INFO - PROCESS | 12972 | |10;stanford-crypto-aes;84;85;85;94;85;85;93;85;85;91 19:30:40 INFO - PROCESS | 12972 | |11;stanford-crypto-ccm;155;142;146;142;140;145;143;150;196;155 19:30:40 INFO - PROCESS | 12972 | |12;stanford-crypto-pbkdf2;187;181;180;184;180;180;190;180;181;181 19:30:40 INFO - PROCESS | 12972 | |13;stanford-crypto-sha256-iterative;71;70;69;69;69;70;70;70;68;72 19:30:40 INFO - PROCESS | 12972 | __end_tp_report 19:30:40 INFO - PROCESS | 12972 | __start_cc_report 19:30:40 INFO - PROCESS | 12972 | _x_x_mozilla_cycle_collect,1966 19:30:40 INFO - PROCESS | 12972 | __end_cc_report 19:30:40 INFO - PROCESS | 12972 | __startTimestamp1480303840263__endTimestamp 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | ------- Summary: start ------- 19:30:40 INFO - PROCESS | 12972 | Number of tests: 14 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:1.20 (1.1%) stddev-sans-first:0.67 19:30:40 INFO - PROCESS | 12972 | Values: 111.0 114.0 114.0 114.0 113.0 115.0 114.0 115.0 114.0 115.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:132.50 stddev:15.35 (11.6%) stddev-sans-first:13.82 19:30:40 INFO - PROCESS | 12972 | Values: 162.0 130.0 128.0 165.0 134.0 130.0 127.0 127.0 155.0 131.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 19:30:40 INFO - PROCESS | 12972 | Values: 161.0 161.0 162.0 161.0 161.0 161.0 161.0 161.0 161.0 161.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#3] audio-fft Cycles:10 Average:82.30 Median:78.00 stddev:11.31 (14.5%) stddev-sans-first:11.77 19:30:40 INFO - PROCESS | 12972 | Values: 76.0 77.0 79.0 112.0 78.0 78.0 77.0 79.0 91.0 76.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:84.00 stddev:10.66 (12.7%) stddev-sans-first:10.93 19:30:40 INFO - PROCESS | 12972 | Values: 83.0 84.0 108.0 85.0 85.0 84.0 103.0 85.0 83.0 107.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 19:30:40 INFO - PROCESS | 12972 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 116.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 19:30:40 INFO - PROCESS | 12972 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:30:40 INFO - PROCESS | 12972 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.00 stddev:2.50 (3.9%) stddev-sans-first:2.60 19:30:40 INFO - PROCESS | 12972 | Values: 63.0 63.0 62.0 63.0 64.0 71.0 65.0 64.0 64.0 65.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:1.62 (2.5%) stddev-sans-first:1.72 19:30:40 INFO - PROCESS | 12972 | Values: 66.0 66.0 66.0 65.0 66.0 65.0 65.0 65.0 64.0 70.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:85.00 stddev:3.85 (4.5%) stddev-sans-first:3.91 19:30:40 INFO - PROCESS | 12972 | Values: 84.0 85.0 85.0 94.0 85.0 85.0 93.0 85.0 85.0 91.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:148.00 stddev:16.55 (11.2%) stddev-sans-first:17.50 19:30:40 INFO - PROCESS | 12972 | Values: 155.0 142.0 146.0 142.0 140.0 145.0 143.0 150.0 196.0 155.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.40 Median:181.00 stddev:3.50 (1.9%) stddev-sans-first:3.30 19:30:40 INFO - PROCESS | 12972 | Values: 187.0 181.0 180.0 184.0 180.0 180.0 190.0 180.0 181.0 181.0 19:30:40 INFO - PROCESS | 12972 | 19:30:40 INFO - PROCESS | 12972 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.00 stddev:1.14 (1.6%) stddev-sans-first:1.12 19:30:40 INFO - PROCESS | 12972 | Values: 71.0 70.0 69.0 69.0 69.0 70.0 70.0 70.0 68.0 72.0 19:30:40 INFO - PROCESS | 12972 | -------- Summary: end -------- 19:30:40 INFO - PROCESS | 12972 | 19:30:41 INFO - TEST-INFO | 12972: exit 0 19:30:41 INFO - TEST-OK | kraken | took 65325ms 19:30:41 INFO - SUITE-END | took 1189s 19:30:41 INFO - Completed test suite (00:19:49) 19:30:41 INFO - javascript benchmark 19:30:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2589.702184248811, 2617.9214623561274, 2645.2857728413323, 2651.681798184218, 2659.6808382994045, 4530.614897733697, 4613.123506533765, 4682.4849266580695, 4692.061587682464, 4692.953070469294, 4621.12198682251, 4684.718916864982, 4723.692959957601, 4777.04459108178, 4781.019890922361, 4265.253580623394, 4290.334998075304, 4332.176886391591, 4363.301871700529, 4441.045175287312, 3966.9537072186354, 4293.935590966138, 4355.869323920288, 4394.47266328041, 4434.246178149706, 4200.075983283673, 4244.830206791725, 4361.498427680825, 4406.845760398371, 4440.844570440036, 4286.935695964563, 4287.035417031174, 4326.394304797331, 4352.586504282088, 4401.801918913625, 4329.978350108261, 4331.826726930919, 4341.435613370273, 4381.846635367747, 4387.605115539617, 4708.505606911259, 4723.787429565644, 4731.455882573514, 4737.786799594027, 4747.0505898820375, 4892.584130348924, 4945.258211268302, 4949.010197960386, 5008.499150084962, 5079.390473143209, 5059.1905295152965, 5114.897702045975, 5114.897702045975, 5141.485851414866, 5162.354705661793, 4762.071396077767, 4765.1660959331975, 4768.213244814636, 4769.070031343855, 4820.421549414093, 4967.453580106186, 5023.623228257895, 5211.296474975829, 5212.035773381936, 5273.1035723927025, 4874.2201247800185, 4877.219644856806, 4913.434954980152, 4921.941782516776, 4979.800807967641, 5008.198688209869, 5021.623378246646, 5025.723585202776, 5025.773840177201, 5073.315102461121, 4925.7783399747095, 4935.851924442272, 4962.032403681247, 4978.253262010655, 5026.271190677331, 4787.8563643090765, 4837.201861692782, 4837.6613637045775, 4889.975550122226, 4893.4617192108835, 4275.0594869128745, 4301.376300436419, 4319.97840010798, 4369.69412141147, 4373.890652733707, 3757.248550289953, 3758.267137908136, 3761.4545890845675, 3796.6962642988497, 3816.9236615268096, 4366.781660916941, 4367.737935723867, 4369.541198174209, 4403.9779801100785, 4409.36064270679, 4830.154722923475, 4878.341423907798, 4884.902301953941, 4952.603791696655, 5066.0, 4214.620684138381, 4261.97869010653, 4264.658827293809, 4265.3815196796295, 4268.445102136702, 4443.355713421536, 4474.507804141542, 4486.506484286726, 4488.483824360241, 4491.461024676994, 4635.0498147878925, 4635.304704294383, 4639.8144074237325, 4641.582257596832, 4642.2340313847835, 4938.16051271292, 4959.603231741524, 4959.7272150032095, 4960.206366981266, 4961.627877909244, 4649.116667833101, 4661.394018777537, 4664.253719404879, 4664.953350466452, 4665.883352916068, 4880.267959806058, 4888.2178851383605, 4893.412790465167, 4898.33872427232, 4936.629752768628, 5093.083245015784, 5095.643304968617, 5096.490350964874, 5097.1844504879045, 5149.0474262261605, 4682.648801339845, 4717.551832575899, 4935.235038569028, 4944.802207911644, 4988.6757360771435, 4820.035992801384, 4857.708537487762, 4860.659753817199, 4902.975485122552, 4948.579370753526, 4817.494163112823, 4818.71087734737, 4821.4696383397095, 4821.927671084866, 4857.927131092966, 4722.0, 4911.140550403594, 4920.212765957573, 4929.704217746947, 4971.875703107451, 4657.441107067174, 4698.248280275139, 4716.764161791828, 4718.504557021601, 4723.881902952454], "value": 4597.604972642755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.4265164229201, 575.4878158438987, 578.4562511239435, 581.5434883616362, 594.8304733151053, 27676.308092297677, 30485.237869053257, 37986.24027519451, 39080.60919390805, 39370.803145984304, 39139.804300978525, 39369.40945885814, 39413.21173576534, 39444.40833387501, 39475.60524394755, 7521.586312752796, 7661.387089032864, 7665.655045522964, 7667.923320766777, 7668.65491052904, 7359.484836061478, 7575.621218939059, 9607.183389411908, 9856.704298871009, 9882.061204185591, 9902.306838521306, 9951.402915825038, 9959.950200248988, 9965.501724913762, 9968.102870741632, 9756.902430975706, 9806.607735690564, 9817.656382026595, 9824.410535367864, 9824.705258842247, 9464.479453630047, 9543.570539325747, 9545.379550329206, 9545.90454095464, 9546.713598592054, 37024.0, 37265.0, 37313.62686373129, 37326.81336593327, 37386.62613373859, 2937.8971735989353, 3124.047165614487, 3289.5394644749754, 3639.836207370675, 3722.088088418328, 27457.725422745716, 27468.450630987467, 27980.720192798017, 27992.300192495146, 28072.298192545146, 3489.3893568625645, 3492.1967947372245, 3499.877504287363, 3537.4870643756512, 3650.671439570451, 167234.16382918006, 169281.0, 169287.0, 169566.0, 169939.99999999878, 51432.0, 51747.48252517502, 51852.0, 51905.2214216787, 52083.73958130261, 55294.72352638291, 55314.72342638261, 55317.44682553203, 55367.16949245684, 55516.0, 13670.93164534171, 14145.858541414455, 14171.716565668628, 14192.219427931357, 14192.22597175353, 9542.284328675378, 9563.378380405251, 9584.66453674118, 9599.376040557481, 10025.699229023141, 14034.157950523002, 18880.24479020824, 18881.433556993314, 18928.43214703561, 19506.974243586003, 6693.832654183587, 6791.762288319984, 6856.828579285558, 7121.394681452031, 7126.501144919909, 755.3768141283463, 821.6549049399266, 861.7457849934159, 892.8973168085753, 911.4303560274828, 26175.73824261733, 28194.85902570515, 28269.28939464358, 28749.71250287512, 30889.69110308868, 934.9579268932914, 960.9231261499064, 978.0317485689117, 1002.063071028576, 1014.7970405918699, 14000.859991399955, 14977.700445991019, 14992.775108373384, 15060.924695376452, 15061.322240499203, 381654.0, 383855.0, 383998.0, 385198.0, 634387.0, 43247.567524324986, 47633.0, 48893.266601001014, 48912.0, 55076.0, 1155.1855941561348, 1156.3871148291419, 1167.2821214952744, 1169.5964892112208, 1199.3163896578867, 26993.325166870985, 27859.442811143257, 28219.858900705363, 28926.566101508073, 29046.12861614155, 42742.14515709606, 43562.564374355854, 43584.564154359316, 43639.0, 43664.56335436605, 341481.0, 343116.0, 343496.0, 344591.0, 345398.0, 25120.61354171351, 25441.872790635927, 25468.872655636602, 25475.87262063678, 25483.74516254888, 41548.3767743478, 43150.78424607857, 43304.566954330054, 47142.22862171087, 47538.0, 34348.82825585856, 35059.47410788891, 35187.0, 35649.82175089108, 35761.28477430385, 35566.0, 35714.0, 36073.278534429686, 36345.636543635286, 36385.454218187275, 34774.82612586921, 34897.82551087228, 35143.47284790679, 35500.11249718675, 35607.46588801118, 30659.846700766353, 32442.675573243967, 32813.34373312568, 32998.34003319968, 33153.83423082869, 24569.754302456746, 24967.87516062408, 25182.013272471995, 25184.24447266585, 25252.74747252504], "value": 17642.960243851725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.307139613905, 358.26019270207024, 360.7618971478823, 363.736291188888, 383.7352226963395, 12399.504019839196, 13327.26700031499, 16144.0, 16598.75101873473, 17354.00857802706, 15013.0, 15262.389504419838, 15419.614509637264, 16276.43032493863, 16453.67092658146, 3962.742421742592, 4772.093302272566, 4988.426330971948, 4992.525710057539, 4995.525425084629, 4891.0, 4975.8258460953875, 4976.452590215092, 4978.477259887696, 4983.975080124595, 4930.482299358569, 4932.630052746041, 4933.580645645125, 4939.703617782927, 4942.505749425065, 4821.590164835993, 4998.8500344989525, 5019.347484826949, 5022.447530771612, 5024.9748751256375, 4510.143072816154, 4527.818887244506, 4535.229011068093, 4541.34150548172, 4546.909061818778, 19460.80539194604, 20132.49668758278, 20193.19227230909, 20487.590248194952, 20638.793612063986, 1855.7958624551288, 1870.5417172792752, 1932.6424611446928, 2135.6155891939443, 2233.8101261392803, 12018.4591693374, 12761.61715148547, 12931.0, 13132.641967463804, 13303.467861285537, 1663.376233912283, 1669.5492217101314, 1686.5530634381898, 1722.767426397433, 1733.904635245061, 64791.352086479004, 66299.66850165767, 66624.0, 67581.66209168923, 68055.65972170107, 9013.684521041732, 9044.81910361789, 9289.584707975975, 9376.5311734413, 9643.649889015544, 12800.61598152057, 12810.743785124245, 12820.551280705316, 12837.037222208372, 12837.871621283854, 6599.637019963852, 6632.237292711304, 6745.696443659949, 6944.409725173316, 6950.721971121098, 6391.872162556816, 6436.67816609177, 6458.451031662361, 6652.933470665232, 6656.500762442836, 5915.526757859361, 5920.111983202468, 5929.0, 5950.404959504015, 6005.879882402328, 1641.564985278902, 1737.6090379664474, 1743.429741974405, 1744.6597913406763, 1749.3702267183803, 783.8902553642495, 810.4205493072482, 837.4472847920343, 848.1645579104577, 881.4345970549016, 16370.67258654844, 16807.83192168087, 17169.7424538632, 17617.471475855746, 17774.46676599704, 826.2728798657143, 872.2062922740275, 877.0, 903.5391950105362, 918.4076270805293, 9432.481213533321, 10051.949740251399, 10173.44046077473, 10182.23633227511, 10192.082712555757, 366775.81750646007, 369885.0, 373702.0, 373795.0, 373835.0, 64071.0, 64737.67631161814, 64981.0, 65317.346826531124, 65616.67191664106, 887.7558671365426, 892.3173772064376, 912.0378001208655, 918.7060140754894, 918.93108016899, 15071.397144114113, 15116.54650360491, 15433.305501252245, 15738.527844164693, 15792.21038948071, 33662.49506257457, 33895.11891489251, 33927.66072339245, 35126.824365878005, 35484.997903899246, 44278.0, 44477.777611111735, 44729.0, 44895.0, 46231.53768462272, 6406.903896441464, 6579.638119903451, 6686.531942764154, 7159.8926016110845, 7541.7360392386, 10745.785084298426, 11243.212975091665, 11462.312261264351, 11561.595344162914, 11902.166848320534, 9557.187639050759, 9789.167920726817, 10011.199104071511, 10293.3823970562, 10331.276810623183, 4973.129702302011, 7125.82185445368, 9901.455419951972, 10132.290739648153, 10348.896511034793, 10087.0, 10239.795204096024, 10285.382877027403, 10614.469276535989, 10749.62376316825, 7362.77911662651, 7405.852092925606, 7527.548347099191, 7532.585707786125, 7555.395568354408, 9790.75523111928, 10155.136813370946, 10297.330673506198, 10370.792584148425, 10534.0], "value": 7939.245057514961, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1138.299945533497, 1289.4390939941122, 1320.518010926012, 1335.7862741961287, 1621.602707336702, 5829.096490044045, 6589.736410543578, 6833.077534532837, 6837.965810170955, 6982.965085174567, 6672.799816005516, 6732.158480189976, 6735.158105236846, 6739.0902228199175, 6740.494462915276, 1734.0549400576654, 1794.3271273272524, 1993.5016245938516, 2022.3629556689668, 2025.1089520610883, 1972.891490967996, 2035.908384122718, 2037.735094437721, 2055.599158164159, 2064.9896750516227, 1992.9601407971832, 2007.8393728501758, 2020.2626041494896, 2021.2016253579827, 2023.271622216001, 2021.4036859126593, 2061.505238742706, 2064.1743302678897, 2064.4322811226884, 2067.204126411335, 1974.5754662747433, 1977.3079422202259, 1979.1291831594197, 1990.920363185471, 1994.601079784049, 6644.069830223772, 6815.727370905158, 6821.727130914807, 6858.279880612564, 6903.827404314882, 4146.730462519927, 4243.342281946303, 4264.808083636212, 4267.466566679165, 4267.893302667459, 2692.8249663771794, 2737.868684961091, 2780.805343625947, 2783.73554512321, 2833.6032955386263, 824.6206744897354, 858.0689951402735, 869.3740506835069, 872.5898827551041, 879.0330636300083, 8828.558572071435, 8831.337649676236, 8841.513716745576, 8886.60010299538, 8934.910650893473, 3768.8894219236768, 3917.0990672145567, 3925.156091440354, 3928.705347098979, 3962.32640451124, 7263.527870688388, 7326.487145899842, 7332.670029848669, 7333.633318334062, 7335.083114610674, 4220.957790422118, 4226.978865105716, 4227.3447615619625, 4233.407322974786, 4238.660907127422, 3799.2591444668024, 3908.4332771748504, 3922.0783115967724, 3927.096767743435, 3931.980340098281, 2948.513495273256, 2974.8810047597854, 3001.9099427017222, 3023.576699262105, 3041.7870749047356, 902.3052249767642, 928.4522131942186, 935.2004036548789, 936.7283487788601, 941.5198248893114, 681.1655721740827, 731.4806487393904, 815.9428839981263, 849.0363437498484, 852.2244757270854, 4782.139214941344, 4876.804927802919, 4878.121938051185, 4901.877453063702, 5036.0, 370.54793152354085, 384.8133655177237, 393.34311699461966, 397.97811120388076, 398.2771270144678, 3194.8242846643657, 3412.3004784019217, 3457.896263112111, 3490.528778614905, 3494.5631796025496, 7466.178720340758, 7873.133955264916, 7875.054993400829, 8055.838883222303, 8058.35533157346, 7979.4015448841565, 7983.441159118806, 7984.6806127754235, 7993.800154996055, 7997.840043199103, 6114.174586430864, 6247.625142491556, 6278.30938596754, 6284.560080794392, 6287.2769631492965, 4763.7141771493825, 4767.809287628456, 4982.501749825134, 4996.15065438869, 5108.616853736059, 7122.501424900414, 7226.530275532074, 7590.696372145052, 7599.088109426904, 7604.505707129019, 6775.1531058617675, 7489.662965166472, 7769.805754855947, 7804.9609751950875, 7813.1796161402235, 5448.56411487088, 5801.709914504342, 5961.850953726192, 6007.0, 6148.262208535004, 6654.73381064752, 6660.966695166493, 6668.066470694203, 6742.4943129266485, 6745.628990405378, 5981.0729336952845, 5991.2510936132985, 6000.759969601167, 6039.094135879477, 6047.213862197887, 5853.1805547222575, 5884.941150588439, 5901.232839730807, 5901.350851406256, 6027.126066720213, 5625.353084395183, 5711.171880077449, 5718.971405142947, 5722.914156287742, 5726.685032323262, 4173.853915113078, 4351.825926962886, 4399.626031787334, 4409.823607055682, 4442.2892337225885, 4221.472315960505, 4232.259354612869, 4274.551172126882, 4376.584224498668, 4376.606105450524], "value": 3616.603148158091, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.45249704307383, 290.9010936281664, 292.96484421869366, 297.05388338143007, 298.68190377248214, 4808.975955120224, 4826.155422801014, 4830.492798256185, 4848.515148485149, 4859.6841205321625, 4782.091402633498, 4784.746372466721, 4793.931883594493, 4799.208130658446, 4809.8797530061765, 3578.2843431313713, 3656.6526180012856, 3683.5211422515035, 3749.1564398010364, 3778.848846046155, 3743.7084205948904, 3755.6619904208615, 3770.7744982880536, 3771.076086358832, 3783.829727662248, 3727.850885964558, 3728.1611637381643, 3732.216234590739, 3735.6077611850774, 3772.566154892181, 3573.178169021127, 3665.138692407282, 3697.0, 3724.925501489955, 3767.830447629863, 3297.258116923697, 3299.6040475142895, 3330.7668463207583, 3332.150301673082, 3335.1328654549748, 4637.258038713823, 4647.070585882837, 4656.301554766778, 4658.743769092698, 4668.159731248374, 3966.682665386762, 4166.7708276044805, 4267.466566679165, 4270.91458170838, 4276.978615106936, 4921.532454416824, 4928.9156385595115, 4943.134951383529, 4943.431505376891, 4946.258061290835, 3506.3162683276773, 3507.018034950218, 3508.438649816043, 3510.1224693826543, 3526.1360966563097, 4907.153850766546, 5154.845354639367, 5195.856911479506, 5202.557782588447, 5204.505571970657, 3537.911552211215, 3570.4465807799834, 3576.1238496567985, 3594.0655429588496, 3610.6750392464805, 3628.9637103628625, 3646.5624125105155, 3646.690031347365, 3646.908827279339, 3651.9634803652157, 4174.248635245624, 4174.791260436966, 4179.185058913483, 4180.832766689359, 4181.790910454526, 4659.394278743742, 4896.902061958741, 4901.632377571696, 4944.431390390079, 4952.628552858478, 1242.577523641966, 1256.7369793357618, 1256.7800634888858, 1256.8750967883875, 1265.5064524835323, 817.6811043692965, 859.7893516088474, 874.1782724239195, 879.1735768377705, 890.2610833008588, 4118.670506359484, 4208.305629571087, 4426.539242050116, 4440.977795111004, 4449.421575195204, 5148.124818780741, 5155.9742201288755, 5173.63784535079, 5201.375834899761, 5210.336748610049, 4452.5547445255215, 4454.814807412716, 4458.375827384169, 4468.530804265571, 4473.114840927648, 2557.347876291571, 2661.4544018476554, 2665.680488158374, 2667.906158475016, 2670.626112344273, 4658.020341354911, 4669.556392142741, 4695.812167513262, 4702.31816386622, 4708.529147085333, 4914.483979182206, 5129.769160387791, 5138.3833939927445, 5146.819861304836, 5181.393767931953, 4694.183489906029, 4718.386609740781, 4724.929126063112, 4725.692829966041, 4749.3588365570895, 4967.379077615298, 4990.276409920614, 4998.175301075427, 5000.6249531284575, 5018.0, 4655.231886738786, 4856.163630731682, 4881.9023619528125, 4896.387951506062, 4927.359443272495, 4676.672632915799, 4680.648951328732, 4681.391419115494, 4696.436427628706, 4712.222483290218, 3465.774724642991, 3490.1449144959647, 3490.3193877193703, 3493.5633045869267, 3505.947410788789, 4945.579625731853, 4988.782235458182, 5019.447860735243, 5020.774065167149, 5040.513048650583, 4711.693739906895, 4775.832845850378, 4858.61529464101, 4876.70794726478, 4879.95120048795, 4845.612351011981, 4852.320675105559, 4867.488913664014, 4876.171050921281, 4889.535494128052, 4793.472718001087, 4808.663393562557, 4813.95186048135, 4815.566599006106, 4820.1805693031565, 4753.691010084333, 4925.408950925912, 5043.218301163325, 5070.6957582545165, 5090.0, 4949.356583644248, 4972.627052970969, 4977.701337919736, 4989.101961646938, 4992.600591952562], "value": 3723.6207251971587, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.4940721533595, 648.7534736800015, 657.3951964192945, 726.8037629839945, 734.0747122715976, 11320.207585469021, 12226.205296655711, 12229.38853057348, 12240.143189976694, 12250.693732656688, 11830.526778928854, 11958.461869215884, 12025.158238923279, 12038.398080095982, 12053.035757139429, 602.9788957386493, 621.0280910375271, 732.6776218463872, 734.1740541890373, 757.8673732096888, 770.9498882572643, 774.7365895595497, 775.0544335910181, 775.151209425678, 775.678093591159, 810.623060276971, 810.7405630198339, 811.407672399149, 811.975640730779, 812.252727490707, 631.2929518938772, 649.1042361541066, 767.8349154931716, 791.956442395668, 799.6001999000441, 583.7373182068044, 584.6638183044766, 584.8596336879161, 585.0376131264089, 585.6105689716334, 11934.641960741192, 11940.40297985106, 11964.581239656658, 11966.940165299206, 11968.102392320523, 11957.641270761891, 12055.517779288817, 12225.083118766039, 12260.632181034583, 12442.315672638002, 10907.563697452093, 10942.726431839188, 10988.450577471174, 11184.105271578253, 11343.886561134366, 3944.881653550398, 4013.1572369802084, 4066.2680717470844, 4069.674426045909, 4099.487564054493, 45378.31932521015, 45822.0, 46181.53818461806, 46568.30147547789, 47454.76272618649, 9397.295202859812, 9606.27952903535, 9627.614895404246, 9794.706158815246, 9813.509324533745, 17350.3059877606, 17374.305027798746, 17377.304907803547, 17378.217980190922, 17393.565160870825, 6269.185005949197, 6436.292007879129, 6541.313162117909, 6616.900746488806, 6623.3376662333385, 3200.071576624288, 3260.4457242268873, 3410.1645096951406, 3464.549608550872, 3520.15516276097, 2652.124798816386, 2704.1211606228053, 2762.7513523782955, 2766.0595397564925, 2766.1286694691107, 454.9226631472658, 456.15155810193016, 456.50469240874025, 465.6879890473391, 470.18892410591457, 448.8070129844133, 491.25329499161074, 542.7665157875393, 542.9375621803464, 549.2420459765498, 6985.301469852974, 7481.625918704043, 7531.096268447822, 7573.240750440209, 7614.961925190339, 420.0988878854839, 511.5856156513236, 523.7355135656469, 582.2721598002497, 629.7795771479946, 3575.856965721394, 3693.132113953219, 3844.2119365529998, 3873.302805495038, 3893.4354518594646, 63385.0, 63477.68261158665, 65129.674351628884, 66639.0, 67753.32246677569, 35280.470792938126, 37743.0, 47645.76177119092, 55680.721596391755, 56990.0, 50967.49032509702, 51059.0, 51130.488695113316, 51545.48454515407, 53044.93910121776, 7282.708691652275, 7347.889781653173, 7459.179490255959, 7576.393888489018, 7829.921700783148, 34748.82625586856, 35451.0, 36700.81649591735, 36847.26305473929, 37823.43264850974, 29301.85349073241, 35372.646273536935, 49168.508314917824, 50624.240636389746, 52547.474525254256, 7813.0, 8115.918840811516, 8768.561571921251, 8797.516136612292, 8982.416142950688, 10090.545925433225, 10153.136983356497, 10432.113270372103, 10603.840942385716, 10748.086412654699, 10259.897401025893, 10558.736031598964, 10941.890581094087, 11246.212765106691, 11861.5848445304, 10891.292066015683, 11233.887661123284, 11291.604793832177, 11381.943090284496, 11597.536098555964, 10491.318064325795, 11082.889171108185, 11149.609763658233, 11522.465466513806, 11985.400729963292, 8450.619722112642, 8693.130686931509, 8738.519381434082, 9053.230799960158, 9299.76750581241, 9261.768455788659, 9296.860547091934, 9447.716568502956, 9563.521823908637, 9574.377665451724], "value": 5834.33042497465, "unit": "score"}], "name": "dromaeo_css", "value": 6081.807677867308, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 114.0, 114.0, 113.0, 115.0, 114.0, 115.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 130.0, 128.0, 165.0, 134.0, 130.0, 127.0, 127.0, 155.0, 131.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 79.0, 112.0, 78.0, 78.0, 77.0, 79.0, 91.0, 76.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 108.0, 85.0, 85.0, 84.0, 103.0, 85.0, 83.0, 107.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 117.0, "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, 147.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 64.0, 71.0, 65.0, 64.0, 64.0, 65.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 65.0, 65.0, 65.0, 64.0, 70.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 94.0, 85.0, 85.0, 93.0, 85.0, 85.0, 91.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 142.0, 146.0, 142.0, 140.0, 145.0, 143.0, 150.0, 196.0, 155.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 181.0, 180.0, 184.0, 180.0, 180.0, 190.0, 180.0, 181.0, 181.0], "value": 182.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 69.0, 69.0, 69.0, 70.0, 70.0, 70.0, 68.0, 72.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1566.2, "alertThreshold": 2.0}]} 19:30:42 INFO - Return code: 0 19:30:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:30:42 INFO - # TBPL SUCCESS # 19:30:42 INFO - Running post-action listener: _resource_record_post_action 19:30:42 INFO - [mozharness: 2016-11-28 03:30:42.068713Z] Finished run-tests step (success) 19:30:42 INFO - Running post-run listener: _resource_record_post_run 19:30:42 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 8192; Write bytes: 388943872; Read time: 196; Write time: 479328 19:30:42 INFO - TinderboxPrint: CPU usage
13.1% 19:30:42 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 196 19:30:42 INFO - TinderboxPrint: I/O write bytes / time
388,943,872 / 479,328 19:30:42 INFO - TinderboxPrint: CPU idle
8,359.3 (86.9%) 19:30:42 INFO - TinderboxPrint: CPU user
1,210.2 (12.6%) 19:30:42 INFO - TinderboxPrint: Swap in / out
0 / 0 19:30:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99577856; Read time: 0; Write time: 144396 19:30:42 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289366016; Read time: 192; Write time: 334932 19:30:42 INFO - Running post-run listener: _upload_blobber_files 19:30:42 INFO - Blob upload gear active. 19:30:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:30:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:30:42 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:30:42 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:30:43 INFO - (blobuploader) - INFO - Open directory for files ... 19:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:30:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:43 INFO - (blobuploader) - INFO - Done attempting. 19:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:30:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:50 INFO - (blobuploader) - INFO - Done attempting. 19:30:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:30:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:30:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:51 INFO - (blobuploader) - INFO - Done attempting. 19:30:51 INFO - (blobuploader) - INFO - Iteration through files over. 19:30:51 INFO - Return code: 0 19:30:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:30:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:30:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e86394ae5c7dcf0c85d82750adf5ef8fea9cffe34c7cf92eb26a2a26f8737d89582634bd43d1115c0e4a3e58d957a80f8bb1746cac972dce752ac65669a3e8a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/946374927269eca85f127d36767880e501a83fcc372efaf6728b24cfb1c9191974e5c326386fc2088961daf5609fec6bf04a861145d8b2d4f0f57a93deb96d6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3ca080f4aedad84ad2a7a48ac2c48684de3a94b142154f395783d826795a10c8e79c6227b0d461153be85b5a7c638ab8050a32aaa097fb3d6e14712627688bef"} 19:30:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:30:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:30:51 INFO - Contents: 19:30:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e86394ae5c7dcf0c85d82750adf5ef8fea9cffe34c7cf92eb26a2a26f8737d89582634bd43d1115c0e4a3e58d957a80f8bb1746cac972dce752ac65669a3e8a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/946374927269eca85f127d36767880e501a83fcc372efaf6728b24cfb1c9191974e5c326386fc2088961daf5609fec6bf04a861145d8b2d4f0f57a93deb96d6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3ca080f4aedad84ad2a7a48ac2c48684de3a94b142154f395783d826795a10c8e79c6227b0d461153be85b5a7c638ab8050a32aaa097fb3d6e14712627688bef"} 19:30:51 INFO - Running post-run listener: copy_logs_to_upload_dir 19:30:51 INFO - Copying logs to upload dir... 19:30:51 INFO - mkdir: /builds/slave/test/build/upload/logs 19:30:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.198874 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-11-27 19:30:50.964368) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 19:30:50.968786) ========= 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=8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e86394ae5c7dcf0c85d82750adf5ef8fea9cffe34c7cf92eb26a2a26f8737d89582634bd43d1115c0e4a3e58d957a80f8bb1746cac972dce752ac65669a3e8a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/946374927269eca85f127d36767880e501a83fcc372efaf6728b24cfb1c9191974e5c326386fc2088961daf5609fec6bf04a861145d8b2d4f0f57a93deb96d6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3ca080f4aedad84ad2a7a48ac2c48684de3a94b142154f395783d826795a10c8e79c6227b0d461153be85b5a7c638ab8050a32aaa097fb3d6e14712627688bef"} build_url:https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011305 build_url: 'https://queue.taskcluster.net/v1/task/Jj8kixPHSAKYlKFErR9N0w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e86394ae5c7dcf0c85d82750adf5ef8fea9cffe34c7cf92eb26a2a26f8737d89582634bd43d1115c0e4a3e58d957a80f8bb1746cac972dce752ac65669a3e8a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/946374927269eca85f127d36767880e501a83fcc372efaf6728b24cfb1c9191974e5c326386fc2088961daf5609fec6bf04a861145d8b2d4f0f57a93deb96d6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3ca080f4aedad84ad2a7a48ac2c48684de3a94b142154f395783d826795a10c8e79c6227b0d461153be85b5a7c638ab8050a32aaa097fb3d6e14712627688bef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 19:30:50.998512) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 19:30:50.998849) ========= 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=8783db0840cc980e80bb0bd400000236-1480301828.592776-981095914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 19:30:51.083800) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 19:30:51.084123) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 19:30:51.084411) ========= ========= Total master_lag: 0.18 =========