builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1479341894.96 results: success (0) buildid: 20161116150023 builduid: 1c49fd6fcdb14dab90bd0eb11ad44e8e revision: 044f8226032fd9084d5fe617ac3b0b0ec6ac783d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:14.955881) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:14.956459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:14.956919) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:14.986505) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:14.986816) ========= 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=920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-16 16:18:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.1M=0s 2016-11-16 16:18:15 (38.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101415 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.102087) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.102404) ========= 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=920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014720 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.151742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.152097) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 044f8226032fd9084d5fe617ac3b0b0ec6ac783d --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 044f8226032fd9084d5fe617ac3b0b0ec6ac783d --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=920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-16 16:18:15,221 truncating revision to first 12 chars 2016-11-16 16:18:15,221 Setting DEBUG logging. 2016-11-16 16:18:15,221 attempt 1/10 2016-11-16 16:18:15,221 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/044f8226032f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 16:18:15,560 unpacking tar archive at: graphics-044f8226032f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.689092 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.854457) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.854762) ========= script_repo_revision: 044f8226032fd9084d5fe617ac3b0b0ec6ac783d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.855170) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.855466) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 16:18:15.865902) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-11-16 16:18:15.866253) ========= /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=920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:18:16 INFO - MultiFileLogger online at 20161116 16:18:16 in /builds/slave/test 16:18:16 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 16:18:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:18:16 INFO - {'append_to_log': False, 16:18:16 INFO - 'base_work_dir': '/builds/slave/test', 16:18:16 INFO - 'blob_upload_branch': 'Graphics', 16:18:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:18:16 INFO - 'branch': 'Graphics', 16:18:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:18:16 INFO - 'config_files': ('talos/linux_config.py',), 16:18:16 INFO - 'default_actions': ('clobber', 16:18:16 INFO - 'read-buildbot-config', 16:18:16 INFO - 'download-and-extract', 16:18:16 INFO - 'populate-webroot', 16:18:16 INFO - 'create-virtualenv', 16:18:16 INFO - 'install', 16:18:16 INFO - 'run-tests'), 16:18:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:18:16 INFO - 'download_minidump_stackwalk': True, 16:18:16 INFO - 'download_symbols': 'ondemand', 16:18:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:18:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:18:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:18:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:18:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:18:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:18:16 INFO - 'installer_path': 'installer.exe', 16:18:16 INFO - 'log_level': 'info', 16:18:16 INFO - 'log_name': 'talos', 16:18:16 INFO - 'log_to_console': True, 16:18:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:18:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:18:16 INFO - 'opt_config_files': (), 16:18:16 INFO - 'pip_index': False, 16:18:16 INFO - 'sps_profile': False, 16:18:16 INFO - 'sps_profile_interval': 0, 16:18:16 INFO - 'suite': 'dromaeojs', 16:18:16 INFO - 'system_bits': '32', 16:18:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:18:16 INFO - 'title': 'talos-linux64-ix-053', 16:18:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:18:16 INFO - 'use_talos_json': True, 16:18:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:18:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:18:16 INFO - 'work_dir': 'build'} 16:18:16 INFO - [mozharness: 2016-11-17 00:18:16.031407Z] Running clobber step. 16:18:16 INFO - Running pre-action listener: _resource_record_pre_action 16:18:16 INFO - Running main action method: clobber 16:18:16 INFO - rmtree: /builds/slave/test/build 16:18:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:18:16 INFO - Running post-action listener: _resource_record_post_action 16:18:16 INFO - [mozharness: 2016-11-17 00:18:16.476569Z] Finished clobber step (success) 16:18:16 INFO - [mozharness: 2016-11-17 00:18:16.476667Z] Running read-buildbot-config step. 16:18:16 INFO - Running pre-action listener: _resource_record_pre_action 16:18:16 INFO - Running main action method: read_buildbot_config 16:18:16 INFO - Using buildbot properties: 16:18:16 INFO - { 16:18:16 INFO - "project": "", 16:18:16 INFO - "product": "firefox", 16:18:16 INFO - "script_repo_revision": "default", 16:18:16 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 16:18:16 INFO - "repository": "", 16:18:16 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 16:18:16 INFO - "buildid": "20161116150023", 16:18:16 INFO - "pgo_build": "False", 16:18:16 INFO - "basedir": "/builds/slave/test", 16:18:16 INFO - "buildnumber": 2, 16:18:16 INFO - "slavename": "talos-linux64-ix-053", 16:18:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:18:16 INFO - "platform": "ubuntu64_hw", 16:18:16 INFO - "scheduler": "tests-graphics-linux64-talos", 16:18:16 INFO - "branch": "graphics", 16:18:16 INFO - "repo_path": "projects/graphics", 16:18:16 INFO - "revision": "044f8226032fd9084d5fe617ac3b0b0ec6ac783d", 16:18:16 INFO - "stage_platform": "linux64", 16:18:16 INFO - "builduid": "1c49fd6fcdb14dab90bd0eb11ad44e8e", 16:18:16 INFO - "slavebuilddir": "test" 16:18:16 INFO - } 16:18:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 16:18:16 INFO - Running post-action listener: _resource_record_post_action 16:18:16 INFO - [mozharness: 2016-11-17 00:18:16.479076Z] Finished read-buildbot-config step (success) 16:18:16 INFO - [mozharness: 2016-11-17 00:18:16.479163Z] Running download-and-extract step. 16:18:16 INFO - Running pre-action listener: _resource_record_pre_action 16:18:16 INFO - Running main action method: download_and_extract 16:18:16 INFO - mkdir: /builds/slave/test/build/tests 16:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:16 INFO - trying https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 16:18:16 INFO - Downloading https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/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 16:18:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/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 16:18:17 INFO - Downloaded 1361 bytes. 16:18:17 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 16:18:17 INFO - Using the following test package requirements: 16:18:17 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 16:18:17 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:17 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:18:17 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:17 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:18:17 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:17 INFO - u'jsshell-linux-x86_64.zip'], 16:18:17 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:17 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:18:17 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 16:18:17 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:17 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:18:17 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:17 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:18:17 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:17 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:18:17 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:17 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:18:17 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:18:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 16:18:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:18:17 INFO - Fetch https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:18:17 INFO - Http code: 200 16:18:17 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 16:18:17 INFO - x-amz-cf-id: rWLpw3r1-QsAOF9BTTJlMizDF30rqf9xT3kSOU4-koMmAiRZb9lbkA== 16:18:17 INFO - x-amz-version-id: RGTLBZJCISRP9fJneoA_oFvVaw1FPDtN 16:18:17 INFO - Expected file size: 20353917 16:18:17 INFO - Obtained file size: 20353917 16:18:21 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 16:18:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 16:18:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:18:21 INFO - Fetch https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:18:22 INFO - Http code: 200 16:18:22 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 16:18:22 INFO - x-amz-cf-id: so2DayBmplxCTDraIL7BoEyiaNer4rTmpWaGGGvrQp2smX4wlN2wbA== 16:18:22 INFO - x-amz-version-id: RGTLBZJCISRP9fJneoA_oFvVaw1FPDtN 16:18:22 INFO - Expected file size: 20353917 16:18:22 INFO - Obtained file size: 20353917 16:18:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 16:18:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:18:27 INFO - Fetch https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 16:18:27 INFO - Http code: 200 16:18:27 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 16:18:27 INFO - x-amz-cf-id: YKkF0bu3aPmL4NCsPOmtGk18qcgcnl76IVZ6srruEQ8Frj4pt8megg== 16:18:27 INFO - x-amz-version-id: UJlF7NvrZNqXqeVBEJzRhXg9yqi.hOI4 16:18:27 INFO - Expected file size: 14409366 16:18:27 INFO - Obtained file size: 14409366 16:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:28 INFO - trying https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:18:28 INFO - Downloading https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/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 16:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/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 16:18:29 INFO - Downloaded 55754889 bytes. 16:18:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:18:29 INFO - mkdir: /builds/slave/test/properties 16:18:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:29 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:29 INFO - Contents: 16:18:29 INFO - build_url:https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:18:29 INFO - Running post-action listener: _resource_record_post_action 16:18:29 INFO - Running post-action listener: set_extra_try_arguments 16:18:29 INFO - [mozharness: 2016-11-17 00:18:29.851097Z] Finished download-and-extract step (success) 16:18:29 INFO - [mozharness: 2016-11-17 00:18:29.851359Z] Running populate-webroot step. 16:18:29 INFO - Running pre-action listener: _resource_record_pre_action 16:18:29 INFO - Running main action method: populate_webroot 16:18:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:18:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:18:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:18:29 INFO - '--tppagecycles', 16:18:29 INFO - '7'], 16:18:29 INFO - 'tests': ['tsvgm']}}, 16:18:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:18:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:18:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:18:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:18:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:18:29 INFO - 'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:18:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:18:29 INFO - 'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['damp', 'tps']}, 16:18:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:18:29 INFO - 'tests': ['damp', 'tps']}, 16:18:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['dromaeo_dom']}, 16:18:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:18:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['basic_compositor_video']}, 16:18:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:18:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['a11yr', 16:18:29 INFO - 'ts_paint', 16:18:29 INFO - 'tpaint', 16:18:29 INFO - 'sessionrestore', 16:18:29 INFO - 'sessionrestore_no_auto_restore', 16:18:29 INFO - 'tabpaint']}, 16:18:29 INFO - 'other-e10s': {'tests': ['a11yr', 16:18:29 INFO - 'ts_paint', 16:18:29 INFO - 'tpaint', 16:18:29 INFO - 'sessionrestore', 16:18:29 INFO - 'sessionrestore_no_auto_restore', 16:18:29 INFO - 'tabpaint']}, 16:18:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:18:29 INFO - 'ts_paint', 16:18:29 INFO - 'tpaint', 16:18:29 INFO - 'sessionrestore', 16:18:29 INFO - 'sessionrestore_no_auto_restore', 16:18:29 INFO - 'tabpaint']}, 16:18:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:18:29 INFO - 'ts_paint', 16:18:29 INFO - 'tpaint', 16:18:29 INFO - 'sessionrestore', 16:18:29 INFO - 'sessionrestore_no_auto_restore', 16:18:29 INFO - 'tabpaint']}, 16:18:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['a11yr', 16:18:29 INFO - 'ts_paint', 16:18:29 INFO - 'tpaint', 16:18:29 INFO - 'sessionrestore', 16:18:29 INFO - 'sessionrestore_no_auto_restore', 16:18:29 INFO - 'tabpaint']}, 16:18:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['a11yr', 16:18:29 INFO - 'ts_paint', 16:18:29 INFO - 'tpaint', 16:18:29 INFO - 'sessionrestore', 16:18:29 INFO - 'sessionrestore_no_auto_restore', 16:18:29 INFO - 'tabpaint']}, 16:18:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['tsvgx', 16:18:29 INFO - 'tsvgr_opacity', 16:18:29 INFO - 'tart', 16:18:29 INFO - 'tscrollx', 16:18:29 INFO - 'cart']}, 16:18:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:18:29 INFO - 'tsvgr_opacity', 16:18:29 INFO - 'tart', 16:18:29 INFO - 'tscrollx', 16:18:29 INFO - 'cart']}, 16:18:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:18:29 INFO - 'talos_options': ['--disable-e10s'], 16:18:29 INFO - 'tests': ['tp5o']}, 16:18:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:18:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:18:29 INFO - 'talos_options': ['--disable-e10s', 16:18:29 INFO - '--xperf_path', 16:18:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:29 INFO - 'tests': ['tp5n']}, 16:18:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:18:29 INFO - 'talos_options': ['--xperf_path', 16:18:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:29 INFO - 'tests': ['tp5n']}}, 16:18:29 INFO - 'talos.zip': {'path': '', 16:18:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:18:29 INFO - Running post-action listener: _resource_record_post_action 16:18:29 INFO - [mozharness: 2016-11-17 00:18:29.871692Z] Finished populate-webroot step (success) 16:18:29 INFO - [mozharness: 2016-11-17 00:18:29.871780Z] Running create-virtualenv step. 16:18:29 INFO - Running pre-action listener: _resource_record_pre_action 16:18:29 INFO - Running main action method: create_virtualenv 16:18:29 INFO - Creating virtualenv /builds/slave/test/build/venv 16:18:29 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 16:18:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:18:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:18:30 INFO - Using real prefix '/usr' 16:18:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:18:30 INFO - Installing distribute.............................................................................................................................................................................................done. 16:18:32 INFO - Installing pip.................done. 16:18:32 INFO - Return code: 0 16:18:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:18:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:18:32 INFO - Reading from file tmpfile_stdout 16:18:32 INFO - Output received: 16:18:32 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) 16:18:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:32 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 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:32 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 16:18:32 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 16:18:32 INFO - Using env: {'DISPLAY': ':0', 16:18:32 INFO - 'HOME': '/home/cltbld', 16:18:32 INFO - 'LANG': 'en_US.UTF-8', 16:18:32 INFO - 'LANGUAGE': 'en_US:en', 16:18:32 INFO - 'LOGNAME': 'cltbld', 16:18:32 INFO - 'MAIL': '/var/mail/cltbld', 16:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:32 INFO - 'MOZ_NO_REMOTE': '1', 16:18:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:32 INFO - 'NO_EM_RESTART': '1', 16:18:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:32 INFO - 'PWD': '/builds/slave/test', 16:18:32 INFO - 'SHELL': '/bin/bash', 16:18:32 INFO - 'SHLVL': '1', 16:18:32 INFO - 'TERM': 'linux', 16:18:32 INFO - 'TMOUT': '86400', 16:18:32 INFO - 'USER': 'cltbld', 16:18:32 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:32 INFO - '_': '/tools/buildbot/bin/python'} 16:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:32 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 16:18:32 INFO - Cleaning up... 16:18:32 INFO - Return code: 0 16:18:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:32 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 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:32 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 16:18:32 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 16:18:32 INFO - Using env: {'DISPLAY': ':0', 16:18:32 INFO - 'HOME': '/home/cltbld', 16:18:32 INFO - 'LANG': 'en_US.UTF-8', 16:18:32 INFO - 'LANGUAGE': 'en_US:en', 16:18:32 INFO - 'LOGNAME': 'cltbld', 16:18:32 INFO - 'MAIL': '/var/mail/cltbld', 16:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:32 INFO - 'MOZ_NO_REMOTE': '1', 16:18:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:32 INFO - 'NO_EM_RESTART': '1', 16:18:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:32 INFO - 'PWD': '/builds/slave/test', 16:18:32 INFO - 'SHELL': '/bin/bash', 16:18:32 INFO - 'SHLVL': '1', 16:18:32 INFO - 'TERM': 'linux', 16:18:32 INFO - 'TMOUT': '86400', 16:18:32 INFO - 'USER': 'cltbld', 16:18:32 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:32 INFO - '_': '/tools/buildbot/bin/python'} 16:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:33 INFO - Downloading/unpacking psutil>=3.1.1 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 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 16:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:18:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:35 INFO - Installing collected packages: psutil 16:18:35 INFO - Running setup.py install for psutil 16:18:35 INFO - building 'psutil._psutil_linux' extension 16:18:35 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 16:18:35 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 16:18:35 INFO - building 'psutil._psutil_posix' extension 16:18:35 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 16:18:35 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 16:18:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:35 INFO - Successfully installed psutil 16:18:35 INFO - Cleaning up... 16:18:35 INFO - Return code: 0 16:18:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:18:35 INFO - Using env: {'DISPLAY': ':0', 16:18:35 INFO - 'HOME': '/home/cltbld', 16:18:35 INFO - 'LANG': 'en_US.UTF-8', 16:18:35 INFO - 'LANGUAGE': 'en_US:en', 16:18:35 INFO - 'LOGNAME': 'cltbld', 16:18:35 INFO - 'MAIL': '/var/mail/cltbld', 16:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:35 INFO - 'MOZ_NO_REMOTE': '1', 16:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:35 INFO - 'NO_EM_RESTART': '1', 16:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:35 INFO - 'PWD': '/builds/slave/test', 16:18:35 INFO - 'SHELL': '/bin/bash', 16:18:35 INFO - 'SHLVL': '1', 16:18:35 INFO - 'TERM': 'linux', 16:18:35 INFO - 'TMOUT': '86400', 16:18:35 INFO - 'USER': 'cltbld', 16:18:35 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:35 INFO - '_': '/tools/buildbot/bin/python'} 16:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:18:35 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 16:18:35 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 16:18:35 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 16:18:35 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 16:18:35 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 16:18:35 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 16:18:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:18:37 INFO - Installing collected packages: mozsystemmonitor 16:18:37 INFO - Running setup.py install for mozsystemmonitor 16:18:37 INFO - Successfully installed mozsystemmonitor 16:18:37 INFO - Cleaning up... 16:18:38 INFO - Return code: 0 16:18:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:38 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 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:38 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 16:18:38 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 16:18:38 INFO - Using env: {'DISPLAY': ':0', 16:18:38 INFO - 'HOME': '/home/cltbld', 16:18:38 INFO - 'LANG': 'en_US.UTF-8', 16:18:38 INFO - 'LANGUAGE': 'en_US:en', 16:18:38 INFO - 'LOGNAME': 'cltbld', 16:18:38 INFO - 'MAIL': '/var/mail/cltbld', 16:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:38 INFO - 'MOZ_NO_REMOTE': '1', 16:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:38 INFO - 'NO_EM_RESTART': '1', 16:18:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:38 INFO - 'PWD': '/builds/slave/test', 16:18:38 INFO - 'SHELL': '/bin/bash', 16:18:38 INFO - 'SHLVL': '1', 16:18:38 INFO - 'TERM': 'linux', 16:18:38 INFO - 'TMOUT': '86400', 16:18:38 INFO - 'USER': 'cltbld', 16:18:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:38 INFO - '_': '/tools/buildbot/bin/python'} 16:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:38 INFO - Downloading/unpacking jsonschema==2.5.1 16:18:38 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 16:18:38 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 16:18:38 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 16:18:38 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 16:18:38 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 16:18:38 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 16:18:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:40 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 16:18:40 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 16:18:40 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 16:18:40 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 16:18:40 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 16:18:40 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 16:18:40 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:40 INFO - warning: no files found matching '*.txt' 16:18:40 INFO - no previously-included directories found matching 'build' 16:18:40 INFO - no previously-included directories found matching 'dist' 16:18:40 INFO - no previously-included directories found matching '.git*' 16:18:40 INFO - Installing collected packages: jsonschema, functools32 16:18:40 INFO - Running setup.py install for functools32 16:18:40 INFO - warning: no files found matching '*.txt' 16:18:40 INFO - no previously-included directories found matching 'build' 16:18:40 INFO - no previously-included directories found matching 'dist' 16:18:40 INFO - no previously-included directories found matching '.git*' 16:18:40 INFO - Successfully installed jsonschema functools32 16:18:40 INFO - Cleaning up... 16:18:40 INFO - Return code: 0 16:18:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:18:40 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')]} 16:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:40 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')]} 16:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:40 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 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:40 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 16:18:40 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 16:18:40 INFO - Using env: {'DISPLAY': ':0', 16:18:40 INFO - 'HOME': '/home/cltbld', 16:18:40 INFO - 'LANG': 'en_US.UTF-8', 16:18:40 INFO - 'LANGUAGE': 'en_US:en', 16:18:40 INFO - 'LOGNAME': 'cltbld', 16:18:40 INFO - 'MAIL': '/var/mail/cltbld', 16:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:40 INFO - 'MOZ_NO_REMOTE': '1', 16:18:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:40 INFO - 'NO_EM_RESTART': '1', 16:18:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:40 INFO - 'PWD': '/builds/slave/test', 16:18:40 INFO - 'SHELL': '/bin/bash', 16:18:40 INFO - 'SHLVL': '1', 16:18:40 INFO - 'TERM': 'linux', 16:18:40 INFO - 'TMOUT': '86400', 16:18:40 INFO - 'USER': 'cltbld', 16:18:40 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:40 INFO - '_': '/tools/buildbot/bin/python'} 16:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:18:40 INFO - Cleaning up... 16:18:40 INFO - Return code: 0 16:18:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18:40 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')]} 16:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:40 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')]} 16:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:40 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 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:40 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 16:18:40 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 16:18:40 INFO - Using env: {'DISPLAY': ':0', 16:18:40 INFO - 'HOME': '/home/cltbld', 16:18:40 INFO - 'LANG': 'en_US.UTF-8', 16:18:40 INFO - 'LANGUAGE': 'en_US:en', 16:18:40 INFO - 'LOGNAME': 'cltbld', 16:18:40 INFO - 'MAIL': '/var/mail/cltbld', 16:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:40 INFO - 'MOZ_NO_REMOTE': '1', 16:18:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:40 INFO - 'NO_EM_RESTART': '1', 16:18:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:40 INFO - 'PWD': '/builds/slave/test', 16:18:40 INFO - 'SHELL': '/bin/bash', 16:18:40 INFO - 'SHLVL': '1', 16:18:40 INFO - 'TERM': 'linux', 16:18:40 INFO - 'TMOUT': '86400', 16:18:40 INFO - 'USER': 'cltbld', 16:18:40 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:40 INFO - '_': '/tools/buildbot/bin/python'} 16:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:41 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:41 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 16:18:41 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 16:18:41 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 16:18:41 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 16:18:41 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 16:18:41 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 16:18:43 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 INFO - Downloading docopt-0.6.1.tar.gz 16:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:43 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:43 INFO - Running setup.py install for blobuploader 16:18:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:43 INFO - Running setup.py install for requests 16:18:44 INFO - Running setup.py install for docopt 16:18:44 INFO - Successfully installed blobuploader requests docopt 16:18:44 INFO - Cleaning up... 16:18:44 INFO - Return code: 0 16:18:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:44 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 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:44 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 16:18:44 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 16:18:44 INFO - Using env: {'DISPLAY': ':0', 16:18:44 INFO - 'HOME': '/home/cltbld', 16:18:44 INFO - 'LANG': 'en_US.UTF-8', 16:18:44 INFO - 'LANGUAGE': 'en_US:en', 16:18:44 INFO - 'LOGNAME': 'cltbld', 16:18:44 INFO - 'MAIL': '/var/mail/cltbld', 16:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:44 INFO - 'MOZ_NO_REMOTE': '1', 16:18:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:44 INFO - 'NO_EM_RESTART': '1', 16:18:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:44 INFO - 'PWD': '/builds/slave/test', 16:18:44 INFO - 'SHELL': '/bin/bash', 16:18:44 INFO - 'SHLVL': '1', 16:18:44 INFO - 'TERM': 'linux', 16:18:44 INFO - 'TMOUT': '86400', 16:18:44 INFO - 'USER': 'cltbld', 16:18:44 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:44 INFO - '_': '/tools/buildbot/bin/python'} 16:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:44 INFO - Running setup.py (path:/tmp/pip-mL1CSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:44 INFO - Running setup.py (path:/tmp/pip-F64xiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:44 INFO - Running setup.py (path:/tmp/pip-reY3nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:44 INFO - Running setup.py (path:/tmp/pip-ZWqf77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:44 INFO - Running setup.py (path:/tmp/pip-pd5HZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:45 INFO - Running setup.py (path:/tmp/pip-hah8qS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:45 INFO - Running setup.py (path:/tmp/pip-w0E6bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:45 INFO - Running setup.py (path:/tmp/pip-bPgq1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:45 INFO - Running setup.py (path:/tmp/pip-K5lgXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:45 INFO - Running setup.py (path:/tmp/pip-IkALl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:45 INFO - Running setup.py (path:/tmp/pip-5UNdi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:45 INFO - Running setup.py (path:/tmp/pip-STaH28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:45 INFO - Running setup.py (path:/tmp/pip-6FYHkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:45 INFO - Running setup.py (path:/tmp/pip-FJJH7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:45 INFO - Running setup.py (path:/tmp/pip-qr4Emy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:45 INFO - Running setup.py (path:/tmp/pip-SDl1so-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:45 INFO - Running setup.py (path:/tmp/pip-Myqked-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:45 INFO - Running setup.py install for manifestparser 16:18:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozcrash 16:18:46 INFO - Running setup.py install for mozdebug 16:18:46 INFO - Running setup.py install for mozdevice 16:18:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozfile 16:18:46 INFO - Running setup.py install for mozhttpd 16:18:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozinfo 16:18:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozInstall 16:18:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozleak 16:18:46 INFO - Running setup.py install for mozlog 16:18:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for moznetwork 16:18:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozprocess 16:18:47 INFO - Running setup.py install for mozprofile 16:18:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozrunner 16:18:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozscreenshot 16:18:47 INFO - Running setup.py install for moztest 16:18:47 INFO - Running setup.py install for mozversion 16:18:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:47 INFO - Cleaning up... 16:18:47 INFO - Return code: 0 16:18:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:47 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 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:47 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 16:18:47 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 16:18:47 INFO - Using env: {'DISPLAY': ':0', 16:18:47 INFO - 'HOME': '/home/cltbld', 16:18:47 INFO - 'LANG': 'en_US.UTF-8', 16:18:47 INFO - 'LANGUAGE': 'en_US:en', 16:18:47 INFO - 'LOGNAME': 'cltbld', 16:18:47 INFO - 'MAIL': '/var/mail/cltbld', 16:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:47 INFO - 'MOZ_NO_REMOTE': '1', 16:18:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:47 INFO - 'NO_EM_RESTART': '1', 16:18:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:47 INFO - 'PWD': '/builds/slave/test', 16:18:47 INFO - 'SHELL': '/bin/bash', 16:18:47 INFO - 'SHLVL': '1', 16:18:47 INFO - 'TERM': 'linux', 16:18:47 INFO - 'TMOUT': '86400', 16:18:47 INFO - 'USER': 'cltbld', 16:18:47 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:47 INFO - '_': '/tools/buildbot/bin/python'} 16:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:47 INFO - Running setup.py (path:/tmp/pip-NHMiUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:47 INFO - Running setup.py (path:/tmp/pip-7PCclP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:48 INFO - Running setup.py (path:/tmp/pip-WR_n4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:48 INFO - Running setup.py (path:/tmp/pip-xS4TVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:48 INFO - Running setup.py (path:/tmp/pip-IkEaly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:48 INFO - Running setup.py (path:/tmp/pip-I4S7PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:48 INFO - Running setup.py (path:/tmp/pip-0EUAbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:48 INFO - Running setup.py (path:/tmp/pip-cnVQgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:48 INFO - Running setup.py (path:/tmp/pip-JSgs9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:48 INFO - Running setup.py (path:/tmp/pip-e0hyb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:48 INFO - Running setup.py (path:/tmp/pip-nPrPey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:48 INFO - Running setup.py (path:/tmp/pip-FK9d86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:48 INFO - Running setup.py (path:/tmp/pip-wUqulr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:48 INFO - Running setup.py (path:/tmp/pip-v30PTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:48 INFO - Running setup.py (path:/tmp/pip-UAEbSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:49 INFO - Running setup.py (path:/tmp/pip-4e2iCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:49 INFO - Running setup.py (path:/tmp/pip-uV7AlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:49 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)) 16:18:49 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)) 16:18:49 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)) 16:18:49 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)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:51 INFO - Downloading blessings-1.6.tar.gz 16:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:51 INFO - Installing collected packages: blessings 16:18:51 INFO - Running setup.py install for blessings 16:18:51 INFO - Successfully installed blessings 16:18:51 INFO - Cleaning up... 16:18:51 INFO - Return code: 0 16:18:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:51 INFO - Reading from file tmpfile_stdout 16:18:51 INFO - Current package versions: 16:18:51 INFO - argparse == 1.2.1 16:18:51 INFO - blessings == 1.6 16:18:51 INFO - blobuploader == 1.2.4 16:18:51 INFO - docopt == 0.6.1 16:18:51 INFO - functools32 == 3.2.3-2 16:18:51 INFO - jsonschema == 2.5.1 16:18:51 INFO - manifestparser == 1.1 16:18:51 INFO - mozInstall == 1.12 16:18:51 INFO - mozcrash == 1.0 16:18:51 INFO - mozdebug == 0.1 16:18:51 INFO - mozdevice == 0.48 16:18:51 INFO - mozfile == 1.2 16:18:51 INFO - mozhttpd == 0.7 16:18:51 INFO - mozinfo == 0.9 16:18:51 INFO - mozleak == 0.1 16:18:51 INFO - mozlog == 3.4 16:18:51 INFO - moznetwork == 0.27 16:18:51 INFO - mozprocess == 0.23 16:18:51 INFO - mozprofile == 0.28 16:18:51 INFO - mozrunner == 6.13 16:18:51 INFO - mozscreenshot == 0.1 16:18:51 INFO - mozsystemmonitor == 0.3 16:18:51 INFO - moztest == 0.7 16:18:51 INFO - mozversion == 1.4 16:18:51 INFO - psutil == 3.1.1 16:18:51 INFO - requests == 1.2.3 16:18:51 INFO - wsgiref == 0.1.2 16:18:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:51 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 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:51 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 16:18:51 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 16:18:51 INFO - Using env: {'DISPLAY': ':0', 16:18:51 INFO - 'HOME': '/home/cltbld', 16:18:51 INFO - 'LANG': 'en_US.UTF-8', 16:18:51 INFO - 'LANGUAGE': 'en_US:en', 16:18:51 INFO - 'LOGNAME': 'cltbld', 16:18:51 INFO - 'MAIL': '/var/mail/cltbld', 16:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:51 INFO - 'MOZ_NO_REMOTE': '1', 16:18:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:51 INFO - 'NO_EM_RESTART': '1', 16:18:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:51 INFO - 'PWD': '/builds/slave/test', 16:18:51 INFO - 'SHELL': '/bin/bash', 16:18:51 INFO - 'SHLVL': '1', 16:18:51 INFO - 'TERM': 'linux', 16:18:51 INFO - 'TMOUT': '86400', 16:18:51 INFO - 'USER': 'cltbld', 16:18:51 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:51 INFO - '_': '/tools/buildbot/bin/python'} 16:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 INFO - Cleaning up... 16:18:51 INFO - Return code: 0 16:18:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0598d50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2458b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, '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': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', '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 16:18:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:18:51 INFO - Using env: {'DISPLAY': ':0', 16:18:51 INFO - 'HOME': '/home/cltbld', 16:18:51 INFO - 'LANG': 'en_US.UTF-8', 16:18:51 INFO - 'LANGUAGE': 'en_US:en', 16:18:51 INFO - 'LOGNAME': 'cltbld', 16:18:51 INFO - 'MAIL': '/var/mail/cltbld', 16:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:51 INFO - 'MOZ_NO_REMOTE': '1', 16:18:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:51 INFO - 'NO_EM_RESTART': '1', 16:18:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:51 INFO - 'PWD': '/builds/slave/test', 16:18:51 INFO - 'SHELL': '/bin/bash', 16:18:51 INFO - 'SHLVL': '1', 16:18:51 INFO - 'TERM': 'linux', 16:18:51 INFO - 'TMOUT': '86400', 16:18:51 INFO - 'USER': 'cltbld', 16:18:51 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:51 INFO - '_': '/tools/buildbot/bin/python'} 16:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:51 INFO - Cleaning up... 16:18:52 INFO - Return code: 0 16:18:52 INFO - Running post-action listener: _resource_record_post_action 16:18:52 INFO - Running post-action listener: _start_resource_monitoring 16:18:52 INFO - Starting resource monitoring. 16:18:52 INFO - [mozharness: 2016-11-17 00:18:52.042463Z] Finished create-virtualenv step (success) 16:18:52 INFO - [mozharness: 2016-11-17 00:18:52.042967Z] Running install step. 16:18:52 INFO - Running pre-action listener: _resource_record_pre_action 16:18:52 INFO - Running main action method: install 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Output received: 16:18:52 INFO - Usage: mozinstall [options] installer 16:18:52 INFO - Options: 16:18:52 INFO - -h, --help show this help message and exit 16:18:52 INFO - -d DEST, --destination=DEST 16:18:52 INFO - Directory to install application into. [default: 16:18:52 INFO - "/builds/slave/test"] 16:18:52 INFO - --app=APP Application being installed. [default: firefox] 16:18:52 INFO - mkdir: /builds/slave/test/build/application 16:18:52 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'] 16:18:52 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 16:19:08 INFO - Reading from file tmpfile_stdout 16:19:08 INFO - Output received: 16:19:08 INFO - /builds/slave/test/build/application/firefox/firefox 16:19:08 INFO - Running post-action listener: _resource_record_post_action 16:19:08 INFO - [mozharness: 2016-11-17 00:19:08.447135Z] Finished install step (success) 16:19:08 INFO - [mozharness: 2016-11-17 00:19:08.447458Z] Running run-tests step. 16:19:08 INFO - Running pre-action listener: _resource_record_pre_action 16:19:08 INFO - Running main action method: run_tests 16:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:19:08 INFO - Python 2.7.3 16:19:08 INFO - Return code: 0 16:19:08 INFO - grabbing minidump binary from tooltool 16:19:08 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')]} 16:19:08 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 0x2682fc0>, '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 0x2683ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2684350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:19:08 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 16:19:08 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 16:19:08 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 16:19:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:19:08 INFO - Return code: 0 16:19:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:19:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:19:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:19:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:19:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:19:08 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/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 16:19:08 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/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 16:19:08 INFO - Using env: {'DISPLAY': ':0', 16:19:08 INFO - 'HOME': '/home/cltbld', 16:19:08 INFO - 'LANG': 'en_US.UTF-8', 16:19:08 INFO - 'LANGUAGE': 'en_US:en', 16:19:08 INFO - 'LOGNAME': 'cltbld', 16:19:08 INFO - 'MAIL': '/var/mail/cltbld', 16:19:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:08 INFO - 'MOZ_NO_REMOTE': '1', 16:19:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:08 INFO - 'NO_EM_RESTART': '1', 16:19:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:08 INFO - 'PWD': '/builds/slave/test', 16:19:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:08 INFO - 'SHELL': '/bin/bash', 16:19:08 INFO - 'SHLVL': '1', 16:19:08 INFO - 'TERM': 'linux', 16:19:08 INFO - 'TMOUT': '86400', 16:19:08 INFO - 'USER': 'cltbld', 16:19:08 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635', 16:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:08 INFO - '_': '/tools/buildbot/bin/python'} 16:19:08 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/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 16:19:08 INFO - mozversion application_buildid: 20161116150023 16:19:08 INFO - mozversion application_changeset: 044f8226032fd9084d5fe617ac3b0b0ec6ac783d 16:19:08 INFO - mozversion application_display_name: Nightly 16:19:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:19:08 INFO - mozversion application_name: Firefox 16:19:08 INFO - mozversion application_remotingname: firefox 16:19:08 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 16:19:08 INFO - mozversion application_vendor: Mozilla 16:19:08 INFO - mozversion application_version: 53.0a1 16:19:08 INFO - mozversion platform_buildid: 20161116150023 16:19:08 INFO - mozversion platform_changeset: 044f8226032fd9084d5fe617ac3b0b0ec6ac783d 16:19:08 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 16:19:08 INFO - mozversion platform_version: 53.0a1 16:19:08 INFO - using testdate: 1479341948 16:19:08 INFO - actual date: 1479341948 16:19:08 INFO - starting webserver on 'localhost:32835' 16:19:08 INFO - SUITE-START | Running 2 tests 16:19:08 INFO - TEST-START | dromaeo_css 16:19:08 INFO - operating with platform_type : linux_ 16:19:08 INFO - Initialising browser for dromaeo_css test... 16:19:08 INFO - TEST-INFO | started process 7234 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1VjUFd/profile http://localhost:32835/getInfo.html) 16:19:13 INFO - PROCESS | 7234 | __metrics Screen width/height:1600/1200 16:19:13 INFO - PROCESS | 7234 | colorDepth:24 16:19:13 INFO - PROCESS | 7234 | Browser inner width/height: 1024/697 16:19:13 INFO - PROCESS | 7234 | __metrics 16:19:13 INFO - PROCESS | 7234 | 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" 16:19:13 INFO - PROCESS | 7234 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:19:14 INFO - PROCESS | 7234 | console.error: PushService: 16:19:14 INFO - PROCESS | 7234 | stateChangeProcessEnqueue: Error transitioning state 16:19:14 INFO - PROCESS | 7234 | UnknownError 16:19:15 INFO - TEST-INFO | 7234: exit 0 16:19:15 INFO - Browser initialized. 16:19:15 INFO - Running cycle 1/1 for dromaeo_css test... 16:19:15 INFO - TEST-INFO | started process 7317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1VjUFd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:27 INFO - PROCESS | 7317 | RSS: Main: 174788608 16:19:27 INFO - PROCESS | 7317 | 16:20:19 INFO - PROCESS | 7317 | 1479342019226 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] 16:22:33 INFO - PROCESS | 7317 | Cycle 1(1): loaded http://localhost:32835/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32835/tests/dromaeo/cssquery-ext.html) 16:22:34 INFO - PROCESS | 7317 | RSS: Main: 290263040 16:22:34 INFO - PROCESS | 7317 | 16:25:37 INFO - PROCESS | 7317 | Cycle 1(1): loaded http://localhost:32835/tests/dromaeo/cssquery-ext.html (next: http://localhost:32835/tests/dromaeo/cssquery-jquery.html) 16:25:37 INFO - PROCESS | 7317 | RSS: Main: 284340224 16:25:37 INFO - PROCESS | 7317 | 16:28:40 INFO - PROCESS | 7317 | Cycle 1(1): loaded http://localhost:32835/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32835/tests/dromaeo/cssquery-mootools.html) 16:28:40 INFO - PROCESS | 7317 | RSS: Main: 221569024 16:28:40 INFO - PROCESS | 7317 | 16:31:43 INFO - PROCESS | 7317 | Cycle 1(1): loaded http://localhost:32835/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32835/tests/dromaeo/cssquery-prototype.html) 16:31:43 INFO - PROCESS | 7317 | RSS: Main: 219152384 16:31:43 INFO - PROCESS | 7317 | 16:34:47 INFO - PROCESS | 7317 | Cycle 1(1): loaded http://localhost:32835/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32835/tests/dromaeo/cssquery-yui.html) 16:34:47 INFO - PROCESS | 7317 | RSS: Main: 224063488 16:34:47 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | Cycle 1(1): loaded http://localhost:32835/tests/dromaeo/cssquery-yui.html (next: http://localhost:32835/tests/dromaeo/cssquery-dojo.html) 16:37:50 INFO - PROCESS | 7317 | RSS: Main: 316596224 16:37:50 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | __start_tp_report 16:37:50 INFO - PROCESS | 7317 | _x_x_mozilla_page_load 16:37:50 INFO - PROCESS | 7317 | _x_x_mozilla_page_load_details 16:37:50 INFO - PROCESS | 7317 | |i|pagename|runs| 16:37:50 INFO - PROCESS | 7317 | |0;dojo.html;2564.3973666188444;2586.974130258699;2607.556715358389;2614.6862376514823;2625.317417471457;4619.584237418631;4664.533546645338;4675.579197872191;4689.226277664181;4693.577578017977;4689.671722979393;4698.483166851644;4703.576678098971;4708.340832283475;4718.127146477896;4297.03316753731;4315.741055536662;4328.97835510822;4352.673549483786;4369.650427965771;4343.305071188611;4373.584509471595;4386.298192289234;4409.426774519308;4473.552644735533;4380.299152135659;4408.316710909814;4447.533009034053;4447.911041779163;4475.395821564096;3757.304898593769;4355.063661312801;4382.430284063052;4394.033312671208;4448.065906159694;4340.544242854518;4346.869593912188;4374.78126093694;4393;4407.2507673695545;4527.139843429738;4556.8860778480475;4561.475430325489;4644.233701439259;4723.338732577442;5009.524095210985;5013.373328333958;5013.423456302536;5037.974810125963;5135.075686376451;5057.064443078006;5068;5102.489751024905;5107.872303192413;5144.5369916707305;4559.270516717375;4591.966807468293;4744;4752.596029337545;4791.6406269529925;5087;5129.461406552333;5150.356205474316;5166.61250406221;5211.843644690665;4920.975395123001;4958;4967.950320496749;4969.950300497021;5011.699298042129;4928.8767780805765;5051.393832740095;5063.0380227756605;5070.112730272188;5106.846794596168;4929.531694489017;4932.975335123301;4939.580135688435;4954.529319714621;4956.826511072095;4960.553550180501;4991.126552853236;5046.520580544916;5119.897602047939;5122.718250496258;4287.335463003239;4287.764172970454;4323.78381080951;4328;4332.610065094157;3755.211405604799;3774.452704357853;3788.0719223790347;3812.066043819282;3845.3270677631863;4218.156368726206;4273.700840941105;4276.080642661843;4332.0469496710675;4368.453943257093;5058.595312375001;5100.744962751847;5113.258577506241;5119.974400127976;5195.428502864682;4393.692441529062;4399.868003959887;4399.956000440018;4404.493483249467;4438.134563760101;4464.308032255006;4599.563041511051;4604.907901842012;4612.031473390559;4654.930176047362;4706.505816889177;4717.481077081587;4742.217534106834;4762.666613337033;4777.856664299938;5025.597952163891;5025.899482010266;5027.195648696191;5030.044291584587;5062.746862656926;4735.052989402065;4747.833825816218;4748.857534273978;4756.619470442425;4791.856244312677;4855.927161092516;4913.164761990542;4969.229769385751;5001.099802035523;5003.524665156804;5128.820491282787;5133.178691409356;5134.871628209325;5171.92242116361;5185.481451854936;4929.926051109308;4962.858542535165;4969.652124351261;4973.229149481836;5017.623678224075;4792.0895029944195;4792.353032340589;4877.585405240594;4972.453030166751;4983.476734942778;4715.269133284346;4736.502667219892;4738.763061846963;4783.210770222874;4786.042791441795;4911.9754401227765;4923.852284431473;4927.802887884444;4932.334134891744;4970.900581988412;4746.216874215854;4761;4793.113274044313;4819.493953135011;4828.203346447937 16:37:50 INFO - PROCESS | 7317 | |1;ext.html;554.2379228560728;569.1263909898307;577.4514211499074;577.6303165973777;580.9970950145254;27650.44699106019;30707.38585228294;40678.18643627126;42860.571394286126;42982.35526467105;38936.61063389365;38981.22037559247;39275;39279.41080883782;39615.60384396155;7582.469227154102;7723.38212942966;7732.845343093135;7733.45865789395;7735.961320193391;7594.5063570868;7684.731034413797;7692.346150577179;7697.191794861543;7697.3457256133015;7544.811379715524;7616.695332186706;7627.504212226216;7640.694372225104;7658;7578.242175782405;7613.0483689538805;7615.46691731579;7624.61876906158;7627.8855817162785;7542.094948606147;7605.733799317053;7619.161892191854;7623.961880190619;7630.198829122917;37734;37901.241975160345;37901.43147852785;37993.24013519742;38072;3022.863971121305;3036.9392612147853;3041.756659467237;3059.128148477673;3063.3720087382035;27495;27762.861185694142;27790.305242369104;28138.859305703547;28224.717752822413;3421.6920477157178;3468.6704763047724;3479.9826000869834;3488.459288810213;3604.0269127335473;178931;179515.10242448704;179578.10210948993;179598;180067;50194.980501950246;50500.949905009205;50587.49412505901;50604;50646.49353506492;31720;33050.504242436386;33149.66850331514;35275;35302.76293359855;15079.321430535652;15255;15267.389304427921;15269;15274.389024438899;10842.566297348176;10880.12958963281;10883.292585981886;10884.346939183675;10888.510017049251;17372.739408908878;17785.911070444825;17851.196696148705;17870.106494675216;17980.943239649292;6701.832454188587;6776.390124888685;6851.725930962805;6951.131108611424;6953.513254072268;775.6238224461132;867.1155421470065;886.8625363068735;955.1833182628891;960.3037797596826;25938.610920836203;26923.461530769277;27075.458490830075;27234.182974510797;27410.5888411674;800.2997377294867;875.7898104454885;934.9579268932779;939.2767568971854;960.0543464687278;12644.873551264369;13248.27134507592;13258.337083145998;13407;13438.731225375437;370311;372711;372980;373777;374537;47062.5293747065;47440.762796186486;47510.287345689845;47694.761526192146;47899.5210047902;1076.4294923690456;1127.4081107418672;1165.463886612168;1170.087331881134;1185.2236784905901;24902.750972490045;28089;28173;28483.430331392843;28816;39652.2069558613;40206.798966004986;40220;40256.59743402529;40365.19269614533;287530;288035;288407;288867;289067;23899.761002389754;24071.879640601685;24621.291657917973;24979.125730599342;25697.614535781606;39821;39874.800625996686;39987.80006099951;40256.59743402529;40535.797321013204;33814;34290.657093428745;34344.48483272703;34514.482282765275;34856.302873942885;33570;33586;34022.48966265558;34249.65750342465;34753.82623086868;33236.1690957728;33367.16582085467;33405;33685.83157084199;33794.66205337915;30359.241018974702;31017.379652406373;31446.52830207503;31641.8417907909;31648.683513164575;23760.52478950446;23929.564226146485;24148.275551733477;24224.152154674503;24295.149669761475 16:37:50 INFO - PROCESS | 7317 | |2;jquery.html;336.79792124725185;348.4807636621435;355.1653614007083;361.4704457969075;379.394865190022;12434.875651243485;13375;13459.057865949388;16687.83312166878;16710.916445417788;15681.137537435436;15862.286197121128;16660.16699165043;16840.494785156465;16845.747313790303;4898.265260210979;5106.463821298766;5114.718690472023;5119.59043276537;5125.589952803767;6274.498040156799;6615.933840661604;6623.701933413008;6651.7006734696815;6755.898661520081;6281.246250449953;6639.966800166017;6664.366885145928;6761.830954226134;6772.018057381677;6826.863462730742;6860.279670634562;6863.10779598649;6867.278935711728;6875.0718652982205;6304.243490781136;6648.301928297507;6681.665916704145;6798.966005169992;6822.3859852613505;22027.559448810935;22660.320190394315;23107.768922310897;23223.651645225335;23245;2011.6379051770675;2017.8083082107175;2068.069368784053;2184.9235276765394;2592.468543948486;13931.721365572734;14011.789823152661;14190.787138192936;14251.857481425157;15211.163386013764;1763.8412542871074;1817.6364727054643;1857.5727582656066;1860.897650629215;1870.9625807483774;60346.698266508385;79123;80836;81202;81896.59051704763;15538.689226215412;16531.91734041322;16721.581960450843;16783.83216167847;16920;28068.719312807018;28129.296767580974;28211.15366539007;28263.858680706464;28359.43281134407;8919.866202006975;9298.814023719488;9356.485393303434;9665.516724163763;9760.560774765152;7758.68965241384;7789.260020298063;7799.571023593643;7972.083210430758;8044;8212.384071194685;8322.542260175627;8416;8445.239928406472;8524.360672949553;3038.384322656032;3128.9374212515622;3302.546879373116;3310.66893310667;3365.730741540671;751.9849603008018;824.1717074340249;858.356232825381;899.5097671768901;900.8468560344758;15908.284127214303;16082.276297566637;16426.11099723523;16980.235889385007;17472.301107955795;767.5663250263694;842.7303262955918;852.2798235491;861.9655213791378;873.7073080517971;8994.325425592975;9078.954605226932;9548.90451095494;9562;9628.855567166498;372339;375300;378617;391102;391548;78897.60551197322;83945;84607.57696211603;85504;86345;903.4985583001497;911.3028533172261;943.1841457139662;947.9952600236954;954.608610469718;15238.77141842851;15269.083854968738;15897;16182.757258641364;16184.190790460665;25155.496890062463;25798;34902;36985.260294793414;37717;50656.746716266185;51585.22622160595;52210.47789522056;52705.47294527159;53286.73356633192;6761.425278851156;6885.416354238453;7172.677229524579;7255.891161632474;7572.182676157792;8183.672653094048;10825.00532629118;12039.518419263131;12269.895709386197;12314.938425307817;9851.211903047883;10009.749756256151;10254.436006019441;10407.68776936693;10766.730831728955;10160.695179144637;10542.15662746963;10627.149828013895;10754.51604677807;10820.134389248999;9384.671536496464;10226.181905447398;10352;10935.125189984623;11136.888631113909;7131.322524360177;7241.782746517613;7343.1555371133;7421.220771818881;7738.264864837832;8701.47791132534;10464.750078732804;10492.842607361048;10533.57865685364;10537.315074520131 16:37:50 INFO - PROCESS | 7317 | |3;mootools.html;1195.8564972203337;1290.6192673161413;1339.0760375341015;1351.3716122003266;1602.8076630804298;5989.910151347728;6461.418472337488;6655.800325990222;6656.933430665692;6814.829629259259;6693.464522838173;6749.898751518719;6750;6752.257251702309;6753.15585551806;2021.8180363767258;2045.6089858895946;2051.7845626209255;2052.291959274048;2052.332991777671;2016.7176595276674;2067.3177851309033;2078.75054993401;2082.9479263018393;2085.7497100347905;2000.7198992141111;2026.614943160802;2041.5202427429542;2043.1725151313742;2044.9079791409422;2008.8192062714352;2032.3191730770209;2032.78655741148;2033.3290014295246;2037.1443993522755;1992.601479704065;2012.23535056679;2025.2810252360493;2027.3107143571256;2027.7161197432372;6518.28298887122;6601.669916504204;6731.49513786468;6741.426978706816;6741.696623651947;4011.7592944423136;4201.495820501559;4203.873883783492;4208.22147902639;4213.494380674308;2588.585826267788;2781.3324802047364;2820.421813528242;2836.7163283671675;2858.414025124865;852.2925971443686;868.2359523619238;871.3639043498218;874.3006340917568;880.467317273047;8468.068512463624;8585.055643879168;8598.140185981352;8661.566921653892;8692.21770040693;3457.1775908247687;3572.374834403969;3592.892213233607;3661.6704496595435;3667.789629422284;7149.249328820503;7154.177269613957;7192.856142877114;7193.460490463236;7221.638918054076;4042.8787136385954;4132.297509423405;4139.855105071308;4140;4145.17096580685;3472.5312082868995;3580.964190358115;3742.401215805458;3868.187680587052;3883.61163883615;3019.788614796943;3024.6219222597174;3148.291634382246;3154.2114471382156;3178.0624715708923;908.822779557993;920.7652048727532;921.9769505762275;927.3276874266238;931.7111695374456;691.7440546997643;759.4190444310108;762.6568044380051;771.6681826814412;813.1868131868132;4453.842001079743;4539.228331183706;4583.220852455091;4590.747508886977;4606.286025666027;378.2472878970883;379.939209726448;392.4956430986169;395.95050618672667;398.84644411901627;3179.650238473766;3236.85091792415;3253.398121347558;3298.10951043217;3322.9335413291597;7491.550506969599;8012.3590112790835;8065.153158918347;8097.514149151069;8113.6754529818145;8046.758597242092;8056.556889371023;8068.717594884269;8078.192180781874;8088.636011379384;6180.876382472416;6405.967970160213;6437.066625339299;6441.355864413615;6457.063725759738;4664.323673067454;4664.766761661971;4685.554872287127;4746.2168742157155;4756.8097276108565;7334.706611735684;7405.703771849066;7512.060992375953;7540.736074237376;7616.657250423634;7475.962620186864;7603.619819009138;7607.771766847003;7615.162332143571;7664.846703066019;5659.575531835047;5727.799527016535;5729.369769325454;5731.340895797037;5733.598648094593;6250.218722659668;6498.285188629153;6520.815526798386;6619.073329733745;6639.867202656016;5914.053751399755;5930.822075337746;5934.376890426615;5963.254593175853;6036.7283472244735;5669.829905102853;5906.734196961061;5923.970380148072;5970.164177015135;5979.671118088547;5455.699936503372;5462.0441422751655;5463.781448742165;5508.862278443071;5521.503064724194;4098.4876890388705;4129.380592911088;4168.437260969851;4172.874813755592;4274.722143060815;4049.440965228467;4067.08490589615;4075.9592404076766;4108.36320370343;4172.833086676499 16:37:50 INFO - PROCESS | 7317 | |4;prototype.html;266.48701250093563;275.078487068321;284.9017089104259;286.38955916818196;291.1948462501184;4592.196365636017;4701.7649117544115;4778.450478195009;4783.856484305467;4810.5189481051875;4693.953060469395;4714.198586240339;4730.526947305268;4730.769999800051;4751.5982785078395;3471.236328007847;3614.4036234021355;3626.963730362689;3635.2366003139373;3663.8351274192723;3615.566132064156;3624.3294990426725;3633.4913112164318;3651.251493443851;3652.5982141964496;3518.5249991251108;3605.152789094574;3624.5469316335457;3634.5275114235246;3648.106213977579;3656;3660.4559830917524;3671.3575124353265;3672.026912868092;3684.171061511165;3190.6649801770945;3194.185482701919;3196.3447493264057;3204.8077115373153;3214.6463888972194;4656.94969794785;4681.648876334254;4726.763661816895;4736.265878788793;4737.076270127326;4084.6732261419015;4162.458880345566;4175.68682348825;4177.811998460046;4194.580541945781;4984.22744474607;4985.302057711923;4986.60375094974;4987.650864439491;4995.275685025651;3486.6513348665185;3491.493733408667;3503.1242189452637;3503.4919936609303;3513.2095278562374;5056.191009438472;5256.763445644955;5280.471952804727;5292.947070529284;5309.4425085365865;3548.201654627688;3585.1395665040204;3587.8385472653795;3589.533360663097;3590.838412271454;3619.2580520993133;3627.437747149196;3627.528421305213;3635.763675361146;3642.1076836175307;3915.804209789499;4034.757914525138;4039.9596004040172;4209.452771139732;4213.810378532973;4524.393840186752;4870.439899411613;4901.926471102936;4932.284818701268;4956.950430495721;1184.3249347871804;1184.6268425446128;1197.6885310584896;1203.9036877049814;1208.6192849252457;797.0376607470948;808.6118663041773;818.5497976113234;819.397742659145;827.1893544326593;4193.993441574;4274.166537525152;4280.76455794928;4317.870463886088;4333.9878784245275;5086.593072554187;5122.180451127876;5132.717700526507;5163.225516172604;5182.663126896739;4356.215881141425;4374.825006999748;4385.254506733863;4396.802143903532;4405.867823965286;2527.6208568714474;2615.333090062022;2617.528844807915;2629.2638061342855;2653.854038027927;4608.216603177468;4622.46841613212;4630.907381852344;4649.535046495391;4653.976730116327;5084.55090299263;5113.718745469035;5117.590592752571;5134.563562097189;5158;4506.616937560344;4665.5101214372;4681.776156325786;4687.578117969331;4696.741679207608;4849.181556916156;4860.3681521401995;4919.311296418576;4937.629677774109;4954.851354459372;4825.890045289533;4845.41854977405;4886.804527818989;4893.6085113191575;4896.873719044568;4434.423524941737;4653.837115700934;4668.533146685304;4691.50739172382;4705.482396936266;3376.5610470638658;3388.8136152511847;3392.9830350848088;3394.1853955051106;3420.3672320620763;4986.32684587576;4988.403710812505;5023.744063984004;5052.898942021213;5055.5702765263895;4727.739974301447;4886.3403440536495;4921.4832442593015;4933.136701077369;4961.776720047678;4837.540433658797;4845.63657725665;4848.248521479177;4852.636052296162;4859.246816743411;4705.0123171786845;4714.976425117852;4722.811087556596;4733.810647574059;4736.19484687597;4704.223803072455;4884.291777692286;4918.852434426973;4932.435133730512;4954.900901982012;4750.762461876961;4785;4789.44921334051;4798.944232268896;4801.255021083561 16:37:50 INFO - PROCESS | 7317 | |5;yui.html;591.5031373646136;645.7868903261924;679.8062552172631;712.7932899459158;714.2000958926004;11974.82037769433;12979.935100324485;13002.349882505872;13027.934860325686;13044.804327935088;12179.086568507353;12275.263484190955;12283;12306.692332691688;12324.32216228107;616.4144063140012;633.7560039384847;742.1947187301784;744.5383862005564;766.8044648614596;761.7105499910026;765.5291995422803;767.1982777997007;767.9616019199045;768.4352001279084;800.4196957206037;801.4870482890955;802.5987006496752;803.0804728585766;803.2329125684956;638.9265234498023;652.3769799841141;774.6514068669065;797.0435477427081;797.4139007829241;586.7828903305762;587.3773799772255;587.4213899309177;587.5505238492557;587.7002728608397;11643.18497705161;11724.237924534878;11747.765044699143;11748.647540573797;11752.353620550952;11849.644510664693;11860.822087668692;11931.522739090426;12102.273863568214;12313.07651926109;9817.705468835946;10145.970805838788;10157.085862272357;10185.930477299851;10444.63443779464;3912.2762288976633;3916.353801622729;4014.959850401517;4036.777977211252;4045.1505183911117;47207;47290.29064564034;47331.290030649565;47356.763216184045;47650.76174619139;9185.219256363283;9516.23870090391;9518.143367096995;9578.664746733832;9702.95148524267;14294;14853.405863765329;16811.243494042796;16831.32674693023;16936.491905242863;6411.743530258737;6508.511861610304;6510.869782604322;6512.706928188243;6520.9347906520325;3375.5105509700957;3451.2752322012657;3510.6313837047255;3531.081918701156;3542.6634469725336;2738.3291093682174;2812.8452935088767;2824.3221626809827;2824.7316504932;2833.7874659400627;487.8438899552109;497.4329264638315;498.69579556470524;500.2246517897245;516.630609114485;491.62390771060166;569.8262030080865;597.1490625858135;603.607655024229;605.6517502436117;7330.413566914634;7344.449166312548;7546.169921308651;7595.5822429766895;7726.227377262228;517.0873408434117;592.1473078766562;607.5261296188979;619.8326451857973;623.9282482514478;3482.669146431085;4076.429299898017;4126.319157339066;4286.962392279871;4365.105153443536;65455.67272163609;68650.65674671595;69522;71111.28888711047;71817.2818271821;58777.41222587805;59577.40422595719;60942.69528652328;61167.388326117056;62153.68923155355;55621.443785562435;55840;56236;57826.42173578295;58868.4113158863;7848.791121680449;8194.38542109356;8230.300424463987;8300.377471689768;8429.157084291523;37706.62293377031;38476.615233848424;39211.6078839208;39602.60397396104;39805.60194398019;42012.159756805304;45500.31749523694;46447.30329044999;47231.763841180575;48906.266406003226;10339.483025848827;10453.790924181625;10598.31110977784;10646.20153488476;10791.56833726642;11177.88822111801;11810.940945295219;11867.881321187024;11991.122130688746;12574.233668592895;11136.33182009082;11262.943685281522;11440.713982150513;11529.481173347052;11977.700557486132;11198.944005279922;11507.654770356903;11632.651020469399;11635.185537012328;12396.690082747642;10635.83585373239;10912.61805836792;10943.343399396063;11136.944315278372;11479.36863472517;9436.764080897758;9474.336796424459;9725.027497250501;9966.422161373459;10250;8866.202041816268;9289.117533834275;9434.292428067785;9757.12185903272;9771.120599145826 16:37:50 INFO - PROCESS | 7317 | __end_tp_report 16:37:50 INFO - PROCESS | 7317 | __start_cc_report 16:37:50 INFO - PROCESS | 7317 | _x_x_mozilla_cycle_collect,1241 16:37:50 INFO - PROCESS | 7317 | __end_cc_report 16:37:50 INFO - PROCESS | 7317 | __startTimestamp1479343070945__endTimestamp 16:37:50 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | ------- Summary: start ------- 16:37:50 INFO - PROCESS | 7317 | Number of tests: 6 16:37:50 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | [#0] dojo.html Cycles:165 Average:4661.35 Median:4748.86 stddev:490.63 (10.3%) stddev-sans-first:463.74 16:37:50 INFO - PROCESS | 7317 | Values: 2564.4 2587.0 2607.6 2614.7 2625.3 4619.6 4664.5 4675.6 4689.2 4693.6 4689.7 4698.5 4703.6 4708.3 4718.1 4297.0 4315.7 4329.0 4352.7 4369.7 4343.3 4373.6 4386.3 4409.4 4473.6 4380.3 4408.3 4447.5 4447.9 4475.4 3757.3 4355.1 4382.4 4394.0 4448.1 4340.5 4346.9 4374.8 4393.0 4407.3 4527.1 4556.9 4561.5 4644.2 4723.3 5009.5 5013.4 5013.4 5038.0 5135.1 5057.1 5068.0 5102.5 5107.9 5144.5 4559.3 4592.0 4744.0 4752.6 4791.6 5087.0 5129.5 5150.4 5166.6 5211.8 4921.0 4958.0 4968.0 4970.0 5011.7 4928.9 5051.4 5063.0 5070.1 5106.8 4929.5 4933.0 4939.6 4954.5 4956.8 4960.6 4991.1 5046.5 5119.9 5122.7 4287.3 4287.8 4323.8 4328.0 4332.6 3755.2 3774.5 3788.1 3812.1 3845.3 4218.2 4273.7 4276.1 4332.0 4368.5 5058.6 5100.7 5113.3 5120.0 5195.4 4393.7 4399.9 4400.0 4404.5 4438.1 4464.3 4599.6 4604.9 4612.0 4654.9 4706.5 4717.5 4742.2 4762.7 4777.9 5025.6 5025.9 5027.2 5030.0 5062.7 4735.1 4747.8 4748.9 4756.6 4791.9 4855.9 4913.2 4969.2 5001.1 5003.5 5128.8 5133.2 5134.9 5171.9 5185.5 4929.9 4962.9 4969.7 4973.2 5017.6 4792.1 4792.4 4877.6 4972.5 4983.5 4715.3 4736.5 4738.8 4783.2 4786.0 4912.0 4923.9 4927.8 4932.3 4970.9 4746.2 4761.0 4793.1 4819.5 4828.2 16:37:50 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | [#1] ext.html Cycles:180 Average:42871.51 Median:19116.90 stddev:76755.31 (401.5%) stddev-sans-first:76904.87 16:37:50 INFO - PROCESS | 7317 | Values: 554.2 569.1 577.5 577.6 581.0 27650.4 30707.4 40678.2 42860.6 42982.4 38936.6 38981.2 39275.0 39279.4 39615.6 7582.5 7723.4 7732.8 7733.5 7736.0 7594.5 7684.7 7692.3 7697.2 7697.3 7544.8 7616.7 7627.5 7640.7 7658.0 7578.2 7613.0 7615.5 7624.6 7627.9 7542.1 7605.7 7619.2 7624.0 7630.2 37734.0 37901.2 37901.4 37993.2 38072.0 3022.9 3036.9 3041.8 3059.1 3063.4 27495.0 27762.9 27790.3 28138.9 28224.7 3421.7 3468.7 3480.0 3488.5 3604.0 178931.0 179515.1 179578.1 179598.0 180067.0 50195.0 50500.9 50587.5 50604.0 50646.5 31720.0 33050.5 33149.7 35275.0 35302.8 15079.3 15255.0 15267.4 15269.0 15274.4 10842.6 10880.1 10883.3 10884.3 10888.5 17372.7 17785.9 17851.2 17870.1 17980.9 6701.8 6776.4 6851.7 6951.1 6953.5 775.6 867.1 886.9 955.2 960.3 25938.6 26923.5 27075.5 27234.2 27410.6 800.3 875.8 935.0 939.3 960.1 12644.9 13248.3 13258.3 13407.0 13438.7 370311.0 372711.0 372980.0 373777.0 374537.0 47062.5 47440.8 47510.3 47694.8 47899.5 1076.4 1127.4 1165.5 1170.1 1185.2 24902.8 28089.0 28173.0 28483.4 28816.0 39652.2 40206.8 40220.0 40256.6 40365.2 287530.0 288035.0 288407.0 288867.0 289067.0 23899.8 24071.9 24621.3 24979.1 25697.6 39821.0 39874.8 39987.8 40256.6 40535.8 33814.0 34290.7 34344.5 34514.5 34856.3 33570.0 33586.0 34022.5 34249.7 34753.8 33236.2 33367.2 33405.0 33685.8 33794.7 30359.2 31017.4 31446.5 31641.8 31648.7 23760.5 23929.6 24148.3 24224.2 24295.1 16:37:50 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | [#2] jquery.html Cycles:180 Average:25435.75 Median:198223.13 stddev:63265.37 (31.9%) stddev-sans-first:63414.78 16:37:50 INFO - PROCESS | 7317 | Values: 336.8 348.5 355.2 361.5 379.4 12434.9 13375.0 13459.1 16687.8 16710.9 15681.1 15862.3 16660.2 16840.5 16845.7 4898.3 5106.5 5114.7 5119.6 5125.6 6274.5 6615.9 6623.7 6651.7 6755.9 6281.2 6640.0 6664.4 6761.8 6772.0 6826.9 6860.3 6863.1 6867.3 6875.1 6304.2 6648.3 6681.7 6799.0 6822.4 22027.6 22660.3 23107.8 23223.7 23245.0 2011.6 2017.8 2068.1 2184.9 2592.5 13931.7 14011.8 14190.8 14251.9 15211.2 1763.8 1817.6 1857.6 1860.9 1871.0 60346.7 79123.0 80836.0 81202.0 81896.6 15538.7 16531.9 16721.6 16783.8 16920.0 28068.7 28129.3 28211.2 28263.9 28359.4 8919.9 9298.8 9356.5 9665.5 9760.6 7758.7 7789.3 7799.6 7972.1 8044.0 8212.4 8322.5 8416.0 8445.2 8524.4 3038.4 3128.9 3302.5 3310.7 3365.7 752.0 824.2 858.4 899.5 900.8 15908.3 16082.3 16426.1 16980.2 17472.3 767.6 842.7 852.3 862.0 873.7 8994.3 9079.0 9548.9 9562.0 9628.9 372339.0 375300.0 378617.0 391102.0 391548.0 78897.6 83945.0 84607.6 85504.0 86345.0 903.5 911.3 943.2 948.0 954.6 15238.8 15269.1 15897.0 16182.8 16184.2 25155.5 25798.0 34902.0 36985.3 37717.0 50656.7 51585.2 52210.5 52705.5 53286.7 6761.4 6885.4 7172.7 7255.9 7572.2 8183.7 10825.0 12039.5 12269.9 12314.9 9851.2 10009.7 10254.4 10407.7 10766.7 10160.7 10542.2 10627.1 10754.5 10820.1 9384.7 10226.2 10352.0 10935.1 11136.9 7131.3 7241.8 7343.2 7421.2 7738.3 8701.5 10464.8 10492.8 10533.6 10537.3 16:37:50 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | [#3] mootools.html Cycles:180 Average:4429.84 Median:5458.87 stddev:2367.57 (43.4%) stddev-sans-first:2361.73 16:37:50 INFO - PROCESS | 7317 | Values: 1195.9 1290.6 1339.1 1351.4 1602.8 5989.9 6461.4 6655.8 6656.9 6814.8 6693.5 6749.9 6750.0 6752.3 6753.2 2021.8 2045.6 2051.8 2052.3 2052.3 2016.7 2067.3 2078.8 2082.9 2085.7 2000.7 2026.6 2041.5 2043.2 2044.9 2008.8 2032.3 2032.8 2033.3 2037.1 1992.6 2012.2 2025.3 2027.3 2027.7 6518.3 6601.7 6731.5 6741.4 6741.7 4011.8 4201.5 4203.9 4208.2 4213.5 2588.6 2781.3 2820.4 2836.7 2858.4 852.3 868.2 871.4 874.3 880.5 8468.1 8585.1 8598.1 8661.6 8692.2 3457.2 3572.4 3592.9 3661.7 3667.8 7149.2 7154.2 7192.9 7193.5 7221.6 4042.9 4132.3 4139.9 4140.0 4145.2 3472.5 3581.0 3742.4 3868.2 3883.6 3019.8 3024.6 3148.3 3154.2 3178.1 908.8 920.8 922.0 927.3 931.7 691.7 759.4 762.7 771.7 813.2 4453.8 4539.2 4583.2 4590.7 4606.3 378.2 379.9 392.5 396.0 398.8 3179.7 3236.9 3253.4 3298.1 3322.9 7491.6 8012.4 8065.2 8097.5 8113.7 8046.8 8056.6 8068.7 8078.2 8088.6 6180.9 6406.0 6437.1 6441.4 6457.1 4664.3 4664.8 4685.6 4746.2 4756.8 7334.7 7405.7 7512.1 7540.7 7616.7 7476.0 7603.6 7607.8 7615.2 7664.8 5659.6 5727.8 5729.4 5731.3 5733.6 6250.2 6498.3 6520.8 6619.1 6639.9 5914.1 5930.8 5934.4 5963.3 6036.7 5669.8 5906.7 5924.0 5970.2 5979.7 5455.7 5462.0 5463.8 5508.9 5521.5 4098.5 4129.4 4168.4 4172.9 4274.7 4049.4 4067.1 4076.0 4108.4 4172.8 16:37:50 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | [#4] prototype.html Cycles:180 Average:4053.88 Median:4661.23 stddev:1190.55 (25.5%) stddev-sans-first:1159.46 16:37:50 INFO - PROCESS | 7317 | Values: 266.5 275.1 284.9 286.4 291.2 4592.2 4701.8 4778.5 4783.9 4810.5 4694.0 4714.2 4730.5 4730.8 4751.6 3471.2 3614.4 3627.0 3635.2 3663.8 3615.6 3624.3 3633.5 3651.3 3652.6 3518.5 3605.2 3624.5 3634.5 3648.1 3656.0 3660.5 3671.4 3672.0 3684.2 3190.7 3194.2 3196.3 3204.8 3214.6 4656.9 4681.6 4726.8 4736.3 4737.1 4084.7 4162.5 4175.7 4177.8 4194.6 4984.2 4985.3 4986.6 4987.7 4995.3 3486.7 3491.5 3503.1 3503.5 3513.2 5056.2 5256.8 5280.5 5292.9 5309.4 3548.2 3585.1 3587.8 3589.5 3590.8 3619.3 3627.4 3627.5 3635.8 3642.1 3915.8 4034.8 4040.0 4209.5 4213.8 4524.4 4870.4 4901.9 4932.3 4957.0 1184.3 1184.6 1197.7 1203.9 1208.6 797.0 808.6 818.5 819.4 827.2 4194.0 4274.2 4280.8 4317.9 4334.0 5086.6 5122.2 5132.7 5163.2 5182.7 4356.2 4374.8 4385.3 4396.8 4405.9 2527.6 2615.3 2617.5 2629.3 2653.9 4608.2 4622.5 4630.9 4649.5 4654.0 5084.6 5113.7 5117.6 5134.6 5158.0 4506.6 4665.5 4681.8 4687.6 4696.7 4849.2 4860.4 4919.3 4937.6 4954.9 4825.9 4845.4 4886.8 4893.6 4896.9 4434.4 4653.8 4668.5 4691.5 4705.5 3376.6 3388.8 3393.0 3394.2 3420.4 4986.3 4988.4 5023.7 5052.9 5055.6 4727.7 4886.3 4921.5 4933.1 4961.8 4837.5 4845.6 4848.2 4852.6 4859.2 4705.0 4715.0 4722.8 4733.8 4736.2 4704.2 4884.3 4918.9 4932.4 4954.9 4750.8 4785.0 4789.4 4798.9 4801.3 16:37:50 INFO - PROCESS | 7317 | 16:37:50 INFO - PROCESS | 7317 | [#5] yui.html Cycles:180 Average:14492.99 Median:24698.95 stddev:18406.45 (74.5%) stddev-sans-first:18428.49 16:37:50 INFO - PROCESS | 7317 | Values: 591.5 645.8 679.8 712.8 714.2 11974.8 12979.9 13002.3 13027.9 13044.8 12179.1 12275.3 12283.0 12306.7 12324.3 616.4 633.8 742.2 744.5 766.8 761.7 765.5 767.2 768.0 768.4 800.4 801.5 802.6 803.1 803.2 638.9 652.4 774.7 797.0 797.4 586.8 587.4 587.4 587.6 587.7 11643.2 11724.2 11747.8 11748.6 11752.4 11849.6 11860.8 11931.5 12102.3 12313.1 9817.7 10146.0 10157.1 10185.9 10444.6 3912.3 3916.4 4015.0 4036.8 4045.2 47207.0 47290.3 47331.3 47356.8 47650.8 9185.2 9516.2 9518.1 9578.7 9703.0 14294.0 14853.4 16811.2 16831.3 16936.5 6411.7 6508.5 6510.9 6512.7 6520.9 3375.5 3451.3 3510.6 3531.1 3542.7 2738.3 2812.8 2824.3 2824.7 2833.8 487.8 497.4 498.7 500.2 516.6 491.6 569.8 597.1 603.6 605.7 7330.4 7344.4 7546.2 7595.6 7726.2 517.1 592.1 607.5 619.8 623.9 3482.7 4076.4 4126.3 4287.0 4365.1 65455.7 68650.7 69522.0 71111.3 71817.3 58777.4 59577.4 60942.7 61167.4 62153.7 55621.4 55840.0 56236.0 57826.4 58868.4 7848.8 8194.4 8230.3 8300.4 8429.2 37706.6 38476.6 39211.6 39602.6 39805.6 42012.2 45500.3 46447.3 47231.8 48906.3 10339.5 10453.8 10598.3 10646.2 10791.6 11177.9 11810.9 11867.9 11991.1 12574.2 11136.3 11262.9 11440.7 11529.5 11977.7 11198.9 11507.7 11632.7 11635.2 12396.7 10635.8 10912.6 10943.3 11136.9 11479.4 9436.8 9474.3 9725.0 9966.4 10250.0 8866.2 9289.1 9434.3 9757.1 9771.1 16:37:50 INFO - PROCESS | 7317 | -------- Summary: end -------- 16:37:50 INFO - PROCESS | 7317 | 16:37:51 INFO - TEST-INFO | 7317: exit 0 16:37:51 INFO - TEST-OK | dromaeo_css | took 1123205ms 16:37:51 INFO - TEST-START | kraken 16:37:51 INFO - operating with platform_type : linux_ 16:37:51 INFO - Initialising browser for kraken test... 16:37:51 INFO - TEST-INFO | started process 7620 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTHUnrS/profile http://localhost:32835/getInfo.html) 16:37:56 INFO - PROCESS | 7620 | __metrics Screen width/height:1600/1200 16:37:56 INFO - PROCESS | 7620 | colorDepth:24 16:37:56 INFO - PROCESS | 7620 | Browser inner width/height: 1024/697 16:37:56 INFO - PROCESS | 7620 | __metrics 16:37:56 INFO - PROCESS | 7620 | 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" 16:37:56 INFO - PROCESS | 7620 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:37:57 INFO - PROCESS | 7620 | console.error: PushService: 16:37:57 INFO - PROCESS | 7620 | stateChangeProcessEnqueue: Error transitioning state 16:37:57 INFO - PROCESS | 7620 | UnknownError 16:37:58 INFO - TEST-INFO | 7620: exit 0 16:37:58 INFO - Browser initialized. 16:37:58 INFO - Running cycle 1/1 for kraken test... 16:37:58 INFO - TEST-INFO | started process 7702 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTHUnrS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:10 INFO - PROCESS | 7702 | RSS: Main: 174653440 16:38:10 INFO - PROCESS | 7702 | 16:38:13 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32835/tests/kraken/driver.html?testName=audio-beat-detection) 16:38:14 INFO - PROCESS | 7702 | RSS: Main: 240467968 16:38:14 INFO - PROCESS | 7702 | 16:38:17 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32835/tests/kraken/driver.html?testName=audio-dft) 16:38:17 INFO - PROCESS | 7702 | RSS: Main: 218152960 16:38:17 INFO - PROCESS | 7702 | 16:38:21 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32835/tests/kraken/driver.html?testName=audio-fft) 16:38:21 INFO - PROCESS | 7702 | RSS: Main: 299974656 16:38:21 INFO - PROCESS | 7702 | 16:38:23 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32835/tests/kraken/driver.html?testName=audio-oscillator) 16:38:24 INFO - PROCESS | 7702 | RSS: Main: 210911232 16:38:24 INFO - PROCESS | 7702 | 16:38:26 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32835/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:38:27 INFO - PROCESS | 7702 | RSS: Main: 214212608 16:38:27 INFO - PROCESS | 7702 | 16:38:30 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32835/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:31 INFO - PROCESS | 7702 | RSS: Main: 254492672 16:38:31 INFO - PROCESS | 7702 | 16:38:34 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32835/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:35 INFO - PROCESS | 7702 | RSS: Main: 248180736 16:38:35 INFO - PROCESS | 7702 | 16:38:38 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32835/tests/kraken/driver.html?testName=json-parse-financial) 16:38:38 INFO - PROCESS | 7702 | RSS: Main: 250785792 16:38:38 INFO - PROCESS | 7702 | 16:38:40 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32835/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:41 INFO - PROCESS | 7702 | RSS: Main: 213839872 16:38:41 INFO - PROCESS | 7702 | 16:38:43 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32835/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:43 INFO - PROCESS | 7702 | RSS: Main: 221077504 16:38:43 INFO - PROCESS | 7702 | 16:38:45 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32835/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:46 INFO - PROCESS | 7702 | RSS: Main: 229982208 16:38:46 INFO - PROCESS | 7702 | 16:38:49 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32835/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:50 INFO - PROCESS | 7702 | RSS: Main: 237821952 16:38:50 INFO - PROCESS | 7702 | 16:38:53 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32835/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:53 INFO - PROCESS | 7702 | RSS: Main: 234668032 16:38:53 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | Cycle 1(1): loaded http://localhost:32835/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32835/tests/kraken/driver.html?testName=ai-astar) 16:38:55 INFO - PROCESS | 7702 | RSS: Main: 286978048 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | __start_tp_report 16:38:55 INFO - PROCESS | 7702 | _x_x_mozilla_page_load 16:38:55 INFO - PROCESS | 7702 | _x_x_mozilla_page_load_details 16:38:55 INFO - PROCESS | 7702 | |i|pagename|runs| 16:38:55 INFO - PROCESS | 7702 | |0;ai-astar;113;117;116;116;116;117;115;120;116;117 16:38:55 INFO - PROCESS | 7702 | |1;audio-beat-detection;153;136;129;186;135;135;130;127;166;136 16:38:55 INFO - PROCESS | 7702 | |2;audio-dft;160;161;160;161;159;162;161;161;161;160 16:38:55 INFO - PROCESS | 7702 | |3;audio-fft;76;76;77;120;77;79;78;77;100;76 16:38:55 INFO - PROCESS | 7702 | |4;audio-oscillator;86;83;131;84;84;83;112;83;84;120 16:38:55 INFO - PROCESS | 7702 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;119;118;118 16:38:55 INFO - PROCESS | 7702 | |6;imaging-darkroom;147;146;146;145;149;146;146;147;146;146 16:38:55 INFO - PROCESS | 7702 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 16:38:55 INFO - PROCESS | 7702 | |8;json-parse-financial;73;64;64;65;65;65;65;64;64;65 16:38:55 INFO - PROCESS | 7702 | |9;json-stringify-tinderbox;66;66;67;66;65;66;65;66;66;66 16:38:55 INFO - PROCESS | 7702 | |10;stanford-crypto-aes;84;83;84;84;83;83;84;84;83;83 16:38:55 INFO - PROCESS | 7702 | |11;stanford-crypto-ccm;153;142;155;141;144;140;142;142;183;152 16:38:55 INFO - PROCESS | 7702 | |12;stanford-crypto-pbkdf2;178;177;176;173;180;176;186;173;174;176 16:38:55 INFO - PROCESS | 7702 | |13;stanford-crypto-sha256-iterative;68;71;75;67;68;68;71;68;71;67 16:38:55 INFO - PROCESS | 7702 | __end_tp_report 16:38:55 INFO - PROCESS | 7702 | __start_cc_report 16:38:55 INFO - PROCESS | 7702 | _x_x_mozilla_cycle_collect,1959 16:38:55 INFO - PROCESS | 7702 | __end_cc_report 16:38:55 INFO - PROCESS | 7702 | __startTimestamp1479343135832__endTimestamp 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | ------- Summary: start ------- 16:38:55 INFO - PROCESS | 7702 | Number of tests: 14 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#0] ai-astar Cycles:10 Average:116.30 Median:116.50 stddev:1.77 (1.5%) stddev-sans-first:1.41 16:38:55 INFO - PROCESS | 7702 | Values: 113.0 117.0 116.0 116.0 116.0 117.0 115.0 120.0 116.0 117.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:136.00 stddev:19.22 (14.1%) stddev-sans-first:20.06 16:38:55 INFO - PROCESS | 7702 | Values: 153.0 136.0 129.0 186.0 135.0 135.0 130.0 127.0 166.0 136.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 16:38:55 INFO - PROCESS | 7702 | Values: 160.0 161.0 160.0 161.0 159.0 162.0 161.0 161.0 161.0 160.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#3] audio-fft Cycles:10 Average:83.60 Median:77.00 stddev:14.72 (19.1%) stddev-sans-first:15.36 16:38:55 INFO - PROCESS | 7702 | Values: 76.0 76.0 77.0 120.0 77.0 79.0 78.0 77.0 100.0 76.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:83.50 stddev:18.52 (22.2%) stddev-sans-first:19.35 16:38:55 INFO - PROCESS | 7702 | Values: 86.0 83.0 131.0 84.0 84.0 83.0 112.0 83.0 84.0 120.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 16:38:55 INFO - PROCESS | 7702 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 119.0 118.0 118.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 16:38:55 INFO - PROCESS | 7702 | Values: 147.0 146.0 146.0 145.0 149.0 146.0 146.0 147.0 146.0 146.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 16:38:55 INFO - PROCESS | 7702 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#8] json-parse-financial Cycles:10 Average:65.40 Median:65.00 stddev:2.72 (4.2%) stddev-sans-first:0.53 16:38:55 INFO - PROCESS | 7702 | Values: 73.0 64.0 64.0 65.0 65.0 65.0 65.0 64.0 64.0 65.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 16:38:55 INFO - PROCESS | 7702 | Values: 66.0 66.0 67.0 66.0 65.0 66.0 65.0 66.0 66.0 66.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 16:38:55 INFO - PROCESS | 7702 | Values: 84.0 83.0 84.0 84.0 83.0 83.0 84.0 84.0 83.0 83.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:148.00 stddev:13.05 (8.8%) stddev-sans-first:13.77 16:38:55 INFO - PROCESS | 7702 | Values: 153.0 142.0 155.0 141.0 144.0 140.0 142.0 142.0 183.0 152.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:176.50 stddev:3.87 (2.2%) stddev-sans-first:4.09 16:38:55 INFO - PROCESS | 7702 | Values: 178.0 177.0 176.0 173.0 180.0 176.0 186.0 173.0 174.0 176.0 16:38:55 INFO - PROCESS | 7702 | 16:38:55 INFO - PROCESS | 7702 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.50 stddev:2.55 (3.7%) stddev-sans-first:2.65 16:38:55 INFO - PROCESS | 7702 | Values: 68.0 71.0 75.0 67.0 68.0 68.0 71.0 68.0 71.0 67.0 16:38:55 INFO - PROCESS | 7702 | -------- Summary: end -------- 16:38:55 INFO - PROCESS | 7702 | 16:38:57 INFO - TEST-INFO | 7702: exit 0 16:38:57 INFO - TEST-OK | kraken | took 65315ms 16:38:57 INFO - SUITE-END | took 1188s 16:38:57 INFO - Completed test suite (00:19:48) 16:38:57 INFO - javascript benchmark 16:38:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2564.3973666188444, 2586.974130258699, 2607.556715358389, 2614.6862376514823, 2625.317417471457, 4619.584237418631, 4664.533546645338, 4675.579197872191, 4689.226277664181, 4693.577578017977, 4689.671722979393, 4698.483166851644, 4703.576678098971, 4708.340832283475, 4718.127146477896, 4297.03316753731, 4315.741055536662, 4328.97835510822, 4352.673549483786, 4369.650427965771, 4343.305071188611, 4373.584509471595, 4386.298192289234, 4409.426774519308, 4473.552644735533, 4380.299152135659, 4408.316710909814, 4447.533009034053, 4447.911041779163, 4475.395821564096, 3757.304898593769, 4355.063661312801, 4382.430284063052, 4394.033312671208, 4448.065906159694, 4340.544242854518, 4346.869593912188, 4374.78126093694, 4393.0, 4407.2507673695545, 4527.139843429738, 4556.8860778480475, 4561.475430325489, 4644.233701439259, 4723.338732577442, 5009.524095210985, 5013.373328333958, 5013.423456302536, 5037.974810125963, 5135.075686376451, 5057.064443078006, 5068.0, 5102.489751024905, 5107.872303192413, 5144.5369916707305, 4559.270516717375, 4591.966807468293, 4744.0, 4752.596029337545, 4791.6406269529925, 5087.0, 5129.461406552333, 5150.356205474316, 5166.61250406221, 5211.843644690665, 4920.975395123001, 4958.0, 4967.950320496749, 4969.950300497021, 5011.699298042129, 4928.8767780805765, 5051.393832740095, 5063.0380227756605, 5070.112730272188, 5106.846794596168, 4929.531694489017, 4932.975335123301, 4939.580135688435, 4954.529319714621, 4956.826511072095, 4960.553550180501, 4991.126552853236, 5046.520580544916, 5119.897602047939, 5122.718250496258, 4287.335463003239, 4287.764172970454, 4323.78381080951, 4328.0, 4332.610065094157, 3755.211405604799, 3774.452704357853, 3788.0719223790347, 3812.066043819282, 3845.3270677631863, 4218.156368726206, 4273.700840941105, 4276.080642661843, 4332.0469496710675, 4368.453943257093, 5058.595312375001, 5100.744962751847, 5113.258577506241, 5119.974400127976, 5195.428502864682, 4393.692441529062, 4399.868003959887, 4399.956000440018, 4404.493483249467, 4438.134563760101, 4464.308032255006, 4599.563041511051, 4604.907901842012, 4612.031473390559, 4654.930176047362, 4706.505816889177, 4717.481077081587, 4742.217534106834, 4762.666613337033, 4777.856664299938, 5025.597952163891, 5025.899482010266, 5027.195648696191, 5030.044291584587, 5062.746862656926, 4735.052989402065, 4747.833825816218, 4748.857534273978, 4756.619470442425, 4791.856244312677, 4855.927161092516, 4913.164761990542, 4969.229769385751, 5001.099802035523, 5003.524665156804, 5128.820491282787, 5133.178691409356, 5134.871628209325, 5171.92242116361, 5185.481451854936, 4929.926051109308, 4962.858542535165, 4969.652124351261, 4973.229149481836, 5017.623678224075, 4792.0895029944195, 4792.353032340589, 4877.585405240594, 4972.453030166751, 4983.476734942778, 4715.269133284346, 4736.502667219892, 4738.763061846963, 4783.210770222874, 4786.042791441795, 4911.9754401227765, 4923.852284431473, 4927.802887884444, 4932.334134891744, 4970.900581988412, 4746.216874215854, 4761.0, 4793.113274044313, 4819.493953135011, 4828.203346447937], "value": 4629.879280494818, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.2379228560728, 569.1263909898307, 577.4514211499074, 577.6303165973777, 580.9970950145254, 27650.44699106019, 30707.38585228294, 40678.18643627126, 42860.571394286126, 42982.35526467105, 38936.61063389365, 38981.22037559247, 39275.0, 39279.41080883782, 39615.60384396155, 7582.469227154102, 7723.38212942966, 7732.845343093135, 7733.45865789395, 7735.961320193391, 7594.5063570868, 7684.731034413797, 7692.346150577179, 7697.191794861543, 7697.3457256133015, 7544.811379715524, 7616.695332186706, 7627.504212226216, 7640.694372225104, 7658.0, 7578.242175782405, 7613.0483689538805, 7615.46691731579, 7624.61876906158, 7627.8855817162785, 7542.094948606147, 7605.733799317053, 7619.161892191854, 7623.961880190619, 7630.198829122917, 37734.0, 37901.241975160345, 37901.43147852785, 37993.24013519742, 38072.0, 3022.863971121305, 3036.9392612147853, 3041.756659467237, 3059.128148477673, 3063.3720087382035, 27495.0, 27762.861185694142, 27790.305242369104, 28138.859305703547, 28224.717752822413, 3421.6920477157178, 3468.6704763047724, 3479.9826000869834, 3488.459288810213, 3604.0269127335473, 178931.0, 179515.10242448704, 179578.10210948993, 179598.0, 180067.0, 50194.980501950246, 50500.949905009205, 50587.49412505901, 50604.0, 50646.49353506492, 31720.0, 33050.504242436386, 33149.66850331514, 35275.0, 35302.76293359855, 15079.321430535652, 15255.0, 15267.389304427921, 15269.0, 15274.389024438899, 10842.566297348176, 10880.12958963281, 10883.292585981886, 10884.346939183675, 10888.510017049251, 17372.739408908878, 17785.911070444825, 17851.196696148705, 17870.106494675216, 17980.943239649292, 6701.832454188587, 6776.390124888685, 6851.725930962805, 6951.131108611424, 6953.513254072268, 775.6238224461132, 867.1155421470065, 886.8625363068735, 955.1833182628891, 960.3037797596826, 25938.610920836203, 26923.461530769277, 27075.458490830075, 27234.182974510797, 27410.5888411674, 800.2997377294867, 875.7898104454885, 934.9579268932779, 939.2767568971854, 960.0543464687278, 12644.873551264369, 13248.27134507592, 13258.337083145998, 13407.0, 13438.731225375437, 370311.0, 372711.0, 372980.0, 373777.0, 374537.0, 47062.5293747065, 47440.762796186486, 47510.287345689845, 47694.761526192146, 47899.5210047902, 1076.4294923690456, 1127.4081107418672, 1165.463886612168, 1170.087331881134, 1185.2236784905901, 24902.750972490045, 28089.0, 28173.0, 28483.430331392843, 28816.0, 39652.2069558613, 40206.798966004986, 40220.0, 40256.59743402529, 40365.19269614533, 287530.0, 288035.0, 288407.0, 288867.0, 289067.0, 23899.761002389754, 24071.879640601685, 24621.291657917973, 24979.125730599342, 25697.614535781606, 39821.0, 39874.800625996686, 39987.80006099951, 40256.59743402529, 40535.797321013204, 33814.0, 34290.657093428745, 34344.48483272703, 34514.482282765275, 34856.302873942885, 33570.0, 33586.0, 34022.48966265558, 34249.65750342465, 34753.82623086868, 33236.1690957728, 33367.16582085467, 33405.0, 33685.83157084199, 33794.66205337915, 30359.241018974702, 31017.379652406373, 31446.52830207503, 31641.8417907909, 31648.683513164575, 23760.52478950446, 23929.564226146485, 24148.275551733477, 24224.152154674503, 24295.149669761475], "value": 16584.23972472526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.79792124725185, 348.4807636621435, 355.1653614007083, 361.4704457969075, 379.394865190022, 12434.875651243485, 13375.0, 13459.057865949388, 16687.83312166878, 16710.916445417788, 15681.137537435436, 15862.286197121128, 16660.16699165043, 16840.494785156465, 16845.747313790303, 4898.265260210979, 5106.463821298766, 5114.718690472023, 5119.59043276537, 5125.589952803767, 6274.498040156799, 6615.933840661604, 6623.701933413008, 6651.7006734696815, 6755.898661520081, 6281.246250449953, 6639.966800166017, 6664.366885145928, 6761.830954226134, 6772.018057381677, 6826.863462730742, 6860.279670634562, 6863.10779598649, 6867.278935711728, 6875.0718652982205, 6304.243490781136, 6648.301928297507, 6681.665916704145, 6798.966005169992, 6822.3859852613505, 22027.559448810935, 22660.320190394315, 23107.768922310897, 23223.651645225335, 23245.0, 2011.6379051770675, 2017.8083082107175, 2068.069368784053, 2184.9235276765394, 2592.468543948486, 13931.721365572734, 14011.789823152661, 14190.787138192936, 14251.857481425157, 15211.163386013764, 1763.8412542871074, 1817.6364727054643, 1857.5727582656066, 1860.897650629215, 1870.9625807483774, 60346.698266508385, 79123.0, 80836.0, 81202.0, 81896.59051704763, 15538.689226215412, 16531.91734041322, 16721.581960450843, 16783.83216167847, 16920.0, 28068.719312807018, 28129.296767580974, 28211.15366539007, 28263.858680706464, 28359.43281134407, 8919.866202006975, 9298.814023719488, 9356.485393303434, 9665.516724163763, 9760.560774765152, 7758.68965241384, 7789.260020298063, 7799.571023593643, 7972.083210430758, 8044.0, 8212.384071194685, 8322.542260175627, 8416.0, 8445.239928406472, 8524.360672949553, 3038.384322656032, 3128.9374212515622, 3302.546879373116, 3310.66893310667, 3365.730741540671, 751.9849603008018, 824.1717074340249, 858.356232825381, 899.5097671768901, 900.8468560344758, 15908.284127214303, 16082.276297566637, 16426.11099723523, 16980.235889385007, 17472.301107955795, 767.5663250263694, 842.7303262955918, 852.2798235491, 861.9655213791378, 873.7073080517971, 8994.325425592975, 9078.954605226932, 9548.90451095494, 9562.0, 9628.855567166498, 372339.0, 375300.0, 378617.0, 391102.0, 391548.0, 78897.60551197322, 83945.0, 84607.57696211603, 85504.0, 86345.0, 903.4985583001497, 911.3028533172261, 943.1841457139662, 947.9952600236954, 954.608610469718, 15238.77141842851, 15269.083854968738, 15897.0, 16182.757258641364, 16184.190790460665, 25155.496890062463, 25798.0, 34902.0, 36985.260294793414, 37717.0, 50656.746716266185, 51585.22622160595, 52210.47789522056, 52705.47294527159, 53286.73356633192, 6761.425278851156, 6885.416354238453, 7172.677229524579, 7255.891161632474, 7572.182676157792, 8183.672653094048, 10825.00532629118, 12039.518419263131, 12269.895709386197, 12314.938425307817, 9851.211903047883, 10009.749756256151, 10254.436006019441, 10407.68776936693, 10766.730831728955, 10160.695179144637, 10542.15662746963, 10627.149828013895, 10754.51604677807, 10820.134389248999, 9384.671536496464, 10226.181905447398, 10352.0, 10935.125189984623, 11136.888631113909, 7131.322524360177, 7241.782746517613, 7343.1555371133, 7421.220771818881, 7738.264864837832, 8701.47791132534, 10464.750078732804, 10492.842607361048, 10533.57865685364, 10537.315074520131], "value": 9184.511259825229, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1195.8564972203337, 1290.6192673161413, 1339.0760375341015, 1351.3716122003266, 1602.8076630804298, 5989.910151347728, 6461.418472337488, 6655.800325990222, 6656.933430665692, 6814.829629259259, 6693.464522838173, 6749.898751518719, 6750.0, 6752.257251702309, 6753.15585551806, 2021.8180363767258, 2045.6089858895946, 2051.7845626209255, 2052.291959274048, 2052.332991777671, 2016.7176595276674, 2067.3177851309033, 2078.75054993401, 2082.9479263018393, 2085.7497100347905, 2000.7198992141111, 2026.614943160802, 2041.5202427429542, 2043.1725151313742, 2044.9079791409422, 2008.8192062714352, 2032.3191730770209, 2032.78655741148, 2033.3290014295246, 2037.1443993522755, 1992.601479704065, 2012.23535056679, 2025.2810252360493, 2027.3107143571256, 2027.7161197432372, 6518.28298887122, 6601.669916504204, 6731.49513786468, 6741.426978706816, 6741.696623651947, 4011.7592944423136, 4201.495820501559, 4203.873883783492, 4208.22147902639, 4213.494380674308, 2588.585826267788, 2781.3324802047364, 2820.421813528242, 2836.7163283671675, 2858.414025124865, 852.2925971443686, 868.2359523619238, 871.3639043498218, 874.3006340917568, 880.467317273047, 8468.068512463624, 8585.055643879168, 8598.140185981352, 8661.566921653892, 8692.21770040693, 3457.1775908247687, 3572.374834403969, 3592.892213233607, 3661.6704496595435, 3667.789629422284, 7149.249328820503, 7154.177269613957, 7192.856142877114, 7193.460490463236, 7221.638918054076, 4042.8787136385954, 4132.297509423405, 4139.855105071308, 4140.0, 4145.17096580685, 3472.5312082868995, 3580.964190358115, 3742.401215805458, 3868.187680587052, 3883.61163883615, 3019.788614796943, 3024.6219222597174, 3148.291634382246, 3154.2114471382156, 3178.0624715708923, 908.822779557993, 920.7652048727532, 921.9769505762275, 927.3276874266238, 931.7111695374456, 691.7440546997643, 759.4190444310108, 762.6568044380051, 771.6681826814412, 813.1868131868132, 4453.842001079743, 4539.228331183706, 4583.220852455091, 4590.747508886977, 4606.286025666027, 378.2472878970883, 379.939209726448, 392.4956430986169, 395.95050618672667, 398.84644411901627, 3179.650238473766, 3236.85091792415, 3253.398121347558, 3298.10951043217, 3322.9335413291597, 7491.550506969599, 8012.3590112790835, 8065.153158918347, 8097.514149151069, 8113.6754529818145, 8046.758597242092, 8056.556889371023, 8068.717594884269, 8078.192180781874, 8088.636011379384, 6180.876382472416, 6405.967970160213, 6437.066625339299, 6441.355864413615, 6457.063725759738, 4664.323673067454, 4664.766761661971, 4685.554872287127, 4746.2168742157155, 4756.8097276108565, 7334.706611735684, 7405.703771849066, 7512.060992375953, 7540.736074237376, 7616.657250423634, 7475.962620186864, 7603.619819009138, 7607.771766847003, 7615.162332143571, 7664.846703066019, 5659.575531835047, 5727.799527016535, 5729.369769325454, 5731.340895797037, 5733.598648094593, 6250.218722659668, 6498.285188629153, 6520.815526798386, 6619.073329733745, 6639.867202656016, 5914.053751399755, 5930.822075337746, 5934.376890426615, 5963.254593175853, 6036.7283472244735, 5669.829905102853, 5906.734196961061, 5923.970380148072, 5970.164177015135, 5979.671118088547, 5455.699936503372, 5462.0441422751655, 5463.781448742165, 5508.862278443071, 5521.503064724194, 4098.4876890388705, 4129.380592911088, 4168.437260969851, 4172.874813755592, 4274.722143060815, 4049.440965228467, 4067.08490589615, 4075.9592404076766, 4108.36320370343, 4172.833086676499], "value": 3571.8761196161704, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.48701250093563, 275.078487068321, 284.9017089104259, 286.38955916818196, 291.1948462501184, 4592.196365636017, 4701.7649117544115, 4778.450478195009, 4783.856484305467, 4810.5189481051875, 4693.953060469395, 4714.198586240339, 4730.526947305268, 4730.769999800051, 4751.5982785078395, 3471.236328007847, 3614.4036234021355, 3626.963730362689, 3635.2366003139373, 3663.8351274192723, 3615.566132064156, 3624.3294990426725, 3633.4913112164318, 3651.251493443851, 3652.5982141964496, 3518.5249991251108, 3605.152789094574, 3624.5469316335457, 3634.5275114235246, 3648.106213977579, 3656.0, 3660.4559830917524, 3671.3575124353265, 3672.026912868092, 3684.171061511165, 3190.6649801770945, 3194.185482701919, 3196.3447493264057, 3204.8077115373153, 3214.6463888972194, 4656.94969794785, 4681.648876334254, 4726.763661816895, 4736.265878788793, 4737.076270127326, 4084.6732261419015, 4162.458880345566, 4175.68682348825, 4177.811998460046, 4194.580541945781, 4984.22744474607, 4985.302057711923, 4986.60375094974, 4987.650864439491, 4995.275685025651, 3486.6513348665185, 3491.493733408667, 3503.1242189452637, 3503.4919936609303, 3513.2095278562374, 5056.191009438472, 5256.763445644955, 5280.471952804727, 5292.947070529284, 5309.4425085365865, 3548.201654627688, 3585.1395665040204, 3587.8385472653795, 3589.533360663097, 3590.838412271454, 3619.2580520993133, 3627.437747149196, 3627.528421305213, 3635.763675361146, 3642.1076836175307, 3915.804209789499, 4034.757914525138, 4039.9596004040172, 4209.452771139732, 4213.810378532973, 4524.393840186752, 4870.439899411613, 4901.926471102936, 4932.284818701268, 4956.950430495721, 1184.3249347871804, 1184.6268425446128, 1197.6885310584896, 1203.9036877049814, 1208.6192849252457, 797.0376607470948, 808.6118663041773, 818.5497976113234, 819.397742659145, 827.1893544326593, 4193.993441574, 4274.166537525152, 4280.76455794928, 4317.870463886088, 4333.9878784245275, 5086.593072554187, 5122.180451127876, 5132.717700526507, 5163.225516172604, 5182.663126896739, 4356.215881141425, 4374.825006999748, 4385.254506733863, 4396.802143903532, 4405.867823965286, 2527.6208568714474, 2615.333090062022, 2617.528844807915, 2629.2638061342855, 2653.854038027927, 4608.216603177468, 4622.46841613212, 4630.907381852344, 4649.535046495391, 4653.976730116327, 5084.55090299263, 5113.718745469035, 5117.590592752571, 5134.563562097189, 5158.0, 4506.616937560344, 4665.5101214372, 4681.776156325786, 4687.578117969331, 4696.741679207608, 4849.181556916156, 4860.3681521401995, 4919.311296418576, 4937.629677774109, 4954.851354459372, 4825.890045289533, 4845.41854977405, 4886.804527818989, 4893.6085113191575, 4896.873719044568, 4434.423524941737, 4653.837115700934, 4668.533146685304, 4691.50739172382, 4705.482396936266, 3376.5610470638658, 3388.8136152511847, 3392.9830350848088, 3394.1853955051106, 3420.3672320620763, 4986.32684587576, 4988.403710812505, 5023.744063984004, 5052.898942021213, 5055.5702765263895, 4727.739974301447, 4886.3403440536495, 4921.4832442593015, 4933.136701077369, 4961.776720047678, 4837.540433658797, 4845.63657725665, 4848.248521479177, 4852.636052296162, 4859.246816743411, 4705.0123171786845, 4714.976425117852, 4722.811087556596, 4733.810647574059, 4736.19484687597, 4704.223803072455, 4884.291777692286, 4918.852434426973, 4932.435133730512, 4954.900901982012, 4750.762461876961, 4785.0, 4789.44921334051, 4798.944232268896, 4801.255021083561], "value": 3670.350342984232, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.5031373646136, 645.7868903261924, 679.8062552172631, 712.7932899459158, 714.2000958926004, 11974.82037769433, 12979.935100324485, 13002.349882505872, 13027.934860325686, 13044.804327935088, 12179.086568507353, 12275.263484190955, 12283.0, 12306.692332691688, 12324.32216228107, 616.4144063140012, 633.7560039384847, 742.1947187301784, 744.5383862005564, 766.8044648614596, 761.7105499910026, 765.5291995422803, 767.1982777997007, 767.9616019199045, 768.4352001279084, 800.4196957206037, 801.4870482890955, 802.5987006496752, 803.0804728585766, 803.2329125684956, 638.9265234498023, 652.3769799841141, 774.6514068669065, 797.0435477427081, 797.4139007829241, 586.7828903305762, 587.3773799772255, 587.4213899309177, 587.5505238492557, 587.7002728608397, 11643.18497705161, 11724.237924534878, 11747.765044699143, 11748.647540573797, 11752.353620550952, 11849.644510664693, 11860.822087668692, 11931.522739090426, 12102.273863568214, 12313.07651926109, 9817.705468835946, 10145.970805838788, 10157.085862272357, 10185.930477299851, 10444.63443779464, 3912.2762288976633, 3916.353801622729, 4014.959850401517, 4036.777977211252, 4045.1505183911117, 47207.0, 47290.29064564034, 47331.290030649565, 47356.763216184045, 47650.76174619139, 9185.219256363283, 9516.23870090391, 9518.143367096995, 9578.664746733832, 9702.95148524267, 14294.0, 14853.405863765329, 16811.243494042796, 16831.32674693023, 16936.491905242863, 6411.743530258737, 6508.511861610304, 6510.869782604322, 6512.706928188243, 6520.9347906520325, 3375.5105509700957, 3451.2752322012657, 3510.6313837047255, 3531.081918701156, 3542.6634469725336, 2738.3291093682174, 2812.8452935088767, 2824.3221626809827, 2824.7316504932, 2833.7874659400627, 487.8438899552109, 497.4329264638315, 498.69579556470524, 500.2246517897245, 516.630609114485, 491.62390771060166, 569.8262030080865, 597.1490625858135, 603.607655024229, 605.6517502436117, 7330.413566914634, 7344.449166312548, 7546.169921308651, 7595.5822429766895, 7726.227377262228, 517.0873408434117, 592.1473078766562, 607.5261296188979, 619.8326451857973, 623.9282482514478, 3482.669146431085, 4076.429299898017, 4126.319157339066, 4286.962392279871, 4365.105153443536, 65455.67272163609, 68650.65674671595, 69522.0, 71111.28888711047, 71817.2818271821, 58777.41222587805, 59577.40422595719, 60942.69528652328, 61167.388326117056, 62153.68923155355, 55621.443785562435, 55840.0, 56236.0, 57826.42173578295, 58868.4113158863, 7848.791121680449, 8194.38542109356, 8230.300424463987, 8300.377471689768, 8429.157084291523, 37706.62293377031, 38476.615233848424, 39211.6078839208, 39602.60397396104, 39805.60194398019, 42012.159756805304, 45500.31749523694, 46447.30329044999, 47231.763841180575, 48906.266406003226, 10339.483025848827, 10453.790924181625, 10598.31110977784, 10646.20153488476, 10791.56833726642, 11177.88822111801, 11810.940945295219, 11867.881321187024, 11991.122130688746, 12574.233668592895, 11136.33182009082, 11262.943685281522, 11440.713982150513, 11529.481173347052, 11977.700557486132, 11198.944005279922, 11507.654770356903, 11632.651020469399, 11635.185537012328, 12396.690082747642, 10635.83585373239, 10912.61805836792, 10943.343399396063, 11136.944315278372, 11479.36863472517, 9436.764080897758, 9474.336796424459, 9725.027497250501, 9966.422161373459, 10250.0, 8866.202041816268, 9289.117533834275, 9434.292428067785, 9757.12185903272, 9771.120599145826], "value": 6072.056019951055, "unit": "score"}], "name": "dromaeo_css", "value": 6188.039726146908, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 116.0, 116.0, 116.0, 117.0, 115.0, 120.0, 116.0, 117.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 136.0, 129.0, 186.0, 135.0, 135.0, 130.0, 127.0, 166.0, 136.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 159.0, 162.0, 161.0, 161.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 120.0, 77.0, 79.0, 78.0, 77.0, 100.0, 76.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 131.0, 84.0, 84.0, 83.0, 112.0, 83.0, 84.0, 120.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 119.0, 118.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 149.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0, 64.0, 64.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 84.0, 83.0, 83.0, 84.0, 84.0, 83.0, 83.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 142.0, 155.0, 141.0, 144.0, 140.0, 142.0, 142.0, 183.0, 152.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 177.0, 176.0, 173.0, 180.0, 176.0, 186.0, 173.0, 174.0, 176.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 75.0, 67.0, 68.0, 68.0, 71.0, 68.0, 71.0, 67.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1568.1000000000004, "alertThreshold": 2.0}]} 16:38:57 INFO - Return code: 0 16:38:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:57 INFO - # TBPL SUCCESS # 16:38:57 INFO - Running post-action listener: _resource_record_post_action 16:38:57 INFO - [mozharness: 2016-11-17 00:38:57.632531Z] Finished run-tests step (success) 16:38:57 INFO - Running post-run listener: _resource_record_post_run 16:38:57 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 344064; Write bytes: 389533696; Read time: 112; Write time: 325268 16:38:57 INFO - TinderboxPrint: CPU usage
13.1% 16:38:57 INFO - TinderboxPrint: I/O read bytes / time
344,064 / 112 16:38:57 INFO - TinderboxPrint: I/O write bytes / time
389,533,696 / 325,268 16:38:57 INFO - TinderboxPrint: CPU idle
8,352.6 (86.9%) 16:38:57 INFO - TinderboxPrint: CPU user
1,211.9 (12.6%) 16:38:57 INFO - TinderboxPrint: Swap in / out
0 / 0 16:38:57 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99110912; Read time: 0; Write time: 70392 16:38:57 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 339968; Write bytes: 290422784; Read time: 104; Write time: 254876 16:38:58 INFO - Running post-run listener: _upload_blobber_files 16:38:58 INFO - Blob upload gear active. 16:38:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:58 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 16:38:58 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'] 16:38:58 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 16:38:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:38:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:59 INFO - (blobuploader) - INFO - Done attempting. 16:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:39:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:03 INFO - (blobuploader) - INFO - Done attempting. 16:39:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:39:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:04 INFO - (blobuploader) - INFO - Done attempting. 16:39:04 INFO - (blobuploader) - INFO - Iteration through files over. 16:39:04 INFO - Return code: 0 16:39:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:39:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:39:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1cc9af2f9a7c7ee9aff8da979adaeff9131b106e36d26e8174a7de66d96c53839e982168fa4b6622a2b984882789a8cbe8edb1fd6d0281c22ee9f6a45cb6a82c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fca5cb1b43bca1e8b94ff271de2059b35023f552e908dc7a6cb92b1c082b219122b62f5c73375c4564b533e6d1c5c0503796bde40778bed5297db4de5ce4b3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/64d396fc53639676917460b41617af28aa7824a5d17a7b1cdbfee5253860e6e717fb00edd286894725228454ed088caa192f4f020538ddb6410bdeec80acfb53"} 16:39:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:39:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:39:04 INFO - Contents: 16:39:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1cc9af2f9a7c7ee9aff8da979adaeff9131b106e36d26e8174a7de66d96c53839e982168fa4b6622a2b984882789a8cbe8edb1fd6d0281c22ee9f6a45cb6a82c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fca5cb1b43bca1e8b94ff271de2059b35023f552e908dc7a6cb92b1c082b219122b62f5c73375c4564b533e6d1c5c0503796bde40778bed5297db4de5ce4b3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/64d396fc53639676917460b41617af28aa7824a5d17a7b1cdbfee5253860e6e717fb00edd286894725228454ed088caa192f4f020538ddb6410bdeec80acfb53"} 16:39:04 INFO - Running post-run listener: copy_logs_to_upload_dir 16:39:04 INFO - Copying logs to upload dir... 16:39:04 INFO - mkdir: /builds/slave/test/build/upload/logs 16:39:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.894608 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-11-16 16:39:04.768071) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:04.771561) ========= 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=920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635 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/1cc9af2f9a7c7ee9aff8da979adaeff9131b106e36d26e8174a7de66d96c53839e982168fa4b6622a2b984882789a8cbe8edb1fd6d0281c22ee9f6a45cb6a82c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fca5cb1b43bca1e8b94ff271de2059b35023f552e908dc7a6cb92b1c082b219122b62f5c73375c4564b533e6d1c5c0503796bde40778bed5297db4de5ce4b3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/64d396fc53639676917460b41617af28aa7824a5d17a7b1cdbfee5253860e6e717fb00edd286894725228454ed088caa192f4f020538ddb6410bdeec80acfb53"} build_url:https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011739 build_url: 'https://queue.taskcluster.net/v1/task/E-FOc-eBSt2blbOIhTNEZQ/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/1cc9af2f9a7c7ee9aff8da979adaeff9131b106e36d26e8174a7de66d96c53839e982168fa4b6622a2b984882789a8cbe8edb1fd6d0281c22ee9f6a45cb6a82c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fca5cb1b43bca1e8b94ff271de2059b35023f552e908dc7a6cb92b1c082b219122b62f5c73375c4564b533e6d1c5c0503796bde40778bed5297db4de5ce4b3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/64d396fc53639676917460b41617af28aa7824a5d17a7b1cdbfee5253860e6e717fb00edd286894725228454ed088caa192f4f020538ddb6410bdeec80acfb53"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:04.797724) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:04.798150) ========= 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=920470bf432669ad7e3a5387000001ca-1479341751.759981-1425317635 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:04.883078) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 16:39:04.883405) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 16:39:04.884075) ========= ========= Total master_lag: 0.16 =========