builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1472718088.4 results: success (0) buildid: 20160831223024 builduid: 7733f32208ac4d97ab3911244a68c8ab revision: b7f7ae14590aced450bb0b0469dfb38edd2c0ace ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.401928) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.402368) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.402653) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.419297) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.419574) ========= 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=62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 01:21:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.2M=0s 2016-09-01 01:21:28 (42.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.176249 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.610089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.610394) ========= 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=62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017032 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.659510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:28.659866) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b7f7ae14590aced450bb0b0469dfb38edd2c0ace --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 b7f7ae14590aced450bb0b0469dfb38edd2c0ace --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=62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 01:21:29,051 truncating revision to first 12 chars 2016-09-01 01:21:29,051 Setting DEBUG logging. 2016-09-01 01:21:29,051 attempt 1/10 2016-09-01 01:21:29,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b7f7ae14590a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 01:21:29,355 unpacking tar archive at: mozilla-central-b7f7ae14590a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.638531 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:29.313233) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:29.313505) ========= script_repo_revision: b7f7ae14590aced450bb0b0469dfb38edd2c0ace ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:29.313894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:29.314156) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 01:21:29.326747) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-01 01:21:29.327091) ========= /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=62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:21:29 INFO - MultiFileLogger online at 20160901 01:21:29 in /builds/slave/test 01:21:29 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 01:21:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:21:29 INFO - {'append_to_log': False, 01:21:29 INFO - 'base_work_dir': '/builds/slave/test', 01:21:29 INFO - 'blob_upload_branch': 'Firefox', 01:21:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:21:29 INFO - 'branch': 'Firefox', 01:21:29 INFO - 'buildbot_json_path': 'buildprops.json', 01:21:29 INFO - 'config_files': ('talos/linux_config.py',), 01:21:29 INFO - 'default_actions': ('clobber', 01:21:29 INFO - 'read-buildbot-config', 01:21:29 INFO - 'download-and-extract', 01:21:29 INFO - 'populate-webroot', 01:21:29 INFO - 'create-virtualenv', 01:21:29 INFO - 'install', 01:21:29 INFO - 'run-tests'), 01:21:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:21:29 INFO - 'download_minidump_stackwalk': True, 01:21:29 INFO - 'download_symbols': 'ondemand', 01:21:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:21:29 INFO - 'tooltool.py': '/tools/tooltool.py', 01:21:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:21:29 INFO - '/tools/misc-python/virtualenv.py')}, 01:21:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:21:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:21:29 INFO - 'installer_path': 'installer.exe', 01:21:29 INFO - 'log_level': 'info', 01:21:29 INFO - 'log_name': 'talos', 01:21:29 INFO - 'log_to_console': True, 01:21:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:21:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:21:29 INFO - 'opt_config_files': (), 01:21:29 INFO - 'pip_index': False, 01:21:29 INFO - 'sps_profile': False, 01:21:29 INFO - 'sps_profile_interval': 0, 01:21:29 INFO - 'suite': 'dromaeojs', 01:21:29 INFO - 'system_bits': '32', 01:21:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:21:29 INFO - 'title': 'talos-linux64-ix-018', 01:21:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:21:29 INFO - 'use_talos_json': True, 01:21:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:21:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:21:29 INFO - 'work_dir': 'build'} 01:21:29 INFO - [mozharness: 2016-09-01 08:21:29.814100Z] Running clobber step. 01:21:29 INFO - Running pre-action listener: _resource_record_pre_action 01:21:29 INFO - Running main action method: clobber 01:21:29 INFO - rmtree: /builds/slave/test/build 01:21:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:21:30 INFO - Running post-action listener: _resource_record_post_action 01:21:30 INFO - [mozharness: 2016-09-01 08:21:30.272411Z] Finished clobber step (success) 01:21:30 INFO - [mozharness: 2016-09-01 08:21:30.272512Z] Running read-buildbot-config step. 01:21:30 INFO - Running pre-action listener: _resource_record_pre_action 01:21:30 INFO - Running main action method: read_buildbot_config 01:21:30 INFO - Using buildbot properties: 01:21:30 INFO - { 01:21:30 INFO - "project": "", 01:21:30 INFO - "product": "firefox", 01:21:30 INFO - "script_repo_revision": "production", 01:21:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 01:21:30 INFO - "repository": "", 01:21:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 01:21:30 INFO - "buildid": "20160831223024", 01:21:30 INFO - "pgo_build": "True", 01:21:30 INFO - "basedir": "/builds/slave/test", 01:21:30 INFO - "buildnumber": 849, 01:21:30 INFO - "slavename": "talos-linux64-ix-018", 01:21:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:21:30 INFO - "platform": "ubuntu64_hw", 01:21:30 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 01:21:30 INFO - "branch": "mozilla-central", 01:21:30 INFO - "repo_path": "mozilla-central", 01:21:30 INFO - "revision": "b7f7ae14590aced450bb0b0469dfb38edd2c0ace", 01:21:30 INFO - "stage_platform": "linux64-pgo", 01:21:30 INFO - "builduid": "7733f32208ac4d97ab3911244a68c8ab", 01:21:30 INFO - "slavebuilddir": "test" 01:21:30 INFO - } 01:21:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 01:21:30 INFO - Running post-action listener: _resource_record_post_action 01:21:30 INFO - [mozharness: 2016-09-01 08:21:30.274946Z] Finished read-buildbot-config step (success) 01:21:30 INFO - [mozharness: 2016-09-01 08:21:30.275036Z] Running download-and-extract step. 01:21:30 INFO - Running pre-action listener: _resource_record_pre_action 01:21:30 INFO - Running main action method: download_and_extract 01:21:30 INFO - mkdir: /builds/slave/test/build/tests 01:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:30 INFO - trying https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:21:30 INFO - Downloading https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:21:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:21:30 INFO - Downloaded 1361 bytes. 01:21:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:21:30 INFO - Using the following test package requirements: 01:21:30 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:21:30 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:21:30 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:21:30 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:30 INFO - u'jsshell-linux-x86_64.zip'], 01:21:30 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:21:30 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:21:30 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:21:30 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:21:30 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:21:30 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:21:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:21:30 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:30 INFO - trying https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:21:30 INFO - Downloading https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:21:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:21:31 INFO - Downloaded 21906685 bytes. 01:21:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 01:21:36 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:21:36 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')]} 01:21:36 INFO - trying https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:21:36 INFO - Downloading https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:21:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:21:36 INFO - Downloaded 21906685 bytes. 01:21:36 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 01:21:41 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')]} 01:21:41 INFO - trying https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:21:41 INFO - Downloading https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:21:42 INFO - Downloaded 14413998 bytes. 01:21:42 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 01:21: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')]} 01:21:42 INFO - trying https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:21:42 INFO - Downloading https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:21:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:21:43 INFO - Downloaded 59723312 bytes. 01:21:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:21:43 INFO - mkdir: /builds/slave/test/properties 01:21:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:21:43 INFO - Writing to file /builds/slave/test/properties/build_url 01:21:43 INFO - Contents: 01:21:43 INFO - build_url:https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:21:44 INFO - Running post-action listener: _resource_record_post_action 01:21:44 INFO - Running post-action listener: set_extra_try_arguments 01:21:44 INFO - [mozharness: 2016-09-01 08:21:44.253646Z] Finished download-and-extract step (success) 01:21:44 INFO - [mozharness: 2016-09-01 08:21:44.253874Z] Running populate-webroot step. 01:21:44 INFO - Running pre-action listener: _resource_record_pre_action 01:21:44 INFO - Running main action method: populate_webroot 01:21:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:21:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:21:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:21:44 INFO - '--tppagecycles', 01:21:44 INFO - '7'], 01:21:44 INFO - 'tests': ['tsvgm']}}, 01:21:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:21:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:21:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:21:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:21:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:44 INFO - 'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:21:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:21:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:44 INFO - 'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['damp', 'tps']}, 01:21:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:44 INFO - 'tests': ['damp', 'tps']}, 01:21:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['dromaeo_dom']}, 01:21:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:21:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['basic_compositor_video']}, 01:21:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:21:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['a11yr', 01:21:44 INFO - 'ts_paint', 01:21:44 INFO - 'tpaint', 01:21:44 INFO - 'sessionrestore', 01:21:44 INFO - 'sessionrestore_no_auto_restore', 01:21:44 INFO - 'tabpaint']}, 01:21:44 INFO - 'other-e10s': {'tests': ['a11yr', 01:21:44 INFO - 'ts_paint', 01:21:44 INFO - 'tpaint', 01:21:44 INFO - 'sessionrestore', 01:21:44 INFO - 'sessionrestore_no_auto_restore', 01:21:44 INFO - 'tabpaint']}, 01:21:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:21:44 INFO - 'ts_paint', 01:21:44 INFO - 'tpaint', 01:21:44 INFO - 'sessionrestore', 01:21:44 INFO - 'sessionrestore_no_auto_restore', 01:21:44 INFO - 'tabpaint']}, 01:21:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:21:44 INFO - 'ts_paint', 01:21:44 INFO - 'tpaint', 01:21:44 INFO - 'sessionrestore', 01:21:44 INFO - 'sessionrestore_no_auto_restore', 01:21:44 INFO - 'tabpaint']}, 01:21:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['a11yr', 01:21:44 INFO - 'ts_paint', 01:21:44 INFO - 'tpaint', 01:21:44 INFO - 'sessionrestore', 01:21:44 INFO - 'sessionrestore_no_auto_restore', 01:21:44 INFO - 'tabpaint']}, 01:21:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['a11yr', 01:21:44 INFO - 'ts_paint', 01:21:44 INFO - 'tpaint', 01:21:44 INFO - 'sessionrestore', 01:21:44 INFO - 'sessionrestore_no_auto_restore', 01:21:44 INFO - 'tabpaint']}, 01:21:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['tsvgx', 01:21:44 INFO - 'tsvgr_opacity', 01:21:44 INFO - 'tart', 01:21:44 INFO - 'tscrollx', 01:21:44 INFO - 'cart']}, 01:21:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:21:44 INFO - 'tsvgr_opacity', 01:21:44 INFO - 'tart', 01:21:44 INFO - 'tscrollx', 01:21:44 INFO - 'cart']}, 01:21:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:44 INFO - 'talos_options': ['--disable-e10s'], 01:21:44 INFO - 'tests': ['tp5o']}, 01:21:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:44 INFO - 'tests': ['tp5o']}, 01:21:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:44 INFO - 'talos_options': ['--disable-e10s', 01:21:44 INFO - '--xperf_path', 01:21:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:21:44 INFO - 'tests': ['tp5n']}, 01:21:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:44 INFO - 'talos_options': ['--xperf_path', 01:21:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:21:44 INFO - 'tests': ['tp5n']}}, 01:21:44 INFO - 'talos.zip': {'path': '', 01:21:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:21:44 INFO - Running post-action listener: _resource_record_post_action 01:21:44 INFO - [mozharness: 2016-09-01 08:21:44.271945Z] Finished populate-webroot step (success) 01:21:44 INFO - [mozharness: 2016-09-01 08:21:44.272036Z] Running create-virtualenv step. 01:21:44 INFO - Running pre-action listener: _resource_record_pre_action 01:21:44 INFO - Running main action method: create_virtualenv 01:21:44 INFO - Creating virtualenv /builds/slave/test/build/venv 01:21:44 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 01:21:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:21:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:21:44 INFO - Using real prefix '/usr' 01:21:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:21:44 INFO - Installing distribute.............................................................................................................................................................................................done. 01:21:46 INFO - Installing pip.................done. 01:21:46 INFO - Return code: 0 01:21:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:46 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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:21:46 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 01:21:46 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 01:21:46 INFO - Using env: {'DISPLAY': ':0', 01:21:46 INFO - 'HOME': '/home/cltbld', 01:21:46 INFO - 'LANG': 'en_US.UTF-8', 01:21:46 INFO - 'LANGUAGE': 'en_US:en', 01:21:46 INFO - 'LOGNAME': 'cltbld', 01:21:46 INFO - 'MAIL': '/var/mail/cltbld', 01:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:46 INFO - 'MOZ_NO_REMOTE': '1', 01:21:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:46 INFO - 'NO_EM_RESTART': '1', 01:21:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:46 INFO - 'PWD': '/builds/slave/test', 01:21:46 INFO - 'SHELL': '/bin/bash', 01:21:46 INFO - 'SHLVL': '1', 01:21:46 INFO - 'TERM': 'linux', 01:21:46 INFO - 'TMOUT': '86400', 01:21:46 INFO - 'USER': 'cltbld', 01:21:46 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:46 INFO - '_': '/tools/buildbot/bin/python'} 01:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:47 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 01:21:47 INFO - Cleaning up... 01:21:47 INFO - Return code: 0 01:21:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:21:47 INFO - Using env: {'DISPLAY': ':0', 01:21:47 INFO - 'HOME': '/home/cltbld', 01:21:47 INFO - 'LANG': 'en_US.UTF-8', 01:21:47 INFO - 'LANGUAGE': 'en_US:en', 01:21:47 INFO - 'LOGNAME': 'cltbld', 01:21:47 INFO - 'MAIL': '/var/mail/cltbld', 01:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:47 INFO - 'MOZ_NO_REMOTE': '1', 01:21:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:47 INFO - 'NO_EM_RESTART': '1', 01:21:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:47 INFO - 'PWD': '/builds/slave/test', 01:21:47 INFO - 'SHELL': '/bin/bash', 01:21:47 INFO - 'SHLVL': '1', 01:21:47 INFO - 'TERM': 'linux', 01:21:47 INFO - 'TMOUT': '86400', 01:21:47 INFO - 'USER': 'cltbld', 01:21:47 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:47 INFO - '_': '/tools/buildbot/bin/python'} 01:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:47 INFO - Downloading/unpacking psutil>=3.1.1 01:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:21:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:21:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:21:49 INFO - Installing collected packages: psutil 01:21:49 INFO - Running setup.py install for psutil 01:21:49 INFO - building 'psutil._psutil_linux' extension 01:21:49 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 01:21:49 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 01:21:49 INFO - building 'psutil._psutil_posix' extension 01:21:49 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 01:21:49 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 01:21:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:21:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:21:49 INFO - Successfully installed psutil 01:21:49 INFO - Cleaning up... 01:21:49 INFO - Return code: 0 01:21:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:21:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:21:49 INFO - Using env: {'DISPLAY': ':0', 01:21:49 INFO - 'HOME': '/home/cltbld', 01:21:49 INFO - 'LANG': 'en_US.UTF-8', 01:21:49 INFO - 'LANGUAGE': 'en_US:en', 01:21:49 INFO - 'LOGNAME': 'cltbld', 01:21:49 INFO - 'MAIL': '/var/mail/cltbld', 01:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:49 INFO - 'MOZ_NO_REMOTE': '1', 01:21:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:49 INFO - 'NO_EM_RESTART': '1', 01:21:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:49 INFO - 'PWD': '/builds/slave/test', 01:21:49 INFO - 'SHELL': '/bin/bash', 01:21:49 INFO - 'SHLVL': '1', 01:21:49 INFO - 'TERM': 'linux', 01:21:49 INFO - 'TMOUT': '86400', 01:21:49 INFO - 'USER': 'cltbld', 01:21:49 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:49 INFO - '_': '/tools/buildbot/bin/python'} 01:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:21:50 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 01:21:50 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 01:21:50 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 01:21:50 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 01:21:50 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 01:21:50 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 01:21:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:21:51 INFO - Installing collected packages: mozsystemmonitor 01:21:51 INFO - Running setup.py install for mozsystemmonitor 01:21:52 INFO - Successfully installed mozsystemmonitor 01:21:52 INFO - Cleaning up... 01:21:52 INFO - Return code: 0 01:21:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:21:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:21:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:21:52 INFO - Using env: {'DISPLAY': ':0', 01:21:52 INFO - 'HOME': '/home/cltbld', 01:21:52 INFO - 'LANG': 'en_US.UTF-8', 01:21:52 INFO - 'LANGUAGE': 'en_US:en', 01:21:52 INFO - 'LOGNAME': 'cltbld', 01:21:52 INFO - 'MAIL': '/var/mail/cltbld', 01:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:52 INFO - 'MOZ_NO_REMOTE': '1', 01:21:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:52 INFO - 'NO_EM_RESTART': '1', 01:21:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:52 INFO - 'PWD': '/builds/slave/test', 01:21:52 INFO - 'SHELL': '/bin/bash', 01:21:52 INFO - 'SHLVL': '1', 01:21:52 INFO - 'TERM': 'linux', 01:21:52 INFO - 'TMOUT': '86400', 01:21:52 INFO - 'USER': 'cltbld', 01:21:52 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:52 INFO - '_': '/tools/buildbot/bin/python'} 01:21:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:52 INFO - Downloading/unpacking jsonschema==2.5.1 01:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:21:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:21:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:21:54 INFO - Downloading functools32-3.2.3-2.tar.gz 01:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:21:54 INFO - warning: no files found matching '*.txt' 01:21:54 INFO - no previously-included directories found matching 'build' 01:21:54 INFO - no previously-included directories found matching 'dist' 01:21:54 INFO - no previously-included directories found matching '.git*' 01:21:54 INFO - Installing collected packages: jsonschema, functools32 01:21:54 INFO - Running setup.py install for functools32 01:21:54 INFO - warning: no files found matching '*.txt' 01:21:54 INFO - no previously-included directories found matching 'build' 01:21:54 INFO - no previously-included directories found matching 'dist' 01:21:54 INFO - no previously-included directories found matching '.git*' 01:21:54 INFO - Successfully installed jsonschema functools32 01:21:54 INFO - Cleaning up... 01:21:54 INFO - Return code: 0 01:21:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:21:54 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')]} 01:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:54 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')]} 01:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:54 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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:21:54 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 01:21:54 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 01:21:54 INFO - Using env: {'DISPLAY': ':0', 01:21:54 INFO - 'HOME': '/home/cltbld', 01:21:54 INFO - 'LANG': 'en_US.UTF-8', 01:21:54 INFO - 'LANGUAGE': 'en_US:en', 01:21:54 INFO - 'LOGNAME': 'cltbld', 01:21:54 INFO - 'MAIL': '/var/mail/cltbld', 01:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:54 INFO - 'MOZ_NO_REMOTE': '1', 01:21:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:54 INFO - 'NO_EM_RESTART': '1', 01:21:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:54 INFO - 'PWD': '/builds/slave/test', 01:21:54 INFO - 'SHELL': '/bin/bash', 01:21:54 INFO - 'SHLVL': '1', 01:21:54 INFO - 'TERM': 'linux', 01:21:54 INFO - 'TMOUT': '86400', 01:21:54 INFO - 'USER': 'cltbld', 01:21:54 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:54 INFO - '_': '/tools/buildbot/bin/python'} 01:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:21:54 INFO - Cleaning up... 01:21:54 INFO - Return code: 0 01:21:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:21:54 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')]} 01:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:54 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')]} 01:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:54 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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:21:54 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 01:21:54 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 01:21:54 INFO - Using env: {'DISPLAY': ':0', 01:21:54 INFO - 'HOME': '/home/cltbld', 01:21:54 INFO - 'LANG': 'en_US.UTF-8', 01:21:54 INFO - 'LANGUAGE': 'en_US:en', 01:21:54 INFO - 'LOGNAME': 'cltbld', 01:21:54 INFO - 'MAIL': '/var/mail/cltbld', 01:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:54 INFO - 'MOZ_NO_REMOTE': '1', 01:21:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:54 INFO - 'NO_EM_RESTART': '1', 01:21:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:54 INFO - 'PWD': '/builds/slave/test', 01:21:54 INFO - 'SHELL': '/bin/bash', 01:21:54 INFO - 'SHLVL': '1', 01:21:54 INFO - 'TERM': 'linux', 01:21:54 INFO - 'TMOUT': '86400', 01:21:54 INFO - 'USER': 'cltbld', 01:21:54 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:54 INFO - '_': '/tools/buildbot/bin/python'} 01:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:55 INFO - Downloading/unpacking blobuploader==1.2.4 01:21:55 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 01:21:55 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 01:21:55 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 01:21:55 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 01:21:55 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 01:21:55 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 01:21:56 INFO - Downloading blobuploader-1.2.4.tar.gz 01:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:21:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:21:57 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 01:21:57 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 01:21:57 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 01:21:57 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 01:21:57 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 01:21:57 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 01:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:21:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:21:57 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 01:21:57 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 01:21:57 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 01:21:57 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 01:21:57 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 01:21:57 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 01:21:57 INFO - Downloading docopt-0.6.1.tar.gz 01:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:21:57 INFO - Installing collected packages: blobuploader, requests, docopt 01:21:57 INFO - Running setup.py install for blobuploader 01:21:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:21:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:21:57 INFO - Running setup.py install for requests 01:21:58 INFO - Running setup.py install for docopt 01:21:58 INFO - Successfully installed blobuploader requests docopt 01:21:58 INFO - Cleaning up... 01:21:58 INFO - Return code: 0 01:21:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:58 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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:21:58 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 01:21:58 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 01:21:58 INFO - Using env: {'DISPLAY': ':0', 01:21:58 INFO - 'HOME': '/home/cltbld', 01:21:58 INFO - 'LANG': 'en_US.UTF-8', 01:21:58 INFO - 'LANGUAGE': 'en_US:en', 01:21:58 INFO - 'LOGNAME': 'cltbld', 01:21:58 INFO - 'MAIL': '/var/mail/cltbld', 01:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:58 INFO - 'MOZ_NO_REMOTE': '1', 01:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:21:58 INFO - 'NO_EM_RESTART': '1', 01:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:58 INFO - 'PWD': '/builds/slave/test', 01:21:58 INFO - 'SHELL': '/bin/bash', 01:21:58 INFO - 'SHLVL': '1', 01:21:58 INFO - 'TERM': 'linux', 01:21:58 INFO - 'TMOUT': '86400', 01:21:58 INFO - 'USER': 'cltbld', 01:21:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:58 INFO - '_': '/tools/buildbot/bin/python'} 01:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:21:58 INFO - Running setup.py (path:/tmp/pip-3ostYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:21:58 INFO - Running setup.py (path:/tmp/pip-NvriWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:21:58 INFO - Running setup.py (path:/tmp/pip-OC1ogh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:21:58 INFO - Running setup.py (path:/tmp/pip-GMN7N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:21:58 INFO - Running setup.py (path:/tmp/pip-xufTdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:21:58 INFO - Running setup.py (path:/tmp/pip-qoxjXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:21:59 INFO - Running setup.py (path:/tmp/pip-44J5oR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:21:59 INFO - Running setup.py (path:/tmp/pip-R7EwOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:21:59 INFO - Running setup.py (path:/tmp/pip-560T5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:21:59 INFO - Running setup.py (path:/tmp/pip-7sCWyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:21:59 INFO - Running setup.py (path:/tmp/pip-VyMvdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:21:59 INFO - Running setup.py (path:/tmp/pip-NX3n9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:21:59 INFO - Running setup.py (path:/tmp/pip-90wEhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:21:59 INFO - Running setup.py (path:/tmp/pip-sqVA6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:21:59 INFO - Running setup.py (path:/tmp/pip-qfN0FM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:21:59 INFO - Running setup.py (path:/tmp/pip-yu3btM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:21:59 INFO - Running setup.py (path:/tmp/pip-7fdAQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:21:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:21:59 INFO - Running setup.py install for manifestparser 01:21:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:21:59 INFO - Running setup.py install for mozcrash 01:22:00 INFO - Running setup.py install for mozdebug 01:22:00 INFO - Running setup.py install for mozdevice 01:22:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Running setup.py install for mozfile 01:22:00 INFO - Running setup.py install for mozhttpd 01:22:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Running setup.py install for mozinfo 01:22:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Running setup.py install for mozInstall 01:22:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Running setup.py install for mozleak 01:22:00 INFO - Running setup.py install for mozlog 01:22:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Running setup.py install for moznetwork 01:22:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:22:00 INFO - Running setup.py install for mozprocess 01:22:01 INFO - Running setup.py install for mozprofile 01:22:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:22:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:22:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:22:01 INFO - Running setup.py install for mozrunner 01:22:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:22:01 INFO - Running setup.py install for mozscreenshot 01:22:01 INFO - Running setup.py install for moztest 01:22:01 INFO - Running setup.py install for mozversion 01:22:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:22:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:22:01 INFO - Cleaning up... 01:22:01 INFO - Return code: 0 01:22:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22:01 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')]} 01:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:01 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')]} 01:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:01 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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:22:01 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 01:22:01 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 01:22:01 INFO - Using env: {'DISPLAY': ':0', 01:22:01 INFO - 'HOME': '/home/cltbld', 01:22:01 INFO - 'LANG': 'en_US.UTF-8', 01:22:01 INFO - 'LANGUAGE': 'en_US:en', 01:22:01 INFO - 'LOGNAME': 'cltbld', 01:22:01 INFO - 'MAIL': '/var/mail/cltbld', 01:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:01 INFO - 'MOZ_NO_REMOTE': '1', 01:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:01 INFO - 'NO_EM_RESTART': '1', 01:22:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:01 INFO - 'PWD': '/builds/slave/test', 01:22:01 INFO - 'SHELL': '/bin/bash', 01:22:01 INFO - 'SHLVL': '1', 01:22:01 INFO - 'TERM': 'linux', 01:22:01 INFO - 'TMOUT': '86400', 01:22:01 INFO - 'USER': 'cltbld', 01:22:01 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:01 INFO - '_': '/tools/buildbot/bin/python'} 01:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:01 INFO - Running setup.py (path:/tmp/pip-qNH_sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:01 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)) 01:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:01 INFO - Running setup.py (path:/tmp/pip-jW3Jka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:01 INFO - Running setup.py (path:/tmp/pip-4UW8Od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:02 INFO - Running setup.py (path:/tmp/pip-tynl_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:02 INFO - Running setup.py (path:/tmp/pip-t1yhS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:02 INFO - Running setup.py (path:/tmp/pip-vNdjX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:02 INFO - Running setup.py (path:/tmp/pip-oS7URM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:02 INFO - Running setup.py (path:/tmp/pip-NGQ_4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:02 INFO - Running setup.py (path:/tmp/pip-iETRIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:02 INFO - Running setup.py (path:/tmp/pip-N_jPr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:02 INFO - Running setup.py (path:/tmp/pip-7xX0Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:02 INFO - Running setup.py (path:/tmp/pip-sJjVYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:02 INFO - Running setup.py (path:/tmp/pip-eceGpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:02 INFO - Running setup.py (path:/tmp/pip-TUYjbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:02 INFO - Running setup.py (path:/tmp/pip-zmbKLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:02 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)) 01:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:02 INFO - Running setup.py (path:/tmp/pip-P4Q35s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:03 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)) 01:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:03 INFO - Running setup.py (path:/tmp/pip-mKnzVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:03 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)) 01:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:22:03 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)) 01:22:03 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)) 01:22:03 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)) 01:22:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:22:03 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 01:22:03 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 01:22:03 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 01:22:03 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 01:22:03 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 01:22:03 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 01:22:04 INFO - Downloading blessings-1.6.tar.gz 01:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:22:05 INFO - Installing collected packages: blessings 01:22:05 INFO - Running setup.py install for blessings 01:22:05 INFO - Successfully installed blessings 01:22:05 INFO - Cleaning up... 01:22:05 INFO - Return code: 0 01:22:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:22:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:22:05 INFO - Reading from file tmpfile_stdout 01:22:05 INFO - Current package versions: 01:22:05 INFO - argparse == 1.2.1 01:22:05 INFO - blessings == 1.6 01:22:05 INFO - blobuploader == 1.2.4 01:22:05 INFO - docopt == 0.6.1 01:22:05 INFO - functools32 == 3.2.3-2 01:22:05 INFO - jsonschema == 2.5.1 01:22:05 INFO - manifestparser == 1.1 01:22:05 INFO - mozInstall == 1.12 01:22:05 INFO - mozcrash == 0.17 01:22:05 INFO - mozdebug == 0.1 01:22:05 INFO - mozdevice == 0.48 01:22:05 INFO - mozfile == 1.2 01:22:05 INFO - mozhttpd == 0.7 01:22:05 INFO - mozinfo == 0.9 01:22:05 INFO - mozleak == 0.1 01:22:05 INFO - mozlog == 3.3 01:22:05 INFO - moznetwork == 0.27 01:22:05 INFO - mozprocess == 0.23 01:22:05 INFO - mozprofile == 0.28 01:22:05 INFO - mozrunner == 6.12 01:22:05 INFO - mozscreenshot == 0.1 01:22:05 INFO - mozsystemmonitor == 0.3 01:22:05 INFO - moztest == 0.7 01:22:05 INFO - mozversion == 1.4 01:22:05 INFO - psutil == 3.1.1 01:22:05 INFO - requests == 1.2.3 01:22:05 INFO - wsgiref == 0.1.2 01:22:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22:05 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')]} 01:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:05 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')]} 01:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:05 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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:22:05 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 01:22:05 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 01:22:05 INFO - Using env: {'DISPLAY': ':0', 01:22:05 INFO - 'HOME': '/home/cltbld', 01:22:05 INFO - 'LANG': 'en_US.UTF-8', 01:22:05 INFO - 'LANGUAGE': 'en_US:en', 01:22:05 INFO - 'LOGNAME': 'cltbld', 01:22:05 INFO - 'MAIL': '/var/mail/cltbld', 01:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:05 INFO - 'MOZ_NO_REMOTE': '1', 01:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:05 INFO - 'NO_EM_RESTART': '1', 01:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:05 INFO - 'PWD': '/builds/slave/test', 01:22:05 INFO - 'SHELL': '/bin/bash', 01:22:05 INFO - 'SHLVL': '1', 01:22:05 INFO - 'TERM': 'linux', 01:22:05 INFO - 'TMOUT': '86400', 01:22:05 INFO - 'USER': 'cltbld', 01:22:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:05 INFO - '_': '/tools/buildbot/bin/python'} 01:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 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)) 01:22:05 INFO - Cleaning up... 01:22:05 INFO - Return code: 0 01:22:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:22:05 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')]} 01:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:05 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')]} 01:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:05 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 0x7f1ca2489f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, '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': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', '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 01:22:05 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 01:22:05 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 01:22:05 INFO - Using env: {'DISPLAY': ':0', 01:22:05 INFO - 'HOME': '/home/cltbld', 01:22:05 INFO - 'LANG': 'en_US.UTF-8', 01:22:05 INFO - 'LANGUAGE': 'en_US:en', 01:22:05 INFO - 'LOGNAME': 'cltbld', 01:22:05 INFO - 'MAIL': '/var/mail/cltbld', 01:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:05 INFO - 'MOZ_NO_REMOTE': '1', 01:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:05 INFO - 'NO_EM_RESTART': '1', 01:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:05 INFO - 'PWD': '/builds/slave/test', 01:22:05 INFO - 'SHELL': '/bin/bash', 01:22:05 INFO - 'SHLVL': '1', 01:22:05 INFO - 'TERM': 'linux', 01:22:05 INFO - 'TMOUT': '86400', 01:22:05 INFO - 'USER': 'cltbld', 01:22:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:05 INFO - '_': '/tools/buildbot/bin/python'} 01:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:22:05 INFO - Cleaning up... 01:22:05 INFO - Return code: 0 01:22:05 INFO - Running post-action listener: _resource_record_post_action 01:22:05 INFO - Running post-action listener: _start_resource_monitoring 01:22:05 INFO - Starting resource monitoring. 01:22:05 INFO - [mozharness: 2016-09-01 08:22:05.901059Z] Finished create-virtualenv step (success) 01:22:05 INFO - [mozharness: 2016-09-01 08:22:05.901656Z] Running install step. 01:22:05 INFO - Running pre-action listener: _resource_record_pre_action 01:22:05 INFO - Running main action method: install 01:22:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:22:06 INFO - Reading from file tmpfile_stdout 01:22:06 INFO - Detecting whether we're running mozinstall >=1.0... 01:22:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:22:06 INFO - Reading from file tmpfile_stdout 01:22:06 INFO - Output received: 01:22:06 INFO - Usage: mozinstall [options] installer 01:22:06 INFO - Options: 01:22:06 INFO - -h, --help show this help message and exit 01:22:06 INFO - -d DEST, --destination=DEST 01:22:06 INFO - Directory to install application into. [default: 01:22:06 INFO - "/builds/slave/test"] 01:22:06 INFO - --app=APP Application being installed. [default: firefox] 01:22:06 INFO - mkdir: /builds/slave/test/build/application 01:22:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:22:23 INFO - Reading from file tmpfile_stdout 01:22:23 INFO - Output received: 01:22:23 INFO - /builds/slave/test/build/application/firefox/firefox 01:22:23 INFO - Running post-action listener: _resource_record_post_action 01:22:23 INFO - [mozharness: 2016-09-01 08:22:23.219077Z] Finished install step (success) 01:22:23 INFO - [mozharness: 2016-09-01 08:22:23.219356Z] Running run-tests step. 01:22:23 INFO - Running pre-action listener: _resource_record_pre_action 01:22:23 INFO - Running main action method: run_tests 01:22:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:22:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:22:23 INFO - Python 2.7.3 01:22:23 INFO - Return code: 0 01:22:23 INFO - grabbing minidump binary from tooltool 01:22: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')]} 01:22:23 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 0x2a9a370>, '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 0x2a9d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a9dd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:22:23 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 01:22:23 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 01:22:23 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 01:22:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:22:23 INFO - Return code: 0 01:22:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:22:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:22:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:22:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:22:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:22:23 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/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 01:22:23 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/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 01:22:23 INFO - Using env: {'DISPLAY': ':0', 01:22:23 INFO - 'HOME': '/home/cltbld', 01:22:23 INFO - 'LANG': 'en_US.UTF-8', 01:22:23 INFO - 'LANGUAGE': 'en_US:en', 01:22:23 INFO - 'LOGNAME': 'cltbld', 01:22:23 INFO - 'MAIL': '/var/mail/cltbld', 01:22:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:22:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:23 INFO - 'MOZ_NO_REMOTE': '1', 01:22:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:22:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:23 INFO - 'NO_EM_RESTART': '1', 01:22:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:23 INFO - 'PWD': '/builds/slave/test', 01:22:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:22:23 INFO - 'SHELL': '/bin/bash', 01:22:23 INFO - 'SHLVL': '1', 01:22:23 INFO - 'TERM': 'linux', 01:22:23 INFO - 'TMOUT': '86400', 01:22:23 INFO - 'USER': 'cltbld', 01:22:23 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480', 01:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:23 INFO - '_': '/tools/buildbot/bin/python'} 01:22:23 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/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 01:22:23 INFO - mozversion application_buildid: 20160831223024 01:22:23 INFO - mozversion application_changeset: b7f7ae14590aced450bb0b0469dfb38edd2c0ace 01:22:23 INFO - mozversion application_display_name: Nightly 01:22:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:22:23 INFO - mozversion application_name: Firefox 01:22:23 INFO - mozversion application_remotingname: firefox 01:22:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:22:23 INFO - mozversion application_vendor: Mozilla 01:22:23 INFO - mozversion application_version: 51.0a1 01:22:23 INFO - mozversion platform_buildid: 20160831223024 01:22:23 INFO - mozversion platform_changeset: b7f7ae14590aced450bb0b0469dfb38edd2c0ace 01:22:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:22:23 INFO - mozversion platform_version: 51.0a1 01:22:23 INFO - using testdate: 1472718143 01:22:23 INFO - actual date: 1472718143 01:22:23 INFO - starting webserver on 'localhost:41406' 01:22:23 INFO - SUITE-START | Running 2 tests 01:22:23 INFO - TEST-START | dromaeo_css 01:22:23 INFO - operating with platform_type : linux_ 01:22:23 INFO - Initialising browser for dromaeo_css test... 01:22:23 INFO - TEST-INFO | started process 23204 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQLzoor/profile http://localhost:41406/getInfo.html) 01:22:27 INFO - PROCESS | 23204 | __metrics Screen width/height:1600/1200 01:22:27 INFO - PROCESS | 23204 | colorDepth:24 01:22:27 INFO - PROCESS | 23204 | Browser inner width/height: 1024/697 01:22:27 INFO - PROCESS | 23204 | __metrics 01:22:28 INFO - PROCESS | 23204 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:22:28 INFO - PROCESS | 23204 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:22:28 INFO - PROCESS | 23204 | console.error: PushService: 01:22:28 INFO - PROCESS | 23204 | stateChangeProcessEnqueue: Error transitioning state 01:22:28 INFO - PROCESS | 23204 | UnknownError 01:22:29 INFO - TEST-INFO | 23204: exit 0 01:22:29 INFO - Browser initialized. 01:22:29 INFO - Running cycle 1/1 for dromaeo_css test... 01:22:29 INFO - TEST-INFO | started process 23284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQLzoor/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:22:41 INFO - PROCESS | 23284 | RSS: Main: 180285440 01:22:41 INFO - PROCESS | 23284 | 01:23:32 INFO - PROCESS | 23284 | 1472718212236 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] 01:25:42 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:41406/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41406/tests/dromaeo/cssquery-ext.html) 01:25:44 INFO - PROCESS | 23284 | RSS: Main: 345190400 01:25:44 INFO - PROCESS | 23284 | 01:28:46 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:41406/tests/dromaeo/cssquery-ext.html (next: http://localhost:41406/tests/dromaeo/cssquery-jquery.html) 01:28:47 INFO - PROCESS | 23284 | RSS: Main: 278110208 01:28:47 INFO - PROCESS | 23284 | 01:31:49 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:41406/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41406/tests/dromaeo/cssquery-mootools.html) 01:31:49 INFO - PROCESS | 23284 | RSS: Main: 219758592 01:31:49 INFO - PROCESS | 23284 | 01:34:52 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:41406/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41406/tests/dromaeo/cssquery-prototype.html) 01:34:52 INFO - PROCESS | 23284 | RSS: Main: 217800704 01:34:52 INFO - PROCESS | 23284 | 01:37:55 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:41406/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41406/tests/dromaeo/cssquery-yui.html) 01:37:55 INFO - PROCESS | 23284 | RSS: Main: 220143616 01:37:55 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:41406/tests/dromaeo/cssquery-yui.html (next: http://localhost:41406/tests/dromaeo/cssquery-dojo.html) 01:40:58 INFO - PROCESS | 23284 | RSS: Main: 306139136 01:40:58 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | __start_tp_report 01:40:58 INFO - PROCESS | 23284 | _x_x_mozilla_page_load 01:40:58 INFO - PROCESS | 23284 | _x_x_mozilla_page_load_details 01:40:58 INFO - PROCESS | 23284 | |i|pagename|runs| 01:40:58 INFO - PROCESS | 23284 | |0;dojo.html;3015.261260991057;3044.695530446955;3068.1409205422488;3103.224193951512;3125.531170324451;5219.373675158977;5294.894102117955;5387.946120538793;5450.236966824643;5452.40023597404;5281.656692314997;5300.469953004697;5345.518903298712;5415.783368665248;5421.891562168754;4865.172920603506;4894.119058569456;4911.336969509124;4941.407031156266;4973.502649735034;4856.830010949618;4861.440934292548;4923.187674033798;4923.55687988081;4927.162382394984;4887.095887260851;4945.010997800454;4959.305697202394;4965.131102057144;4968.22992436173;4703.200455922467;4885.853424397274;4903.509649035103;4944.208926571767;4948.307236986825;4668.43978722552;4702.083093796711;4784.30627559002;4828.589569886564;4854.6116310695415;5184.170532714786;5201.635885488017;5254.789808407659;5278.97360513195;5319.0957537218765;5564.888702225973;5603.859903502445;5610.49505544503;5611.298587676541;5612.15817627355;5719.199312096268;5737.08206686928;5746.367899531048;5750.539956803445;5753.539716822645;5335.333083364619;5358.812441564526;5390.78436862525;5391.110466772939;5393.056215162331;5740.368559458456;5829.59192856496;5880.500157486576;5891.852703682356;5896.852578685482;5560.499555040065;5650.63270887391;5652.18043383707;5698.8290351289525;5714.914276285859;5712.485876271156;5716.228309178291;5717.971410143005;5720.8283751487525;5723.198752174699;5474.042042642522;5574.860628484239;5715.428457154251;5718.656880587178;5725.427457254241;5598.216249725042;5625;5636.408177141423;5651.9152212716845;5709.65742055478;4350.129974005148;4388.253996820485;4388.780560971939;4391;4441.977790111094;3928.744631598937;3935.58676338986;3945.309570825094;3959.5050618672667;3965.78188199646;5463.071277882769;5472.26124473199;5499.202615620713;5548.361938377138;5559.9444005560235;5732;5742.052561327418;5745.8563535911935;5746.454086861741;5751.59738818287;5347.2781174541715;5362.115250983622;5447.509724124848;5490.945090549123;5502.422245664227;4632.930506042412;4648.721076735406;4666.230072038075;4698.1778188816825;4700.365450664185;5844.3571207168025;5844.47399734026;5846.386129456431;5847.356790753014;5850.210221620112;5602.439756024365;5619.80330688424;5621.971890140523;5680.545556355564;5722.971385143048;5977.701114944322;6021.518278537794;6060.969695151496;6080.8783824324155;6087.908681369871;5645.294338207724;5681.630694004876;5681.744321505459;5687.090065589486;5690.743916523849;5564.554835613222;5645.604807663424;5784.595078344475;5787.305523337226;5791.507721843691;5478.726063696719;5545.944540554704;5553.750081246273;5554.444555544414;5559.2217089606675;5527.613067085426;5533.170024496358;5542.5565954722715;5550.361708403424;5559.082751345983;5463.4536546346635;5491.643043202179;5500.917486237629;5514.6691198528215;5530.474604912527;5650.717464126859;5721.22763426932;5723.6851973141875;5724.313082430135;5731.856703582277;5547.389787123332;5555.2778138841695;5556.888862222652;5559;5559.749811258423 01:40:58 INFO - PROCESS | 23284 | |1;ext.html;585.7334912614759;592.733270028487;598.8443004818752;601.9608725432845;607.1014897951032;45677.771611141936;47668.523314766884;47742;47782.76108619461;47835.28247076288;44647.55352446483;45154;45183.322250166275;45201.32198017032;45326;7989.360851131895;8834.734957951256;8955.731328060168;8980.730578082635;8993.505357205351;7898.802529936769;8275.08974012858;8275.213854683825;10559.947200263989;10808;10833.620823271187;10881.401522916236;10887.782244355109;10893.128549716004;10895.291806032583;10836;10889.401082940434;10894.400807955519;10897.67306980792;10901.236913416064;10604.469776511221;10651.786964260671;10663.146948244123;10675.78648427035;10681;43257.78371108156;43469;43606.34590481145;43627.34558981618;43645;3292.506124081383;3312.4865645824934;3325.3515564464938;3374.8818791342187;3476.8087755173456;32541;32548.511772323433;32621;32661.51007734886;32719.50920736191;4200.831966721327;4302.354646802974;4320.82716691332;4334.631556317717;4402.361657559668;350029;350314;350349;354145;354333;35194.64805351914;59071.40928590659;59360.40639593635;59393;59462.40537594569;36554.80671159686;38107;38118.809405952794;38121.42817857734;38123.75948044392;14619.269036548343;14766.63083422923;14771.482998095014;14783.712723310335;14785.556433306803;12197.51209951592;12204.840540148673;12269.815952760715;12345.814812777815;12351.629451116482;8097.149799270992;8389.916100839035;8568.400211985101;8705.47767133974;8815.647374104963;3261.673832616719;3364.5794275715534;3399.2691571312284;3409.556757621543;3411.692947634675;965.7295957132014;972.6644307713827;994.3387647214666;1001.7946321004176;1125.2966895690195;32101.839490802395;32500.512492312635;33296.33407331888;33691.663083368854;35791.284174316366;1042.8331466965249;1052.9894701052892;1064.6752740414092;1067.7063807452857;1131.4060118437885;16360.918195408947;16978.151092445576;17046.31814727397;17108.74336884948;17132.571685707957;534676;543385;544684;545973;573246;59293.70353148207;60152.39847601556;60178;60200.39799602036;60251.096233555654;1229.5327775445273;1326.562234462625;1340.2896464873634;1353.6006877971022;1364.911280766747;30870.228244293176;32997.17507062342;33972.320553588295;34303.14242143966;34357.48463773095;50754.74622626863;51387.743061284455;51401.7429912848;51416.228756567936;51494.742526288625;307419;309753;309814;310390;310625;28315.292117697223;29525;29618.259543511584;30751.077467676005;30810.229744255677;49297.50702492929;49424.25863612121;49465.25802113043;49931.50068499269;50287;41154.58845411507;41469;41697.1660566793;42082.15835683208;43179.13641727211;41371.58628413677;42094.78952605218;42298.78850605727;43484.34773478337;43867.56132438635;39948.2010359797;41395.172096558505;41415.7929210352;41588.37617435801;43904.78047609742;33893.661063390035;37587.248255035294;37857.43213851739;37933;38432.42351364676;28096.85951570229;28269.57595636108;28298.575521366784;28330.2917427066;28606.42787144287 01:40:58 INFO - PROCESS | 23284 | |2;jquery.html;362.1146297303095;385.67795890431506;392.4348937529958;401.7991004497751;421.38267438203025;16523.75214371784;19034.714479282822;19237;19420.126094325755;20692.48268793281;17710.557236069104;17877.64244715105;18721.472601198835;18930.526736831587;19829.305974290903;5670.829875103733;5702.857428564288;5873.706314684248;5977.760889564434;5980.671063091529;5807.361190269088;5845.444682755153;5868.882622347551;5879.735411906453;5880.823575292727;5636.943630563704;5749.31008279005;5824.0390335594575;5853.063509838426;5858.502027327682;5835;5860.853478663025;5867.647941123503;5879;5879.7648094076185;5130.410002849682;5182.53357197854;5236.293100431431;5264.631475796696;5267.604929630293;23386.532269354688;23490.64764028541;23621.645675314885;23667.171648992204;23714.881425592936;2486.7388924162883;2531.3291977625945;2620.5414052540727;2726.5501192303245;2967.8812847486074;14497.927510362486;14615.707685846224;14657.56027319182;14996.400143994228;15110.244487775568;1884.3713020336554;1959.480737604536;2029.0666293505005;2031.207828946712;2048.150017742635;84490;85470;85595;87005.56497217537;87710.56144719299;10788.244822862482;10945.945270273598;11079;11209.495572699085;11307.830382544103;15245;15285.6178595536;15308.387664493519;15313.702944558183;15336.846631533543;8232.958835205785;8401.70594029206;8403.789905252295;8465.95767021161;8662.263707584925;8107.594620268963;8125.79685507867;8356.197929941862;8387.454815437099;8443.240108390275;6347.873462390059;6563.901541476882;6639.302873198341;6660.633665148366;6662;1872.850171986238;1888.282452668005;1932.7487426634448;1969.960600788005;1977.1399441243104;895.4582477601087;937.929655275857;963.1283688261994;1024.109025148126;1045.4197920154388;19747.308844190386;19935.700964485546;20024.699629505278;20108.798912010985;20218.696719549218;942.4298299528823;971.145392054987;987.1411871671548;1003.7189586915675;1023.4575674892319;10759.30064545802;11438.542458301576;11508.884911150948;11524.884751152382;11612.593559225556;470955;473929;474768;477910;486781;88397;90440;90742.54628726946;92505.99999999865;93469.5326523363;1020.1125021231576;1075.5858994287255;1079.3200283821143;1100.2353364411656;1123.8707548631849;17252.741208881627;17425.651486969935;17961.371352002618;18141.546461338574;18189.272429102686;43393;43396.783016084715;43508.34737478877;43628.56371436245;44121.55878441175;59005;59076.40923590709;59368;59709.70145149247;60067.09899351426;8418.789530261793;8497.957510212409;8551.57242137903;8560.357973151915;8719.607617657093;12869.22784632925;13096.803547946598;13274.867251327749;13415.664608384479;13436.39536220892;11265.380404077854;11459.484323205643;11540.192186547198;11912.98739607143;12023.21849079807;11247.66257012291;11292.943535282271;11569.19015668895;11637.53449861996;12094.818577721166;11094.167937404565;11466.885331146581;11554.59558915434;11982.820257696316;12446.626601201979;7544.886826697713;8528.74413767588;8530.872036919327;8691.95654021726;8788.91211087881;11261.155413343868;11595.942020289845;12073.456694448596;12223;12427 01:40:58 INFO - PROCESS | 23284 | |3;mootools.html;1365.9453621855125;1455.9563213103604;1496.5360738171166;1601.4314918204038;1774.3878361965117;8127.837443251132;8473.449225800327;8505.957470212641;8520.275776558992;8815.206631403173;8347.332213422927;8504.744857654265;8694;8706.695265665703;8715.128487151282;2174.608570457317;2378.6194208926577;2391.473875747337;2448.9877550612223;2455.7789798918093;2421.987890060556;2435.3789783605157;2438.207582535674;2443.4990826880535;2445.3397582652674;2359.539889721505;2364.1252736487436;2374.679418278536;2387.892544835478;2388.36708272308;2281.53228588139;2333.801626861719;2339.298210536849;2351.5767161910935;2354.5526349993534;2358.092134528219;2366.396568874926;2381.6427535869584;2386.3198988288254;2388.558116748407;8290.295324897392;8361.623726932303;8364.079951205362;8813.42712723671;8861.379703420764;5200.453952334989;5340.813071542478;5366.221897824794;5367.355917289911;5371.865703357408;3449.5688038995127;3476.89569312921;3568.4647302904514;3582.3551760683067;3647.7993710345922;1223.7613665335264;1247.4885297028266;1252.1172573335778;1261.7224210673637;1300.2003767682806;11577.421128943519;11759.35323557204;11852.881471185265;11860.169788114836;11904.107191960638;4507;4694.333511603331;4796.424429068465;4816.566509014206;4858.5384388483035;10167.847482287772;10177;10187.898121018696;10194.133498652698;10195.949020254851;5341.839744807662;5366.785328586813;5367.677939323653;5371.328583927009;5371.677699338053;4282.850100246476;4395;4792.640551958547;4882.829100981448;4885.9755701221975;3979.9403008954887;4085.611866872642;4188.518320393133;4203.6637069034405;4208.053188032668;1266.5693664154232;1274.8024056271292;1285.8135570342436;1296.6887946892866;1299.155548893227;803.6544285957094;954.1317401164906;954.2032402943481;968.3657204531044;998.9051040151174;5706.771729130871;5781.364049954502;5897.469227769521;5972.25346831646;6098.69506524672;945.239082538563;967.709687093869;980.2011360740973;1001.1340190734962;1012.2003617142523;3988.521377434726;4143.399207114951;4166.854160104382;4203.91592168155;4214.367844823301;10777.946110269555;10873.293235939638;11041.39272340013;11056.11551075912;11057.778844423066;10964.506597203144;10971.670849874517;10973.067289280341;10986.450677466095;10989.835152472719;8031.558264295403;8090.312323452573;8095.838083238302;8150.633221505046;8159.26566609008;5736.397678243891;5844.3571207168025;5861.589688721749;5887.499562537232;6051.273847138372;9760.755981100529;9994.850077248702;10126;10169.796604068026;10186;10209.9489502552;10235.181185505291;10297.279190456897;10330.431826249629;10344;6621.370969757865;6805.081314022544;7207.603581803051;7285.344319011315;7427.628618569158;8308.12764659702;8665.783355416164;8680.913190868263;8681.6093275804;8684.826303474021;7503.399728021855;7568.167501574712;7704;7777.105632852295;7817.726379576915;7417.851642967218;7438.367738742268;7445.218252083453;7473.290037446434;7722.691092356467;6853.657317134023;6996.370326670624;6997.580145191101;7004.264552221944;7006.159260888726;4979.867038046616;5065.822696205762;5104.33643626341;5127.79488820443;5312.814051508333;5184.948150518598;5218.217267409767;5284.154535274338;5286.524212820865;5309.097453432849 01:40:58 INFO - PROCESS | 23284 | |4;prototype.html;321.8712514994002;327.7410845432109;330.34261818980224;339.984700688469;341.69076985328286;5097.490250974905;5172.224166375041;5190.1176799944005;5198.220266959958;5216.817411390597;5095.312132862063;5097.488722480603;5123.795048198077;5128;5131.512506311894;3814.313423583754;4057.2291264659693;4062.53280872699;4082.9795851020704;4085.5710150434224;3951.46655201547;4003.939940900889;4020.8592699255532;4025.6376926076646;4025.859094931678;3968.1468484275874;3978.661813745835;3981.28336899358;3985.1033517458486;3988.501437320335;3775.9811200944096;3783.451399547078;3938.980305098456;3985.6612187964056;4000.9799951000205;3452.6374730653424;3453.240287136826;3465.8093804840723;3466.8212807645523;3467.930641387158;4975.328330675348;4982.775775090094;4993.900121997576;5007.173816320303;5012.268313768968;4295.506016808075;4337.609615134622;4520.982778874727;4524.773761311922;4533.274676051816;5301.522862942354;5313.548348390392;5314.80416906193;5322.627416080876;5353.197020446964;3838.2707285615643;3840.8463661453507;3842.2315536892734;3842.751105890569;3845.8269377878064;5353.197020446925;5497.257870187514;5518.33779946409;5540.9168862467095;5558.86102847428;3953.8220780064967;3975.7018223633345;3976.6818654508147;3978.5822488638855;3994.6404823566018;3986.7807270599815;4003.619656132663;4034.3141665916864;4043.939340909889;4058.5332686741403;4307.729219880143;4528.50186479487;4547.818087276538;4552.499225085238;4606.378138951266;5298.523132918056;5315.3621565412395;5316.627836051517;5321.042212401805;5329.813456529003;1346.8720471555187;1367.6785955300497;1370.294298436306;1371.7119404925077;1375.8761711446018;838.7376997618545;879.305348774461;888.320434867327;891.9598618062073;899.3524662243175;4548.74981876;4570.908581828345;4576.633869290449;4644.790984405642;4667.533246675373;4754.7147171169145;4779.593734532534;4804.759762011886;4816.662833601685;4827.782749776268;4496.3705081288645;4606.30905364198;4716.929246061243;4739.881502962454;4742.502037286035;2574.2663340948116;2605.1793684989166;2778.152663437671;2834.2630915961586;2878.8484606157285;5665.688387138664;5668.206451096849;5686.147077938343;5715.22811436453;5721.141828725641;5413.215083812904;5519.39286678465;5548.72256387179;5614.242077319591;5624.493795558419;5611.158326251013;5631.6902570359025;5646.124850648156;5675.404082571271;5683.943160568341;5427.810026649048;5432.56539476833;5434.402215756343;5443.265159203457;5463.781448742165;5225.817096401608;5324.8136315228785;5380;5414.593905457028;5427.538659214011;5648.57635677334;5648.943510564842;5655.632383895034;5666.858328541819;5679;3953.150072734376;3955.545112312121;3961.663258622934;3969.404589311511;3970.9205815884097;5228.7647055883335;5408.8377348679605;5444.8366549003595;5482.122860342326;5485.451454854483;5310.176922577007;5319.734013299397;5321.456777534627;5321.631873816114;5330.173823057432;5276.788928442819;5296;5332.360116786011;5346.166531738185;5348.358197016383;5059.114654935298;5301.363836339509;5310.761015754378;5327.786888524415;5348.866278343073;5373.462653734595;5384.219288203267;5393.6763794172475;5405.432429594836;5423.7830486780085;5028.823991160438;5287.180487024363;5288.2860813791185;5310.123829568232;5317.8404647860625 01:40:58 INFO - PROCESS | 23284 | |5;yui.html;718.4360277182412;725.2892165677636;746.9738559150427;781.7420251317066;833.9041010283815;14823.481178158752;15947.122908240042;15953.601159971007;15968.121753303563;16586.25361858716;16443.91778041111;16638.750418743697;17294.56763580911;17386.217620207088;17391.91304043481;668.0980676087268;788.1448628238368;827.5820710541168;839.5634270179503;839.6557411461303;787.0398114300544;810.2181394953868;811.3630799822141;815.5229190923317;816.073756286616;859.8194379180379;861.7371701631;863.8056437301589;863.8229163021565;863.87905693203;690.7444245629099;858.8024754306545;861.5089399042548;862.3403096631051;863.0765081362944;606.0938896349941;615.0497481391217;616.0943413182614;616.442119881508;616.8889599872022;15611.531654050397;15617.375304987787;15631.92184039084;15638.530844074696;15655.53033408988;14521.636959076002;14649.633759156;14807.185604791732;14944.62638434037;14984.925075374662;13375.33123343829;13416.329183540882;13720.335086070783;13792.586222413343;13853.515126970507;4344.304911214223;4345.130973805251;4583.03756211196;4608.354830323758;4631.58315751584;77926;79868.20131798666;80895.59552202202;85858.57070714606;87002;11594;11840;11867.940660296643;12598.496060157675;12633.178843375152;22829.88585057064;22847.200347987742;22871.31386058421;22896.65655015176;22905.31284061481;7574.659140338698;7724;7801.985741853523;7850.450468467267;7882.408819338572;4450;4595.687935075162;4606.723596584216;4685.9531404686195;4745.786439610226;3429.2798512312693;3558.5907620623443;3561.4479755637917;3570.6607872252093;3581.6239294874185;551.8923809857039;575.5625724449449;581.2037508613142;582.28960667985;582.4932308891207;623.7536173211556;648.9318621544764;652.393274254938;672.9596224226464;767.8272388712495;9496.335256531976;10288;10537.894621053845;10582.576696932036;10656.680299591024;664.8736740019381;721.938635216002;723.7322190789337;737.937840639152;740.2708332292688;5127.384713834289;5177.58342854006;5245.26566280721;5247.26840142752;5298.364196296469;74010.62994684992;75872.24127758761;126465;129419.35290323677;129743;67204.6639766798;95994;109665.45167274273;109795.45102274597;110329.44835275772;54452.45547544474;56271.43728562662;57501.712491437276;58336;72430.63784681042;9320.766980825534;10214.642487512901;10266.281360304707;10295.3822770634;10453.68638940833;38624.80687596656;52744;53099;53412;54251;75794.62102689451;80396;81264.59367703124;81322.59338703268;81994;12776.425060872469;13226.470941162246;13303;13806.102603330752;13819.654508636962;14877.553673389815;15417.306221220297;15661.451849185223;16093.114878681317;17024;14234.14595124296;14361.928190358982;14723.631909202355;14996.775048374066;15604.453844115402;13939.303034848013;14048.578542643738;14292.928535357256;14322.928385358424;14324;14510.709785804014;14569.708605828037;14770.852291476947;14789.556313310617;15718.528444146694;10753.202017034208;11065.391403472886;11088.722781930517;11126.388048657402;11258.268212566158;11184.384858832842;11345.602903898323;11961.880381196077;12233.571824986082;12329 01:40:58 INFO - PROCESS | 23284 | __end_tp_report 01:40:58 INFO - PROCESS | 23284 | __start_cc_report 01:40:58 INFO - PROCESS | 23284 | _x_x_mozilla_cycle_collect,1746 01:40:58 INFO - PROCESS | 23284 | __end_cc_report 01:40:58 INFO - PROCESS | 23284 | __startTimestamp1472719258929__endTimestamp 01:40:58 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | ------- Summary: start ------- 01:40:58 INFO - PROCESS | 23284 | Number of tests: 6 01:40:58 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | [#0] dojo.html Cycles:165 Average:5307.06 Median:5542.56 stddev:606.43 (10.9%) stddev-sans-first:581.03 01:40:58 INFO - PROCESS | 23284 | Values: 3015.3 3044.7 3068.1 3103.2 3125.5 5219.4 5294.9 5387.9 5450.2 5452.4 5281.7 5300.5 5345.5 5415.8 5421.9 4865.2 4894.1 4911.3 4941.4 4973.5 4856.8 4861.4 4923.2 4923.6 4927.2 4887.1 4945.0 4959.3 4965.1 4968.2 4703.2 4885.9 4903.5 4944.2 4948.3 4668.4 4702.1 4784.3 4828.6 4854.6 5184.2 5201.6 5254.8 5279.0 5319.1 5564.9 5603.9 5610.5 5611.3 5612.2 5719.2 5737.1 5746.4 5750.5 5753.5 5335.3 5358.8 5390.8 5391.1 5393.1 5740.4 5829.6 5880.5 5891.9 5896.9 5560.5 5650.6 5652.2 5698.8 5714.9 5712.5 5716.2 5718.0 5720.8 5723.2 5474.0 5574.9 5715.4 5718.7 5725.4 5598.2 5625.0 5636.4 5651.9 5709.7 4350.1 4388.3 4388.8 4391.0 4442.0 3928.7 3935.6 3945.3 3959.5 3965.8 5463.1 5472.3 5499.2 5548.4 5559.9 5732.0 5742.1 5745.9 5746.5 5751.6 5347.3 5362.1 5447.5 5490.9 5502.4 4632.9 4648.7 4666.2 4698.2 4700.4 5844.4 5844.5 5846.4 5847.4 5850.2 5602.4 5619.8 5622.0 5680.5 5723.0 5977.7 6021.5 6061.0 6080.9 6087.9 5645.3 5681.6 5681.7 5687.1 5690.7 5564.6 5645.6 5784.6 5787.3 5791.5 5478.7 5545.9 5553.8 5554.4 5559.2 5527.6 5533.2 5542.6 5550.4 5559.1 5463.5 5491.6 5500.9 5514.7 5530.5 5650.7 5721.2 5723.7 5724.3 5731.9 5547.4 5555.3 5556.9 5559.0 5559.7 01:40:58 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | [#1] ext.html Cycles:180 Average:56604.56 Median:36173.05 stddev:110551.40 (305.6%) stddev-sans-first:110781.51 01:40:58 INFO - PROCESS | 23284 | Values: 585.7 592.7 598.8 602.0 607.1 45677.8 47668.5 47742.0 47782.8 47835.3 44647.6 45154.0 45183.3 45201.3 45326.0 7989.4 8834.7 8955.7 8980.7 8993.5 7898.8 8275.1 8275.2 10559.9 10808.0 10833.6 10881.4 10887.8 10893.1 10895.3 10836.0 10889.4 10894.4 10897.7 10901.2 10604.5 10651.8 10663.1 10675.8 10681.0 43257.8 43469.0 43606.3 43627.3 43645.0 3292.5 3312.5 3325.4 3374.9 3476.8 32541.0 32548.5 32621.0 32661.5 32719.5 4200.8 4302.4 4320.8 4334.6 4402.4 350029.0 350314.0 350349.0 354145.0 354333.0 35194.6 59071.4 59360.4 59393.0 59462.4 36554.8 38107.0 38118.8 38121.4 38123.8 14619.3 14766.6 14771.5 14783.7 14785.6 12197.5 12204.8 12269.8 12345.8 12351.6 8097.1 8389.9 8568.4 8705.5 8815.6 3261.7 3364.6 3399.3 3409.6 3411.7 965.7 972.7 994.3 1001.8 1125.3 32101.8 32500.5 33296.3 33691.7 35791.3 1042.8 1053.0 1064.7 1067.7 1131.4 16360.9 16978.2 17046.3 17108.7 17132.6 534676.0 543385.0 544684.0 545973.0 573246.0 59293.7 60152.4 60178.0 60200.4 60251.1 1229.5 1326.6 1340.3 1353.6 1364.9 30870.2 32997.2 33972.3 34303.1 34357.5 50754.7 51387.7 51401.7 51416.2 51494.7 307419.0 309753.0 309814.0 310390.0 310625.0 28315.3 29525.0 29618.3 30751.1 30810.2 49297.5 49424.3 49465.3 49931.5 50287.0 41154.6 41469.0 41697.2 42082.2 43179.1 41371.6 42094.8 42298.8 43484.3 43867.6 39948.2 41395.2 41415.8 41588.4 43904.8 33893.7 37587.2 37857.4 37933.0 38432.4 28096.9 28269.6 28298.6 28330.3 28606.4 01:40:58 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | [#2] jquery.html Cycles:180 Average:29096.13 Median:2575.94 stddev:78751.03 (3057.2%) stddev-sans-first:78942.39 01:40:58 INFO - PROCESS | 23284 | Values: 362.1 385.7 392.4 401.8 421.4 16523.8 19034.7 19237.0 19420.1 20692.5 17710.6 17877.6 18721.5 18930.5 19829.3 5670.8 5702.9 5873.7 5977.8 5980.7 5807.4 5845.4 5868.9 5879.7 5880.8 5636.9 5749.3 5824.0 5853.1 5858.5 5835.0 5860.9 5867.6 5879.0 5879.8 5130.4 5182.5 5236.3 5264.6 5267.6 23386.5 23490.6 23621.6 23667.2 23714.9 2486.7 2531.3 2620.5 2726.6 2967.9 14497.9 14615.7 14657.6 14996.4 15110.2 1884.4 1959.5 2029.1 2031.2 2048.2 84490.0 85470.0 85595.0 87005.6 87710.6 10788.2 10945.9 11079.0 11209.5 11307.8 15245.0 15285.6 15308.4 15313.7 15336.8 8233.0 8401.7 8403.8 8466.0 8662.3 8107.6 8125.8 8356.2 8387.5 8443.2 6347.9 6563.9 6639.3 6660.6 6662.0 1872.9 1888.3 1932.7 1970.0 1977.1 895.5 937.9 963.1 1024.1 1045.4 19747.3 19935.7 20024.7 20108.8 20218.7 942.4 971.1 987.1 1003.7 1023.5 10759.3 11438.5 11508.9 11524.9 11612.6 470955.0 473929.0 474768.0 477910.0 486781.0 88397.0 90440.0 90742.5 92506.0 93469.5 1020.1 1075.6 1079.3 1100.2 1123.9 17252.7 17425.7 17961.4 18141.5 18189.3 43393.0 43396.8 43508.3 43628.6 44121.6 59005.0 59076.4 59368.0 59709.7 60067.1 8418.8 8498.0 8551.6 8560.4 8719.6 12869.2 13096.8 13274.9 13415.7 13436.4 11265.4 11459.5 11540.2 11913.0 12023.2 11247.7 11292.9 11569.2 11637.5 12094.8 11094.2 11466.9 11554.6 11982.8 12446.6 7544.9 8528.7 8530.9 8692.0 8788.9 11261.2 11595.9 12073.5 12223.0 12427.0 01:40:58 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | [#3] mootools.html Cycles:180 Average:5769.90 Median:4806.50 stddev:3219.85 (67.0%) stddev-sans-first:3211.87 01:40:58 INFO - PROCESS | 23284 | Values: 1365.9 1456.0 1496.5 1601.4 1774.4 8127.8 8473.4 8506.0 8520.3 8815.2 8347.3 8504.7 8694.0 8706.7 8715.1 2174.6 2378.6 2391.5 2449.0 2455.8 2422.0 2435.4 2438.2 2443.5 2445.3 2359.5 2364.1 2374.7 2387.9 2388.4 2281.5 2333.8 2339.3 2351.6 2354.6 2358.1 2366.4 2381.6 2386.3 2388.6 8290.3 8361.6 8364.1 8813.4 8861.4 5200.5 5340.8 5366.2 5367.4 5371.9 3449.6 3476.9 3568.5 3582.4 3647.8 1223.8 1247.5 1252.1 1261.7 1300.2 11577.4 11759.4 11852.9 11860.2 11904.1 4507.0 4694.3 4796.4 4816.6 4858.5 10167.8 10177.0 10187.9 10194.1 10195.9 5341.8 5366.8 5367.7 5371.3 5371.7 4282.9 4395.0 4792.6 4882.8 4886.0 3979.9 4085.6 4188.5 4203.7 4208.1 1266.6 1274.8 1285.8 1296.7 1299.2 803.7 954.1 954.2 968.4 998.9 5706.8 5781.4 5897.5 5972.3 6098.7 945.2 967.7 980.2 1001.1 1012.2 3988.5 4143.4 4166.9 4203.9 4214.4 10777.9 10873.3 11041.4 11056.1 11057.8 10964.5 10971.7 10973.1 10986.5 10989.8 8031.6 8090.3 8095.8 8150.6 8159.3 5736.4 5844.4 5861.6 5887.5 6051.3 9760.8 9994.9 10126.0 10169.8 10186.0 10209.9 10235.2 10297.3 10330.4 10344.0 6621.4 6805.1 7207.6 7285.3 7427.6 8308.1 8665.8 8680.9 8681.6 8684.8 7503.4 7568.2 7704.0 7777.1 7817.7 7417.9 7438.4 7445.2 7473.3 7722.7 6853.7 6996.4 6997.6 7004.3 7006.2 4979.9 5065.8 5104.3 5127.8 5312.8 5184.9 5218.2 5284.2 5286.5 5309.1 01:40:58 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | [#4] prototype.html Cycles:180 Average:4450.29 Median:5043.97 stddev:1308.66 (25.9%) stddev-sans-first:1275.12 01:40:58 INFO - PROCESS | 23284 | Values: 321.9 327.7 330.3 340.0 341.7 5097.5 5172.2 5190.1 5198.2 5216.8 5095.3 5097.5 5123.8 5128.0 5131.5 3814.3 4057.2 4062.5 4083.0 4085.6 3951.5 4003.9 4020.9 4025.6 4025.9 3968.1 3978.7 3981.3 3985.1 3988.5 3776.0 3783.5 3939.0 3985.7 4001.0 3452.6 3453.2 3465.8 3466.8 3467.9 4975.3 4982.8 4993.9 5007.2 5012.3 4295.5 4337.6 4521.0 4524.8 4533.3 5301.5 5313.5 5314.8 5322.6 5353.2 3838.3 3840.8 3842.2 3842.8 3845.8 5353.2 5497.3 5518.3 5540.9 5558.9 3953.8 3975.7 3976.7 3978.6 3994.6 3986.8 4003.6 4034.3 4043.9 4058.5 4307.7 4528.5 4547.8 4552.5 4606.4 5298.5 5315.4 5316.6 5321.0 5329.8 1346.9 1367.7 1370.3 1371.7 1375.9 838.7 879.3 888.3 892.0 899.4 4548.7 4570.9 4576.6 4644.8 4667.5 4754.7 4779.6 4804.8 4816.7 4827.8 4496.4 4606.3 4716.9 4739.9 4742.5 2574.3 2605.2 2778.2 2834.3 2878.8 5665.7 5668.2 5686.1 5715.2 5721.1 5413.2 5519.4 5548.7 5614.2 5624.5 5611.2 5631.7 5646.1 5675.4 5683.9 5427.8 5432.6 5434.4 5443.3 5463.8 5225.8 5324.8 5380.0 5414.6 5427.5 5648.6 5648.9 5655.6 5666.9 5679.0 3953.2 3955.5 3961.7 3969.4 3970.9 5228.8 5408.8 5444.8 5482.1 5485.5 5310.2 5319.7 5321.5 5321.6 5330.2 5276.8 5296.0 5332.4 5346.2 5348.4 5059.1 5301.4 5310.8 5327.8 5348.9 5373.5 5384.2 5393.7 5405.4 5423.8 5028.8 5287.2 5288.3 5310.1 5317.8 01:40:58 INFO - PROCESS | 23284 | 01:40:58 INFO - PROCESS | 23284 | [#5] yui.html Cycles:180 Average:20525.22 Median:4344.72 stddev:28926.19 (665.8%) stddev-sans-first:28969.10 01:40:58 INFO - PROCESS | 23284 | Values: 718.4 725.3 747.0 781.7 833.9 14823.5 15947.1 15953.6 15968.1 16586.3 16443.9 16638.8 17294.6 17386.2 17391.9 668.1 788.1 827.6 839.6 839.7 787.0 810.2 811.4 815.5 816.1 859.8 861.7 863.8 863.8 863.9 690.7 858.8 861.5 862.3 863.1 606.1 615.0 616.1 616.4 616.9 15611.5 15617.4 15631.9 15638.5 15655.5 14521.6 14649.6 14807.2 14944.6 14984.9 13375.3 13416.3 13720.3 13792.6 13853.5 4344.3 4345.1 4583.0 4608.4 4631.6 77926.0 79868.2 80895.6 85858.6 87002.0 11594.0 11840.0 11867.9 12598.5 12633.2 22829.9 22847.2 22871.3 22896.7 22905.3 7574.7 7724.0 7802.0 7850.5 7882.4 4450.0 4595.7 4606.7 4686.0 4745.8 3429.3 3558.6 3561.4 3570.7 3581.6 551.9 575.6 581.2 582.3 582.5 623.8 648.9 652.4 673.0 767.8 9496.3 10288.0 10537.9 10582.6 10656.7 664.9 721.9 723.7 737.9 740.3 5127.4 5177.6 5245.3 5247.3 5298.4 74010.6 75872.2 126465.0 129419.4 129743.0 67204.7 95994.0 109665.5 109795.5 110329.4 54452.5 56271.4 57501.7 58336.0 72430.6 9320.8 10214.6 10266.3 10295.4 10453.7 38624.8 52744.0 53099.0 53412.0 54251.0 75794.6 80396.0 81264.6 81322.6 81994.0 12776.4 13226.5 13303.0 13806.1 13819.7 14877.6 15417.3 15661.5 16093.1 17024.0 14234.1 14361.9 14723.6 14996.8 15604.5 13939.3 14048.6 14292.9 14322.9 14324.0 14510.7 14569.7 14770.9 14789.6 15718.5 10753.2 11065.4 11088.7 11126.4 11258.3 11184.4 11345.6 11961.9 12233.6 12329.0 01:40:58 INFO - PROCESS | 23284 | -------- Summary: end -------- 01:40:58 INFO - PROCESS | 23284 | 01:40:59 INFO - TEST-INFO | 23284: exit 0 01:40:59 INFO - TEST-OK | dromaeo_css | took 1116260ms 01:40:59 INFO - TEST-START | kraken 01:40:59 INFO - operating with platform_type : linux_ 01:40:59 INFO - Initialising browser for kraken test... 01:40:59 INFO - TEST-INFO | started process 23588 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4BxoRK/profile http://localhost:41406/getInfo.html) 01:41:03 INFO - PROCESS | 23588 | __metrics Screen width/height:1600/1200 01:41:03 INFO - PROCESS | 23588 | colorDepth:24 01:41:03 INFO - PROCESS | 23588 | Browser inner width/height: 1024/697 01:41:03 INFO - PROCESS | 23588 | __metrics 01:41:04 INFO - PROCESS | 23588 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:41:04 INFO - PROCESS | 23588 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:41:04 INFO - PROCESS | 23588 | console.error: PushService: 01:41:04 INFO - PROCESS | 23588 | stateChangeProcessEnqueue: Error transitioning state 01:41:04 INFO - PROCESS | 23588 | UnknownError 01:41:05 INFO - TEST-INFO | 23588: exit 0 01:41:05 INFO - Browser initialized. 01:41:05 INFO - Running cycle 1/1 for kraken test... 01:41:05 INFO - TEST-INFO | started process 23674 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4BxoRK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:17 INFO - PROCESS | 23674 | RSS: Main: 180183040 01:41:17 INFO - PROCESS | 23674 | 01:41:20 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41406/tests/kraken/driver.html?testName=audio-beat-detection) 01:41:20 INFO - PROCESS | 23674 | RSS: Main: 242438144 01:41:20 INFO - PROCESS | 23674 | 01:41:23 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41406/tests/kraken/driver.html?testName=audio-dft) 01:41:24 INFO - PROCESS | 23674 | RSS: Main: 224661504 01:41:24 INFO - PROCESS | 23674 | 01:41:27 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41406/tests/kraken/driver.html?testName=audio-fft) 01:41:27 INFO - PROCESS | 23674 | RSS: Main: 301850624 01:41:27 INFO - PROCESS | 23674 | 01:41:30 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41406/tests/kraken/driver.html?testName=audio-oscillator) 01:41:30 INFO - PROCESS | 23674 | RSS: Main: 211906560 01:41:30 INFO - PROCESS | 23674 | 01:41:32 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41406/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:41:33 INFO - PROCESS | 23674 | RSS: Main: 213950464 01:41:33 INFO - PROCESS | 23674 | 01:41:36 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41406/tests/kraken/driver.html?testName=imaging-darkroom) 01:41:36 INFO - PROCESS | 23674 | RSS: Main: 252399616 01:41:36 INFO - PROCESS | 23674 | 01:41:40 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41406/tests/kraken/driver.html?testName=imaging-desaturate) 01:41:40 INFO - PROCESS | 23674 | RSS: Main: 250617856 01:41:40 INFO - PROCESS | 23674 | 01:41:43 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41406/tests/kraken/driver.html?testName=json-parse-financial) 01:41:44 INFO - PROCESS | 23674 | RSS: Main: 251387904 01:41:44 INFO - PROCESS | 23674 | 01:41:45 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41406/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:41:46 INFO - PROCESS | 23674 | RSS: Main: 213680128 01:41:46 INFO - PROCESS | 23674 | 01:41:48 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41406/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:41:48 INFO - PROCESS | 23674 | RSS: Main: 221409280 01:41:48 INFO - PROCESS | 23674 | 01:41:50 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41406/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:41:51 INFO - PROCESS | 23674 | RSS: Main: 222146560 01:41:51 INFO - PROCESS | 23674 | 01:41:54 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41406/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:41:54 INFO - PROCESS | 23674 | RSS: Main: 223617024 01:41:54 INFO - PROCESS | 23674 | 01:41:57 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41406/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:41:58 INFO - PROCESS | 23674 | RSS: Main: 221073408 01:41:58 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | Cycle 1(1): loaded http://localhost:41406/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41406/tests/kraken/driver.html?testName=ai-astar) 01:41:59 INFO - PROCESS | 23674 | RSS: Main: 272019456 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | __start_tp_report 01:41:59 INFO - PROCESS | 23674 | _x_x_mozilla_page_load 01:41:59 INFO - PROCESS | 23674 | _x_x_mozilla_page_load_details 01:41:59 INFO - PROCESS | 23674 | |i|pagename|runs| 01:41:59 INFO - PROCESS | 23674 | |0;ai-astar;112;112;130;112;113;111;110;113;113;112 01:41:59 INFO - PROCESS | 23674 | |1;audio-beat-detection;146;121;148;185;120;149;146;119;185;118 01:41:59 INFO - PROCESS | 23674 | |2;audio-dft;160;159;160;161;162;163;163;161;162;162 01:41:59 INFO - PROCESS | 23674 | |3;audio-fft;75;75;75;175;75;76;75;75;158;75 01:41:59 INFO - PROCESS | 23674 | |4;audio-oscillator;81;82;147;82;81;82;142;81;81;134 01:41:59 INFO - PROCESS | 23674 | |5;imaging-gaussian-blur;116;116;116;117;117;117;116;117;117;117 01:41:59 INFO - PROCESS | 23674 | |6;imaging-darkroom;151;146;146;145;146;146;145;146;145;145 01:41:59 INFO - PROCESS | 23674 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;95 01:41:59 INFO - PROCESS | 23674 | |8;json-parse-financial;53;51;53;53;54;53;53;54;52;52 01:41:59 INFO - PROCESS | 23674 | |9;json-stringify-tinderbox;61;61;62;61;62;62;62;62;62;62 01:41:59 INFO - PROCESS | 23674 | |10;stanford-crypto-aes;82;78;81;84;78;78;79;78;78;77 01:41:59 INFO - PROCESS | 23674 | |11;stanford-crypto-ccm;138;123;124;129;127;123;132;123;177;130 01:41:59 INFO - PROCESS | 23674 | |12;stanford-crypto-pbkdf2;161;160;161;160;161;166;161;164;170;161 01:41:59 INFO - PROCESS | 23674 | |13;stanford-crypto-sha256-iterative;65;71;65;66;66;65;63;65;66;64 01:41:59 INFO - PROCESS | 23674 | __end_tp_report 01:41:59 INFO - PROCESS | 23674 | __start_cc_report 01:41:59 INFO - PROCESS | 23674 | _x_x_mozilla_cycle_collect,1699 01:41:59 INFO - PROCESS | 23674 | __end_cc_report 01:41:59 INFO - PROCESS | 23674 | __startTimestamp1472719319916__endTimestamp 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | ------- Summary: start ------- 01:41:59 INFO - PROCESS | 23674 | Number of tests: 14 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#0] ai-astar Cycles:10 Average:113.80 Median:112.50 stddev:5.77 (5.1%) stddev-sans-first:6.08 01:41:59 INFO - PROCESS | 23674 | Values: 112.0 112.0 130.0 112.0 113.0 111.0 110.0 113.0 113.0 112.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:147.00 stddev:25.42 (17.3%) stddev-sans-first:26.95 01:41:59 INFO - PROCESS | 23674 | Values: 146.0 121.0 148.0 185.0 120.0 149.0 146.0 119.0 185.0 118.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:1.34 (0.8%) stddev-sans-first:1.33 01:41:59 INFO - PROCESS | 23674 | Values: 160.0 159.0 160.0 161.0 162.0 163.0 163.0 161.0 162.0 162.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#3] audio-fft Cycles:10 Average:93.40 Median:75.00 stddev:38.74 (51.6%) stddev-sans-first:40.51 01:41:59 INFO - PROCESS | 23674 | Values: 75.0 75.0 75.0 175.0 75.0 76.0 75.0 75.0 158.0 75.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#4] audio-oscillator Cycles:10 Average:99.30 Median:81.00 stddev:28.94 (35.7%) stddev-sans-first:29.93 01:41:59 INFO - PROCESS | 23674 | Values: 81.0 82.0 147.0 82.0 81.0 82.0 142.0 81.0 81.0 134.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 01:41:59 INFO - PROCESS | 23674 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:1.79 (1.2%) stddev-sans-first:0.53 01:41:59 INFO - PROCESS | 23674 | Values: 151.0 146.0 146.0 145.0 146.0 146.0 145.0 146.0 145.0 145.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 01:41:59 INFO - PROCESS | 23674 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:0.92 (1.7%) stddev-sans-first:0.97 01:41:59 INFO - PROCESS | 23674 | Values: 53.0 51.0 53.0 53.0 54.0 53.0 53.0 54.0 52.0 52.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 01:41:59 INFO - PROCESS | 23674 | Values: 61.0 61.0 62.0 61.0 62.0 62.0 62.0 62.0 62.0 62.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:78.50 stddev:2.26 (2.9%) stddev-sans-first:2.18 01:41:59 INFO - PROCESS | 23674 | Values: 82.0 78.0 81.0 84.0 78.0 78.0 79.0 78.0 78.0 77.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:129.50 stddev:16.34 (12.6%) stddev-sans-first:17.21 01:41:59 INFO - PROCESS | 23674 | Values: 138.0 123.0 124.0 129.0 127.0 123.0 132.0 123.0 177.0 130.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:161.00 stddev:3.24 (2.0%) stddev-sans-first:3.39 01:41:59 INFO - PROCESS | 23674 | Values: 161.0 160.0 161.0 160.0 161.0 166.0 161.0 164.0 170.0 161.0 01:41:59 INFO - PROCESS | 23674 | 01:41:59 INFO - PROCESS | 23674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:2.12 (3.2%) stddev-sans-first:2.24 01:41:59 INFO - PROCESS | 23674 | Values: 65.0 71.0 65.0 66.0 66.0 65.0 63.0 65.0 66.0 64.0 01:41:59 INFO - PROCESS | 23674 | -------- Summary: end -------- 01:41:59 INFO - PROCESS | 23674 | 01:42:01 INFO - TEST-INFO | 23674: exit 0 01:42:01 INFO - TEST-OK | kraken | took 61418ms 01:42:01 INFO - SUITE-END | took 1177s 01:42:01 INFO - Completed test suite (00:19:38) 01:42:01 INFO - javascript benchmark 01:42:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3015.261260991057, 3044.695530446955, 3068.1409205422488, 3103.224193951512, 3125.531170324451, 5219.373675158977, 5294.894102117955, 5387.946120538793, 5450.236966824643, 5452.40023597404, 5281.656692314997, 5300.469953004697, 5345.518903298712, 5415.783368665248, 5421.891562168754, 4865.172920603506, 4894.119058569456, 4911.336969509124, 4941.407031156266, 4973.502649735034, 4856.830010949618, 4861.440934292548, 4923.187674033798, 4923.55687988081, 4927.162382394984, 4887.095887260851, 4945.010997800454, 4959.305697202394, 4965.131102057144, 4968.22992436173, 4703.200455922467, 4885.853424397274, 4903.509649035103, 4944.208926571767, 4948.307236986825, 4668.43978722552, 4702.083093796711, 4784.30627559002, 4828.589569886564, 4854.6116310695415, 5184.170532714786, 5201.635885488017, 5254.789808407659, 5278.97360513195, 5319.0957537218765, 5564.888702225973, 5603.859903502445, 5610.49505544503, 5611.298587676541, 5612.15817627355, 5719.199312096268, 5737.08206686928, 5746.367899531048, 5750.539956803445, 5753.539716822645, 5335.333083364619, 5358.812441564526, 5390.78436862525, 5391.110466772939, 5393.056215162331, 5740.368559458456, 5829.59192856496, 5880.500157486576, 5891.852703682356, 5896.852578685482, 5560.499555040065, 5650.63270887391, 5652.18043383707, 5698.8290351289525, 5714.914276285859, 5712.485876271156, 5716.228309178291, 5717.971410143005, 5720.8283751487525, 5723.198752174699, 5474.042042642522, 5574.860628484239, 5715.428457154251, 5718.656880587178, 5725.427457254241, 5598.216249725042, 5625.0, 5636.408177141423, 5651.9152212716845, 5709.65742055478, 4350.129974005148, 4388.253996820485, 4388.780560971939, 4391.0, 4441.977790111094, 3928.744631598937, 3935.58676338986, 3945.309570825094, 3959.5050618672667, 3965.78188199646, 5463.071277882769, 5472.26124473199, 5499.202615620713, 5548.361938377138, 5559.9444005560235, 5732.0, 5742.052561327418, 5745.8563535911935, 5746.454086861741, 5751.59738818287, 5347.2781174541715, 5362.115250983622, 5447.509724124848, 5490.945090549123, 5502.422245664227, 4632.930506042412, 4648.721076735406, 4666.230072038075, 4698.1778188816825, 4700.365450664185, 5844.3571207168025, 5844.47399734026, 5846.386129456431, 5847.356790753014, 5850.210221620112, 5602.439756024365, 5619.80330688424, 5621.971890140523, 5680.545556355564, 5722.971385143048, 5977.701114944322, 6021.518278537794, 6060.969695151496, 6080.8783824324155, 6087.908681369871, 5645.294338207724, 5681.630694004876, 5681.744321505459, 5687.090065589486, 5690.743916523849, 5564.554835613222, 5645.604807663424, 5784.595078344475, 5787.305523337226, 5791.507721843691, 5478.726063696719, 5545.944540554704, 5553.750081246273, 5554.444555544414, 5559.2217089606675, 5527.613067085426, 5533.170024496358, 5542.5565954722715, 5550.361708403424, 5559.082751345983, 5463.4536546346635, 5491.643043202179, 5500.917486237629, 5514.6691198528215, 5530.474604912527, 5650.717464126859, 5721.22763426932, 5723.6851973141875, 5724.313082430135, 5731.856703582277, 5547.389787123332, 5555.2778138841695, 5556.888862222652, 5559.0, 5559.749811258423], "value": 5265.828776301251, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.7334912614759, 592.733270028487, 598.8443004818752, 601.9608725432845, 607.1014897951032, 45677.771611141936, 47668.523314766884, 47742.0, 47782.76108619461, 47835.28247076288, 44647.55352446483, 45154.0, 45183.322250166275, 45201.32198017032, 45326.0, 7989.360851131895, 8834.734957951256, 8955.731328060168, 8980.730578082635, 8993.505357205351, 7898.802529936769, 8275.08974012858, 8275.213854683825, 10559.947200263989, 10808.0, 10833.620823271187, 10881.401522916236, 10887.782244355109, 10893.128549716004, 10895.291806032583, 10836.0, 10889.401082940434, 10894.400807955519, 10897.67306980792, 10901.236913416064, 10604.469776511221, 10651.786964260671, 10663.146948244123, 10675.78648427035, 10681.0, 43257.78371108156, 43469.0, 43606.34590481145, 43627.34558981618, 43645.0, 3292.506124081383, 3312.4865645824934, 3325.3515564464938, 3374.8818791342187, 3476.8087755173456, 32541.0, 32548.511772323433, 32621.0, 32661.51007734886, 32719.50920736191, 4200.831966721327, 4302.354646802974, 4320.82716691332, 4334.631556317717, 4402.361657559668, 350029.0, 350314.0, 350349.0, 354145.0, 354333.0, 35194.64805351914, 59071.40928590659, 59360.40639593635, 59393.0, 59462.40537594569, 36554.80671159686, 38107.0, 38118.809405952794, 38121.42817857734, 38123.75948044392, 14619.269036548343, 14766.63083422923, 14771.482998095014, 14783.712723310335, 14785.556433306803, 12197.51209951592, 12204.840540148673, 12269.815952760715, 12345.814812777815, 12351.629451116482, 8097.149799270992, 8389.916100839035, 8568.400211985101, 8705.47767133974, 8815.647374104963, 3261.673832616719, 3364.5794275715534, 3399.2691571312284, 3409.556757621543, 3411.692947634675, 965.7295957132014, 972.6644307713827, 994.3387647214666, 1001.7946321004176, 1125.2966895690195, 32101.839490802395, 32500.512492312635, 33296.33407331888, 33691.663083368854, 35791.284174316366, 1042.8331466965249, 1052.9894701052892, 1064.6752740414092, 1067.7063807452857, 1131.4060118437885, 16360.918195408947, 16978.151092445576, 17046.31814727397, 17108.74336884948, 17132.571685707957, 534676.0, 543385.0, 544684.0, 545973.0, 573246.0, 59293.70353148207, 60152.39847601556, 60178.0, 60200.39799602036, 60251.096233555654, 1229.5327775445273, 1326.562234462625, 1340.2896464873634, 1353.6006877971022, 1364.911280766747, 30870.228244293176, 32997.17507062342, 33972.320553588295, 34303.14242143966, 34357.48463773095, 50754.74622626863, 51387.743061284455, 51401.7429912848, 51416.228756567936, 51494.742526288625, 307419.0, 309753.0, 309814.0, 310390.0, 310625.0, 28315.292117697223, 29525.0, 29618.259543511584, 30751.077467676005, 30810.229744255677, 49297.50702492929, 49424.25863612121, 49465.25802113043, 49931.50068499269, 50287.0, 41154.58845411507, 41469.0, 41697.1660566793, 42082.15835683208, 43179.13641727211, 41371.58628413677, 42094.78952605218, 42298.78850605727, 43484.34773478337, 43867.56132438635, 39948.2010359797, 41395.172096558505, 41415.7929210352, 41588.37617435801, 43904.78047609742, 33893.661063390035, 37587.248255035294, 37857.43213851739, 37933.0, 38432.42351364676, 28096.85951570229, 28269.57595636108, 28298.575521366784, 28330.2917427066, 28606.42787144287], "value": 19220.885259534098, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.1146297303095, 385.67795890431506, 392.4348937529958, 401.7991004497751, 421.38267438203025, 16523.75214371784, 19034.714479282822, 19237.0, 19420.126094325755, 20692.48268793281, 17710.557236069104, 17877.64244715105, 18721.472601198835, 18930.526736831587, 19829.305974290903, 5670.829875103733, 5702.857428564288, 5873.706314684248, 5977.760889564434, 5980.671063091529, 5807.361190269088, 5845.444682755153, 5868.882622347551, 5879.735411906453, 5880.823575292727, 5636.943630563704, 5749.31008279005, 5824.0390335594575, 5853.063509838426, 5858.502027327682, 5835.0, 5860.853478663025, 5867.647941123503, 5879.0, 5879.7648094076185, 5130.410002849682, 5182.53357197854, 5236.293100431431, 5264.631475796696, 5267.604929630293, 23386.532269354688, 23490.64764028541, 23621.645675314885, 23667.171648992204, 23714.881425592936, 2486.7388924162883, 2531.3291977625945, 2620.5414052540727, 2726.5501192303245, 2967.8812847486074, 14497.927510362486, 14615.707685846224, 14657.56027319182, 14996.400143994228, 15110.244487775568, 1884.3713020336554, 1959.480737604536, 2029.0666293505005, 2031.207828946712, 2048.150017742635, 84490.0, 85470.0, 85595.0, 87005.56497217537, 87710.56144719299, 10788.244822862482, 10945.945270273598, 11079.0, 11209.495572699085, 11307.830382544103, 15245.0, 15285.6178595536, 15308.387664493519, 15313.702944558183, 15336.846631533543, 8232.958835205785, 8401.70594029206, 8403.789905252295, 8465.95767021161, 8662.263707584925, 8107.594620268963, 8125.79685507867, 8356.197929941862, 8387.454815437099, 8443.240108390275, 6347.873462390059, 6563.901541476882, 6639.302873198341, 6660.633665148366, 6662.0, 1872.850171986238, 1888.282452668005, 1932.7487426634448, 1969.960600788005, 1977.1399441243104, 895.4582477601087, 937.929655275857, 963.1283688261994, 1024.109025148126, 1045.4197920154388, 19747.308844190386, 19935.700964485546, 20024.699629505278, 20108.798912010985, 20218.696719549218, 942.4298299528823, 971.145392054987, 987.1411871671548, 1003.7189586915675, 1023.4575674892319, 10759.30064545802, 11438.542458301576, 11508.884911150948, 11524.884751152382, 11612.593559225556, 470955.0, 473929.0, 474768.0, 477910.0, 486781.0, 88397.0, 90440.0, 90742.54628726946, 92505.99999999865, 93469.5326523363, 1020.1125021231576, 1075.5858994287255, 1079.3200283821143, 1100.2353364411656, 1123.8707548631849, 17252.741208881627, 17425.651486969935, 17961.371352002618, 18141.546461338574, 18189.272429102686, 43393.0, 43396.783016084715, 43508.34737478877, 43628.56371436245, 44121.55878441175, 59005.0, 59076.40923590709, 59368.0, 59709.70145149247, 60067.09899351426, 8418.789530261793, 8497.957510212409, 8551.57242137903, 8560.357973151915, 8719.607617657093, 12869.22784632925, 13096.803547946598, 13274.867251327749, 13415.664608384479, 13436.39536220892, 11265.380404077854, 11459.484323205643, 11540.192186547198, 11912.98739607143, 12023.21849079807, 11247.66257012291, 11292.943535282271, 11569.19015668895, 11637.53449861996, 12094.818577721166, 11094.167937404565, 11466.885331146581, 11554.59558915434, 11982.820257696316, 12446.626601201979, 7544.886826697713, 8528.74413767588, 8530.872036919327, 8691.95654021726, 8788.91211087881, 11261.155413343868, 11595.942020289845, 12073.456694448596, 12223.0, 12427.0], "value": 9519.762168214853, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.9453621855125, 1455.9563213103604, 1496.5360738171166, 1601.4314918204038, 1774.3878361965117, 8127.837443251132, 8473.449225800327, 8505.957470212641, 8520.275776558992, 8815.206631403173, 8347.332213422927, 8504.744857654265, 8694.0, 8706.695265665703, 8715.128487151282, 2174.608570457317, 2378.6194208926577, 2391.473875747337, 2448.9877550612223, 2455.7789798918093, 2421.987890060556, 2435.3789783605157, 2438.207582535674, 2443.4990826880535, 2445.3397582652674, 2359.539889721505, 2364.1252736487436, 2374.679418278536, 2387.892544835478, 2388.36708272308, 2281.53228588139, 2333.801626861719, 2339.298210536849, 2351.5767161910935, 2354.5526349993534, 2358.092134528219, 2366.396568874926, 2381.6427535869584, 2386.3198988288254, 2388.558116748407, 8290.295324897392, 8361.623726932303, 8364.079951205362, 8813.42712723671, 8861.379703420764, 5200.453952334989, 5340.813071542478, 5366.221897824794, 5367.355917289911, 5371.865703357408, 3449.5688038995127, 3476.89569312921, 3568.4647302904514, 3582.3551760683067, 3647.7993710345922, 1223.7613665335264, 1247.4885297028266, 1252.1172573335778, 1261.7224210673637, 1300.2003767682806, 11577.421128943519, 11759.35323557204, 11852.881471185265, 11860.169788114836, 11904.107191960638, 4507.0, 4694.333511603331, 4796.424429068465, 4816.566509014206, 4858.5384388483035, 10167.847482287772, 10177.0, 10187.898121018696, 10194.133498652698, 10195.949020254851, 5341.839744807662, 5366.785328586813, 5367.677939323653, 5371.328583927009, 5371.677699338053, 4282.850100246476, 4395.0, 4792.640551958547, 4882.829100981448, 4885.9755701221975, 3979.9403008954887, 4085.611866872642, 4188.518320393133, 4203.6637069034405, 4208.053188032668, 1266.5693664154232, 1274.8024056271292, 1285.8135570342436, 1296.6887946892866, 1299.155548893227, 803.6544285957094, 954.1317401164906, 954.2032402943481, 968.3657204531044, 998.9051040151174, 5706.771729130871, 5781.364049954502, 5897.469227769521, 5972.25346831646, 6098.69506524672, 945.239082538563, 967.709687093869, 980.2011360740973, 1001.1340190734962, 1012.2003617142523, 3988.521377434726, 4143.399207114951, 4166.854160104382, 4203.91592168155, 4214.367844823301, 10777.946110269555, 10873.293235939638, 11041.39272340013, 11056.11551075912, 11057.778844423066, 10964.506597203144, 10971.670849874517, 10973.067289280341, 10986.450677466095, 10989.835152472719, 8031.558264295403, 8090.312323452573, 8095.838083238302, 8150.633221505046, 8159.26566609008, 5736.397678243891, 5844.3571207168025, 5861.589688721749, 5887.499562537232, 6051.273847138372, 9760.755981100529, 9994.850077248702, 10126.0, 10169.796604068026, 10186.0, 10209.9489502552, 10235.181185505291, 10297.279190456897, 10330.431826249629, 10344.0, 6621.370969757865, 6805.081314022544, 7207.603581803051, 7285.344319011315, 7427.628618569158, 8308.12764659702, 8665.783355416164, 8680.913190868263, 8681.6093275804, 8684.826303474021, 7503.399728021855, 7568.167501574712, 7704.0, 7777.105632852295, 7817.726379576915, 7417.851642967218, 7438.367738742268, 7445.218252083453, 7473.290037446434, 7722.691092356467, 6853.657317134023, 6996.370326670624, 6997.580145191101, 7004.264552221944, 7006.159260888726, 4979.867038046616, 5065.822696205762, 5104.33643626341, 5127.79488820443, 5312.814051508333, 5184.948150518598, 5218.217267409767, 5284.154535274338, 5286.524212820865, 5309.097453432849], "value": 4658.267981215732, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.8712514994002, 327.7410845432109, 330.34261818980224, 339.984700688469, 341.69076985328286, 5097.490250974905, 5172.224166375041, 5190.1176799944005, 5198.220266959958, 5216.817411390597, 5095.312132862063, 5097.488722480603, 5123.795048198077, 5128.0, 5131.512506311894, 3814.313423583754, 4057.2291264659693, 4062.53280872699, 4082.9795851020704, 4085.5710150434224, 3951.46655201547, 4003.939940900889, 4020.8592699255532, 4025.6376926076646, 4025.859094931678, 3968.1468484275874, 3978.661813745835, 3981.28336899358, 3985.1033517458486, 3988.501437320335, 3775.9811200944096, 3783.451399547078, 3938.980305098456, 3985.6612187964056, 4000.9799951000205, 3452.6374730653424, 3453.240287136826, 3465.8093804840723, 3466.8212807645523, 3467.930641387158, 4975.328330675348, 4982.775775090094, 4993.900121997576, 5007.173816320303, 5012.268313768968, 4295.506016808075, 4337.609615134622, 4520.982778874727, 4524.773761311922, 4533.274676051816, 5301.522862942354, 5313.548348390392, 5314.80416906193, 5322.627416080876, 5353.197020446964, 3838.2707285615643, 3840.8463661453507, 3842.2315536892734, 3842.751105890569, 3845.8269377878064, 5353.197020446925, 5497.257870187514, 5518.33779946409, 5540.9168862467095, 5558.86102847428, 3953.8220780064967, 3975.7018223633345, 3976.6818654508147, 3978.5822488638855, 3994.6404823566018, 3986.7807270599815, 4003.619656132663, 4034.3141665916864, 4043.939340909889, 4058.5332686741403, 4307.729219880143, 4528.50186479487, 4547.818087276538, 4552.499225085238, 4606.378138951266, 5298.523132918056, 5315.3621565412395, 5316.627836051517, 5321.042212401805, 5329.813456529003, 1346.8720471555187, 1367.6785955300497, 1370.294298436306, 1371.7119404925077, 1375.8761711446018, 838.7376997618545, 879.305348774461, 888.320434867327, 891.9598618062073, 899.3524662243175, 4548.74981876, 4570.908581828345, 4576.633869290449, 4644.790984405642, 4667.533246675373, 4754.7147171169145, 4779.593734532534, 4804.759762011886, 4816.662833601685, 4827.782749776268, 4496.3705081288645, 4606.30905364198, 4716.929246061243, 4739.881502962454, 4742.502037286035, 2574.2663340948116, 2605.1793684989166, 2778.152663437671, 2834.2630915961586, 2878.8484606157285, 5665.688387138664, 5668.206451096849, 5686.147077938343, 5715.22811436453, 5721.141828725641, 5413.215083812904, 5519.39286678465, 5548.72256387179, 5614.242077319591, 5624.493795558419, 5611.158326251013, 5631.6902570359025, 5646.124850648156, 5675.404082571271, 5683.943160568341, 5427.810026649048, 5432.56539476833, 5434.402215756343, 5443.265159203457, 5463.781448742165, 5225.817096401608, 5324.8136315228785, 5380.0, 5414.593905457028, 5427.538659214011, 5648.57635677334, 5648.943510564842, 5655.632383895034, 5666.858328541819, 5679.0, 3953.150072734376, 3955.545112312121, 3961.663258622934, 3969.404589311511, 3970.9205815884097, 5228.7647055883335, 5408.8377348679605, 5444.8366549003595, 5482.122860342326, 5485.451454854483, 5310.176922577007, 5319.734013299397, 5321.456777534627, 5321.631873816114, 5330.173823057432, 5276.788928442819, 5296.0, 5332.360116786011, 5346.166531738185, 5348.358197016383, 5059.114654935298, 5301.363836339509, 5310.761015754378, 5327.786888524415, 5348.866278343073, 5373.462653734595, 5384.219288203267, 5393.6763794172475, 5405.432429594836, 5423.7830486780085, 5028.823991160438, 5287.180487024363, 5288.2860813791185, 5310.123829568232, 5317.8404647860625], "value": 4036.6642695973237, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.4360277182412, 725.2892165677636, 746.9738559150427, 781.7420251317066, 833.9041010283815, 14823.481178158752, 15947.122908240042, 15953.601159971007, 15968.121753303563, 16586.25361858716, 16443.91778041111, 16638.750418743697, 17294.56763580911, 17386.217620207088, 17391.91304043481, 668.0980676087268, 788.1448628238368, 827.5820710541168, 839.5634270179503, 839.6557411461303, 787.0398114300544, 810.2181394953868, 811.3630799822141, 815.5229190923317, 816.073756286616, 859.8194379180379, 861.7371701631, 863.8056437301589, 863.8229163021565, 863.87905693203, 690.7444245629099, 858.8024754306545, 861.5089399042548, 862.3403096631051, 863.0765081362944, 606.0938896349941, 615.0497481391217, 616.0943413182614, 616.442119881508, 616.8889599872022, 15611.531654050397, 15617.375304987787, 15631.92184039084, 15638.530844074696, 15655.53033408988, 14521.636959076002, 14649.633759156, 14807.185604791732, 14944.62638434037, 14984.925075374662, 13375.33123343829, 13416.329183540882, 13720.335086070783, 13792.586222413343, 13853.515126970507, 4344.304911214223, 4345.130973805251, 4583.03756211196, 4608.354830323758, 4631.58315751584, 77926.0, 79868.20131798666, 80895.59552202202, 85858.57070714606, 87002.0, 11594.0, 11840.0, 11867.940660296643, 12598.496060157675, 12633.178843375152, 22829.88585057064, 22847.200347987742, 22871.31386058421, 22896.65655015176, 22905.31284061481, 7574.659140338698, 7724.0, 7801.985741853523, 7850.450468467267, 7882.408819338572, 4450.0, 4595.687935075162, 4606.723596584216, 4685.9531404686195, 4745.786439610226, 3429.2798512312693, 3558.5907620623443, 3561.4479755637917, 3570.6607872252093, 3581.6239294874185, 551.8923809857039, 575.5625724449449, 581.2037508613142, 582.28960667985, 582.4932308891207, 623.7536173211556, 648.9318621544764, 652.393274254938, 672.9596224226464, 767.8272388712495, 9496.335256531976, 10288.0, 10537.894621053845, 10582.576696932036, 10656.680299591024, 664.8736740019381, 721.938635216002, 723.7322190789337, 737.937840639152, 740.2708332292688, 5127.384713834289, 5177.58342854006, 5245.26566280721, 5247.26840142752, 5298.364196296469, 74010.62994684992, 75872.24127758761, 126465.0, 129419.35290323677, 129743.0, 67204.6639766798, 95994.0, 109665.45167274273, 109795.45102274597, 110329.44835275772, 54452.45547544474, 56271.43728562662, 57501.712491437276, 58336.0, 72430.63784681042, 9320.766980825534, 10214.642487512901, 10266.281360304707, 10295.3822770634, 10453.68638940833, 38624.80687596656, 52744.0, 53099.0, 53412.0, 54251.0, 75794.62102689451, 80396.0, 81264.59367703124, 81322.59338703268, 81994.0, 12776.425060872469, 13226.470941162246, 13303.0, 13806.102603330752, 13819.654508636962, 14877.553673389815, 15417.306221220297, 15661.451849185223, 16093.114878681317, 17024.0, 14234.14595124296, 14361.928190358982, 14723.631909202355, 14996.775048374066, 15604.453844115402, 13939.303034848013, 14048.578542643738, 14292.928535357256, 14322.928385358424, 14324.0, 14510.709785804014, 14569.708605828037, 14770.852291476947, 14789.556313310617, 15718.528444146694, 10753.202017034208, 11065.391403472886, 11088.722781930517, 11126.388048657402, 11258.268212566158, 11184.384858832842, 11345.602903898323, 11961.880381196077, 12233.571824986082, 12329.0], "value": 7693.523033739664, "unit": "score"}], "name": "dromaeo_css", "value": 7200.800432519035, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 130.0, 112.0, 113.0, 111.0, 110.0, 113.0, 113.0, 112.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 121.0, 148.0, 185.0, 120.0, 149.0, 146.0, 119.0, 185.0, 118.0], "value": 143.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 161.0, 162.0, 163.0, 163.0, 161.0, 162.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 175.0, 75.0, 76.0, 75.0, 75.0, 158.0, 75.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 147.0, 82.0, 81.0, 82.0, 142.0, 81.0, 81.0, 134.0], "value": 99.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 53.0, 53.0, 54.0, 53.0, 53.0, 54.0, 52.0, 52.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 81.0, 84.0, 78.0, 78.0, 79.0, 78.0, 78.0, 77.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 123.0, 124.0, 129.0, 127.0, 123.0, 132.0, 123.0, 177.0, 130.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 161.0, 166.0, 161.0, 164.0, 170.0, 161.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 65.0, 66.0, 66.0, 65.0, 63.0, 65.0, 66.0, 64.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1523.7999999999997, "alertThreshold": 2.0}]} 01:42:01 INFO - Return code: 0 01:42:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:42:01 INFO - # TBPL SUCCESS # 01:42:01 INFO - Running post-action listener: _resource_record_post_action 01:42:01 INFO - [mozharness: 2016-09-01 08:42:01.703226Z] Finished run-tests step (success) 01:42:01 INFO - Running post-run listener: _resource_record_post_run 01:42:01 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 418787328; Read time: 16; Write time: 310272 01:42:01 INFO - TinderboxPrint: CPU usage
13.0% 01:42:01 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 01:42:01 INFO - TinderboxPrint: I/O write bytes / time
418,787,328 / 310,272 01:42:01 INFO - TinderboxPrint: CPU idle
8,291.9 (87.0%) 01:42:01 INFO - TinderboxPrint: CPU user
1,192.8 (12.5%) 01:42:01 INFO - TinderboxPrint: Swap in / out
0 / 0 01:42:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 128864256; Read time: 0; Write time: 90520 01:42:01 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 289390592; Read time: 0; Write time: 219720 01:42:02 INFO - Running post-run listener: _upload_blobber_files 01:42:02 INFO - Blob upload gear active. 01:42:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:42:02 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 01:42:02 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'] 01:42:02 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 01:42:02 INFO - (blobuploader) - INFO - Open directory for files ... 01:42:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:42:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:42:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:03 INFO - (blobuploader) - INFO - Done attempting. 01:42:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:42:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:42:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:04 INFO - (blobuploader) - INFO - Done attempting. 01:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:42:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:09 INFO - (blobuploader) - INFO - Done attempting. 01:42:09 INFO - (blobuploader) - INFO - Iteration through files over. 01:42:09 INFO - Return code: 0 01:42:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:42:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:42:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f1e2bfb234750d79f821d138758aa74748ae62c52a0b472911f99c0ae94c664b0d063bf08069850935ec66f98e1da50b4da9e744bcf0bdedbaa86c64529f157", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fb00296007aa07f22886aa214317e971cffb92a10ae6a309ca7961701c024f13d0ec541d5173e4198d5680564271087671b2d4e1db0b0ee79f872ec702f1596", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4fd9a1847f58fbc661fe7af9d1be5131b97271093d051ea46b157896169644a454e77dbe693def4fb521e53e531371c6947f63dc2c37854db1f55378418a362"} 01:42:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:42:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:42:09 INFO - Contents: 01:42:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f1e2bfb234750d79f821d138758aa74748ae62c52a0b472911f99c0ae94c664b0d063bf08069850935ec66f98e1da50b4da9e744bcf0bdedbaa86c64529f157", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fb00296007aa07f22886aa214317e971cffb92a10ae6a309ca7961701c024f13d0ec541d5173e4198d5680564271087671b2d4e1db0b0ee79f872ec702f1596", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4fd9a1847f58fbc661fe7af9d1be5131b97271093d051ea46b157896169644a454e77dbe693def4fb521e53e531371c6947f63dc2c37854db1f55378418a362"} 01:42:09 INFO - Running post-run listener: copy_logs_to_upload_dir 01:42:09 INFO - Copying logs to upload dir... 01:42:09 INFO - mkdir: /builds/slave/test/build/upload/logs 01:42:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.895747 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-01 01:42:09.255865) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 01:42:09.263097) ========= 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=62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480 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/0f1e2bfb234750d79f821d138758aa74748ae62c52a0b472911f99c0ae94c664b0d063bf08069850935ec66f98e1da50b4da9e744bcf0bdedbaa86c64529f157", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fb00296007aa07f22886aa214317e971cffb92a10ae6a309ca7961701c024f13d0ec541d5173e4198d5680564271087671b2d4e1db0b0ee79f872ec702f1596", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4fd9a1847f58fbc661fe7af9d1be5131b97271093d051ea46b157896169644a454e77dbe693def4fb521e53e531371c6947f63dc2c37854db1f55378418a362"} build_url:https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/SxOEHPEuQ86LDhACWZGqkQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f1e2bfb234750d79f821d138758aa74748ae62c52a0b472911f99c0ae94c664b0d063bf08069850935ec66f98e1da50b4da9e744bcf0bdedbaa86c64529f157", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fb00296007aa07f22886aa214317e971cffb92a10ae6a309ca7961701c024f13d0ec541d5173e4198d5680564271087671b2d4e1db0b0ee79f872ec702f1596", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4fd9a1847f58fbc661fe7af9d1be5131b97271093d051ea46b157896169644a454e77dbe693def4fb521e53e531371c6947f63dc2c37854db1f55378418a362"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 01:42:09.291399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:42:09.291738) ========= 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=62c568580acbfdaac3eec7be000001dc-1472716498.313034-738091480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 01:42:09.373463) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 01:42:09.376799) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 01:42:09.377093) ========= ========= Total master_lag: 0.19 =========