builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1479266161.48 results: success (0) buildid: 20161115163015 builduid: 561146e0dca243139edfe4cd84bbb99f revision: 79feeed4293336089590320a9f30a813fade8e3c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.482786) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.483231) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.483508) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.498094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.498418) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-15 19:16:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.5M=0s 2016-11-15 19:16:01 (37.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100836 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.613143) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.613458) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019021 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.663089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:01.663441) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 79feeed4293336089590320a9f30a813fade8e3c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 79feeed4293336089590320a9f30a813fade8e3c --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=74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-15 19:16:02,089 truncating revision to first 12 chars 2016-11-15 19:16:02,089 Setting DEBUG logging. 2016-11-15 19:16:02,089 attempt 1/10 2016-11-15 19:16:02,089 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/79feeed42933?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-15 19:16:02,711 unpacking tar archive at: mozilla-central-79feeed42933/testing/mozharness/ program finished with exit code 0 elapsedTime=0.922122 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:02.598930) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:02.599260) ========= script_repo_revision: 79feeed4293336089590320a9f30a813fade8e3c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:02.599638) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:02.599900) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 19:16:02.609371) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-11-15 19:16:02.609684) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:16:03 INFO - MultiFileLogger online at 20161115 19:16:03 in /builds/slave/test 19:16:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:16:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:16:03 INFO - {'append_to_log': False, 19:16:03 INFO - 'base_work_dir': '/builds/slave/test', 19:16:03 INFO - 'blob_upload_branch': 'Firefox', 19:16:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:16:03 INFO - 'branch': 'Firefox', 19:16:03 INFO - 'buildbot_json_path': 'buildprops.json', 19:16:03 INFO - 'config_files': ('talos/linux_config.py',), 19:16:03 INFO - 'default_actions': ('clobber', 19:16:03 INFO - 'read-buildbot-config', 19:16:03 INFO - 'download-and-extract', 19:16:03 INFO - 'populate-webroot', 19:16:03 INFO - 'create-virtualenv', 19:16:03 INFO - 'install', 19:16:03 INFO - 'run-tests'), 19:16:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:16:03 INFO - 'download_minidump_stackwalk': True, 19:16:03 INFO - 'download_symbols': 'ondemand', 19:16:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:16:03 INFO - 'tooltool.py': '/tools/tooltool.py', 19:16:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:16:03 INFO - '/tools/misc-python/virtualenv.py')}, 19:16:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:16:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:16:03 INFO - 'installer_path': 'installer.exe', 19:16:03 INFO - 'log_level': 'info', 19:16:03 INFO - 'log_name': 'talos', 19:16:03 INFO - 'log_to_console': True, 19:16:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:16:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:16:03 INFO - 'opt_config_files': (), 19:16:03 INFO - 'pip_index': False, 19:16:03 INFO - 'sps_profile': False, 19:16:03 INFO - 'sps_profile_interval': 0, 19:16:03 INFO - 'suite': 'dromaeojs', 19:16:03 INFO - 'system_bits': '32', 19:16:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:16:03 INFO - 'title': 'talos-linux64-ix-042', 19:16:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:16:03 INFO - 'use_talos_json': True, 19:16:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:16:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:16:03 INFO - 'work_dir': 'build'} 19:16:03 INFO - [mozharness: 2016-11-16 03:16:03.138633Z] Running clobber step. 19:16:03 INFO - Running pre-action listener: _resource_record_pre_action 19:16:03 INFO - Running main action method: clobber 19:16:03 INFO - rmtree: /builds/slave/test/build 19:16:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:16:04 INFO - Running post-action listener: _resource_record_post_action 19:16:04 INFO - [mozharness: 2016-11-16 03:16:04.124814Z] Finished clobber step (success) 19:16:04 INFO - [mozharness: 2016-11-16 03:16:04.124915Z] Running read-buildbot-config step. 19:16:04 INFO - Running pre-action listener: _resource_record_pre_action 19:16:04 INFO - Running main action method: read_buildbot_config 19:16:04 INFO - Using buildbot properties: 19:16:04 INFO - { 19:16:04 INFO - "project": "", 19:16:04 INFO - "product": "firefox", 19:16:04 INFO - "script_repo_revision": "production", 19:16:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:16:04 INFO - "repository": "", 19:16:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:16:04 INFO - "buildid": "20161115163015", 19:16:04 INFO - "pgo_build": "True", 19:16:04 INFO - "basedir": "/builds/slave/test", 19:16:04 INFO - "buildnumber": 69, 19:16:04 INFO - "slavename": "talos-linux64-ix-042", 19:16:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:16:04 INFO - "platform": "ubuntu64_hw", 19:16:04 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:16:04 INFO - "branch": "mozilla-central", 19:16:04 INFO - "repo_path": "mozilla-central", 19:16:04 INFO - "revision": "79feeed4293336089590320a9f30a813fade8e3c", 19:16:04 INFO - "stage_platform": "linux64-pgo", 19:16:04 INFO - "builduid": "561146e0dca243139edfe4cd84bbb99f", 19:16:04 INFO - "slavebuilddir": "test" 19:16:04 INFO - } 19:16:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 19:16:04 INFO - Running post-action listener: _resource_record_post_action 19:16:04 INFO - [mozharness: 2016-11-16 03:16:04.127332Z] Finished read-buildbot-config step (success) 19:16:04 INFO - [mozharness: 2016-11-16 03:16:04.127420Z] Running download-and-extract step. 19:16:04 INFO - Running pre-action listener: _resource_record_pre_action 19:16:04 INFO - Running main action method: download_and_extract 19:16:04 INFO - mkdir: /builds/slave/test/build/tests 19:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:04 INFO - trying https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:16:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:16:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:16:04 INFO - Downloaded 1361 bytes. 19:16:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:16:04 INFO - Using the following test package requirements: 19:16:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:16:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:16:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:16:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:16:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:16:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:16:04 INFO - u'jsshell-linux-x86_64.zip'], 19:16:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:16:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:16:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:16:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:16:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:16:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:16:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:16:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:16:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:16:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:16:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:16:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:16:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:16:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:16:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:16:05 INFO - Http code: 200 19:16:05 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 19:16:05 INFO - x-amz-cf-id: comXM5ONIpj-zmwdxzSt3px7CTEKe0BZ_iJvtKygeExdxWj92PToEw== 19:16:05 INFO - x-amz-version-id: dCngxJ36Rwy0GjDL8D3Ok3B2Vzh12oS_ 19:16:06 INFO - Expected file size: 21971597 19:16:06 INFO - Obtained file size: 21971597 19:16:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:16:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:16:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:16:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:16:10 INFO - Http code: 200 19:16:10 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 19:16:10 INFO - x-amz-cf-id: bS-2Ih6YHiIGP_9wJYdVexuYzt4mKII-KHdlHG1Sz5bGHfpml0_5PA== 19:16:10 INFO - x-amz-version-id: dCngxJ36Rwy0GjDL8D3Ok3B2Vzh12oS_ 19:16:11 INFO - Expected file size: 21971597 19:16:11 INFO - Obtained file size: 21971597 19:16:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 19:16:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:16:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:16:16 INFO - Http code: 200 19:16:16 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 19:16:16 INFO - x-amz-cf-id: QrwjTvXu0fkfHc9im2W64I63Mn6hiTnMI4bmtrlaBJ4ynDRHt8xZjw== 19:16:16 INFO - x-amz-version-id: huU.o_8TAihn0GjXa.MQhe42fzFfQaH8 19:16:17 INFO - Expected file size: 14409038 19:16:17 INFO - Obtained file size: 14409038 19:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:17 INFO - trying https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:16:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:16:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:16:19 INFO - Downloaded 59568904 bytes. 19:16:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:16:19 INFO - mkdir: /builds/slave/test/properties 19:16:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:16:19 INFO - Writing to file /builds/slave/test/properties/build_url 19:16:19 INFO - Contents: 19:16:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:16:20 INFO - Running post-action listener: _resource_record_post_action 19:16:20 INFO - Running post-action listener: set_extra_try_arguments 19:16:20 INFO - [mozharness: 2016-11-16 03:16:20.543804Z] Finished download-and-extract step (success) 19:16:20 INFO - [mozharness: 2016-11-16 03:16:20.544032Z] Running populate-webroot step. 19:16:20 INFO - Running pre-action listener: _resource_record_pre_action 19:16:20 INFO - Running main action method: populate_webroot 19:16:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:16:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:16:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:16:20 INFO - '--tppagecycles', 19:16:20 INFO - '7'], 19:16:20 INFO - 'tests': ['tsvgm']}}, 19:16:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:16:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:16:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:16:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:16:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:20 INFO - 'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:16:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:16:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:20 INFO - 'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['damp', 'tps']}, 19:16:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:20 INFO - 'tests': ['damp', 'tps']}, 19:16:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['dromaeo_dom']}, 19:16:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:16:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['basic_compositor_video']}, 19:16:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:16:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['a11yr', 19:16:20 INFO - 'ts_paint', 19:16:20 INFO - 'tpaint', 19:16:20 INFO - 'sessionrestore', 19:16:20 INFO - 'sessionrestore_no_auto_restore', 19:16:20 INFO - 'tabpaint']}, 19:16:20 INFO - 'other-e10s': {'tests': ['a11yr', 19:16:20 INFO - 'ts_paint', 19:16:20 INFO - 'tpaint', 19:16:20 INFO - 'sessionrestore', 19:16:20 INFO - 'sessionrestore_no_auto_restore', 19:16:20 INFO - 'tabpaint']}, 19:16:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:16:20 INFO - 'ts_paint', 19:16:20 INFO - 'tpaint', 19:16:20 INFO - 'sessionrestore', 19:16:20 INFO - 'sessionrestore_no_auto_restore', 19:16:20 INFO - 'tabpaint']}, 19:16:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:16:20 INFO - 'ts_paint', 19:16:20 INFO - 'tpaint', 19:16:20 INFO - 'sessionrestore', 19:16:20 INFO - 'sessionrestore_no_auto_restore', 19:16:20 INFO - 'tabpaint']}, 19:16:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['a11yr', 19:16:20 INFO - 'ts_paint', 19:16:20 INFO - 'tpaint', 19:16:20 INFO - 'sessionrestore', 19:16:20 INFO - 'sessionrestore_no_auto_restore', 19:16:20 INFO - 'tabpaint']}, 19:16:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['a11yr', 19:16:20 INFO - 'ts_paint', 19:16:20 INFO - 'tpaint', 19:16:20 INFO - 'sessionrestore', 19:16:20 INFO - 'sessionrestore_no_auto_restore', 19:16:20 INFO - 'tabpaint']}, 19:16:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['tsvgx', 19:16:20 INFO - 'tsvgr_opacity', 19:16:20 INFO - 'tart', 19:16:20 INFO - 'tscrollx', 19:16:20 INFO - 'cart']}, 19:16:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:16:20 INFO - 'tsvgr_opacity', 19:16:20 INFO - 'tart', 19:16:20 INFO - 'tscrollx', 19:16:20 INFO - 'cart']}, 19:16:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:20 INFO - 'talos_options': ['--disable-e10s'], 19:16:20 INFO - 'tests': ['tp5o']}, 19:16:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:20 INFO - 'tests': ['tp5o']}, 19:16:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:20 INFO - 'talos_options': ['--disable-e10s', 19:16:20 INFO - '--xperf_path', 19:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:16:20 INFO - 'tests': ['tp5n']}, 19:16:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:20 INFO - 'talos_options': ['--xperf_path', 19:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:16:20 INFO - 'tests': ['tp5n']}}, 19:16:20 INFO - 'talos.zip': {'path': '', 19:16:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:16:20 INFO - Running post-action listener: _resource_record_post_action 19:16:20 INFO - [mozharness: 2016-11-16 03:16:20.565820Z] Finished populate-webroot step (success) 19:16:20 INFO - [mozharness: 2016-11-16 03:16:20.565906Z] Running create-virtualenv step. 19:16:20 INFO - Running pre-action listener: _resource_record_pre_action 19:16:20 INFO - Running main action method: create_virtualenv 19:16:20 INFO - Creating virtualenv /builds/slave/test/build/venv 19:16:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:16:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:16:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:16:20 INFO - Using real prefix '/usr' 19:16:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:16:21 INFO - Installing distribute.............................................................................................................................................................................................done. 19:16:23 INFO - Installing pip.................done. 19:16:23 INFO - Return code: 0 19:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:16:23 INFO - Reading from file tmpfile_stdout 19:16:23 INFO - Output received: 19:16:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:16:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:23 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:16:23 INFO - Using env: {'DISPLAY': ':0', 19:16:23 INFO - 'HOME': '/home/cltbld', 19:16:23 INFO - 'LANG': 'en_US.UTF-8', 19:16:23 INFO - 'LANGUAGE': 'en_US:en', 19:16:23 INFO - 'LOGNAME': 'cltbld', 19:16:23 INFO - 'MAIL': '/var/mail/cltbld', 19:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:23 INFO - 'MOZ_NO_REMOTE': '1', 19:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:23 INFO - 'NO_EM_RESTART': '1', 19:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:23 INFO - 'PWD': '/builds/slave/test', 19:16:23 INFO - 'SHELL': '/bin/bash', 19:16:23 INFO - 'SHLVL': '1', 19:16:23 INFO - 'TERM': 'linux', 19:16:23 INFO - 'TMOUT': '86400', 19:16:23 INFO - 'USER': 'cltbld', 19:16:23 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:23 INFO - '_': '/tools/buildbot/bin/python'} 19:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:16:23 INFO - Cleaning up... 19:16:23 INFO - Return code: 0 19:16:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:23 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:16:23 INFO - Using env: {'DISPLAY': ':0', 19:16:23 INFO - 'HOME': '/home/cltbld', 19:16:23 INFO - 'LANG': 'en_US.UTF-8', 19:16:23 INFO - 'LANGUAGE': 'en_US:en', 19:16:23 INFO - 'LOGNAME': 'cltbld', 19:16:23 INFO - 'MAIL': '/var/mail/cltbld', 19:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:23 INFO - 'MOZ_NO_REMOTE': '1', 19:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:23 INFO - 'NO_EM_RESTART': '1', 19:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:23 INFO - 'PWD': '/builds/slave/test', 19:16:23 INFO - 'SHELL': '/bin/bash', 19:16:23 INFO - 'SHLVL': '1', 19:16:23 INFO - 'TERM': 'linux', 19:16:23 INFO - 'TMOUT': '86400', 19:16:23 INFO - 'USER': 'cltbld', 19:16:23 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:23 INFO - '_': '/tools/buildbot/bin/python'} 19:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:23 INFO - Downloading/unpacking psutil>=3.1.1 19:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:16:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:16:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:16:25 INFO - Installing collected packages: psutil 19:16:25 INFO - Running setup.py install for psutil 19:16:25 INFO - building 'psutil._psutil_linux' extension 19:16:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:16:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:16:26 INFO - building 'psutil._psutil_posix' extension 19:16:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:16:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:16:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:16:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:16:26 INFO - Successfully installed psutil 19:16:26 INFO - Cleaning up... 19:16:26 INFO - Return code: 0 19:16:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:26 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:16:26 INFO - Using env: {'DISPLAY': ':0', 19:16:26 INFO - 'HOME': '/home/cltbld', 19:16:26 INFO - 'LANG': 'en_US.UTF-8', 19:16:26 INFO - 'LANGUAGE': 'en_US:en', 19:16:26 INFO - 'LOGNAME': 'cltbld', 19:16:26 INFO - 'MAIL': '/var/mail/cltbld', 19:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:26 INFO - 'MOZ_NO_REMOTE': '1', 19:16:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:26 INFO - 'NO_EM_RESTART': '1', 19:16:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:26 INFO - 'PWD': '/builds/slave/test', 19:16:26 INFO - 'SHELL': '/bin/bash', 19:16:26 INFO - 'SHLVL': '1', 19:16:26 INFO - 'TERM': 'linux', 19:16:26 INFO - 'TMOUT': '86400', 19:16:26 INFO - 'USER': 'cltbld', 19:16:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:26 INFO - '_': '/tools/buildbot/bin/python'} 19:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:16:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:16:28 INFO - Installing collected packages: mozsystemmonitor 19:16:28 INFO - Running setup.py install for mozsystemmonitor 19:16:28 INFO - Successfully installed mozsystemmonitor 19:16:28 INFO - Cleaning up... 19:16:28 INFO - Return code: 0 19:16:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:16: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')]} 19:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16: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')]} 19:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:28 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:16:28 INFO - Using env: {'DISPLAY': ':0', 19:16:28 INFO - 'HOME': '/home/cltbld', 19:16:28 INFO - 'LANG': 'en_US.UTF-8', 19:16:28 INFO - 'LANGUAGE': 'en_US:en', 19:16:28 INFO - 'LOGNAME': 'cltbld', 19:16:28 INFO - 'MAIL': '/var/mail/cltbld', 19:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:28 INFO - 'MOZ_NO_REMOTE': '1', 19:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:28 INFO - 'NO_EM_RESTART': '1', 19:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:28 INFO - 'PWD': '/builds/slave/test', 19:16:28 INFO - 'SHELL': '/bin/bash', 19:16:28 INFO - 'SHLVL': '1', 19:16:28 INFO - 'TERM': 'linux', 19:16:28 INFO - 'TMOUT': '86400', 19:16:28 INFO - 'USER': 'cltbld', 19:16:28 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:28 INFO - '_': '/tools/buildbot/bin/python'} 19:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:29 INFO - Downloading/unpacking jsonschema==2.5.1 19:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:16:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:31 INFO - Downloading functools32-3.2.3-2.tar.gz 19:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:16:31 INFO - warning: no files found matching '*.txt' 19:16:31 INFO - no previously-included directories found matching 'build' 19:16:31 INFO - no previously-included directories found matching 'dist' 19:16:31 INFO - no previously-included directories found matching '.git*' 19:16:31 INFO - Installing collected packages: jsonschema, functools32 19:16:31 INFO - Running setup.py install for functools32 19:16:31 INFO - warning: no files found matching '*.txt' 19:16:31 INFO - no previously-included directories found matching 'build' 19:16:31 INFO - no previously-included directories found matching 'dist' 19:16:31 INFO - no previously-included directories found matching '.git*' 19:16:31 INFO - Successfully installed jsonschema functools32 19:16:31 INFO - Cleaning up... 19:16:31 INFO - Return code: 0 19:16:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:31 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:16:31 INFO - Using env: {'DISPLAY': ':0', 19:16:31 INFO - 'HOME': '/home/cltbld', 19:16:31 INFO - 'LANG': 'en_US.UTF-8', 19:16:31 INFO - 'LANGUAGE': 'en_US:en', 19:16:31 INFO - 'LOGNAME': 'cltbld', 19:16:31 INFO - 'MAIL': '/var/mail/cltbld', 19:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:31 INFO - 'MOZ_NO_REMOTE': '1', 19:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:31 INFO - 'NO_EM_RESTART': '1', 19:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:31 INFO - 'PWD': '/builds/slave/test', 19:16:31 INFO - 'SHELL': '/bin/bash', 19:16:31 INFO - 'SHLVL': '1', 19:16:31 INFO - 'TERM': 'linux', 19:16:31 INFO - 'TMOUT': '86400', 19:16:31 INFO - 'USER': 'cltbld', 19:16:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:31 INFO - '_': '/tools/buildbot/bin/python'} 19:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:16:31 INFO - Cleaning up... 19:16:31 INFO - Return code: 0 19:16:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:31 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:16:31 INFO - Using env: {'DISPLAY': ':0', 19:16:31 INFO - 'HOME': '/home/cltbld', 19:16:31 INFO - 'LANG': 'en_US.UTF-8', 19:16:31 INFO - 'LANGUAGE': 'en_US:en', 19:16:31 INFO - 'LOGNAME': 'cltbld', 19:16:31 INFO - 'MAIL': '/var/mail/cltbld', 19:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:31 INFO - 'MOZ_NO_REMOTE': '1', 19:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:31 INFO - 'NO_EM_RESTART': '1', 19:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:31 INFO - 'PWD': '/builds/slave/test', 19:16:31 INFO - 'SHELL': '/bin/bash', 19:16:31 INFO - 'SHLVL': '1', 19:16:31 INFO - 'TERM': 'linux', 19:16:31 INFO - 'TMOUT': '86400', 19:16:31 INFO - 'USER': 'cltbld', 19:16:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:31 INFO - '_': '/tools/buildbot/bin/python'} 19:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:31 INFO - Downloading/unpacking blobuploader==1.2.4 19:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:33 INFO - Downloading blobuploader-1.2.4.tar.gz 19:16:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:16:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:16: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 19:16: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 19:16: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 19:16: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 19:16: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 19:16: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 19:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:16:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:34 INFO - Downloading docopt-0.6.1.tar.gz 19:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:16:34 INFO - Installing collected packages: blobuploader, requests, docopt 19:16:34 INFO - Running setup.py install for blobuploader 19:16:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:16:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:16:34 INFO - Running setup.py install for requests 19:16:35 INFO - Running setup.py install for docopt 19:16:35 INFO - Successfully installed blobuploader requests docopt 19:16:35 INFO - Cleaning up... 19:16:35 INFO - Return code: 0 19:16:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:35 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:16:35 INFO - Using env: {'DISPLAY': ':0', 19:16:35 INFO - 'HOME': '/home/cltbld', 19:16:35 INFO - 'LANG': 'en_US.UTF-8', 19:16:35 INFO - 'LANGUAGE': 'en_US:en', 19:16:35 INFO - 'LOGNAME': 'cltbld', 19:16:35 INFO - 'MAIL': '/var/mail/cltbld', 19:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:35 INFO - 'MOZ_NO_REMOTE': '1', 19:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:35 INFO - 'NO_EM_RESTART': '1', 19:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:35 INFO - 'PWD': '/builds/slave/test', 19:16:35 INFO - 'SHELL': '/bin/bash', 19:16:35 INFO - 'SHLVL': '1', 19:16:35 INFO - 'TERM': 'linux', 19:16:35 INFO - 'TMOUT': '86400', 19:16:35 INFO - 'USER': 'cltbld', 19:16:35 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:35 INFO - '_': '/tools/buildbot/bin/python'} 19:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:16:35 INFO - Running setup.py (path:/tmp/pip-pIM44c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:16:35 INFO - Running setup.py (path:/tmp/pip-RgPXaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:16:35 INFO - Running setup.py (path:/tmp/pip-eXbvOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:16:35 INFO - Running setup.py (path:/tmp/pip-UgmW4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:16:35 INFO - Running setup.py (path:/tmp/pip-s9Atde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:16:35 INFO - Running setup.py (path:/tmp/pip-blrn9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:16:35 INFO - Running setup.py (path:/tmp/pip-R8hpNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:16:35 INFO - Running setup.py (path:/tmp/pip-3mXOAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:16:36 INFO - Running setup.py (path:/tmp/pip-uul00N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:16:36 INFO - Running setup.py (path:/tmp/pip-NDGWPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:16:36 INFO - Running setup.py (path:/tmp/pip-s_t5l9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:16:36 INFO - Running setup.py (path:/tmp/pip-IIYFy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:16:36 INFO - Running setup.py (path:/tmp/pip-4IsDzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:16:36 INFO - Running setup.py (path:/tmp/pip-UCKFTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:36 INFO - Running setup.py (path:/tmp/pip-4XpcLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:16:36 INFO - Running setup.py (path:/tmp/pip-pIwYNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:16:36 INFO - Running setup.py (path:/tmp/pip-lofeU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:16:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:16:36 INFO - Running setup.py install for manifestparser 19:16:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:16:36 INFO - Running setup.py install for mozcrash 19:16:36 INFO - Running setup.py install for mozdebug 19:16:36 INFO - Running setup.py install for mozdevice 19:16:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for mozfile 19:16:37 INFO - Running setup.py install for mozhttpd 19:16:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for mozinfo 19:16:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for mozInstall 19:16:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for mozleak 19:16:37 INFO - Running setup.py install for mozlog 19:16:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for moznetwork 19:16:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:16:37 INFO - Running setup.py install for mozprocess 19:16:37 INFO - Running setup.py install for mozprofile 19:16:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:16:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:16:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:16:38 INFO - Running setup.py install for mozrunner 19:16:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:16:38 INFO - Running setup.py install for mozscreenshot 19:16:38 INFO - Running setup.py install for moztest 19:16:38 INFO - Running setup.py install for mozversion 19:16:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:16:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:16:38 INFO - Cleaning up... 19:16:38 INFO - Return code: 0 19:16:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:16: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')]} 19:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16: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')]} 19:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:38 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:16:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:16:38 INFO - Using env: {'DISPLAY': ':0', 19:16:38 INFO - 'HOME': '/home/cltbld', 19:16:38 INFO - 'LANG': 'en_US.UTF-8', 19:16:38 INFO - 'LANGUAGE': 'en_US:en', 19:16:38 INFO - 'LOGNAME': 'cltbld', 19:16:38 INFO - 'MAIL': '/var/mail/cltbld', 19:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:38 INFO - 'MOZ_NO_REMOTE': '1', 19:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:38 INFO - 'NO_EM_RESTART': '1', 19:16:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:38 INFO - 'PWD': '/builds/slave/test', 19:16:38 INFO - 'SHELL': '/bin/bash', 19:16:38 INFO - 'SHLVL': '1', 19:16:38 INFO - 'TERM': 'linux', 19:16:38 INFO - 'TMOUT': '86400', 19:16:38 INFO - 'USER': 'cltbld', 19:16:38 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:38 INFO - '_': '/tools/buildbot/bin/python'} 19:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:16:38 INFO - Running setup.py (path:/tmp/pip-SdsR9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:16:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:16:38 INFO - Running setup.py (path:/tmp/pip-ndQz_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:16:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:16:38 INFO - Running setup.py (path:/tmp/pip-wCJDhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:16:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:16:38 INFO - Running setup.py (path:/tmp/pip-PR60NP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:16:38 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)) 19:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:16:39 INFO - Running setup.py (path:/tmp/pip-j13OBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:16:39 INFO - Running setup.py (path:/tmp/pip-o8VD6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:16:39 INFO - Running setup.py (path:/tmp/pip-_ovzwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:16:39 INFO - Running setup.py (path:/tmp/pip-oc6Nan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:16:39 INFO - Running setup.py (path:/tmp/pip-CEdDUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:16:39 INFO - Running setup.py (path:/tmp/pip-lwMG_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:16:39 INFO - Running setup.py (path:/tmp/pip-L_cprW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:16:39 INFO - Running setup.py (path:/tmp/pip-sGad5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:16:39 INFO - Running setup.py (path:/tmp/pip-lE3EzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:16:39 INFO - Running setup.py (path:/tmp/pip-jQUOUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:39 INFO - Running setup.py (path:/tmp/pip-iVXaZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:16:39 INFO - Running setup.py (path:/tmp/pip-DBhnAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:16:39 INFO - Running setup.py (path:/tmp/pip-uhTx5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:16:39 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)) 19:16:39 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)) 19:16:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:16:41 INFO - Downloading blessings-1.6.tar.gz 19:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:16:41 INFO - Installing collected packages: blessings 19:16:41 INFO - Running setup.py install for blessings 19:16:42 INFO - Successfully installed blessings 19:16:42 INFO - Cleaning up... 19:16:42 INFO - Return code: 0 19:16:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:16:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:16:42 INFO - Reading from file tmpfile_stdout 19:16:42 INFO - Current package versions: 19:16:42 INFO - argparse == 1.2.1 19:16:42 INFO - blessings == 1.6 19:16:42 INFO - blobuploader == 1.2.4 19:16:42 INFO - docopt == 0.6.1 19:16:42 INFO - functools32 == 3.2.3-2 19:16:42 INFO - jsonschema == 2.5.1 19:16:42 INFO - manifestparser == 1.1 19:16:42 INFO - mozInstall == 1.12 19:16:42 INFO - mozcrash == 1.0 19:16:42 INFO - mozdebug == 0.1 19:16:42 INFO - mozdevice == 0.48 19:16:42 INFO - mozfile == 1.2 19:16:42 INFO - mozhttpd == 0.7 19:16:42 INFO - mozinfo == 0.9 19:16:42 INFO - mozleak == 0.1 19:16:42 INFO - mozlog == 3.4 19:16:42 INFO - moznetwork == 0.27 19:16:42 INFO - mozprocess == 0.23 19:16:42 INFO - mozprofile == 0.28 19:16:42 INFO - mozrunner == 6.13 19:16:42 INFO - mozscreenshot == 0.1 19:16:42 INFO - mozsystemmonitor == 0.3 19:16:42 INFO - moztest == 0.7 19:16:42 INFO - mozversion == 1.4 19:16:42 INFO - psutil == 3.1.1 19:16:42 INFO - requests == 1.2.3 19:16:42 INFO - wsgiref == 0.1.2 19:16:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:42 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:16:42 INFO - Using env: {'DISPLAY': ':0', 19:16:42 INFO - 'HOME': '/home/cltbld', 19:16:42 INFO - 'LANG': 'en_US.UTF-8', 19:16:42 INFO - 'LANGUAGE': 'en_US:en', 19:16:42 INFO - 'LOGNAME': 'cltbld', 19:16:42 INFO - 'MAIL': '/var/mail/cltbld', 19:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:42 INFO - 'MOZ_NO_REMOTE': '1', 19:16:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:42 INFO - 'NO_EM_RESTART': '1', 19:16:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:42 INFO - 'PWD': '/builds/slave/test', 19:16:42 INFO - 'SHELL': '/bin/bash', 19:16:42 INFO - 'SHLVL': '1', 19:16:42 INFO - 'TERM': 'linux', 19:16:42 INFO - 'TMOUT': '86400', 19:16:42 INFO - 'USER': 'cltbld', 19:16:42 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:42 INFO - '_': '/tools/buildbot/bin/python'} 19:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:16:42 INFO - Cleaning up... 19:16:42 INFO - Return code: 0 19:16:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:42 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 0x7fca826eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef1790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:16:42 INFO - Using env: {'DISPLAY': ':0', 19:16:42 INFO - 'HOME': '/home/cltbld', 19:16:42 INFO - 'LANG': 'en_US.UTF-8', 19:16:42 INFO - 'LANGUAGE': 'en_US:en', 19:16:42 INFO - 'LOGNAME': 'cltbld', 19:16:42 INFO - 'MAIL': '/var/mail/cltbld', 19:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:42 INFO - 'MOZ_NO_REMOTE': '1', 19:16:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:42 INFO - 'NO_EM_RESTART': '1', 19:16:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:42 INFO - 'PWD': '/builds/slave/test', 19:16:42 INFO - 'SHELL': '/bin/bash', 19:16:42 INFO - 'SHLVL': '1', 19:16:42 INFO - 'TERM': 'linux', 19:16:42 INFO - 'TMOUT': '86400', 19:16:42 INFO - 'USER': 'cltbld', 19:16:42 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:42 INFO - '_': '/tools/buildbot/bin/python'} 19:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:16:42 INFO - Cleaning up... 19:16:42 INFO - Return code: 0 19:16:42 INFO - Running post-action listener: _resource_record_post_action 19:16:42 INFO - Running post-action listener: _start_resource_monitoring 19:16:42 INFO - Starting resource monitoring. 19:16:42 INFO - [mozharness: 2016-11-16 03:16:42.845387Z] Finished create-virtualenv step (success) 19:16:42 INFO - [mozharness: 2016-11-16 03:16:42.845959Z] Running install step. 19:16:42 INFO - Running pre-action listener: _resource_record_pre_action 19:16:42 INFO - Running main action method: install 19:16:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:16:43 INFO - Reading from file tmpfile_stdout 19:16:43 INFO - Detecting whether we're running mozinstall >=1.0... 19:16:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:16:43 INFO - Reading from file tmpfile_stdout 19:16:43 INFO - Output received: 19:16:43 INFO - Usage: mozinstall [options] installer 19:16:43 INFO - Options: 19:16:43 INFO - -h, --help show this help message and exit 19:16:43 INFO - -d DEST, --destination=DEST 19:16:43 INFO - Directory to install application into. [default: 19:16:43 INFO - "/builds/slave/test"] 19:16:43 INFO - --app=APP Application being installed. [default: firefox] 19:16:43 INFO - mkdir: /builds/slave/test/build/application 19:16:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:17:00 INFO - Reading from file tmpfile_stdout 19:17:00 INFO - Output received: 19:17:00 INFO - /builds/slave/test/build/application/firefox/firefox 19:17:00 INFO - Running post-action listener: _resource_record_post_action 19:17:00 INFO - [mozharness: 2016-11-16 03:17:00.044553Z] Finished install step (success) 19:17:00 INFO - [mozharness: 2016-11-16 03:17:00.044795Z] Running run-tests step. 19:17:00 INFO - Running pre-action listener: _resource_record_pre_action 19:17:00 INFO - Running main action method: run_tests 19:17:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:17:00 INFO - Python 2.7.3 19:17:00 INFO - Return code: 0 19:17:00 INFO - grabbing minidump binary from tooltool 19:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:00 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 0x1eee930>, '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 0x1eef830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eefcc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:17:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:17:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:17:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:17:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:17:00 INFO - Return code: 0 19:17:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:17:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:17:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:17:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:17:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:17:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:17:00 INFO - Using env: {'DISPLAY': ':0', 19:17:00 INFO - 'HOME': '/home/cltbld', 19:17:00 INFO - 'LANG': 'en_US.UTF-8', 19:17:00 INFO - 'LANGUAGE': 'en_US:en', 19:17:00 INFO - 'LOGNAME': 'cltbld', 19:17:00 INFO - 'MAIL': '/var/mail/cltbld', 19:17:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:17:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:00 INFO - 'MOZ_NO_REMOTE': '1', 19:17:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:17:00 INFO - 'NO_EM_RESTART': '1', 19:17:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:00 INFO - 'PWD': '/builds/slave/test', 19:17:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:17:00 INFO - 'SHELL': '/bin/bash', 19:17:00 INFO - 'SHLVL': '1', 19:17:00 INFO - 'TERM': 'linux', 19:17:00 INFO - 'TMOUT': '86400', 19:17:00 INFO - 'USER': 'cltbld', 19:17:00 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795', 19:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:00 INFO - '_': '/tools/buildbot/bin/python'} 19:17:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:17:00 INFO - mozversion application_buildid: 20161115163015 19:17:00 INFO - mozversion application_changeset: 79feeed4293336089590320a9f30a813fade8e3c 19:17:00 INFO - mozversion application_display_name: Nightly 19:17:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:17:00 INFO - mozversion application_name: Firefox 19:17:00 INFO - mozversion application_remotingname: firefox 19:17:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:17:00 INFO - mozversion application_vendor: Mozilla 19:17:00 INFO - mozversion application_version: 53.0a1 19:17:00 INFO - mozversion platform_buildid: 20161115163015 19:17:00 INFO - mozversion platform_changeset: 79feeed4293336089590320a9f30a813fade8e3c 19:17:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:17:00 INFO - mozversion platform_version: 53.0a1 19:17:00 INFO - using testdate: 1479266220 19:17:00 INFO - actual date: 1479266220 19:17:00 INFO - starting webserver on 'localhost:50038' 19:17:00 INFO - SUITE-START | Running 2 tests 19:17:00 INFO - TEST-START | dromaeo_css 19:17:00 INFO - operating with platform_type : linux_ 19:17:00 INFO - Initialising browser for dromaeo_css test... 19:17:00 INFO - TEST-INFO | started process 1748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy9QGMt/profile http://localhost:50038/getInfo.html) 19:17:04 INFO - PROCESS | 1748 | __metrics Screen width/height:1600/1200 19:17:04 INFO - PROCESS | 1748 | colorDepth:24 19:17:04 INFO - PROCESS | 1748 | Browser inner width/height: 1024/697 19:17:04 INFO - PROCESS | 1748 | __metrics 19:17:05 INFO - PROCESS | 1748 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:17:05 INFO - PROCESS | 1748 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:17:05 INFO - PROCESS | 1748 | console.error: PushService: 19:17:05 INFO - PROCESS | 1748 | stateChangeProcessEnqueue: Error transitioning state 19:17:05 INFO - PROCESS | 1748 | UnknownError 19:17:06 INFO - TEST-INFO | 1748: exit 0 19:17:06 INFO - Browser initialized. 19:17:06 INFO - Running cycle 1/1 for dromaeo_css test... 19:17:06 INFO - TEST-INFO | started process 1835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy9QGMt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:17:18 INFO - PROCESS | 1835 | RSS: Main: 173281280 19:17:18 INFO - PROCESS | 1835 | 19:18:10 INFO - PROCESS | 1835 | 1479266290100 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:20:20 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:50038/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50038/tests/dromaeo/cssquery-ext.html) 19:20:22 INFO - PROCESS | 1835 | RSS: Main: 396566528 19:20:22 INFO - PROCESS | 1835 | 19:23:25 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:50038/tests/dromaeo/cssquery-ext.html (next: http://localhost:50038/tests/dromaeo/cssquery-jquery.html) 19:23:26 INFO - PROCESS | 1835 | RSS: Main: 282169344 19:23:26 INFO - PROCESS | 1835 | 19:26:28 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:50038/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50038/tests/dromaeo/cssquery-mootools.html) 19:26:28 INFO - PROCESS | 1835 | RSS: Main: 256905216 19:26:28 INFO - PROCESS | 1835 | 19:29:30 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:50038/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50038/tests/dromaeo/cssquery-prototype.html) 19:29:31 INFO - PROCESS | 1835 | RSS: Main: 281874432 19:29:31 INFO - PROCESS | 1835 | 19:32:34 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:50038/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50038/tests/dromaeo/cssquery-yui.html) 19:32:34 INFO - PROCESS | 1835 | RSS: Main: 220680192 19:32:34 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:50038/tests/dromaeo/cssquery-yui.html (next: http://localhost:50038/tests/dromaeo/cssquery-dojo.html) 19:35:38 INFO - PROCESS | 1835 | RSS: Main: 314290176 19:35:38 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | __start_tp_report 19:35:38 INFO - PROCESS | 1835 | _x_x_mozilla_page_load 19:35:38 INFO - PROCESS | 1835 | _x_x_mozilla_page_load_details 19:35:38 INFO - PROCESS | 1835 | |i|pagename|runs| 19:35:38 INFO - PROCESS | 1835 | |0;dojo.html;2965.4217427601616;3022.1235841605935;3044.3454657248685;3051.130427828068;3068.8465576721173;5167.354080739908;5242.108841496945;5325.627206095575;5379.139337705968;5398.109311963526;5246.947530524693;5252.448492908247;5254.4220135785035;5336.946630533694;5384.892302153955;4802.97598512007;4901.950980490185;4914.213725803863;4931.901361972758;4944.728039957801;4821.919439510173;4850.854474365757;4863.343448634442;4867.196912509432;4896.094222568819;4318.481782186126;4873.585745211643;4958.355413796219;4961.231009193581;4990.351254336932;4676.579107880307;4856.902861942776;4887.633427492917;4891.951080489203;4924.827631032933;4711.481737008926;4743.596794272491;4775.474697783241;4780.737059461728;4786.234202527578;5258.55302299305;5308.203769434615;5336.91994620081;5337.439568845254;5398.325209348832;5699.173619825102;5711.114777209539;5711.657300561938;5713.74288157034;5752.338481074688;5422.511973922326;5436.565074794046;5445.210444485528;5510.0632892408385;5740.052891272977;5193.63644544882;5196.0387328343995;5202.375714914158;5202.427732949334;5203.479652034766;5581.6371935824045;5635.267415236075;5643.604947653625;5646.632968857011;5649.576281778883;5409.56723462122;5439.700816455053;5454.59090568209;5460.6177567569885;5461.2354270402175;5509.228707980886;5512.972435137879;5513.035218836688;5517.917231241535;5537.1140617501815;5464.808731694451;5473;5475;5481.945180548223;5482.80810171642;5494.5054945055135;5496.340439147248;5588.832335029955;5598.1602759586385;5603.663780173202;4309.61213490781;4387.670924680661;4393.319035549495;4402;4434;3793.013816407754;3827.78947157909;3896.551896531935;3918.2555314490155;3992.001999500125;5187.844364669066;5268.15709486488;5279.313689220454;5306.734663266821;5313.203019547099;5642.26650535436;5642.322921249476;5649.68926709035;5669.262995810601;5683.573731970118;5150.97424512875;5208.557272631793;5293.814716484904;5308.708021058879;5348.144296912553;4803.327534145223;4808.831690890802;4812.5668689818085;4867.245576935581;4914.606831453475;5882.470577648032;5883.558733095035;5888.587798854125;5896.469317761422;5902.911456328158;5516.117421212587;5664.291963504562;5669.744861481326;5671.773129074791;5673.120666296565;5741.13882917549;5876.236089308362;5885.55858310637;5899.115132729953;5968.671723055274;5548.278723765885;5583.553315734813;5593.552515798808;5597.776088956396;5604.355499117653;5673.8297851064535;5705.942940570541;5707.258056452634;5716.942830571641;5721;5363.115086010765;5378.542823859859;5384.6499977501335;5450.563954883679;5453.209284653783;5425.945740542544;5438.755256013568;5442.183672449164;5443.646162999393;5445.183222516495;5432.348118225838;5437;5442.9455705444025;5443.7822487100075;5462.317210348707;5460.180972853945;5510.090835012178;5522.0336441121835;5526.613137080366;5528.44715528444;5374.408815030265;5390.353157621111;5407.756650950795;5414.918776218281;5507.807226747044 19:35:38 INFO - PROCESS | 1835 | |1;ext.html;590.6987436407433;606.4451027310012;625.4808508937582;644.6067898581862;648.8702259548091;32171.67828321716;34599.30801383974;34636.48045279323;34662.48006279905;41805;42899.78550107253;44263.77868110655;44388.77805610967;44429.33355999654;44430.33354499685;8533.232009119194;8680.348973826956;8689.04420513743;8696.21734043936;8696.39125261232;8254.793630159264;8344.165583441669;8346.749597512084;8350.079481719209;8351.164883511661;8340.416170868042;8397.370197235232;8399.580020998956;8402.201790829862;8406;8250.876236856451;8264.752057438287;8279.917200827975;8335.499870007789;8365.163483651617;8461.873071903927;8487.575621218914;8526.829463410697;8531.616077276476;8535.743927682179;40596.59403405958;41229;41296.58703412987;41352.34981528397;41355.1728965419;3358.126887009395;3404.914877128067;3492.388831954423;3590.892273231781;3752.4558938953974;31087.22281942947;31098.689013109804;31148;31992.360152796813;32654.836725816458;4077.111449987329;4177.289860723684;4202.642775364097;4243.108947121108;4251.341042138442;248993.9999999982;249910;250491;251448;252042;57971.710141449024;58036;58315.708421457624;58332.4166758327;58347;36301.818490907375;36983;61339;61356.39827012902;61506;16920.57698557546;17366.3053477862;17382.478525644252;17383.130843457777;17499.737503937453;13258.668533286746;13342;13344.866551334362;13345.266010369329;13351.799723004162;18979.671423000535;19127.52181195481;19169.74114400555;19173.712394314098;19189.232430702894;6995.1955525114245;7009.12385951756;7119.3592576667315;7120.362316667196;7177.533460325062;897.3942588752566;966.7341481092615;982.6609819612222;1061.379093230469;1100.7853468573712;31638.367232655677;31884.202894927814;31959.840200798848;32392.35215295728;32573.837130814194;907.1654078248027;1097.9011888930036;1138.299945533491;1155.6301983365242;1244.520859469111;16013.839861601467;16586.419475318307;16588.419405320758;16643.33426662944;17104.401345952832;537503;541026;541979;542381;544381;56611.15083273754;57176.42823571711;57196;57215.427845721846;57367.71316143392;1227.2329793878826;1277.1506947879557;1309.9541516047084;1338.4846833968993;1374.8350197976106;30032.914690293506;32137.678623214404;32369.838150809097;32447.513287300237;32611.0866695998;48260.517394825605;48815.75592122017;48828.755856220494;48913;48958.75520622374;379842;380341;381160;381884;383878;27244.31889202786;27824.16527504178;28556;28580.85709571439;29866.10401687953;45252.32121518114;45493.54506454893;45527;46320.305195421424;46479;38358.415585191724;38432;39399.606003939596;39934.600653994254;40274.59725402826;38598.6140138595;38655.2941294118;38926.4161037579;39556.40665390079;40421.59578404178;39892.60107398889;40400;40631.593684062784;40641.1871762569;40856.182876341714;33860.3227935435;34647;35326.293474130885;35663.64336356707;35664.465033024004;26905;27096.32259193536;27184.72815271822;27194.456110877276;27415.45169096567 19:35:38 INFO - PROCESS | 1835 | |2;jquery.html;376.3470378892621;379.49716625471257;396.5756640394777;406.27074401449397;412.17770547757226;13235.861072747952;15470.226488675564;16643.833561664378;18910.338138165167;19381.32165374212;18401.53996150097;18648.81351186488;18661.626767464677;19230.711539326887;19278.13248403822;5438.477226376621;5679.261695979517;5689.6017278790505;5691.117876729114;5696.658200507962;5328.893422131555;5544.140658197985;5577.079781835992;5580.218769372271;5580.832575022756;5561.916571251414;5565.888682226353;5565.888682226353;5579.246801681762;5579.442055794409;5444.40111587727;5667.0932650775885;5699.715014249292;5763.366029736726;5775.104858746901;5439.755211015514;5517.420670829545;5572.359178694421;5583.3858275589655;5653.406392328788;14066.437342506288;14313.212773297464;14314.92842535791;14321.283935803272;19411;2229.4092065602626;2230.6877037214804;2310.514791893704;2479.5536803375385;2738.219607411898;9974.900250997542;10008.79982400348;10139.7465063374;10427.582896684124;10660.20048496366;1836;1873.260062275407;1889.9055047247582;1925.1529327096173;1956.0317642766922;80570;83038.16961830365;83378.58310708408;83553.58223208906;84590.99999999939;10867.29362591429;11016.50425730828;11025.944870275758;11138.610148644759;11319.849228646532;15413.845861541242;15441.459548915735;15491.690166196613;15604.29780659873;15608.53174404747;7856.842863142705;7949.244821741925;8062.355011599058;8065.39509536787;8192.18078192176;7377.741779037708;7416.184219735825;7428.814279643052;7590.734324298623;7612.581308028111;4687.789049492712;4727.747147006046;4744.4069491313585;4772.570468657837;4854.466008739036;1941.175000624729;1946.776120746104;1969.4091772468203;1999.0904138616895;2011.869228500172;845.2984023260678;939.3377668743519;966.6471737815647;982.6069572171189;1023.8361862102028;17921.641567168583;18595.25618975253;18824.623507529774;18959.263811036806;19012.429627111207;942.4109931292942;981.3572110267786;982.8525721141885;986.8914419413859;988.8467287570438;9787.36382135159;10324.690259292232;10348.223883208639;10450.68647940563;10529.631462898762;434694;435166;438654;450899;472019;85795.57102214574;86460.56769716111;86996.90557246804;89119.55440222757;90143.54928225317;959.4147569982305;979.6816034788723;1006.8036732837027;1007.1137399088755;1016.9796604067877;15751.291191896622;16321.347146114498;16510.33958641641;16593.78607589251;16686.833131669013;39137.41293880651;39222;39641.40537891992;40009.79995100122;40831.795841020605;54035.45964540304;55548.44451555433;55641.16538252011;56649.71675141598;56677;8294.792630184294;8353.20644538768;8409.705660301861;8432.957835210786;8743.939621132553;11090.445477726244;13065.869341306465;13126.278054707083;13268.867311327149;13324.467021319426;11278.37968911685;11375.20373573842;11602.245854019462;11695.356755378536;11886.227395219283;11066.336019838835;11293.661190164308;11478.540858365906;11513.712157196138;11788.646340609796;11503;11665.416729163677;11695.590654327058;11852.822207666719;11997.520099195934;8634.870476942726;8760.824783504422;8850.601722922613;11131.721706957067;11154.442277886235;11306.321620702784;11837.526498939946;11956.820647690465;12081.939590301992;12093.213941093802 19:35:38 INFO - PROCESS | 1835 | |3;mootools.html;1305.47780887645;1470.7058588282339;1483.8367779544249;1589.6741168060541;1739.1912760566333;7080.752173673916;7917.564533950637;8266.421350505467;8272.296854767344;8482.151784821515;8252.422330436872;8336.249737523622;8336.91663083369;8349.749507514784;8362.247397734203;2312.283192210416;2338.1816364272454;2340.4265954841003;2341.7424083350816;2345.4253707841604;2271.6819645249598;2311.8612883226974;2325.163121134302;2329.3943574670625;2330.3940975346445;2248.8425810193294;2268.353519247015;2271.239134889814;2276.6698828669914;2289.8053665438456;2153.859999100069;2166.306781829815;2183.9344819655435;2185.0385830234645;2185.1914791527156;2200.8019278264874;2219.3341997400717;2219.933401997943;2221.3335999200335;2227.610168220555;8106.067802202763;8124;8271.793205169859;8274.213949674771;8281.130481299497;5016.322796422509;5229.764660590283;5233.267342572043;5237.79048838046;5245.580353571705;3319.535265062893;3352.2625022495013;3357.9664203357897;3408.897733068012;3420.794752314869;1075.4300220882944;1101.0750969185922;1104.0735652251556;1122.4668282565797;1133.8696049954278;11179.44102794857;11232.887671123266;11235.494402751896;11256.606018789385;11349.319040857576;4015.15681706839;4153.335466325373;4284.528701842794;4293.549177336397;4322.789618906649;8784.253338466302;8795.164459376349;8796.692115775917;8799.692010779592;8802.735917922473;4959.70241785494;5013.39839219296;5018.974905125451;5020.1214787412055;5023.296738456619;3875.670568001695;4002.1795531915886;4062.7968601569805;4182.916341673211;4711.976440117777;3511.9122021949656;3548.6451354864307;3574.3208790330273;3658.34149853029;3666.3034023535442;1103.6302838549177;1113.7494063835734;1149.385078982757;1164.3130552973787;1167.2471256039976;859.7678626770737;890.0432035561797;894.9910500894907;896.2158111652304;935.9672411465566;5602.439756024446;5610.663360198401;5712.371639119693;5735.684537350486;5735.741891614887;942.6559305853314;949.990500095004;953.2231231546267;981.0777868803444;995.4226548601794;3777.0934975606206;3779.905502362463;3921.9607803922167;4026.5772093929713;4042.514898212233;9853.704388868346;10090.09189172978;10197.082262596401;10198.694039178688;10259.538320775582;10110.595576177018;10213.693589192337;10231.386116832866;10232.079112879728;10234.48827558619;7598.20218877021;7745.341645960157;7864;7868.606569671608;7872.409569282327;5131.973605278885;5595.664260144405;5611.158326251095;5721.799737009184;5747.655140691572;9053.275737940818;9096.772580685536;9271.119243671841;9368.57841397125;9420.293477989042;9291;9425.104615061558;9484.05159484046;9591.232701383733;9709.902900971183;6944.583325000516;6980.965095174492;7060.8940865886025;7078.362947334765;7078.610676412847;7858.13560508355;7890.802729931797;7912.960435197787;7935.047794264494;7945.006874140732;6899.586024838526;7027.016217729624;7052.33002864727;7056.188538318159;7248.818779530343;7026.543274687129;7141.928580714334;7142.357187853118;7146.857062858818;7171.03191069199;6094.421030002142;6272.059191121369;6289.088082228142;6434.260060093149;6685.39831415175;4949.925751113664;4988.326575912205;5052.823151189691;5117.283580298836;5129.8717532062;4942.654014219114;4950.603951683928;4971.204607262821;5071.467495912876;5230.738463076755 19:35:38 INFO - PROCESS | 1835 | |4;prototype.html;311.85342888842234;330.10212222754103;333.7430178762353;339.123366098635;342.2709628491313;5029.9125862767;5099.413567439746;5134.820281290155;5138.588912886969;5187.507186817251;5095.541401273885;5106.131957567213;5118.309028281181;5124.48900038981;5165.656929198407;4022.8793136205813;4078.3882417637296;4083.4691490106243;4086.856960006408;4095.9180816383655;3862.1503269280715;4055.8986025349454;4056.8782936511952;4103.302438585448;4112.485939257593;3990.5410877748996;4016.2369149861433;4023.6177563131455;4044.170944956292;4045.37296719009;3974.3044967130636;3985.7608543487486;3985.80070996449;3997.4003899415175;4034.798260086984;3549.0594992327055;3556.5554305711785;3584.426491761306;3589.94615080774;3596.766210196355;4801.207800712878;4964.751762411865;4972.980538989498;5035.395752509686;5060.595152387837;4561.247394179956;4599.2871104978785;4601.769911504445;4604.756715686779;4614.884627884297;5332.866678333034;5333.653312534673;5338.195623938448;5353.464653534655;5354.759035843396;3692.4276737105793;3828.980855095707;3874.8643797467234;3888.5528164260904;3925.9585015791627;5235.973820130875;5430.891382172374;5468.835934921919;5517.613767036309;5517.641353312062;3823.9426408603895;3890.630390112935;3902.1415288636454;3930.862419815293;3939.3893946438347;3769.359208934488;3873.2640798248244;3983.152046286919;3986.60133986599;3987.6809855211513;4477.776111194427;4549.795259213343;4552.203364411214;4552.408186935677;4564.566366195206;5331.81338653153;5333;5388.407275199725;5390.975714614211;5406.91889621656;1363.8295213098363;1385.6882201504782;1388.673661689502;1394.9721005579831;1399.8810101141314;935.9391639543408;946.2666433514082;947.966821161256;954.8758661373977;958.5925981457852;4975.800967961241;5007.3240112584335;5031.22016087507;5032.874178145576;5064.696118232918;4973.477784832613;5293.470652934753;5300.04599172145;5332.760025798849;5377.865553361197;4920.384951881015;4945.505449455026;4951.207806750903;4979.6265280104135;5007.424146223231;2782.693903670635;2786.6902555798742;2793.2458236276498;2827.1094605199296;2829.2458675621247;5455.263539422207;5498.862528436821;5712.6286791358425;5731.512821410143;5741.569382296345;5617.971910140505;5626.803061892896;5645.5201307888465;5649.576281778883;5657.858553536194;5375.973120134374;5723.399043100498;5745.913811292834;5771.480566749013;5775.740091695884;5449.3188351456065;5502.752376143003;5510.126556970489;5520.089185284383;5524;5205.969671827915;5303.284056652303;5355.384130825005;5462.672239665474;5464.153056276284;5692.914606280826;5697.57268204878;5704.235204765001;5712.37163911961;5727.7422515986045;3997.2804895117993;3998.7800670963375;4012.81621921528;4013.7190396671954;4022.1352409232154;5294.576433885203;5510.641808282448;5545;5560.499555040065;5570.554355651457;5380.381256155592;5393.6224464287125;5394.217838413487;5400.216968539618;5404;5147.279380886754;5420.593455490775;5425.131978883517;5430.70131142791;5439.184122381675;5340.626156169031;5358.839234822962;5375.5162035417;5389.784408623611;5392.325959255093;5400.594955378284;5406.216098665593;5407.324084489439;5407.891842163056;5424.403315635356;5084.872878177927;5228.424873263862;5248.475152484721;5279.551238144801;5311.353480421082 19:35:38 INFO - PROCESS | 1835 | |5;yui.html;729.4820677319104;737.5722081192912;750.7522517569203;792.342355844649;846.3694547562067;13856.376463059161;14611.926940365296;15140.318685659144;15150;15173.772393414107;12423.813642795365;12437.875621243786;12455.750884982295;12533.435995380207;12533.435995380207;765.9310662040415;768.0361146760807;788.9842203155933;795.3597354129923;797.5015615240475;648.4034688086971;669.9832504187385;755.5240198674827;784.9215078492163;788.692409960116;827.9668813247494;832.0223737108903;832.9625166867505;833.9249467547919;834.0783434305097;827.1273806134526;828.0973738624867;828.3994104274353;828.4780588229437;828.7762304177868;505.71174430574587;524.0200824458287;606.4935778624854;606.754264522869;607.1075518987082;13721.519746808814;14184.787228191586;14257.14457132565;14648.12111273327;14662.046966947115;12859.549915752998;13064.608061758163;13086.541971031063;13153.408096635674;13435.798463022964;10920.781584368347;10959.342439453578;10963.50664220112;11033;11114.944080312358;3555.377808883461;3570.785752854837;3603.819809009539;3648.9817550912076;3745.4569087482437;59272;59759.70120149371;59828.40171598316;60067;60240.698796505734;10318.76765801114;10511.632092876713;10522.31604945676;10588.311759735747;10796.190285728602;19405.32081377145;19451.708224376645;19550.8044919549;19577.608447830964;19695.50761230952;7749.263819937097;7776.688932442752;7927.445078844426;7930.960345198352;8021.799455013671;4137.379393091061;4327.935080973788;4328.826846926151;4374.081442897027;4428.047969686533;3241.638511825039;3335.4329763940186;3345.1971526833872;3359.882404115844;3383;593.44809327326;599.3526990849872;603.8218725475995;606.6663335165641;617.8980468222783;687.9208890977602;701.9964900175466;710.9573425594481;711.1466240511304;711.9110111236096;8446.282066024456;8708;9310.813783724423;9385.62457501706;9513.334066615409;647.5531883000715;670.6780745242311;704.5314865614309;709.2942522190453;728.5118970289813;4440.866773996785;4505.526919673387;4735.573798358164;4770.358549842944;4814.422269327633;82065.58967205245;85711.57144214239;86238.56880715556;87588;89442.55278723694;46654.76672616683;47486.5251347489;75290.62354688192;78051.60974195093;78539;43078.78460607677;43757.56242437599;44949.55050449454;45219.54780452219;54298.45701543092;8682.590201969315;8843.778905527413;8936.86594701067;9140.817183656423;9505.714828555154;33928.97848343424;45282;45899;47445;47592.048159035934;50449;60652.39347606467;65893.67053164703;66248.66875665591;66750.66624666845;11355.943220283845;11443.48504317291;11553.653390398302;11718.765624687629;11816.468258928197;13891.936164404042;13980.510682126076;13996;14152;14170.787438188228;12535.490916310713;12823.871761282267;13482.393292302066;13538.458461661423;14069;12772.361381930681;13014.15407998477;13638.522651707142;13732.244726540137;14016.579502614937;11859.58491452795;11871.406429678309;11993.760124797629;12101.273923564615;12789.808152877527;10780.784384312426;10859.619913302997;11007.889921100686;11386.146039046938;11568.305901645592;10415.94792026035;10622.681319560426;10798;10842;11002.50488728025 19:35:38 INFO - PROCESS | 1835 | __end_tp_report 19:35:38 INFO - PROCESS | 1835 | __start_cc_report 19:35:38 INFO - PROCESS | 1835 | _x_x_mozilla_cycle_collect,2236 19:35:38 INFO - PROCESS | 1835 | __end_cc_report 19:35:38 INFO - PROCESS | 1835 | __startTimestamp1479267338270__endTimestamp 19:35:38 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | ------- Summary: start ------- 19:35:38 INFO - PROCESS | 1835 | Number of tests: 6 19:35:38 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | [#0] dojo.html Cycles:165 Average:5224.58 Median:5414.92 stddev:579.48 (10.7%) stddev-sans-first:553.49 19:35:38 INFO - PROCESS | 1835 | Values: 2965.4 3022.1 3044.3 3051.1 3068.8 5167.4 5242.1 5325.6 5379.1 5398.1 5246.9 5252.4 5254.4 5336.9 5384.9 4803.0 4902.0 4914.2 4931.9 4944.7 4821.9 4850.9 4863.3 4867.2 4896.1 4318.5 4873.6 4958.4 4961.2 4990.4 4676.6 4856.9 4887.6 4892.0 4924.8 4711.5 4743.6 4775.5 4780.7 4786.2 5258.6 5308.2 5336.9 5337.4 5398.3 5699.2 5711.1 5711.7 5713.7 5752.3 5422.5 5436.6 5445.2 5510.1 5740.1 5193.6 5196.0 5202.4 5202.4 5203.5 5581.6 5635.3 5643.6 5646.6 5649.6 5409.6 5439.7 5454.6 5460.6 5461.2 5509.2 5513.0 5513.0 5517.9 5537.1 5464.8 5473.0 5475.0 5481.9 5482.8 5494.5 5496.3 5588.8 5598.2 5603.7 4309.6 4387.7 4393.3 4402.0 4434.0 3793.0 3827.8 3896.6 3918.3 3992.0 5187.8 5268.2 5279.3 5306.7 5313.2 5642.3 5642.3 5649.7 5669.3 5683.6 5151.0 5208.6 5293.8 5308.7 5348.1 4803.3 4808.8 4812.6 4867.2 4914.6 5882.5 5883.6 5888.6 5896.5 5902.9 5516.1 5664.3 5669.7 5671.8 5673.1 5741.1 5876.2 5885.6 5899.1 5968.7 5548.3 5583.6 5593.6 5597.8 5604.4 5673.8 5705.9 5707.3 5716.9 5721.0 5363.1 5378.5 5384.6 5450.6 5453.2 5425.9 5438.8 5442.2 5443.6 5445.2 5432.3 5437.0 5442.9 5443.8 5462.3 5460.2 5510.1 5522.0 5526.6 5528.4 5374.4 5390.4 5407.8 5414.9 5507.8 19:35:38 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | [#1] ext.html Cycles:180 Average:55228.81 Median:40104.60 stddev:108646.58 (270.9%) stddev-sans-first:108873.91 19:35:38 INFO - PROCESS | 1835 | Values: 590.7 606.4 625.5 644.6 648.9 32171.7 34599.3 34636.5 34662.5 41805.0 42899.8 44263.8 44388.8 44429.3 44430.3 8533.2 8680.3 8689.0 8696.2 8696.4 8254.8 8344.2 8346.7 8350.1 8351.2 8340.4 8397.4 8399.6 8402.2 8406.0 8250.9 8264.8 8279.9 8335.5 8365.2 8461.9 8487.6 8526.8 8531.6 8535.7 40596.6 41229.0 41296.6 41352.3 41355.2 3358.1 3404.9 3492.4 3590.9 3752.5 31087.2 31098.7 31148.0 31992.4 32654.8 4077.1 4177.3 4202.6 4243.1 4251.3 248994.0 249910.0 250491.0 251448.0 252042.0 57971.7 58036.0 58315.7 58332.4 58347.0 36301.8 36983.0 61339.0 61356.4 61506.0 16920.6 17366.3 17382.5 17383.1 17499.7 13258.7 13342.0 13344.9 13345.3 13351.8 18979.7 19127.5 19169.7 19173.7 19189.2 6995.2 7009.1 7119.4 7120.4 7177.5 897.4 966.7 982.7 1061.4 1100.8 31638.4 31884.2 31959.8 32392.4 32573.8 907.2 1097.9 1138.3 1155.6 1244.5 16013.8 16586.4 16588.4 16643.3 17104.4 537503.0 541026.0 541979.0 542381.0 544381.0 56611.2 57176.4 57196.0 57215.4 57367.7 1227.2 1277.2 1310.0 1338.5 1374.8 30032.9 32137.7 32369.8 32447.5 32611.1 48260.5 48815.8 48828.8 48913.0 48958.8 379842.0 380341.0 381160.0 381884.0 383878.0 27244.3 27824.2 28556.0 28580.9 29866.1 45252.3 45493.5 45527.0 46320.3 46479.0 38358.4 38432.0 39399.6 39934.6 40274.6 38598.6 38655.3 38926.4 39556.4 40421.6 39892.6 40400.0 40631.6 40641.2 40856.2 33860.3 34647.0 35326.3 35663.6 35664.5 26905.0 27096.3 27184.7 27194.5 27415.5 19:35:38 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | [#2] jquery.html Cycles:180 Average:27245.35 Median:19758.46 stddev:73837.95 (373.7%) stddev-sans-first:74017.53 19:35:38 INFO - PROCESS | 1835 | Values: 376.3 379.5 396.6 406.3 412.2 13235.9 15470.2 16643.8 18910.3 19381.3 18401.5 18648.8 18661.6 19230.7 19278.1 5438.5 5679.3 5689.6 5691.1 5696.7 5328.9 5544.1 5577.1 5580.2 5580.8 5561.9 5565.9 5565.9 5579.2 5579.4 5444.4 5667.1 5699.7 5763.4 5775.1 5439.8 5517.4 5572.4 5583.4 5653.4 14066.4 14313.2 14314.9 14321.3 19411.0 2229.4 2230.7 2310.5 2479.6 2738.2 9974.9 10008.8 10139.7 10427.6 10660.2 1836.0 1873.3 1889.9 1925.2 1956.0 80570.0 83038.2 83378.6 83553.6 84591.0 10867.3 11016.5 11025.9 11138.6 11319.8 15413.8 15441.5 15491.7 15604.3 15608.5 7856.8 7949.2 8062.4 8065.4 8192.2 7377.7 7416.2 7428.8 7590.7 7612.6 4687.8 4727.7 4744.4 4772.6 4854.5 1941.2 1946.8 1969.4 1999.1 2011.9 845.3 939.3 966.6 982.6 1023.8 17921.6 18595.3 18824.6 18959.3 19012.4 942.4 981.4 982.9 986.9 988.8 9787.4 10324.7 10348.2 10450.7 10529.6 434694.0 435166.0 438654.0 450899.0 472019.0 85795.6 86460.6 86996.9 89119.6 90143.5 959.4 979.7 1006.8 1007.1 1017.0 15751.3 16321.3 16510.3 16593.8 16686.8 39137.4 39222.0 39641.4 40009.8 40831.8 54035.5 55548.4 55641.2 56649.7 56677.0 8294.8 8353.2 8409.7 8433.0 8743.9 11090.4 13065.9 13126.3 13268.9 13324.5 11278.4 11375.2 11602.2 11695.4 11886.2 11066.3 11293.7 11478.5 11513.7 11788.6 11503.0 11665.4 11695.6 11852.8 11997.5 8634.9 8760.8 8850.6 11131.7 11154.4 11306.3 11837.5 11956.8 12081.9 12093.2 19:35:38 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | [#3] mootools.html Cycles:180 Average:5406.50 Median:5062.15 stddev:2998.07 (59.2%) stddev-sans-first:2990.64 19:35:38 INFO - PROCESS | 1835 | Values: 1305.5 1470.7 1483.8 1589.7 1739.2 7080.8 7917.6 8266.4 8272.3 8482.2 8252.4 8336.2 8336.9 8349.7 8362.2 2312.3 2338.2 2340.4 2341.7 2345.4 2271.7 2311.9 2325.2 2329.4 2330.4 2248.8 2268.4 2271.2 2276.7 2289.8 2153.9 2166.3 2183.9 2185.0 2185.2 2200.8 2219.3 2219.9 2221.3 2227.6 8106.1 8124.0 8271.8 8274.2 8281.1 5016.3 5229.8 5233.3 5237.8 5245.6 3319.5 3352.3 3358.0 3408.9 3420.8 1075.4 1101.1 1104.1 1122.5 1133.9 11179.4 11232.9 11235.5 11256.6 11349.3 4015.2 4153.3 4284.5 4293.5 4322.8 8784.3 8795.2 8796.7 8799.7 8802.7 4959.7 5013.4 5019.0 5020.1 5023.3 3875.7 4002.2 4062.8 4182.9 4712.0 3511.9 3548.6 3574.3 3658.3 3666.3 1103.6 1113.7 1149.4 1164.3 1167.2 859.8 890.0 895.0 896.2 936.0 5602.4 5610.7 5712.4 5735.7 5735.7 942.7 950.0 953.2 981.1 995.4 3777.1 3779.9 3922.0 4026.6 4042.5 9853.7 10090.1 10197.1 10198.7 10259.5 10110.6 10213.7 10231.4 10232.1 10234.5 7598.2 7745.3 7864.0 7868.6 7872.4 5132.0 5595.7 5611.2 5721.8 5747.7 9053.3 9096.8 9271.1 9368.6 9420.3 9291.0 9425.1 9484.1 9591.2 9709.9 6944.6 6981.0 7060.9 7078.4 7078.6 7858.1 7890.8 7913.0 7935.0 7945.0 6899.6 7027.0 7052.3 7056.2 7248.8 7026.5 7141.9 7142.4 7146.9 7171.0 6094.4 6272.1 6289.1 6434.3 6685.4 4949.9 4988.3 5052.8 5117.3 5129.9 4942.7 4950.6 4971.2 5071.5 5230.7 19:35:38 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | [#4] prototype.html Cycles:180 Average:4510.04 Median:5112.22 stddev:1316.39 (25.7%) stddev-sans-first:1281.81 19:35:38 INFO - PROCESS | 1835 | Values: 311.9 330.1 333.7 339.1 342.3 5029.9 5099.4 5134.8 5138.6 5187.5 5095.5 5106.1 5118.3 5124.5 5165.7 4022.9 4078.4 4083.5 4086.9 4095.9 3862.2 4055.9 4056.9 4103.3 4112.5 3990.5 4016.2 4023.6 4044.2 4045.4 3974.3 3985.8 3985.8 3997.4 4034.8 3549.1 3556.6 3584.4 3589.9 3596.8 4801.2 4964.8 4973.0 5035.4 5060.6 4561.2 4599.3 4601.8 4604.8 4614.9 5332.9 5333.7 5338.2 5353.5 5354.8 3692.4 3829.0 3874.9 3888.6 3926.0 5236.0 5430.9 5468.8 5517.6 5517.6 3823.9 3890.6 3902.1 3930.9 3939.4 3769.4 3873.3 3983.2 3986.6 3987.7 4477.8 4549.8 4552.2 4552.4 4564.6 5331.8 5333.0 5388.4 5391.0 5406.9 1363.8 1385.7 1388.7 1395.0 1399.9 935.9 946.3 948.0 954.9 958.6 4975.8 5007.3 5031.2 5032.9 5064.7 4973.5 5293.5 5300.0 5332.8 5377.9 4920.4 4945.5 4951.2 4979.6 5007.4 2782.7 2786.7 2793.2 2827.1 2829.2 5455.3 5498.9 5712.6 5731.5 5741.6 5618.0 5626.8 5645.5 5649.6 5657.9 5376.0 5723.4 5745.9 5771.5 5775.7 5449.3 5502.8 5510.1 5520.1 5524.0 5206.0 5303.3 5355.4 5462.7 5464.2 5692.9 5697.6 5704.2 5712.4 5727.7 3997.3 3998.8 4012.8 4013.7 4022.1 5294.6 5510.6 5545.0 5560.5 5570.6 5380.4 5393.6 5394.2 5400.2 5404.0 5147.3 5420.6 5425.1 5430.7 5439.2 5340.6 5358.8 5375.5 5389.8 5392.3 5400.6 5406.2 5407.3 5407.9 5424.4 5084.9 5228.4 5248.5 5279.6 5311.4 19:35:38 INFO - PROCESS | 1835 | 19:35:38 INFO - PROCESS | 1835 | [#5] yui.html Cycles:180 Average:16501.08 Median:24862.54 stddev:21421.10 (86.2%) stddev-sans-first:21448.46 19:35:38 INFO - PROCESS | 1835 | Values: 729.5 737.6 750.8 792.3 846.4 13856.4 14611.9 15140.3 15150.0 15173.8 12423.8 12437.9 12455.8 12533.4 12533.4 765.9 768.0 789.0 795.4 797.5 648.4 670.0 755.5 784.9 788.7 828.0 832.0 833.0 833.9 834.1 827.1 828.1 828.4 828.5 828.8 505.7 524.0 606.5 606.8 607.1 13721.5 14184.8 14257.1 14648.1 14662.0 12859.5 13064.6 13086.5 13153.4 13435.8 10920.8 10959.3 10963.5 11033.0 11114.9 3555.4 3570.8 3603.8 3649.0 3745.5 59272.0 59759.7 59828.4 60067.0 60240.7 10318.8 10511.6 10522.3 10588.3 10796.2 19405.3 19451.7 19550.8 19577.6 19695.5 7749.3 7776.7 7927.4 7931.0 8021.8 4137.4 4327.9 4328.8 4374.1 4428.0 3241.6 3335.4 3345.2 3359.9 3383.0 593.4 599.4 603.8 606.7 617.9 687.9 702.0 711.0 711.1 711.9 8446.3 8708.0 9310.8 9385.6 9513.3 647.6 670.7 704.5 709.3 728.5 4440.9 4505.5 4735.6 4770.4 4814.4 82065.6 85711.6 86238.6 87588.0 89442.6 46654.8 47486.5 75290.6 78051.6 78539.0 43078.8 43757.6 44949.6 45219.5 54298.5 8682.6 8843.8 8936.9 9140.8 9505.7 33929.0 45282.0 45899.0 47445.0 47592.0 50449.0 60652.4 65893.7 66248.7 66750.7 11355.9 11443.5 11553.7 11718.8 11816.5 13891.9 13980.5 13996.0 14152.0 14170.8 12535.5 12823.9 13482.4 13538.5 14069.0 12772.4 13014.2 13638.5 13732.2 14016.6 11859.6 11871.4 11993.8 12101.3 12789.8 10780.8 10859.6 11007.9 11386.1 11568.3 10415.9 10622.7 10798.0 10842.0 11002.5 19:35:38 INFO - PROCESS | 1835 | -------- Summary: end -------- 19:35:38 INFO - PROCESS | 1835 | 19:35:39 INFO - TEST-INFO | 1835: exit 0 19:35:39 INFO - TEST-OK | dromaeo_css | took 1118923ms 19:35:39 INFO - TEST-START | kraken 19:35:39 INFO - operating with platform_type : linux_ 19:35:39 INFO - Initialising browser for kraken test... 19:35:39 INFO - TEST-INFO | started process 2145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpovb3RO/profile http://localhost:50038/getInfo.html) 19:35:43 INFO - PROCESS | 2145 | __metrics Screen width/height:1600/1200 19:35:43 INFO - PROCESS | 2145 | colorDepth:24 19:35:43 INFO - PROCESS | 2145 | Browser inner width/height: 1024/697 19:35:43 INFO - PROCESS | 2145 | __metrics 19:35:43 INFO - PROCESS | 2145 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:35:43 INFO - PROCESS | 2145 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:35:44 INFO - PROCESS | 2145 | console.error: PushService: 19:35:44 INFO - PROCESS | 2145 | stateChangeProcessEnqueue: Error transitioning state 19:35:44 INFO - PROCESS | 2145 | UnknownError 19:35:45 INFO - TEST-INFO | 2145: exit 0 19:35:45 INFO - Browser initialized. 19:35:45 INFO - Running cycle 1/1 for kraken test... 19:35:45 INFO - TEST-INFO | started process 2229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpovb3RO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:35:57 INFO - PROCESS | 2229 | RSS: Main: 172867584 19:35:57 INFO - PROCESS | 2229 | 19:35:59 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50038/tests/kraken/driver.html?testName=audio-beat-detection) 19:36:00 INFO - PROCESS | 2229 | RSS: Main: 234635264 19:36:00 INFO - PROCESS | 2229 | 19:36:03 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50038/tests/kraken/driver.html?testName=audio-dft) 19:36:03 INFO - PROCESS | 2229 | RSS: Main: 216739840 19:36:03 INFO - PROCESS | 2229 | 19:36:07 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50038/tests/kraken/driver.html?testName=audio-fft) 19:36:07 INFO - PROCESS | 2229 | RSS: Main: 297242624 19:36:07 INFO - PROCESS | 2229 | 19:36:09 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50038/tests/kraken/driver.html?testName=audio-oscillator) 19:36:10 INFO - PROCESS | 2229 | RSS: Main: 207511552 19:36:10 INFO - PROCESS | 2229 | 19:36:12 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50038/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:36:13 INFO - PROCESS | 2229 | RSS: Main: 212688896 19:36:13 INFO - PROCESS | 2229 | 19:36:16 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50038/tests/kraken/driver.html?testName=imaging-darkroom) 19:36:16 INFO - PROCESS | 2229 | RSS: Main: 253345792 19:36:16 INFO - PROCESS | 2229 | 19:36:20 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50038/tests/kraken/driver.html?testName=imaging-desaturate) 19:36:20 INFO - PROCESS | 2229 | RSS: Main: 252104704 19:36:20 INFO - PROCESS | 2229 | 19:36:23 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50038/tests/kraken/driver.html?testName=json-parse-financial) 19:36:24 INFO - PROCESS | 2229 | RSS: Main: 251019264 19:36:24 INFO - PROCESS | 2229 | 19:36:25 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50038/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:36:26 INFO - PROCESS | 2229 | RSS: Main: 212889600 19:36:26 INFO - PROCESS | 2229 | 19:36:27 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50038/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:36:28 INFO - PROCESS | 2229 | RSS: Main: 219807744 19:36:28 INFO - PROCESS | 2229 | 19:36:30 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50038/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:36:31 INFO - PROCESS | 2229 | RSS: Main: 229523456 19:36:31 INFO - PROCESS | 2229 | 19:36:33 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50038/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:36:34 INFO - PROCESS | 2229 | RSS: Main: 238649344 19:36:34 INFO - PROCESS | 2229 | 19:36:37 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50038/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:36:38 INFO - PROCESS | 2229 | RSS: Main: 234692608 19:36:38 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:50038/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50038/tests/kraken/driver.html?testName=ai-astar) 19:36:39 INFO - PROCESS | 2229 | RSS: Main: 285626368 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | __start_tp_report 19:36:39 INFO - PROCESS | 2229 | _x_x_mozilla_page_load 19:36:39 INFO - PROCESS | 2229 | _x_x_mozilla_page_load_details 19:36:39 INFO - PROCESS | 2229 | |i|pagename|runs| 19:36:39 INFO - PROCESS | 2229 | |0;ai-astar;109;111;111;111;111;114;112;110;114;111 19:36:39 INFO - PROCESS | 2229 | |1;audio-beat-detection;145;121;125;170;123;125;125;126;158;125 19:36:39 INFO - PROCESS | 2229 | |2;audio-dft;161;160;161;161;160;160;161;160;163;161 19:36:39 INFO - PROCESS | 2229 | |3;audio-fft;75;75;76;115;77;76;75;76;104;75 19:36:39 INFO - PROCESS | 2229 | |4;audio-oscillator;83;83;122;82;82;85;109;81;82;112 19:36:39 INFO - PROCESS | 2229 | |5;imaging-gaussian-blur;118;117;117;117;116;116;116;117;117;118 19:36:39 INFO - PROCESS | 2229 | |6;imaging-darkroom;147;148;147;147;147;147;147;146;146;148 19:36:39 INFO - PROCESS | 2229 | |7;imaging-desaturate;95;95;95;96;95;95;95;96;96;95 19:36:39 INFO - PROCESS | 2229 | |8;json-parse-financial;54;51;51;51;51;51;52;53;51;51 19:36:39 INFO - PROCESS | 2229 | |9;json-stringify-tinderbox;63;63;64;63;63;62;63;63;63;64 19:36:39 INFO - PROCESS | 2229 | |10;stanford-crypto-aes;89;83;85;80;79;78;78;79;78;78 19:36:39 INFO - PROCESS | 2229 | |11;stanford-crypto-ccm;134;133;135;146;131;143;134;128;165;139 19:36:39 INFO - PROCESS | 2229 | |12;stanford-crypto-pbkdf2;166;163;165;165;163;163;164;167;166;161 19:36:39 INFO - PROCESS | 2229 | |13;stanford-crypto-sha256-iterative;65;65;65;66;64;64;64;63;64;63 19:36:39 INFO - PROCESS | 2229 | __end_tp_report 19:36:39 INFO - PROCESS | 2229 | __start_cc_report 19:36:39 INFO - PROCESS | 2229 | _x_x_mozilla_cycle_collect,1685 19:36:39 INFO - PROCESS | 2229 | __end_cc_report 19:36:39 INFO - PROCESS | 2229 | __startTimestamp1479267399935__endTimestamp 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | ------- Summary: start ------- 19:36:39 INFO - PROCESS | 2229 | Number of tests: 14 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.00 stddev:1.58 (1.4%) stddev-sans-first:1.41 19:36:39 INFO - PROCESS | 2229 | Values: 109.0 111.0 111.0 111.0 111.0 114.0 112.0 110.0 114.0 111.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:125.50 stddev:17.22 (13.7%) stddev-sans-first:17.83 19:36:39 INFO - PROCESS | 2229 | Values: 145.0 121.0 125.0 170.0 123.0 125.0 125.0 126.0 158.0 125.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 19:36:39 INFO - PROCESS | 2229 | Values: 161.0 160.0 161.0 161.0 160.0 160.0 161.0 160.0 163.0 161.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.50 stddev:14.53 (19.2%) stddev-sans-first:15.16 19:36:39 INFO - PROCESS | 2229 | Values: 75.0 75.0 76.0 115.0 77.0 76.0 75.0 76.0 104.0 75.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:82.00 stddev:15.71 (19.2%) stddev-sans-first:16.31 19:36:39 INFO - PROCESS | 2229 | Values: 83.0 83.0 122.0 82.0 82.0 85.0 109.0 81.0 82.0 112.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 19:36:39 INFO - PROCESS | 2229 | Values: 118.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 118.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 19:36:39 INFO - PROCESS | 2229 | Values: 147.0 148.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 148.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 19:36:39 INFO - PROCESS | 2229 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 96.0 95.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.00 stddev:1.07 (2.1%) stddev-sans-first:0.71 19:36:39 INFO - PROCESS | 2229 | Values: 54.0 51.0 51.0 51.0 51.0 51.0 52.0 53.0 51.0 51.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 19:36:39 INFO - PROCESS | 2229 | Values: 63.0 63.0 64.0 63.0 63.0 62.0 63.0 63.0 63.0 64.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:79.50 stddev:3.77 (4.7%) stddev-sans-first:2.54 19:36:39 INFO - PROCESS | 2229 | Values: 89.0 83.0 85.0 80.0 79.0 78.0 78.0 79.0 78.0 78.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:137.00 stddev:10.69 (7.8%) stddev-sans-first:11.19 19:36:39 INFO - PROCESS | 2229 | Values: 134.0 133.0 135.0 146.0 131.0 143.0 134.0 128.0 165.0 139.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:165.00 stddev:1.83 (1.1%) stddev-sans-first:1.83 19:36:39 INFO - PROCESS | 2229 | Values: 166.0 163.0 165.0 165.0 163.0 163.0 164.0 167.0 166.0 161.0 19:36:39 INFO - PROCESS | 2229 | 19:36:39 INFO - PROCESS | 2229 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.50 stddev:0.95 (1.5%) stddev-sans-first:0.97 19:36:39 INFO - PROCESS | 2229 | Values: 65.0 65.0 65.0 66.0 64.0 64.0 64.0 63.0 64.0 63.0 19:36:39 INFO - PROCESS | 2229 | -------- Summary: end -------- 19:36:39 INFO - PROCESS | 2229 | 19:36:41 INFO - TEST-INFO | 2229: exit 0 19:36:41 INFO - TEST-OK | kraken | took 61956ms 19:36:41 INFO - SUITE-END | took 1180s 19:36:41 INFO - Completed test suite (00:19:41) 19:36:41 INFO - javascript benchmark 19:36:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2965.4217427601616, 3022.1235841605935, 3044.3454657248685, 3051.130427828068, 3068.8465576721173, 5167.354080739908, 5242.108841496945, 5325.627206095575, 5379.139337705968, 5398.109311963526, 5246.947530524693, 5252.448492908247, 5254.4220135785035, 5336.946630533694, 5384.892302153955, 4802.97598512007, 4901.950980490185, 4914.213725803863, 4931.901361972758, 4944.728039957801, 4821.919439510173, 4850.854474365757, 4863.343448634442, 4867.196912509432, 4896.094222568819, 4318.481782186126, 4873.585745211643, 4958.355413796219, 4961.231009193581, 4990.351254336932, 4676.579107880307, 4856.902861942776, 4887.633427492917, 4891.951080489203, 4924.827631032933, 4711.481737008926, 4743.596794272491, 4775.474697783241, 4780.737059461728, 4786.234202527578, 5258.55302299305, 5308.203769434615, 5336.91994620081, 5337.439568845254, 5398.325209348832, 5699.173619825102, 5711.114777209539, 5711.657300561938, 5713.74288157034, 5752.338481074688, 5422.511973922326, 5436.565074794046, 5445.210444485528, 5510.0632892408385, 5740.052891272977, 5193.63644544882, 5196.0387328343995, 5202.375714914158, 5202.427732949334, 5203.479652034766, 5581.6371935824045, 5635.267415236075, 5643.604947653625, 5646.632968857011, 5649.576281778883, 5409.56723462122, 5439.700816455053, 5454.59090568209, 5460.6177567569885, 5461.2354270402175, 5509.228707980886, 5512.972435137879, 5513.035218836688, 5517.917231241535, 5537.1140617501815, 5464.808731694451, 5473.0, 5475.0, 5481.945180548223, 5482.80810171642, 5494.5054945055135, 5496.340439147248, 5588.832335029955, 5598.1602759586385, 5603.663780173202, 4309.61213490781, 4387.670924680661, 4393.319035549495, 4402.0, 4434.0, 3793.013816407754, 3827.78947157909, 3896.551896531935, 3918.2555314490155, 3992.001999500125, 5187.844364669066, 5268.15709486488, 5279.313689220454, 5306.734663266821, 5313.203019547099, 5642.26650535436, 5642.322921249476, 5649.68926709035, 5669.262995810601, 5683.573731970118, 5150.97424512875, 5208.557272631793, 5293.814716484904, 5308.708021058879, 5348.144296912553, 4803.327534145223, 4808.831690890802, 4812.5668689818085, 4867.245576935581, 4914.606831453475, 5882.470577648032, 5883.558733095035, 5888.587798854125, 5896.469317761422, 5902.911456328158, 5516.117421212587, 5664.291963504562, 5669.744861481326, 5671.773129074791, 5673.120666296565, 5741.13882917549, 5876.236089308362, 5885.55858310637, 5899.115132729953, 5968.671723055274, 5548.278723765885, 5583.553315734813, 5593.552515798808, 5597.776088956396, 5604.355499117653, 5673.8297851064535, 5705.942940570541, 5707.258056452634, 5716.942830571641, 5721.0, 5363.115086010765, 5378.542823859859, 5384.6499977501335, 5450.563954883679, 5453.209284653783, 5425.945740542544, 5438.755256013568, 5442.183672449164, 5443.646162999393, 5445.183222516495, 5432.348118225838, 5437.0, 5442.9455705444025, 5443.7822487100075, 5462.317210348707, 5460.180972853945, 5510.090835012178, 5522.0336441121835, 5526.613137080366, 5528.44715528444, 5374.408815030265, 5390.353157621111, 5407.756650950795, 5414.918776218281, 5507.807226747044], "value": 5186.345231220652, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.6987436407433, 606.4451027310012, 625.4808508937582, 644.6067898581862, 648.8702259548091, 32171.67828321716, 34599.30801383974, 34636.48045279323, 34662.48006279905, 41805.0, 42899.78550107253, 44263.77868110655, 44388.77805610967, 44429.33355999654, 44430.33354499685, 8533.232009119194, 8680.348973826956, 8689.04420513743, 8696.21734043936, 8696.39125261232, 8254.793630159264, 8344.165583441669, 8346.749597512084, 8350.079481719209, 8351.164883511661, 8340.416170868042, 8397.370197235232, 8399.580020998956, 8402.201790829862, 8406.0, 8250.876236856451, 8264.752057438287, 8279.917200827975, 8335.499870007789, 8365.163483651617, 8461.873071903927, 8487.575621218914, 8526.829463410697, 8531.616077276476, 8535.743927682179, 40596.59403405958, 41229.0, 41296.58703412987, 41352.34981528397, 41355.1728965419, 3358.126887009395, 3404.914877128067, 3492.388831954423, 3590.892273231781, 3752.4558938953974, 31087.22281942947, 31098.689013109804, 31148.0, 31992.360152796813, 32654.836725816458, 4077.111449987329, 4177.289860723684, 4202.642775364097, 4243.108947121108, 4251.341042138442, 248993.9999999982, 249910.0, 250491.0, 251448.0, 252042.0, 57971.710141449024, 58036.0, 58315.708421457624, 58332.4166758327, 58347.0, 36301.818490907375, 36983.0, 61339.0, 61356.39827012902, 61506.0, 16920.57698557546, 17366.3053477862, 17382.478525644252, 17383.130843457777, 17499.737503937453, 13258.668533286746, 13342.0, 13344.866551334362, 13345.266010369329, 13351.799723004162, 18979.671423000535, 19127.52181195481, 19169.74114400555, 19173.712394314098, 19189.232430702894, 6995.1955525114245, 7009.12385951756, 7119.3592576667315, 7120.362316667196, 7177.533460325062, 897.3942588752566, 966.7341481092615, 982.6609819612222, 1061.379093230469, 1100.7853468573712, 31638.367232655677, 31884.202894927814, 31959.840200798848, 32392.35215295728, 32573.837130814194, 907.1654078248027, 1097.9011888930036, 1138.299945533491, 1155.6301983365242, 1244.520859469111, 16013.839861601467, 16586.419475318307, 16588.419405320758, 16643.33426662944, 17104.401345952832, 537503.0, 541026.0, 541979.0, 542381.0, 544381.0, 56611.15083273754, 57176.42823571711, 57196.0, 57215.427845721846, 57367.71316143392, 1227.2329793878826, 1277.1506947879557, 1309.9541516047084, 1338.4846833968993, 1374.8350197976106, 30032.914690293506, 32137.678623214404, 32369.838150809097, 32447.513287300237, 32611.0866695998, 48260.517394825605, 48815.75592122017, 48828.755856220494, 48913.0, 48958.75520622374, 379842.0, 380341.0, 381160.0, 381884.0, 383878.0, 27244.31889202786, 27824.16527504178, 28556.0, 28580.85709571439, 29866.10401687953, 45252.32121518114, 45493.54506454893, 45527.0, 46320.305195421424, 46479.0, 38358.415585191724, 38432.0, 39399.606003939596, 39934.600653994254, 40274.59725402826, 38598.6140138595, 38655.2941294118, 38926.4161037579, 39556.40665390079, 40421.59578404178, 39892.60107398889, 40400.0, 40631.593684062784, 40641.1871762569, 40856.182876341714, 33860.3227935435, 34647.0, 35326.293474130885, 35663.64336356707, 35664.465033024004, 26905.0, 27096.32259193536, 27184.72815271822, 27194.456110877276, 27415.45169096567], "value": 19343.93457476777, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.3470378892621, 379.49716625471257, 396.5756640394777, 406.27074401449397, 412.17770547757226, 13235.861072747952, 15470.226488675564, 16643.833561664378, 18910.338138165167, 19381.32165374212, 18401.53996150097, 18648.81351186488, 18661.626767464677, 19230.711539326887, 19278.13248403822, 5438.477226376621, 5679.261695979517, 5689.6017278790505, 5691.117876729114, 5696.658200507962, 5328.893422131555, 5544.140658197985, 5577.079781835992, 5580.218769372271, 5580.832575022756, 5561.916571251414, 5565.888682226353, 5565.888682226353, 5579.246801681762, 5579.442055794409, 5444.40111587727, 5667.0932650775885, 5699.715014249292, 5763.366029736726, 5775.104858746901, 5439.755211015514, 5517.420670829545, 5572.359178694421, 5583.3858275589655, 5653.406392328788, 14066.437342506288, 14313.212773297464, 14314.92842535791, 14321.283935803272, 19411.0, 2229.4092065602626, 2230.6877037214804, 2310.514791893704, 2479.5536803375385, 2738.219607411898, 9974.900250997542, 10008.79982400348, 10139.7465063374, 10427.582896684124, 10660.20048496366, 1836.0, 1873.260062275407, 1889.9055047247582, 1925.1529327096173, 1956.0317642766922, 80570.0, 83038.16961830365, 83378.58310708408, 83553.58223208906, 84590.99999999939, 10867.29362591429, 11016.50425730828, 11025.944870275758, 11138.610148644759, 11319.849228646532, 15413.845861541242, 15441.459548915735, 15491.690166196613, 15604.29780659873, 15608.53174404747, 7856.842863142705, 7949.244821741925, 8062.355011599058, 8065.39509536787, 8192.18078192176, 7377.741779037708, 7416.184219735825, 7428.814279643052, 7590.734324298623, 7612.581308028111, 4687.789049492712, 4727.747147006046, 4744.4069491313585, 4772.570468657837, 4854.466008739036, 1941.175000624729, 1946.776120746104, 1969.4091772468203, 1999.0904138616895, 2011.869228500172, 845.2984023260678, 939.3377668743519, 966.6471737815647, 982.6069572171189, 1023.8361862102028, 17921.641567168583, 18595.25618975253, 18824.623507529774, 18959.263811036806, 19012.429627111207, 942.4109931292942, 981.3572110267786, 982.8525721141885, 986.8914419413859, 988.8467287570438, 9787.36382135159, 10324.690259292232, 10348.223883208639, 10450.68647940563, 10529.631462898762, 434694.0, 435166.0, 438654.0, 450899.0, 472019.0, 85795.57102214574, 86460.56769716111, 86996.90557246804, 89119.55440222757, 90143.54928225317, 959.4147569982305, 979.6816034788723, 1006.8036732837027, 1007.1137399088755, 1016.9796604067877, 15751.291191896622, 16321.347146114498, 16510.33958641641, 16593.78607589251, 16686.833131669013, 39137.41293880651, 39222.0, 39641.40537891992, 40009.79995100122, 40831.795841020605, 54035.45964540304, 55548.44451555433, 55641.16538252011, 56649.71675141598, 56677.0, 8294.792630184294, 8353.20644538768, 8409.705660301861, 8432.957835210786, 8743.939621132553, 11090.445477726244, 13065.869341306465, 13126.278054707083, 13268.867311327149, 13324.467021319426, 11278.37968911685, 11375.20373573842, 11602.245854019462, 11695.356755378536, 11886.227395219283, 11066.336019838835, 11293.661190164308, 11478.540858365906, 11513.712157196138, 11788.646340609796, 11503.0, 11665.416729163677, 11695.590654327058, 11852.822207666719, 11997.520099195934, 8634.870476942726, 8760.824783504422, 8850.601722922613, 11131.721706957067, 11154.442277886235, 11306.321620702784, 11837.526498939946, 11956.820647690465, 12081.939590301992, 12093.213941093802], "value": 8957.074796518393, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.47780887645, 1470.7058588282339, 1483.8367779544249, 1589.6741168060541, 1739.1912760566333, 7080.752173673916, 7917.564533950637, 8266.421350505467, 8272.296854767344, 8482.151784821515, 8252.422330436872, 8336.249737523622, 8336.91663083369, 8349.749507514784, 8362.247397734203, 2312.283192210416, 2338.1816364272454, 2340.4265954841003, 2341.7424083350816, 2345.4253707841604, 2271.6819645249598, 2311.8612883226974, 2325.163121134302, 2329.3943574670625, 2330.3940975346445, 2248.8425810193294, 2268.353519247015, 2271.239134889814, 2276.6698828669914, 2289.8053665438456, 2153.859999100069, 2166.306781829815, 2183.9344819655435, 2185.0385830234645, 2185.1914791527156, 2200.8019278264874, 2219.3341997400717, 2219.933401997943, 2221.3335999200335, 2227.610168220555, 8106.067802202763, 8124.0, 8271.793205169859, 8274.213949674771, 8281.130481299497, 5016.322796422509, 5229.764660590283, 5233.267342572043, 5237.79048838046, 5245.580353571705, 3319.535265062893, 3352.2625022495013, 3357.9664203357897, 3408.897733068012, 3420.794752314869, 1075.4300220882944, 1101.0750969185922, 1104.0735652251556, 1122.4668282565797, 1133.8696049954278, 11179.44102794857, 11232.887671123266, 11235.494402751896, 11256.606018789385, 11349.319040857576, 4015.15681706839, 4153.335466325373, 4284.528701842794, 4293.549177336397, 4322.789618906649, 8784.253338466302, 8795.164459376349, 8796.692115775917, 8799.692010779592, 8802.735917922473, 4959.70241785494, 5013.39839219296, 5018.974905125451, 5020.1214787412055, 5023.296738456619, 3875.670568001695, 4002.1795531915886, 4062.7968601569805, 4182.916341673211, 4711.976440117777, 3511.9122021949656, 3548.6451354864307, 3574.3208790330273, 3658.34149853029, 3666.3034023535442, 1103.6302838549177, 1113.7494063835734, 1149.385078982757, 1164.3130552973787, 1167.2471256039976, 859.7678626770737, 890.0432035561797, 894.9910500894907, 896.2158111652304, 935.9672411465566, 5602.439756024446, 5610.663360198401, 5712.371639119693, 5735.684537350486, 5735.741891614887, 942.6559305853314, 949.990500095004, 953.2231231546267, 981.0777868803444, 995.4226548601794, 3777.0934975606206, 3779.905502362463, 3921.9607803922167, 4026.5772093929713, 4042.514898212233, 9853.704388868346, 10090.09189172978, 10197.082262596401, 10198.694039178688, 10259.538320775582, 10110.595576177018, 10213.693589192337, 10231.386116832866, 10232.079112879728, 10234.48827558619, 7598.20218877021, 7745.341645960157, 7864.0, 7868.606569671608, 7872.409569282327, 5131.973605278885, 5595.664260144405, 5611.158326251095, 5721.799737009184, 5747.655140691572, 9053.275737940818, 9096.772580685536, 9271.119243671841, 9368.57841397125, 9420.293477989042, 9291.0, 9425.104615061558, 9484.05159484046, 9591.232701383733, 9709.902900971183, 6944.583325000516, 6980.965095174492, 7060.8940865886025, 7078.362947334765, 7078.610676412847, 7858.13560508355, 7890.802729931797, 7912.960435197787, 7935.047794264494, 7945.006874140732, 6899.586024838526, 7027.016217729624, 7052.33002864727, 7056.188538318159, 7248.818779530343, 7026.543274687129, 7141.928580714334, 7142.357187853118, 7146.857062858818, 7171.03191069199, 6094.421030002142, 6272.059191121369, 6289.088082228142, 6434.260060093149, 6685.39831415175, 4949.925751113664, 4988.326575912205, 5052.823151189691, 5117.283580298836, 5129.8717532062, 4942.654014219114, 4950.603951683928, 4971.204607262821, 5071.467495912876, 5230.738463076755], "value": 4371.73854859957, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.85342888842234, 330.10212222754103, 333.7430178762353, 339.123366098635, 342.2709628491313, 5029.9125862767, 5099.413567439746, 5134.820281290155, 5138.588912886969, 5187.507186817251, 5095.541401273885, 5106.131957567213, 5118.309028281181, 5124.48900038981, 5165.656929198407, 4022.8793136205813, 4078.3882417637296, 4083.4691490106243, 4086.856960006408, 4095.9180816383655, 3862.1503269280715, 4055.8986025349454, 4056.8782936511952, 4103.302438585448, 4112.485939257593, 3990.5410877748996, 4016.2369149861433, 4023.6177563131455, 4044.170944956292, 4045.37296719009, 3974.3044967130636, 3985.7608543487486, 3985.80070996449, 3997.4003899415175, 4034.798260086984, 3549.0594992327055, 3556.5554305711785, 3584.426491761306, 3589.94615080774, 3596.766210196355, 4801.207800712878, 4964.751762411865, 4972.980538989498, 5035.395752509686, 5060.595152387837, 4561.247394179956, 4599.2871104978785, 4601.769911504445, 4604.756715686779, 4614.884627884297, 5332.866678333034, 5333.653312534673, 5338.195623938448, 5353.464653534655, 5354.759035843396, 3692.4276737105793, 3828.980855095707, 3874.8643797467234, 3888.5528164260904, 3925.9585015791627, 5235.973820130875, 5430.891382172374, 5468.835934921919, 5517.613767036309, 5517.641353312062, 3823.9426408603895, 3890.630390112935, 3902.1415288636454, 3930.862419815293, 3939.3893946438347, 3769.359208934488, 3873.2640798248244, 3983.152046286919, 3986.60133986599, 3987.6809855211513, 4477.776111194427, 4549.795259213343, 4552.203364411214, 4552.408186935677, 4564.566366195206, 5331.81338653153, 5333.0, 5388.407275199725, 5390.975714614211, 5406.91889621656, 1363.8295213098363, 1385.6882201504782, 1388.673661689502, 1394.9721005579831, 1399.8810101141314, 935.9391639543408, 946.2666433514082, 947.966821161256, 954.8758661373977, 958.5925981457852, 4975.800967961241, 5007.3240112584335, 5031.22016087507, 5032.874178145576, 5064.696118232918, 4973.477784832613, 5293.470652934753, 5300.04599172145, 5332.760025798849, 5377.865553361197, 4920.384951881015, 4945.505449455026, 4951.207806750903, 4979.6265280104135, 5007.424146223231, 2782.693903670635, 2786.6902555798742, 2793.2458236276498, 2827.1094605199296, 2829.2458675621247, 5455.263539422207, 5498.862528436821, 5712.6286791358425, 5731.512821410143, 5741.569382296345, 5617.971910140505, 5626.803061892896, 5645.5201307888465, 5649.576281778883, 5657.858553536194, 5375.973120134374, 5723.399043100498, 5745.913811292834, 5771.480566749013, 5775.740091695884, 5449.3188351456065, 5502.752376143003, 5510.126556970489, 5520.089185284383, 5524.0, 5205.969671827915, 5303.284056652303, 5355.384130825005, 5462.672239665474, 5464.153056276284, 5692.914606280826, 5697.57268204878, 5704.235204765001, 5712.37163911961, 5727.7422515986045, 3997.2804895117993, 3998.7800670963375, 4012.81621921528, 4013.7190396671954, 4022.1352409232154, 5294.576433885203, 5510.641808282448, 5545.0, 5560.499555040065, 5570.554355651457, 5380.381256155592, 5393.6224464287125, 5394.217838413487, 5400.216968539618, 5404.0, 5147.279380886754, 5420.593455490775, 5425.131978883517, 5430.70131142791, 5439.184122381675, 5340.626156169031, 5358.839234822962, 5375.5162035417, 5389.784408623611, 5392.325959255093, 5400.594955378284, 5406.216098665593, 5407.324084489439, 5407.891842163056, 5424.403315635356, 5084.872878177927, 5228.424873263862, 5248.475152484721, 5279.551238144801, 5311.353480421082], "value": 4096.171190628804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.4820677319104, 737.5722081192912, 750.7522517569203, 792.342355844649, 846.3694547562067, 13856.376463059161, 14611.926940365296, 15140.318685659144, 15150.0, 15173.772393414107, 12423.813642795365, 12437.875621243786, 12455.750884982295, 12533.435995380207, 12533.435995380207, 765.9310662040415, 768.0361146760807, 788.9842203155933, 795.3597354129923, 797.5015615240475, 648.4034688086971, 669.9832504187385, 755.5240198674827, 784.9215078492163, 788.692409960116, 827.9668813247494, 832.0223737108903, 832.9625166867505, 833.9249467547919, 834.0783434305097, 827.1273806134526, 828.0973738624867, 828.3994104274353, 828.4780588229437, 828.7762304177868, 505.71174430574587, 524.0200824458287, 606.4935778624854, 606.754264522869, 607.1075518987082, 13721.519746808814, 14184.787228191586, 14257.14457132565, 14648.12111273327, 14662.046966947115, 12859.549915752998, 13064.608061758163, 13086.541971031063, 13153.408096635674, 13435.798463022964, 10920.781584368347, 10959.342439453578, 10963.50664220112, 11033.0, 11114.944080312358, 3555.377808883461, 3570.785752854837, 3603.819809009539, 3648.9817550912076, 3745.4569087482437, 59272.0, 59759.70120149371, 59828.40171598316, 60067.0, 60240.698796505734, 10318.76765801114, 10511.632092876713, 10522.31604945676, 10588.311759735747, 10796.190285728602, 19405.32081377145, 19451.708224376645, 19550.8044919549, 19577.608447830964, 19695.50761230952, 7749.263819937097, 7776.688932442752, 7927.445078844426, 7930.960345198352, 8021.799455013671, 4137.379393091061, 4327.935080973788, 4328.826846926151, 4374.081442897027, 4428.047969686533, 3241.638511825039, 3335.4329763940186, 3345.1971526833872, 3359.882404115844, 3383.0, 593.44809327326, 599.3526990849872, 603.8218725475995, 606.6663335165641, 617.8980468222783, 687.9208890977602, 701.9964900175466, 710.9573425594481, 711.1466240511304, 711.9110111236096, 8446.282066024456, 8708.0, 9310.813783724423, 9385.62457501706, 9513.334066615409, 647.5531883000715, 670.6780745242311, 704.5314865614309, 709.2942522190453, 728.5118970289813, 4440.866773996785, 4505.526919673387, 4735.573798358164, 4770.358549842944, 4814.422269327633, 82065.58967205245, 85711.57144214239, 86238.56880715556, 87588.0, 89442.55278723694, 46654.76672616683, 47486.5251347489, 75290.62354688192, 78051.60974195093, 78539.0, 43078.78460607677, 43757.56242437599, 44949.55050449454, 45219.54780452219, 54298.45701543092, 8682.590201969315, 8843.778905527413, 8936.86594701067, 9140.817183656423, 9505.714828555154, 33928.97848343424, 45282.0, 45899.0, 47445.0, 47592.048159035934, 50449.0, 60652.39347606467, 65893.67053164703, 66248.66875665591, 66750.66624666845, 11355.943220283845, 11443.48504317291, 11553.653390398302, 11718.765624687629, 11816.468258928197, 13891.936164404042, 13980.510682126076, 13996.0, 14152.0, 14170.787438188228, 12535.490916310713, 12823.871761282267, 13482.393292302066, 13538.458461661423, 14069.0, 12772.361381930681, 13014.15407998477, 13638.522651707142, 13732.244726540137, 14016.579502614937, 11859.58491452795, 11871.406429678309, 11993.760124797629, 12101.273923564615, 12789.808152877527, 10780.784384312426, 10859.619913302997, 11007.889921100686, 11386.146039046938, 11568.305901645592, 10415.94792026035, 10622.681319560426, 10798.0, 10842.0, 11002.50488728025], "value": 6835.978496520878, "unit": "score"}], "name": "dromaeo_css", "value": 6922.167241378548, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 111.0, 111.0, 111.0, 114.0, 112.0, 110.0, 114.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 125.0, 170.0, 123.0, 125.0, 125.0, 126.0, 158.0, 125.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 160.0, 160.0, 161.0, 160.0, 163.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 115.0, 77.0, 76.0, 75.0, 76.0, 104.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 122.0, 82.0, 82.0, 85.0, 109.0, 81.0, 82.0, 112.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 148.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 51.0, 51.0, 51.0, 51.0, 52.0, 53.0, 51.0, 51.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0, 64.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 85.0, 80.0, 79.0, 78.0, 78.0, 79.0, 78.0, 78.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 135.0, 146.0, 131.0, 143.0, 134.0, 128.0, 165.0, 139.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 165.0, 165.0, 163.0, 163.0, 164.0, 167.0, 166.0, 161.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 64.0, 64.0, 64.0, 63.0, 64.0, 63.0], "value": 64.3, "unit": "score"}], "name": "kraken", "value": 1502.9999999999998, "alertThreshold": 2.0}]} 19:36:41 INFO - Return code: 0 19:36:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:36:41 INFO - # TBPL SUCCESS # 19:36:41 INFO - Running post-action listener: _resource_record_post_action 19:36:41 INFO - [mozharness: 2016-11-16 03:36:41.741883Z] Finished run-tests step (success) 19:36:41 INFO - Running post-run listener: _resource_record_post_run 19:36:41 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391507968; Read time: 8; Write time: 358296 19:36:41 INFO - TinderboxPrint: CPU usage
13.1% 19:36:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 19:36:41 INFO - TinderboxPrint: I/O write bytes / time
391,507,968 / 358,296 19:36:41 INFO - TinderboxPrint: CPU idle
8,306.4 (86.9%) 19:36:41 INFO - TinderboxPrint: CPU user
1,201.1 (12.6%) 19:36:41 INFO - TinderboxPrint: Swap in / out
0 / 0 19:36:41 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 102957056; Read time: 0; Write time: 86856 19:36:41 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 287514624; Read time: 0; Write time: 271412 19:36:42 INFO - Running post-run listener: _upload_blobber_files 19:36:42 INFO - Blob upload gear active. 19:36:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:36:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:36:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:36:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:36:42 INFO - (blobuploader) - INFO - Open directory for files ... 19:36:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:36:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:36:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:43 INFO - (blobuploader) - INFO - Done attempting. 19:36:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:36:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:36:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:44 INFO - (blobuploader) - INFO - Done attempting. 19:36:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:36:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:36:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:48 INFO - (blobuploader) - INFO - Done attempting. 19:36:48 INFO - (blobuploader) - INFO - Iteration through files over. 19:36:48 INFO - Return code: 0 19:36:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:36:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:36:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bf985deb579839dc8e798a3d92772adff2e964a83f5dfc4b3da523dfc21907cbf401d72464896ec6a0691cf78bb9146d34c3c5b5915702991d64503cd16ecc9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46c2bd3ac5012220cd9edfe48037adb2e4a66e09b64ca6ccafe8d16af8bc4de9a92e143df1c682f257711883201fa0d190573b2233db778dc778dd12538d7b21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ee1ff76e96949614d327b8233312c9633f23dae8d53767dcb8cda73b051f3f957cfbee49cb6016be957948d6aef79fd3a5bf4f3247400603c4ee8ed1f6dc9fd"} 19:36:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:36:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:36:48 INFO - Contents: 19:36:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bf985deb579839dc8e798a3d92772adff2e964a83f5dfc4b3da523dfc21907cbf401d72464896ec6a0691cf78bb9146d34c3c5b5915702991d64503cd16ecc9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46c2bd3ac5012220cd9edfe48037adb2e4a66e09b64ca6ccafe8d16af8bc4de9a92e143df1c682f257711883201fa0d190573b2233db778dc778dd12538d7b21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ee1ff76e96949614d327b8233312c9633f23dae8d53767dcb8cda73b051f3f957cfbee49cb6016be957948d6aef79fd3a5bf4f3247400603c4ee8ed1f6dc9fd"} 19:36:48 INFO - Running post-run listener: copy_logs_to_upload_dir 19:36:48 INFO - Copying logs to upload dir... 19:36:48 INFO - mkdir: /builds/slave/test/build/upload/logs 19:36:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.078987 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-11-15 19:36:47.699342) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 19:36:47.702662) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bf985deb579839dc8e798a3d92772adff2e964a83f5dfc4b3da523dfc21907cbf401d72464896ec6a0691cf78bb9146d34c3c5b5915702991d64503cd16ecc9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46c2bd3ac5012220cd9edfe48037adb2e4a66e09b64ca6ccafe8d16af8bc4de9a92e143df1c682f257711883201fa0d190573b2233db778dc778dd12538d7b21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ee1ff76e96949614d327b8233312c9633f23dae8d53767dcb8cda73b051f3f957cfbee49cb6016be957948d6aef79fd3a5bf4f3247400603c4ee8ed1f6dc9fd"} build_url:https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/Let9Pf_zRAmIo-CRMJA14w/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/Firefox/sha512/8bf985deb579839dc8e798a3d92772adff2e964a83f5dfc4b3da523dfc21907cbf401d72464896ec6a0691cf78bb9146d34c3c5b5915702991d64503cd16ecc9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46c2bd3ac5012220cd9edfe48037adb2e4a66e09b64ca6ccafe8d16af8bc4de9a92e143df1c682f257711883201fa0d190573b2233db778dc778dd12538d7b21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ee1ff76e96949614d327b8233312c9633f23dae8d53767dcb8cda73b051f3f957cfbee49cb6016be957948d6aef79fd3a5bf4f3247400603c4ee8ed1f6dc9fd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 19:36:47.729031) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 19:36:47.729399) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1479266159.805763-1152136795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004393 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 19:36:47.814475) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 19:36:47.814845) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 19:36:47.815220) ========= ========= Total master_lag: 0.16 =========