builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1471523851.44 results: success (0) buildid: 20160817193029 builduid: a9d60d6febdf42ec92e8e6940018cd28 revision: 97a52326b06a07930216ebefa5af333271578904 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.436238) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.436699) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.437004) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.451466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.451767) ========= 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=52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 05:37:32-- 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% 220M=0s 2016-08-18 05:37:32 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.202816 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.668369) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.668699) ========= 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=52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024602 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.718530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:31.718898) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 97a52326b06a07930216ebefa5af333271578904 --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 97a52326b06a07930216ebefa5af333271578904 --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=52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 05:37:33,050 truncating revision to first 12 chars 2016-08-18 05:37:33,050 Setting DEBUG logging. 2016-08-18 05:37:33,050 attempt 1/10 2016-08-18 05:37:33,050 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/97a52326b06a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 05:37:33,353 unpacking tar archive at: mozilla-central-97a52326b06a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.641533 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:32.374428) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:32.374757) ========= script_repo_revision: 97a52326b06a07930216ebefa5af333271578904 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:32.375155) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:32.375426) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:32.384041) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-18 05:37:32.384311) ========= /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=52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:37:33 INFO - MultiFileLogger online at 20160818 05:37:33 in /builds/slave/test 05:37:33 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 05:37:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:37:33 INFO - {'append_to_log': False, 05:37:33 INFO - 'base_work_dir': '/builds/slave/test', 05:37:33 INFO - 'blob_upload_branch': 'Firefox', 05:37:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:37:33 INFO - 'branch': 'Firefox', 05:37:33 INFO - 'buildbot_json_path': 'buildprops.json', 05:37:33 INFO - 'config_files': ('talos/linux_config.py',), 05:37:33 INFO - 'default_actions': ('clobber', 05:37:33 INFO - 'read-buildbot-config', 05:37:33 INFO - 'download-and-extract', 05:37:33 INFO - 'populate-webroot', 05:37:33 INFO - 'create-virtualenv', 05:37:33 INFO - 'install', 05:37:33 INFO - 'run-tests'), 05:37:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:37:33 INFO - 'download_minidump_stackwalk': True, 05:37:33 INFO - 'download_symbols': 'ondemand', 05:37:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:37:33 INFO - 'tooltool.py': '/tools/tooltool.py', 05:37:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:37:33 INFO - '/tools/misc-python/virtualenv.py')}, 05:37:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:37:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:37:33 INFO - 'installer_path': 'installer.exe', 05:37:33 INFO - 'log_level': 'info', 05:37:33 INFO - 'log_name': 'talos', 05:37:33 INFO - 'log_to_console': True, 05:37:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:37:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:37:33 INFO - 'opt_config_files': (), 05:37:33 INFO - 'pip_index': False, 05:37:33 INFO - 'sps_profile': False, 05:37:33 INFO - 'sps_profile_interval': 0, 05:37:33 INFO - 'suite': 'dromaeojs', 05:37:33 INFO - 'system_bits': '32', 05:37:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:37:33 INFO - 'title': 'talos-linux64-ix-056', 05:37:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:37:33 INFO - 'use_talos_json': True, 05:37:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:37:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:37:33 INFO - 'work_dir': 'build'} 05:37:33 INFO - [mozharness: 2016-08-18 12:37:33.801740Z] Running clobber step. 05:37:33 INFO - Running pre-action listener: _resource_record_pre_action 05:37:33 INFO - Running main action method: clobber 05:37:33 INFO - rmtree: /builds/slave/test/build 05:37:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:37:34 INFO - Running post-action listener: _resource_record_post_action 05:37:34 INFO - [mozharness: 2016-08-18 12:37:34.775051Z] Finished clobber step (success) 05:37:34 INFO - [mozharness: 2016-08-18 12:37:34.775149Z] Running read-buildbot-config step. 05:37:34 INFO - Running pre-action listener: _resource_record_pre_action 05:37:34 INFO - Running main action method: read_buildbot_config 05:37:34 INFO - Using buildbot properties: 05:37:34 INFO - { 05:37:34 INFO - "project": "", 05:37:34 INFO - "product": "firefox", 05:37:34 INFO - "script_repo_revision": "production", 05:37:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:37:34 INFO - "repository": "", 05:37:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:37:34 INFO - "buildid": "20160817193029", 05:37:34 INFO - "pgo_build": "True", 05:37:34 INFO - "basedir": "/builds/slave/test", 05:37:34 INFO - "buildnumber": 834, 05:37:34 INFO - "slavename": "talos-linux64-ix-056", 05:37:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:37:34 INFO - "platform": "ubuntu64_hw", 05:37:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:37:34 INFO - "branch": "mozilla-central", 05:37:34 INFO - "repo_path": "mozilla-central", 05:37:34 INFO - "revision": "97a52326b06a07930216ebefa5af333271578904", 05:37:34 INFO - "stage_platform": "linux64-pgo", 05:37:34 INFO - "builduid": "a9d60d6febdf42ec92e8e6940018cd28", 05:37:34 INFO - "slavebuilddir": "test" 05:37:34 INFO - } 05:37:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:37:34 INFO - Running post-action listener: _resource_record_post_action 05:37:34 INFO - [mozharness: 2016-08-18 12:37:34.777577Z] Finished read-buildbot-config step (success) 05:37:34 INFO - [mozharness: 2016-08-18 12:37:34.777665Z] Running download-and-extract step. 05:37:34 INFO - Running pre-action listener: _resource_record_pre_action 05:37:34 INFO - Running main action method: download_and_extract 05:37:34 INFO - mkdir: /builds/slave/test/build/tests 05:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:34 INFO - trying https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:37:34 INFO - Downloading https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:37:35 INFO - Downloaded 1361 bytes. 05:37:35 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:37:35 INFO - Using the following test package requirements: 05:37:35 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:37:35 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:37:35 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:37:35 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:35 INFO - u'jsshell-linux-x86_64.zip'], 05:37:35 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:37:35 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:37:35 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:37:35 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:37:35 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:37:35 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:35 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:37:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:35 INFO - trying https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:37:35 INFO - Downloading https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:37:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:37:36 INFO - Downloaded 21907829 bytes. 05:37: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 05:37:40 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:40 INFO - trying https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:37:40 INFO - Downloading https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:37:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:37:41 INFO - Downloaded 21907829 bytes. 05:37:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 05:37: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')]} 05:37:46 INFO - trying https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:37:46 INFO - Downloading https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:37:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:37:46 INFO - Downloaded 14413981 bytes. 05:37:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 05:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:47 INFO - trying https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:37:47 INFO - Downloading https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:37:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:37:48 INFO - Downloaded 59599188 bytes. 05:37:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:37:48 INFO - mkdir: /builds/slave/test/properties 05:37:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:37:48 INFO - Writing to file /builds/slave/test/properties/build_url 05:37:48 INFO - Contents: 05:37:48 INFO - build_url:https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:37:48 INFO - Running post-action listener: _resource_record_post_action 05:37:48 INFO - Running post-action listener: set_extra_try_arguments 05:37:48 INFO - [mozharness: 2016-08-18 12:37:48.939843Z] Finished download-and-extract step (success) 05:37:48 INFO - [mozharness: 2016-08-18 12:37:48.940068Z] Running populate-webroot step. 05:37:48 INFO - Running pre-action listener: _resource_record_pre_action 05:37:48 INFO - Running main action method: populate_webroot 05:37:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:37:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:37:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:37:48 INFO - '--tppagecycles', 05:37:48 INFO - '7'], 05:37:48 INFO - 'tests': ['tsvgm']}}, 05:37:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:37:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:37:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:37:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:37:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['damp', 'tps']}, 05:37:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['damp', 'tps']}, 05:37:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['dromaeo_dom']}, 05:37:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['basic_compositor_video']}, 05:37:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:37:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['a11yr', 05:37:48 INFO - 'ts_paint', 05:37:48 INFO - 'tpaint', 05:37:48 INFO - 'sessionrestore', 05:37:48 INFO - 'sessionrestore_no_auto_restore', 05:37:48 INFO - 'tabpaint']}, 05:37:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tsvgx', 05:37:48 INFO - 'tsvgr_opacity', 05:37:48 INFO - 'tart', 05:37:48 INFO - 'tscrollx', 05:37:48 INFO - 'cart']}, 05:37:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:37:48 INFO - 'tsvgr_opacity', 05:37:48 INFO - 'tart', 05:37:48 INFO - 'tscrollx', 05:37:48 INFO - 'cart']}, 05:37:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s'], 05:37:48 INFO - 'tests': ['tp5o']}, 05:37:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'tests': ['tp5o']}, 05:37:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--disable-e10s', 05:37:48 INFO - '--xperf_path', 05:37:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:48 INFO - 'tests': ['tp5n']}, 05:37:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:48 INFO - 'talos_options': ['--xperf_path', 05:37:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:48 INFO - 'tests': ['tp5n']}}, 05:37:48 INFO - 'talos.zip': {'path': '', 05:37:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:37:48 INFO - Running post-action listener: _resource_record_post_action 05:37:48 INFO - [mozharness: 2016-08-18 12:37:48.962914Z] Finished populate-webroot step (success) 05:37:48 INFO - [mozharness: 2016-08-18 12:37:48.963001Z] Running create-virtualenv step. 05:37:48 INFO - Running pre-action listener: _resource_record_pre_action 05:37:48 INFO - Running main action method: create_virtualenv 05:37:48 INFO - Creating virtualenv /builds/slave/test/build/venv 05:37:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:37:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:37:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:49 INFO - Using real prefix '/usr' 05:37:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:37:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:37:51 INFO - Installing pip.................done. 05:37:51 INFO - Return code: 0 05:37:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:37:51 INFO - Using env: {'DISPLAY': ':0', 05:37:51 INFO - 'HOME': '/home/cltbld', 05:37:51 INFO - 'LANG': 'en_US.UTF-8', 05:37:51 INFO - 'LANGUAGE': 'en_US:en', 05:37:51 INFO - 'LOGNAME': 'cltbld', 05:37:51 INFO - 'MAIL': '/var/mail/cltbld', 05:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:51 INFO - 'MOZ_NO_REMOTE': '1', 05:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:51 INFO - 'NO_EM_RESTART': '1', 05:37:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:51 INFO - 'PWD': '/builds/slave/test', 05:37:51 INFO - 'SHELL': '/bin/bash', 05:37:51 INFO - 'SHLVL': '1', 05:37:51 INFO - 'TERM': 'linux', 05:37:51 INFO - 'TMOUT': '86400', 05:37:51 INFO - 'USER': 'cltbld', 05:37:51 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:51 INFO - '_': '/tools/buildbot/bin/python'} 05:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:37:51 INFO - Cleaning up... 05:37:51 INFO - Return code: 0 05:37:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:37:51 INFO - Using env: {'DISPLAY': ':0', 05:37:51 INFO - 'HOME': '/home/cltbld', 05:37:51 INFO - 'LANG': 'en_US.UTF-8', 05:37:51 INFO - 'LANGUAGE': 'en_US:en', 05:37:51 INFO - 'LOGNAME': 'cltbld', 05:37:51 INFO - 'MAIL': '/var/mail/cltbld', 05:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:51 INFO - 'MOZ_NO_REMOTE': '1', 05:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:51 INFO - 'NO_EM_RESTART': '1', 05:37:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:51 INFO - 'PWD': '/builds/slave/test', 05:37:51 INFO - 'SHELL': '/bin/bash', 05:37:51 INFO - 'SHLVL': '1', 05:37:51 INFO - 'TERM': 'linux', 05:37:51 INFO - 'TMOUT': '86400', 05:37:51 INFO - 'USER': 'cltbld', 05:37:51 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:51 INFO - '_': '/tools/buildbot/bin/python'} 05:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:51 INFO - Downloading/unpacking psutil>=3.1.1 05:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:37:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:53 INFO - Installing collected packages: psutil 05:37:53 INFO - Running setup.py install for psutil 05:37:54 INFO - building 'psutil._psutil_linux' extension 05:37:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:37:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:37:54 INFO - building 'psutil._psutil_posix' extension 05:37:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:37:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:37:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:54 INFO - Successfully installed psutil 05:37:54 INFO - Cleaning up... 05:37:54 INFO - Return code: 0 05:37:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:37: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')]} 05:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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')]} 05:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:37: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 mozsystemmonitor==0.3 05:37:54 INFO - Using env: {'DISPLAY': ':0', 05:37:54 INFO - 'HOME': '/home/cltbld', 05:37:54 INFO - 'LANG': 'en_US.UTF-8', 05:37:54 INFO - 'LANGUAGE': 'en_US:en', 05:37:54 INFO - 'LOGNAME': 'cltbld', 05:37:54 INFO - 'MAIL': '/var/mail/cltbld', 05:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:54 INFO - 'MOZ_NO_REMOTE': '1', 05:37:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:54 INFO - 'NO_EM_RESTART': '1', 05:37:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:54 INFO - 'PWD': '/builds/slave/test', 05:37:54 INFO - 'SHELL': '/bin/bash', 05:37:54 INFO - 'SHLVL': '1', 05:37:54 INFO - 'TERM': 'linux', 05:37:54 INFO - 'TMOUT': '86400', 05:37:54 INFO - 'USER': 'cltbld', 05:37:54 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:54 INFO - '_': '/tools/buildbot/bin/python'} 05:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:37:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:37:56 INFO - Installing collected packages: mozsystemmonitor 05:37:56 INFO - Running setup.py install for mozsystemmonitor 05:37:56 INFO - Successfully installed mozsystemmonitor 05:37:56 INFO - Cleaning up... 05:37:56 INFO - Return code: 0 05:37:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:56 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 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:37:56 INFO - Using env: {'DISPLAY': ':0', 05:37:56 INFO - 'HOME': '/home/cltbld', 05:37:56 INFO - 'LANG': 'en_US.UTF-8', 05:37:56 INFO - 'LANGUAGE': 'en_US:en', 05:37:56 INFO - 'LOGNAME': 'cltbld', 05:37:56 INFO - 'MAIL': '/var/mail/cltbld', 05:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:56 INFO - 'MOZ_NO_REMOTE': '1', 05:37:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:56 INFO - 'NO_EM_RESTART': '1', 05:37:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:56 INFO - 'PWD': '/builds/slave/test', 05:37:56 INFO - 'SHELL': '/bin/bash', 05:37:56 INFO - 'SHLVL': '1', 05:37:56 INFO - 'TERM': 'linux', 05:37:56 INFO - 'TMOUT': '86400', 05:37:56 INFO - 'USER': 'cltbld', 05:37:56 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:56 INFO - '_': '/tools/buildbot/bin/python'} 05:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:57 INFO - Downloading/unpacking jsonschema==2.5.1 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:37:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:59 INFO - Downloading functools32-3.2.3-2.tar.gz 05:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:37:59 INFO - warning: no files found matching '*.txt' 05:37:59 INFO - no previously-included directories found matching 'build' 05:37:59 INFO - no previously-included directories found matching 'dist' 05:37:59 INFO - no previously-included directories found matching '.git*' 05:37:59 INFO - Installing collected packages: jsonschema, functools32 05:37:59 INFO - Running setup.py install for functools32 05:37:59 INFO - warning: no files found matching '*.txt' 05:37:59 INFO - no previously-included directories found matching 'build' 05:37:59 INFO - no previously-included directories found matching 'dist' 05:37:59 INFO - no previously-included directories found matching '.git*' 05:37:59 INFO - Successfully installed jsonschema functools32 05:37:59 INFO - Cleaning up... 05:37:59 INFO - Return code: 0 05:37:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:59 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 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:37:59 INFO - Using env: {'DISPLAY': ':0', 05:37:59 INFO - 'HOME': '/home/cltbld', 05:37:59 INFO - 'LANG': 'en_US.UTF-8', 05:37:59 INFO - 'LANGUAGE': 'en_US:en', 05:37:59 INFO - 'LOGNAME': 'cltbld', 05:37:59 INFO - 'MAIL': '/var/mail/cltbld', 05:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:59 INFO - 'MOZ_NO_REMOTE': '1', 05:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:59 INFO - 'NO_EM_RESTART': '1', 05:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:59 INFO - 'PWD': '/builds/slave/test', 05:37:59 INFO - 'SHELL': '/bin/bash', 05:37:59 INFO - 'SHLVL': '1', 05:37:59 INFO - 'TERM': 'linux', 05:37:59 INFO - 'TMOUT': '86400', 05:37:59 INFO - 'USER': 'cltbld', 05:37:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:59 INFO - '_': '/tools/buildbot/bin/python'} 05:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:37:59 INFO - Cleaning up... 05:37:59 INFO - Return code: 0 05:37:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:59 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 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:37:59 INFO - Using env: {'DISPLAY': ':0', 05:37:59 INFO - 'HOME': '/home/cltbld', 05:37:59 INFO - 'LANG': 'en_US.UTF-8', 05:37:59 INFO - 'LANGUAGE': 'en_US:en', 05:37:59 INFO - 'LOGNAME': 'cltbld', 05:37:59 INFO - 'MAIL': '/var/mail/cltbld', 05:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:59 INFO - 'MOZ_NO_REMOTE': '1', 05:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:59 INFO - 'NO_EM_RESTART': '1', 05:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:59 INFO - 'PWD': '/builds/slave/test', 05:37:59 INFO - 'SHELL': '/bin/bash', 05:37:59 INFO - 'SHLVL': '1', 05:37:59 INFO - 'TERM': 'linux', 05:37:59 INFO - 'TMOUT': '86400', 05:37:59 INFO - 'USER': 'cltbld', 05:37:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:59 INFO - '_': '/tools/buildbot/bin/python'} 05:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:59 INFO - Downloading/unpacking blobuploader==1.2.4 05:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:01 INFO - Downloading blobuploader-1.2.4.tar.gz 05:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:38:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:38:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:02 INFO - Downloading docopt-0.6.1.tar.gz 05:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:38:02 INFO - Installing collected packages: blobuploader, requests, docopt 05:38:02 INFO - Running setup.py install for blobuploader 05:38:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:38:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:38:02 INFO - Running setup.py install for requests 05:38:02 INFO - Running setup.py install for docopt 05:38:03 INFO - Successfully installed blobuploader requests docopt 05:38:03 INFO - Cleaning up... 05:38:03 INFO - Return code: 0 05:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:03 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 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:03 INFO - Using env: {'DISPLAY': ':0', 05:38:03 INFO - 'HOME': '/home/cltbld', 05:38:03 INFO - 'LANG': 'en_US.UTF-8', 05:38:03 INFO - 'LANGUAGE': 'en_US:en', 05:38:03 INFO - 'LOGNAME': 'cltbld', 05:38:03 INFO - 'MAIL': '/var/mail/cltbld', 05:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:03 INFO - 'MOZ_NO_REMOTE': '1', 05:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:03 INFO - 'NO_EM_RESTART': '1', 05:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:03 INFO - 'PWD': '/builds/slave/test', 05:38:03 INFO - 'SHELL': '/bin/bash', 05:38:03 INFO - 'SHLVL': '1', 05:38:03 INFO - 'TERM': 'linux', 05:38:03 INFO - 'TMOUT': '86400', 05:38:03 INFO - 'USER': 'cltbld', 05:38:03 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:03 INFO - '_': '/tools/buildbot/bin/python'} 05:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:03 INFO - Running setup.py (path:/tmp/pip-GRETfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:03 INFO - Running setup.py (path:/tmp/pip-Bh5azt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:03 INFO - Running setup.py (path:/tmp/pip-DViKjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:03 INFO - Running setup.py (path:/tmp/pip-Cmegon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:03 INFO - Running setup.py (path:/tmp/pip-21cS3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:03 INFO - Running setup.py (path:/tmp/pip-gfbwVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:03 INFO - Running setup.py (path:/tmp/pip-NLuLZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:03 INFO - Running setup.py (path:/tmp/pip-W_uSo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:03 INFO - Running setup.py (path:/tmp/pip-TBq33g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:03 INFO - Running setup.py (path:/tmp/pip-b9IrWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:04 INFO - Running setup.py (path:/tmp/pip-ncMApc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:04 INFO - Running setup.py (path:/tmp/pip-OMImjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:04 INFO - Running setup.py (path:/tmp/pip-ypOy9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:04 INFO - Running setup.py (path:/tmp/pip-3m7hXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:04 INFO - Running setup.py (path:/tmp/pip-Xcqtj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:04 INFO - Running setup.py (path:/tmp/pip-3jswA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:04 INFO - Running setup.py (path:/tmp/pip-WAdele-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:38:04 INFO - Running setup.py install for manifestparser 05:38:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Running setup.py install for mozcrash 05:38:04 INFO - Running setup.py install for mozdebug 05:38:04 INFO - Running setup.py install for mozdevice 05:38:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Running setup.py install for mozfile 05:38:05 INFO - Running setup.py install for mozhttpd 05:38:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozinfo 05:38:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozInstall 05:38:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozleak 05:38:05 INFO - Running setup.py install for mozlog 05:38:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for moznetwork 05:38:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozprocess 05:38:05 INFO - Running setup.py install for mozprofile 05:38:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozrunner 05:38:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:38:06 INFO - Running setup.py install for mozscreenshot 05:38:06 INFO - Running setup.py install for moztest 05:38:06 INFO - Running setup.py install for mozversion 05:38:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:38:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:38:06 INFO - Cleaning up... 05:38:06 INFO - Return code: 0 05:38:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:06 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 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:06 INFO - Using env: {'DISPLAY': ':0', 05:38:06 INFO - 'HOME': '/home/cltbld', 05:38:06 INFO - 'LANG': 'en_US.UTF-8', 05:38:06 INFO - 'LANGUAGE': 'en_US:en', 05:38:06 INFO - 'LOGNAME': 'cltbld', 05:38:06 INFO - 'MAIL': '/var/mail/cltbld', 05:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:06 INFO - 'MOZ_NO_REMOTE': '1', 05:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:06 INFO - 'NO_EM_RESTART': '1', 05:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:06 INFO - 'PWD': '/builds/slave/test', 05:38:06 INFO - 'SHELL': '/bin/bash', 05:38:06 INFO - 'SHLVL': '1', 05:38:06 INFO - 'TERM': 'linux', 05:38:06 INFO - 'TMOUT': '86400', 05:38:06 INFO - 'USER': 'cltbld', 05:38:06 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:06 INFO - '_': '/tools/buildbot/bin/python'} 05:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:06 INFO - Running setup.py (path:/tmp/pip-Szdtyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:06 INFO - Running setup.py (path:/tmp/pip-LPo_mV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:06 INFO - Running setup.py (path:/tmp/pip-pTcZDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:06 INFO - Running setup.py (path:/tmp/pip-orBebM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:06 INFO - Running setup.py (path:/tmp/pip-HDQycM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:06 INFO - Running setup.py (path:/tmp/pip-98DfHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:07 INFO - Running setup.py (path:/tmp/pip-A_PxSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:07 INFO - Running setup.py (path:/tmp/pip-Gc5TFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:07 INFO - Running setup.py (path:/tmp/pip-CsQ4Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:07 INFO - Running setup.py (path:/tmp/pip-77Wlfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:07 INFO - Running setup.py (path:/tmp/pip-G2ew_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:07 INFO - Running setup.py (path:/tmp/pip-qhQsAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:07 INFO - Running setup.py (path:/tmp/pip-vR7TpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:07 INFO - Running setup.py (path:/tmp/pip-JqTBzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:07 INFO - Running setup.py (path:/tmp/pip-5YRPI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:07 INFO - Running setup.py (path:/tmp/pip-odajh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:07 INFO - Running setup.py (path:/tmp/pip-2EXx4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:09 INFO - Downloading blessings-1.6.tar.gz 05:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:38:09 INFO - Installing collected packages: blessings 05:38:09 INFO - Running setup.py install for blessings 05:38:09 INFO - Successfully installed blessings 05:38:09 INFO - Cleaning up... 05:38:09 INFO - Return code: 0 05:38:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:10 INFO - Reading from file tmpfile_stdout 05:38:10 INFO - Current package versions: 05:38:10 INFO - argparse == 1.2.1 05:38:10 INFO - blessings == 1.6 05:38:10 INFO - blobuploader == 1.2.4 05:38:10 INFO - docopt == 0.6.1 05:38:10 INFO - functools32 == 3.2.3-2 05:38:10 INFO - jsonschema == 2.5.1 05:38:10 INFO - manifestparser == 1.1 05:38:10 INFO - mozInstall == 1.12 05:38:10 INFO - mozcrash == 0.17 05:38:10 INFO - mozdebug == 0.1 05:38:10 INFO - mozdevice == 0.48 05:38:10 INFO - mozfile == 1.2 05:38:10 INFO - mozhttpd == 0.7 05:38:10 INFO - mozinfo == 0.9 05:38:10 INFO - mozleak == 0.1 05:38:10 INFO - mozlog == 3.3 05:38:10 INFO - moznetwork == 0.27 05:38:10 INFO - mozprocess == 0.23 05:38:10 INFO - mozprofile == 0.28 05:38:10 INFO - mozrunner == 6.12 05:38:10 INFO - mozscreenshot == 0.1 05:38:10 INFO - mozsystemmonitor == 0.3 05:38:10 INFO - moztest == 0.7 05:38:10 INFO - mozversion == 1.4 05:38:10 INFO - psutil == 3.1.1 05:38:10 INFO - requests == 1.2.3 05:38:10 INFO - wsgiref == 0.1.2 05:38:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:10 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 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:10 INFO - Using env: {'DISPLAY': ':0', 05:38:10 INFO - 'HOME': '/home/cltbld', 05:38:10 INFO - 'LANG': 'en_US.UTF-8', 05:38:10 INFO - 'LANGUAGE': 'en_US:en', 05:38:10 INFO - 'LOGNAME': 'cltbld', 05:38:10 INFO - 'MAIL': '/var/mail/cltbld', 05:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:10 INFO - 'MOZ_NO_REMOTE': '1', 05:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:10 INFO - 'NO_EM_RESTART': '1', 05:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:10 INFO - 'PWD': '/builds/slave/test', 05:38:10 INFO - 'SHELL': '/bin/bash', 05:38:10 INFO - 'SHLVL': '1', 05:38:10 INFO - 'TERM': 'linux', 05:38:10 INFO - 'TMOUT': '86400', 05:38:10 INFO - 'USER': 'cltbld', 05:38:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:10 INFO - '_': '/tools/buildbot/bin/python'} 05:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:38:10 INFO - Cleaning up... 05:38:10 INFO - Return code: 0 05:38:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:10 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 0x7fcc3d2bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, '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': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:38:10 INFO - Using env: {'DISPLAY': ':0', 05:38:10 INFO - 'HOME': '/home/cltbld', 05:38:10 INFO - 'LANG': 'en_US.UTF-8', 05:38:10 INFO - 'LANGUAGE': 'en_US:en', 05:38:10 INFO - 'LOGNAME': 'cltbld', 05:38:10 INFO - 'MAIL': '/var/mail/cltbld', 05:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:10 INFO - 'MOZ_NO_REMOTE': '1', 05:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:10 INFO - 'NO_EM_RESTART': '1', 05:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:10 INFO - 'PWD': '/builds/slave/test', 05:38:10 INFO - 'SHELL': '/bin/bash', 05:38:10 INFO - 'SHLVL': '1', 05:38:10 INFO - 'TERM': 'linux', 05:38:10 INFO - 'TMOUT': '86400', 05:38:10 INFO - 'USER': 'cltbld', 05:38:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:10 INFO - '_': '/tools/buildbot/bin/python'} 05:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:38:10 INFO - Cleaning up... 05:38:10 INFO - Return code: 0 05:38:10 INFO - Running post-action listener: _resource_record_post_action 05:38:10 INFO - Running post-action listener: _start_resource_monitoring 05:38:10 INFO - Starting resource monitoring. 05:38:10 INFO - [mozharness: 2016-08-18 12:38:10.676758Z] Finished create-virtualenv step (success) 05:38:10 INFO - [mozharness: 2016-08-18 12:38:10.677209Z] Running install step. 05:38:10 INFO - Running pre-action listener: _resource_record_pre_action 05:38:10 INFO - Running main action method: install 05:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:10 INFO - Reading from file tmpfile_stdout 05:38:10 INFO - Detecting whether we're running mozinstall >=1.0... 05:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:38:10 INFO - Reading from file tmpfile_stdout 05:38:10 INFO - Output received: 05:38:10 INFO - Usage: mozinstall [options] installer 05:38:10 INFO - Options: 05:38:10 INFO - -h, --help show this help message and exit 05:38:10 INFO - -d DEST, --destination=DEST 05:38:10 INFO - Directory to install application into. [default: 05:38:10 INFO - "/builds/slave/test"] 05:38:10 INFO - --app=APP Application being installed. [default: firefox] 05:38:10 INFO - mkdir: /builds/slave/test/build/application 05:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:38:27 INFO - Reading from file tmpfile_stdout 05:38:27 INFO - Output received: 05:38:27 INFO - /builds/slave/test/build/application/firefox/firefox 05:38:27 INFO - Running post-action listener: _resource_record_post_action 05:38:27 INFO - [mozharness: 2016-08-18 12:38:27.967772Z] Finished install step (success) 05:38:27 INFO - [mozharness: 2016-08-18 12:38:27.968018Z] Running run-tests step. 05:38:27 INFO - Running pre-action listener: _resource_record_pre_action 05:38:27 INFO - Running main action method: run_tests 05:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:38:27 INFO - Python 2.7.3 05:38:27 INFO - Return code: 0 05:38:27 INFO - grabbing minidump binary from tooltool 05:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:27 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 0x163c370>, '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 0x163f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163fd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:38:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:38:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:38:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:38:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:28 INFO - Return code: 0 05:38:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:38:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:38:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:38:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:38:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:38:28 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/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:38:28 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/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:38:28 INFO - Using env: {'DISPLAY': ':0', 05:38:28 INFO - 'HOME': '/home/cltbld', 05:38:28 INFO - 'LANG': 'en_US.UTF-8', 05:38:28 INFO - 'LANGUAGE': 'en_US:en', 05:38:28 INFO - 'LOGNAME': 'cltbld', 05:38:28 INFO - 'MAIL': '/var/mail/cltbld', 05:38:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:38:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:28 INFO - 'MOZ_NO_REMOTE': '1', 05:38:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:38:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:28 INFO - 'NO_EM_RESTART': '1', 05:38:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:28 INFO - 'PWD': '/builds/slave/test', 05:38:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:38:28 INFO - 'SHELL': '/bin/bash', 05:38:28 INFO - 'SHLVL': '1', 05:38:28 INFO - 'TERM': 'linux', 05:38:28 INFO - 'TMOUT': '86400', 05:38:28 INFO - 'USER': 'cltbld', 05:38:28 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187', 05:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:28 INFO - '_': '/tools/buildbot/bin/python'} 05:38:28 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/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:38:28 INFO - mozversion application_buildid: 20160817193029 05:38:28 INFO - mozversion application_changeset: 97a52326b06a07930216ebefa5af333271578904 05:38:28 INFO - mozversion application_display_name: Nightly 05:38:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:28 INFO - mozversion application_name: Firefox 05:38:28 INFO - mozversion application_remotingname: firefox 05:38:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:38:28 INFO - mozversion application_vendor: Mozilla 05:38:28 INFO - mozversion application_version: 51.0a1 05:38:28 INFO - mozversion platform_buildid: 20160817193029 05:38:28 INFO - mozversion platform_changeset: 97a52326b06a07930216ebefa5af333271578904 05:38:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:38:28 INFO - mozversion platform_version: 51.0a1 05:38:28 INFO - using testdate: 1471523908 05:38:28 INFO - actual date: 1471523908 05:38:28 INFO - starting webserver on 'localhost:56922' 05:38:28 INFO - SUITE-START | Running 2 tests 05:38:28 INFO - TEST-START | dromaeo_css 05:38:28 INFO - operating with platform_type : linux_ 05:38:28 INFO - Initialising browser for dromaeo_css test... 05:38:28 INFO - TEST-INFO | started process 20167 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4V1o7h/profile http://localhost:56922/getInfo.html) 05:38:32 INFO - PROCESS | 20167 | __metrics Screen width/height:1600/1200 05:38:32 INFO - PROCESS | 20167 | colorDepth:24 05:38:32 INFO - PROCESS | 20167 | Browser inner width/height: 1024/697 05:38:32 INFO - PROCESS | 20167 | __metrics 05:38:32 INFO - PROCESS | 20167 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:38:32 INFO - PROCESS | 20167 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:38:33 INFO - PROCESS | 20167 | console.error: PushService: 05:38:33 INFO - PROCESS | 20167 | stateChangeProcessEnqueue: Error transitioning state 05:38:33 INFO - PROCESS | 20167 | UnknownError 05:38:34 INFO - TEST-INFO | 20167: exit 0 05:38:34 INFO - Browser initialized. 05:38:34 INFO - Running cycle 1/1 for dromaeo_css test... 05:38:34 INFO - TEST-INFO | started process 20249 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4V1o7h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:45 INFO - PROCESS | 20249 | RSS: Main: 156123136 05:38:45 INFO - PROCESS | 20249 | 05:39:37 INFO - PROCESS | 20249 | 1471523977536 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:41:47 INFO - PROCESS | 20249 | Cycle 1(1): loaded http://localhost:56922/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56922/tests/dromaeo/cssquery-ext.html) 05:41:48 INFO - PROCESS | 20249 | RSS: Main: 244940800 05:41:48 INFO - PROCESS | 20249 | 05:44:50 INFO - PROCESS | 20249 | Cycle 1(1): loaded http://localhost:56922/tests/dromaeo/cssquery-ext.html (next: http://localhost:56922/tests/dromaeo/cssquery-jquery.html) 05:44:51 INFO - PROCESS | 20249 | RSS: Main: 242032640 05:44:51 INFO - PROCESS | 20249 | 05:47:53 INFO - PROCESS | 20249 | Cycle 1(1): loaded http://localhost:56922/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56922/tests/dromaeo/cssquery-mootools.html) 05:47:53 INFO - PROCESS | 20249 | RSS: Main: 186888192 05:47:53 INFO - PROCESS | 20249 | 05:50:55 INFO - PROCESS | 20249 | Cycle 1(1): loaded http://localhost:56922/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56922/tests/dromaeo/cssquery-prototype.html) 05:50:56 INFO - PROCESS | 20249 | RSS: Main: 184160256 05:50:56 INFO - PROCESS | 20249 | 05:53:59 INFO - PROCESS | 20249 | Cycle 1(1): loaded http://localhost:56922/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56922/tests/dromaeo/cssquery-yui.html) 05:53:59 INFO - PROCESS | 20249 | RSS: Main: 185438208 05:53:59 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | Cycle 1(1): loaded http://localhost:56922/tests/dromaeo/cssquery-yui.html (next: http://localhost:56922/tests/dromaeo/cssquery-dojo.html) 05:57:02 INFO - PROCESS | 20249 | RSS: Main: 267776000 05:57:02 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | __start_tp_report 05:57:02 INFO - PROCESS | 20249 | _x_x_mozilla_page_load 05:57:02 INFO - PROCESS | 20249 | _x_x_mozilla_page_load_details 05:57:02 INFO - PROCESS | 20249 | |i|pagename|runs| 05:57:02 INFO - PROCESS | 20249 | |0;dojo.html;2985.492466280732;2993.805402648826;3008.563758255053;3018.426498965195;3021.471242532556;5071.84784456466;5125.154349532332;5177.326947496825;5316.707581083039;5358.732063396829;5206.323177986866;5214.791408343671;5232.973835130829;5276.630635855492;5309.389420216677;4656.5110663380365;4704.458987216462;4752.738599377033;4778.068276686047;4914.1400254955415;4867.269909513584;4879.8780030499165;4904.975475122619;4906.607471402297;4915.926261106087;4647.976760116211;4750.382450281476;4812.903741925177;4816.5068828663225;4862.124817532843;4756.143894099061;4756.881077973078;4762.333273341735;4772.42730872294;4860.659753817235;4772.76136193189;4799.9760001199775;4801.399825021872;4819.638527110481;4841.612670986347;5152.871178220537;5212.765425555821;5251.86870328241;5276.182191760283;5281.419043905167;5686.488216060533;5737.7131143443075;5739.311282646109;5753.137029445575;5756.510696590794;5789.710514474302;5791.739371728282;5798.217240672498;5805.651660900359;5807.448292412214;5165.457626949154;5318.335208098988;5350.143976963706;5374.086405311028;5455.509004189642;5849.824505264849;5901.498372638288;5909.763609455659;5910.793122240787;5910.793122240787;5682.346530149086;5688.345840228427;5691.516221121168;5693.772249110072;5699.145128230715;5493.340799104133;5599.888002239933;5633.408492108351;5655.236543066716;5720.8283751487525;5648.774049037993;5660.801871934463;5661.179129026268;5690.573207009491;5696.287964004499;5664.971675141598;5697.03150464422;5700.116481945222;5702.059160238514;5711.371749107595;4256.212600668886;4332.718373305725;4338.305871060616;4380.474343078851;4383.7588932609015;3836.635519625631;3915.530136383595;3920.0591857954455;3934.2328245991757;3954.0905591714068;5398.676079435247;5402.702851343135;5404.162354835007;5437.429069947678;5453.972730136324;5741.081426971665;5741.913871291934;5748.109043098243;5753.395893431213;5754.56840736938;5192.7403629818355;5341.198820176927;5380.704061276589;5385.75764090609;5391.083515802323;4933.901321973541;4973.403191617029;4976.726280054631;4991.126552853236;5004.324416203766;5991.161237426763;5991.88016239664;5995.160677505153;6006.819795406145;6007.038873780174;5515.09001014828;5634;5744.109663002241;5751.482366587027;5752.913706294493;5950.434708702666;5953.077273022688;5955.493783028318;5992.67040312787;6002.69986500682;5581.218629391969;5582.49757521825;5585.720713964367;5589.972050139723;5592.804251851166;5486.1222204447095;5650.57620678459;5664.207011018543;5675.233843431249;5688.459596338341;5633.802816901389;5646.407127251579;5655.462731040545;5684.8578785529035;5709.058005429058;5556.472135147154;5616.5787565931905;5620.690862002465;5626.1560765883805;5629.211910332639;5277.182036784305;5497.670139791625;5498.752556134904;5529.198266251291;5554.91667624994;5677.489025987681;5695.345035320991;5722.599418040697;5726.484616384545;5743.3395159557185;5493.450654934474;5507.173923911285;5511.559075273888;5565.6104072714525;5566.777328906798 05:57:02 INFO - PROCESS | 20249 | |1;ext.html;587.3216435017554;606.8772770374804;609.3388673289481;616.3959319866531;638.109836777695;32171.356572868557;34868.12829679256;35358;35370.64629353706;47286.29070563944;46596;47030.29454558184;47090;47210.52789472104;47234.52765472344;8995.190432861042;9151.222146117589;9157.31320150988;9164.908350916472;9175.311851611123;8911.420757650732;9016;9026;9029.729108126767;9036.051214622468;8921.197092261695;9067.909320906805;9079.773005674879;9094.727158185233;9096.27229821616;9013.504257265848;9017.909820901772;9069.18377346042;9070.909290907106;9071.59177836999;8896.110388961051;8989.640414383417;8994.640214391417;8998.86501702475;9003.954980225057;43777;44079.33880991788;44112.3383149253;44215;44230;3818.4081467372603;3869.361555343365;4068.30838757409;4202.285611446062;4246;32359.191020224447;32510.674893251;32511.6748832511;33032.50451243233;33202.50196247058;4281.272183728774;4330.588594083557;4366.781660916941;4435.955640443619;4471.217536931024;349417;353040;353086;354084;354095;58104.4189558099;58427.4157258422;58462.23066539968;58665.41334586685;58703.70648146732;36816;37578.43632345517;37579.62420375816;61657;61957;15907.920460397623;16316.265768040466;16341;16346.509604711877;16364.263608137424;12080.939595301967;12195.268283902817;12207.328596927255;12209.450574724158;12220.633380998584;19407.010764011324;19560.706589401172;19596.53026023054;19600.941901100465;19654.803451965585;7154.177269614061;7230.313120253568;7354.852902941911;7377.225391333833;7399.778006659809;965.9227261819037;1033.6020632056711;1058.0688993685508;1119.361963680694;1124.814405623063;33524.66475335264;33653.831730841186;33691.66308336934;33762.49356259658;33881;1119.3899324867966;1127.9266847654876;1135.3415019288793;1146.48408216303;1215.2222577550374;17413.216405261795;17499.82500174982;17577;17695.646087078185;17820.19809108593;546960;553439;562115;562256;565505;57652;58015.709921450965;58485.70757146187;59095;59136;1232.8890399864056;1322.9404676789568;1335.7862741961435;1361.9319034048258;1371.1841454334656;30867;34051.4892276621;34151;34354.312913742084;34527.79089124595;50450.49549504458;50747.238791418895;50928;50951.235731464796;51113.48886511087;427464;428682;429076;429515;429968;24826.37934051591;27803.58294625623;29225.269368265963;29393.971211007512;29647;43452.782736087385;47906.85583450752;47920;48561.757191213816;48946.021079578924;40515.78452646425;40744.59255407408;41449.79275103605;42582.36126458044;43319.56680433155;40375.3943690839;40976;41251.793741031106;41666;42911;40826.59173408228;41468.37797432981;42925.78537107295;42969;43017.3547396783;32594;36831.263374732895;37034.44448333331;37189.282040526996;37683.43474847934;28490.287742806595;28519.429611407242;28574.57138142888;28588.857055714587;28975.7102428973 05:57:02 INFO - PROCESS | 20249 | |2;jquery.html;375.93045286621975;381.7823840410965;386.9748466349687;402.3622558245181;411.3151602581702;17774.200160992754;19314.90342548287;19439.41681749546;19480.31818886341;19578;18934.90532547335;19184.904075479604;19220.327288544868;19444.125014374353;19596.41210763676;5557.166425036236;5696.829095127143;5786.508146807527;5921.674307913063;5926.851828704295;5872.324682661506;5880.382559831199;5893.55798315126;5896.027155519334;5901.970490147543;5541.667499950016;5762.596618236725;5807.41925807418;5893.734781934823;5902.557308201902;5962.105684147369;5991.281046274431;5999.490043346299;6000.6099603525845;6004.12940123684;5664.915026274609;5805.36141024487;5846.473817356458;5849.093390524476;5858.297004359461;13866.098703584235;14127.505537306248;18730.81269187304;23641.527169456516;23821.880890595436;2311.722593288816;2435.8294919355653;2482.726900040994;2631.6841978962457;2906.418716256736;9940.701778946643;9983.301168918179;10075.143612792921;10586.258961872672;10732.463376831127;1838.5771272607242;1873.9505876709086;1886.207792727058;1905.7617797775279;1947.47418197086;73432;82294.17705822925;83347;84830.57584712037;85842.5707871463;11185.440727963569;11199.272047317061;11264.66206013821;11321.660350189508;11504.25222360544;15782.13198274083;15878.68242635164;15922.76115858263;15948.441804536786;15950.122743249001;7695.345895598824;7711.460197786213;7918.28735413804;7938.087119981161;8017.518948863087;7055.506114571931;7085.681144348517;7161.248068952789;7317.707291708379;7327.963360183272;4817.9518204817505;4842.539958703917;4843.1524483216;4910.558049775537;4971.105201063843;1967.537628657293;1988.8707234029741;1992.1732481020215;2026.706127611488;2050.4361300642445;957.9521023948774;978.7308490165261;985.4727720669407;1039.9652345915863;1047.3297089862494;18953.715694264596;19578.216871325272;19883.701744473845;19905.303314383927;20233.898830505754;977.7946631207384;1013.6705570689408;1015.9949200253951;1021.9029192226726;1038.0605551975323;10267.613872127167;10483.528241229164;10736.677899663022;11180.44097795107;11524.135689823297;485715;492615;507899;512962;517110;87113.82215444665;92668.07331926594;95312.52343738236;95917.5204123975;97601.51199243958;1009.4094954451728;1014.4015031131668;1050.0706874416044;1050.7110544600296;1054.478033373485;17510.212040458464;17893.821061789735;18369.724454133466;18482.260709571467;18803;40855;41350;41812.790936046345;42593;42932.35601465918;52682.47317526929;58594.707026464595;60086;61414;62149;5551.833444996657;5874;6805.013273075476;7731.806704832424;8339.291160251447;11264.493097810455;13126.212427254395;13160.341982900625;13202.801957970447;13875.79186312186;11727.47226374798;11742.58900938463;11865.822012669989;11932.76134477323;12107.515699371927;11424.600138995096;11560.537578496765;11713.1800773945;11768.764624707286;12213.572524961584;11725.179237453638;11772.17594768358;11873.346965916957;12008.819867701817;12323.198992065487;7246.565206087652;7339.486235963432;8060.556669383241;8362.498250105013;8774.122587741174;10751.24741268135;11709.355985420883;11727.068232941765;11767.941160294486;11784.58753943608 05:57:02 INFO - PROCESS | 20249 | |3;mootools.html;1346.1115663661974;1502.819661640603;1529.4876216467483;1635.19875261122;1770.4865588979196;7845.411594130442;8210.178982101786;8339.583020848964;8512.616932238048;8559.614817333219;8374.330053595713;8511.233988940994;8513.276371508413;8519.701810436636;8528.74413767588;2357.233898982829;2385.6779334789753;2385.8329916905823;2389.9761002390014;2395.700537432821;2307.7576854430295;2347.0611755297846;2354.0701422937927;2357.57563638545;2357.8467399619058;2294.2314324701247;2317.4901521665292;2319.037599396257;2366.5740166769974;2370.1941339944415;2246.5619204255013;2300.964565945323;2308.0767692922796;2310.745817960023;2316.81465482761;2291.5875142474347;2301.838871279011;2304.6632952887453;2306.054517647773;2306.573283942476;8338.749837504885;8363.498190108612;8420;8451.197136272045;8481.533515656636;4880.780364883553;4991.07665081961;5038.420581633122;5064.088464076464;5084.51697088776;3170.1599076244815;3400.30293789775;3417.6411476795074;3442.0706409269355;3480;1071.3893080943865;1104.7017305327597;1111.5109351885226;1143.2911594811187;1146.7305183282006;11254.155938304579;11310.547578096866;11312.660620181408;11330.830037549444;11344.376059316817;4098.877033688935;4247.299195632748;4375.693701440932;4430.113977204572;4435.1351486459835;9194.724158275263;9219.400738952081;9238.030006849318;9241;9249.16757491829;5025.195968644999;5032.370953630796;5033.194688849767;5033.270175824485;5041.521055499722;3967.305721498726;4462.642988560908;4668.883277918011;4706.129366067288;4726.26742854858;3541.592716837545;3619.3485172668647;3674.889753307405;3696.722745794076;3721.7208709346905;1097.4403054442132;1101.862267216598;1109.877913429522;1119.921605487608;1127.577158565538;1008.3042700536606;1012.094175712733;1015.8120747661707;1028.8508166315992;1036.0727149201423;5953.76184952597;6005.879882402328;6051.546134039964;6069.15031895535;6176.752929882844;455.6218338778806;467.72078365669864;477.1030462730045;477.8590315856761;478.9976050119727;4214.894627634272;4221.261279276115;4256.510501292391;4297.828086876552;4317.956820431818;9268.629254829732;9623.807523849484;9638.036196380306;9648.662296819577;9686.740723706014;9104.271658267322;9306.162445379949;9374.437533747998;9611.855822162674;9613.855792163124;7649.694012239559;7682.231776822273;7825.217478252129;7829.569373684502;7851.097123830833;5873.647581145145;5881.852953676193;6045.183900173421;6104.969475152596;6156.815295541141;8809.339299552432;9272.72181834546;9303.906960930575;9319.627214911328;9372;9093.454392736458;9370.203532699798;9373.953130234579;9626.47054412014;9627;6260.624362538262;6420.229572451336;6433.646149461637;7119.82200444993;7315.743948961761;7073.787786366417;7799.376049916106;8015.358771298399;8140.063892652421;8229.382796290422;7144;7208.171060328129;7240.565566066053;7280.854382912206;7464.925350746423;7180.964095179491;7239.565626062453;7303.926960730325;7369.078865141857;7414.888776668462;6576.177977752781;6764.323567643196;6795.116634837439;6801.18385793708;6801.829954251183;5053.26727624485;5322.654027488201;5396.325459317585;5412.323459567554;5433.782648694008;5303.15149576066;5347.251384806053;5356.705381204071;5528.751206195811;5562.415946325577 05:57:02 INFO - PROCESS | 20249 | |4;prototype.html;317.92858068309795;325.84848045658777;328.6607076164629;331.3952037531504;332.9783564068337;5154.922676159856;5198.376194856618;5209.348831396075;5211.24436956642;5225.921611175835;5124.308218390516;5137.203733421316;5139.948600513993;5146.639735218536;5147.794088236466;3743.438484227374;3824.216035712679;3914.4911161548957;3921.8823435296986;3948.170884114325;4041.616046475595;4044.6562042226446;4066.7966601669946;4075.4090656854737;4080.9183816323657;4018.754186202272;4020.618041286073;4025.0339918419513;4026.3155263605113;4035.435039094529;3922.5685174630767;3976.264391087629;4013.8961785508927;4041.1513582147786;4050.8784736457956;3586.4261718125235;3586.677199052072;3588.8743893963588;3596.8381422836037;3598.298331825295;4994.450610432849;4996.850094497171;5002.874928126789;5006.173981293082;5007.624428167902;4473.015936493966;4613.215753321943;4619.422572178478;4624.705082576884;4629.976850115761;5328.307320048408;5355.544778693816;5358.678479291255;5371.140617501181;5377.193420986883;3936.626020528045;3943.2310699413624;3948.9802550987347;3949.8420063197436;3954.3079961006997;5409.647588102975;5551.694656793875;5561.555075593983;5572.944270557283;5590.8043218487155;3980.7810570418906;4024.557298697141;4026;4027.5166979962005;4040.9797951010055;4048.959510404917;4064.4919385076864;4069.7558146511305;4069.8372065117064;4077.4903137107863;4556.88607784808;4558.9772051139535;4581.358609794632;4585.495595484494;4632.536746325408;5162.070827251056;5351.759170837322;5367.221752845816;5390.97304513475;5396.4603539646505;1390.721855628858;1399.6710772968345;1408.4718230663502;1411.1321537254514;1411.985880141206;838.8741688746737;881.5107615273456;892.7544925145637;896.4531635702216;900.5812297281665;5087.974560127176;5240.9213861792105;5247.895042099213;5252.711100889412;5287.603429742785;5557;5558.499735023786;5565.3599836019375;5566.304211973504;5569.415211402826;4949.208126699711;5201.011807756514;5249.343832020997;5250.97374513125;5269.894602108013;2912.85435728217;2940.5001149804157;2947.3368492089107;2949.9410011799646;2952.985235073854;5720.542356611461;5737.426257374313;5761.567882408836;5774.797882074107;5779.33537643168;5564.527015203672;5688.687122208318;5694.604821818598;5716.28546431696;5740.225069615632;5723.998300297515;5782.5663075268685;5801.738921748616;5812.9709351452975;5816.47651711348;5324.683762563297;5514.36584792754;5535.612507124462;5550.836023466747;5555.972220138873;5522.97238513821;5535.7785688572;5535.972320138373;5548.944510554843;5554.444555544414;5704.429557044263;5715.342735585295;5725.427457254241;5734.34055083654;5756.42435756421;3968.980155099206;4039.9394009090474;4047.1192551798613;4054.553999060042;4063.6139566741113;5483.75323110453;5496.1755736639825;5497.890042199214;5498.752556135064;5501.734601041703;5328;5340.439253878442;5348.251244825649;5355.892882142258;5365.651232669864;5092.668976516515;5359.196120581944;5423.376311724202;5448.482394172547;5462.9726851365485;5375.865603359947;5389.326334208224;5391.7034563099405;5397.406285308534;5405.702686352289;5213.713245771519;5268.552173065333;5456;5456.79950410909;5460.481254280837;5302.045631786161;5321.201819726917;5330.520253177233;5356.276902618097;5370.9731451342495 05:57:02 INFO - PROCESS | 20249 | |5;yui.html;734.342763226912;748.2330611123601;765.3647472597744;768.5350363030367;782.6204290918907;15600.375984960601;16173;16174.919125404385;16216.75674864878;16287.674246515062;14977.176255305982;15025.62435939102;15036.323365448554;15086.773698394505;15086.849131508681;656.3108735827386;790.3874497264609;802.2579114319246;804.6178065418909;805.122416565943;795.9204079592023;796.1719811396141;799.2007992007992;799.8600244957138;801.7915342011061;875.5884734174928;876.5179151466668;878.165742544582;878.5607196401799;879.4283715584857;693.2686016252851;855.7860534866284;869.482657818597;870.3733312015371;870.4081224767187;608.3156448994881;624.0919462182559;629.015590600713;630.8391360203165;632.6836581709146;14938.402463901431;14942.103473791607;15113.09321440717;15116.848831511765;15149.091054536762;13795.793063104062;14129.364178611988;14277.214753188571;14811.259437028106;14890.478833240893;13352.465901363934;13387.263700496469;13414.86585134146;13807.378667959965;14260.001799874013;4474.731516109044;4479.350494178326;4542.977285113553;4659.808031498401;4743.691660042121;81212.50299975988;81702.18297817005;84070.15929840745;84077;84137.57931210304;11516.827247591293;11585.88414115848;11595.420228988516;11646.883531164749;11854.703632409086;22340.329790106323;22499.66250506244;22727.431814204778;22755.203567875044;22771;8825.514596697243;8909.599068041955;8934.86597701035;8955.373123881396;9049.592768325441;4733.242681170997;4781.593564547053;4791.401074865642;4798.520147985243;4798.712077275374;3425.3320602482777;3485.041613556242;3498.6151523332924;3513.3675938331344;3521.5245941798044;607.4927435591243;623.5884316351277;628.7924984755019;629.0187307799847;645.4707140145036;587.5152998775941;614.4040280927493;649.9220093588799;663.867226554691;696.3002182806349;8599.699010534601;9160;9265.11981361783;9427.292953028413;9612.855807162898;600.9513399118566;646.3375040583346;689.5311188391842;693.5387967001988;694.2120693013466;4197.559256278108;4277.165952639206;4419.602235798793;4544.272916333371;4565.885852853705;72769.16694573456;73950.63024684842;74501.6274918622;74743;137730;106453.4677326624;109578.72698066961;109676.4516177414;112169.43915280535;112870.43564782123;59798;59993.70003149956;84336;96140.0385996145;98307;8692.174243446863;8701.43440676354;8937.744906198737;8989.640414383352;9024;54056;54256.72871635616;55698;55776;56289.43710562842;76636;79543;82129.58935205525;84760;85015;12241.877581224073;12302.200356976768;12335.814962775386;12410.81383779226;13061.08572399923;13231.470741170631;13236.272005039815;13395.531156409479;13670.11144275619;13672.794908076188;12391.876081239072;12399.628011159679;12466.189697669623;12630.87369126297;12984.610461686165;11892.643220703392;12215.45030473613;12506.249625022529;12545.811812822998;12906;12229.75540489203;12460.43928023223;12636.494540218657;12659.746805064031;12663.620091397273;10300.278980471296;10455.895441045797;10670.786584268426;10831.620893268699;11011.61459348919;8997.550122493718;10568.630097946534;10898.455077245948;11010.50452729645;11077 05:57:02 INFO - PROCESS | 20249 | __end_tp_report 05:57:02 INFO - PROCESS | 20249 | __start_cc_report 05:57:02 INFO - PROCESS | 20249 | _x_x_mozilla_cycle_collect,906 05:57:02 INFO - PROCESS | 20249 | __end_cc_report 05:57:02 INFO - PROCESS | 20249 | __startTimestamp1471525022795__endTimestamp 05:57:02 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | ------- Summary: start ------- 05:57:02 INFO - PROCESS | 20249 | Number of tests: 6 05:57:02 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | [#0] dojo.html Cycles:165 Average:5295.49 Median:5511.56 stddev:626.58 (11.4%) stddev-sans-first:601.73 05:57:02 INFO - PROCESS | 20249 | Values: 2985.5 2993.8 3008.6 3018.4 3021.5 5071.8 5125.2 5177.3 5316.7 5358.7 5206.3 5214.8 5233.0 5276.6 5309.4 4656.5 4704.5 4752.7 4778.1 4914.1 4867.3 4879.9 4905.0 4906.6 4915.9 4648.0 4750.4 4812.9 4816.5 4862.1 4756.1 4756.9 4762.3 4772.4 4860.7 4772.8 4800.0 4801.4 4819.6 4841.6 5152.9 5212.8 5251.9 5276.2 5281.4 5686.5 5737.7 5739.3 5753.1 5756.5 5789.7 5791.7 5798.2 5805.7 5807.4 5165.5 5318.3 5350.1 5374.1 5455.5 5849.8 5901.5 5909.8 5910.8 5910.8 5682.3 5688.3 5691.5 5693.8 5699.1 5493.3 5599.9 5633.4 5655.2 5720.8 5648.8 5660.8 5661.2 5690.6 5696.3 5665.0 5697.0 5700.1 5702.1 5711.4 4256.2 4332.7 4338.3 4380.5 4383.8 3836.6 3915.5 3920.1 3934.2 3954.1 5398.7 5402.7 5404.2 5437.4 5454.0 5741.1 5741.9 5748.1 5753.4 5754.6 5192.7 5341.2 5380.7 5385.8 5391.1 4933.9 4973.4 4976.7 4991.1 5004.3 5991.2 5991.9 5995.2 6006.8 6007.0 5515.1 5634.0 5744.1 5751.5 5752.9 5950.4 5953.1 5955.5 5992.7 6002.7 5581.2 5582.5 5585.7 5590.0 5592.8 5486.1 5650.6 5664.2 5675.2 5688.5 5633.8 5646.4 5655.5 5684.9 5709.1 5556.5 5616.6 5620.7 5626.2 5629.2 5277.2 5497.7 5498.8 5529.2 5554.9 5677.5 5695.3 5722.6 5726.5 5743.3 5493.5 5507.2 5511.6 5565.6 5566.8 05:57:02 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | [#1] ext.html Cycles:180 Average:60536.13 Median:235023.18 stddev:120599.14 (51.3%) stddev-sans-first:120853.46 05:57:02 INFO - PROCESS | 20249 | Values: 587.3 606.9 609.3 616.4 638.1 32171.4 34868.1 35358.0 35370.6 47286.3 46596.0 47030.3 47090.0 47210.5 47234.5 8995.2 9151.2 9157.3 9164.9 9175.3 8911.4 9016.0 9026.0 9029.7 9036.1 8921.2 9067.9 9079.8 9094.7 9096.3 9013.5 9017.9 9069.2 9070.9 9071.6 8896.1 8989.6 8994.6 8998.9 9004.0 43777.0 44079.3 44112.3 44215.0 44230.0 3818.4 3869.4 4068.3 4202.3 4246.0 32359.2 32510.7 32511.7 33032.5 33202.5 4281.3 4330.6 4366.8 4436.0 4471.2 349417.0 353040.0 353086.0 354084.0 354095.0 58104.4 58427.4 58462.2 58665.4 58703.7 36816.0 37578.4 37579.6 61657.0 61957.0 15907.9 16316.3 16341.0 16346.5 16364.3 12080.9 12195.3 12207.3 12209.5 12220.6 19407.0 19560.7 19596.5 19600.9 19654.8 7154.2 7230.3 7354.9 7377.2 7399.8 965.9 1033.6 1058.1 1119.4 1124.8 33524.7 33653.8 33691.7 33762.5 33881.0 1119.4 1127.9 1135.3 1146.5 1215.2 17413.2 17499.8 17577.0 17695.6 17820.2 546960.0 553439.0 562115.0 562256.0 565505.0 57652.0 58015.7 58485.7 59095.0 59136.0 1232.9 1322.9 1335.8 1361.9 1371.2 30867.0 34051.5 34151.0 34354.3 34527.8 50450.5 50747.2 50928.0 50951.2 51113.5 427464.0 428682.0 429076.0 429515.0 429968.0 24826.4 27803.6 29225.3 29394.0 29647.0 43452.8 47906.9 47920.0 48561.8 48946.0 40515.8 40744.6 41449.8 42582.4 43319.6 40375.4 40976.0 41251.8 41666.0 42911.0 40826.6 41468.4 42925.8 42969.0 43017.4 32594.0 36831.3 37034.4 37189.3 37683.4 28490.3 28519.4 28574.6 28588.9 28975.7 05:57:02 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | [#2] jquery.html Cycles:180 Average:29365.99 Median:2459.28 stddev:83020.43 (3375.8%) stddev-sans-first:83224.79 05:57:02 INFO - PROCESS | 20249 | Values: 375.9 381.8 387.0 402.4 411.3 17774.2 19314.9 19439.4 19480.3 19578.0 18934.9 19184.9 19220.3 19444.1 19596.4 5557.2 5696.8 5786.5 5921.7 5926.9 5872.3 5880.4 5893.6 5896.0 5902.0 5541.7 5762.6 5807.4 5893.7 5902.6 5962.1 5991.3 5999.5 6000.6 6004.1 5664.9 5805.4 5846.5 5849.1 5858.3 13866.1 14127.5 18730.8 23641.5 23821.9 2311.7 2435.8 2482.7 2631.7 2906.4 9940.7 9983.3 10075.1 10586.3 10732.5 1838.6 1874.0 1886.2 1905.8 1947.5 73432.0 82294.2 83347.0 84830.6 85842.6 11185.4 11199.3 11264.7 11321.7 11504.3 15782.1 15878.7 15922.8 15948.4 15950.1 7695.3 7711.5 7918.3 7938.1 8017.5 7055.5 7085.7 7161.2 7317.7 7328.0 4818.0 4842.5 4843.2 4910.6 4971.1 1967.5 1988.9 1992.2 2026.7 2050.4 958.0 978.7 985.5 1040.0 1047.3 18953.7 19578.2 19883.7 19905.3 20233.9 977.8 1013.7 1016.0 1021.9 1038.1 10267.6 10483.5 10736.7 11180.4 11524.1 485715.0 492615.0 507899.0 512962.0 517110.0 87113.8 92668.1 95312.5 95917.5 97601.5 1009.4 1014.4 1050.1 1050.7 1054.5 17510.2 17893.8 18369.7 18482.3 18803.0 40855.0 41350.0 41812.8 42593.0 42932.4 52682.5 58594.7 60086.0 61414.0 62149.0 5551.8 5874.0 6805.0 7731.8 8339.3 11264.5 13126.2 13160.3 13202.8 13875.8 11727.5 11742.6 11865.8 11932.8 12107.5 11424.6 11560.5 11713.2 11768.8 12213.6 11725.2 11772.2 11873.3 12008.8 12323.2 7246.6 7339.5 8060.6 8362.5 8774.1 10751.2 11709.4 11727.1 11767.9 11784.6 05:57:02 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | [#3] mootools.html Cycles:180 Average:5469.28 Median:5193.83 stddev:2976.33 (57.3%) stddev-sans-first:2968.54 05:57:02 INFO - PROCESS | 20249 | Values: 1346.1 1502.8 1529.5 1635.2 1770.5 7845.4 8210.2 8339.6 8512.6 8559.6 8374.3 8511.2 8513.3 8519.7 8528.7 2357.2 2385.7 2385.8 2390.0 2395.7 2307.8 2347.1 2354.1 2357.6 2357.8 2294.2 2317.5 2319.0 2366.6 2370.2 2246.6 2301.0 2308.1 2310.7 2316.8 2291.6 2301.8 2304.7 2306.1 2306.6 8338.7 8363.5 8420.0 8451.2 8481.5 4880.8 4991.1 5038.4 5064.1 5084.5 3170.2 3400.3 3417.6 3442.1 3480.0 1071.4 1104.7 1111.5 1143.3 1146.7 11254.2 11310.5 11312.7 11330.8 11344.4 4098.9 4247.3 4375.7 4430.1 4435.1 9194.7 9219.4 9238.0 9241.0 9249.2 5025.2 5032.4 5033.2 5033.3 5041.5 3967.3 4462.6 4668.9 4706.1 4726.3 3541.6 3619.3 3674.9 3696.7 3721.7 1097.4 1101.9 1109.9 1119.9 1127.6 1008.3 1012.1 1015.8 1028.9 1036.1 5953.8 6005.9 6051.5 6069.2 6176.8 455.6 467.7 477.1 477.9 479.0 4214.9 4221.3 4256.5 4297.8 4318.0 9268.6 9623.8 9638.0 9648.7 9686.7 9104.3 9306.2 9374.4 9611.9 9613.9 7649.7 7682.2 7825.2 7829.6 7851.1 5873.6 5881.9 6045.2 6105.0 6156.8 8809.3 9272.7 9303.9 9319.6 9372.0 9093.5 9370.2 9374.0 9626.5 9627.0 6260.6 6420.2 6433.6 7119.8 7315.7 7073.8 7799.4 8015.4 8140.1 8229.4 7144.0 7208.2 7240.6 7280.9 7464.9 7181.0 7239.6 7303.9 7369.1 7414.9 6576.2 6764.3 6795.1 6801.2 6801.8 5053.3 5322.7 5396.3 5412.3 5433.8 5303.2 5347.3 5356.7 5528.8 5562.4 05:57:02 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | [#4] prototype.html Cycles:180 Average:4560.39 Median:5233.42 stddev:1337.10 (25.5%) stddev-sans-first:1302.38 05:57:02 INFO - PROCESS | 20249 | Values: 317.9 325.8 328.7 331.4 333.0 5154.9 5198.4 5209.3 5211.2 5225.9 5124.3 5137.2 5139.9 5146.6 5147.8 3743.4 3824.2 3914.5 3921.9 3948.2 4041.6 4044.7 4066.8 4075.4 4080.9 4018.8 4020.6 4025.0 4026.3 4035.4 3922.6 3976.3 4013.9 4041.2 4050.9 3586.4 3586.7 3588.9 3596.8 3598.3 4994.5 4996.9 5002.9 5006.2 5007.6 4473.0 4613.2 4619.4 4624.7 4630.0 5328.3 5355.5 5358.7 5371.1 5377.2 3936.6 3943.2 3949.0 3949.8 3954.3 5409.6 5551.7 5561.6 5572.9 5590.8 3980.8 4024.6 4026.0 4027.5 4041.0 4049.0 4064.5 4069.8 4069.8 4077.5 4556.9 4559.0 4581.4 4585.5 4632.5 5162.1 5351.8 5367.2 5391.0 5396.5 1390.7 1399.7 1408.5 1411.1 1412.0 838.9 881.5 892.8 896.5 900.6 5088.0 5240.9 5247.9 5252.7 5287.6 5557.0 5558.5 5565.4 5566.3 5569.4 4949.2 5201.0 5249.3 5251.0 5269.9 2912.9 2940.5 2947.3 2949.9 2953.0 5720.5 5737.4 5761.6 5774.8 5779.3 5564.5 5688.7 5694.6 5716.3 5740.2 5724.0 5782.6 5801.7 5813.0 5816.5 5324.7 5514.4 5535.6 5550.8 5556.0 5523.0 5535.8 5536.0 5548.9 5554.4 5704.4 5715.3 5725.4 5734.3 5756.4 3969.0 4039.9 4047.1 4054.6 4063.6 5483.8 5496.2 5497.9 5498.8 5501.7 5328.0 5340.4 5348.3 5355.9 5365.7 5092.7 5359.2 5423.4 5448.5 5463.0 5375.9 5389.3 5391.7 5397.4 5405.7 5213.7 5268.6 5456.0 5456.8 5460.5 5302.0 5321.2 5330.5 5356.3 5371.0 05:57:02 INFO - PROCESS | 20249 | 05:57:02 INFO - PROCESS | 20249 | [#5] yui.html Cycles:180 Average:20566.79 Median:4762.64 stddev:29638.48 (622.3%) stddev-sans-first:29684.21 05:57:02 INFO - PROCESS | 20249 | Values: 734.3 748.2 765.4 768.5 782.6 15600.4 16173.0 16174.9 16216.8 16287.7 14977.2 15025.6 15036.3 15086.8 15086.8 656.3 790.4 802.3 804.6 805.1 795.9 796.2 799.2 799.9 801.8 875.6 876.5 878.2 878.6 879.4 693.3 855.8 869.5 870.4 870.4 608.3 624.1 629.0 630.8 632.7 14938.4 14942.1 15113.1 15116.8 15149.1 13795.8 14129.4 14277.2 14811.3 14890.5 13352.5 13387.3 13414.9 13807.4 14260.0 4474.7 4479.4 4543.0 4659.8 4743.7 81212.5 81702.2 84070.2 84077.0 84137.6 11516.8 11585.9 11595.4 11646.9 11854.7 22340.3 22499.7 22727.4 22755.2 22771.0 8825.5 8909.6 8934.9 8955.4 9049.6 4733.2 4781.6 4791.4 4798.5 4798.7 3425.3 3485.0 3498.6 3513.4 3521.5 607.5 623.6 628.8 629.0 645.5 587.5 614.4 649.9 663.9 696.3 8599.7 9160.0 9265.1 9427.3 9612.9 601.0 646.3 689.5 693.5 694.2 4197.6 4277.2 4419.6 4544.3 4565.9 72769.2 73950.6 74501.6 74743.0 137730.0 106453.5 109578.7 109676.5 112169.4 112870.4 59798.0 59993.7 84336.0 96140.0 98307.0 8692.2 8701.4 8937.7 8989.6 9024.0 54056.0 54256.7 55698.0 55776.0 56289.4 76636.0 79543.0 82129.6 84760.0 85015.0 12241.9 12302.2 12335.8 12410.8 13061.1 13231.5 13236.3 13395.5 13670.1 13672.8 12391.9 12399.6 12466.2 12630.9 12984.6 11892.6 12215.5 12506.2 12545.8 12906.0 12229.8 12460.4 12636.5 12659.7 12663.6 10300.3 10455.9 10670.8 10831.6 11011.6 8997.6 10568.6 10898.5 11010.5 11077.0 05:57:02 INFO - PROCESS | 20249 | -------- Summary: end -------- 05:57:02 INFO - PROCESS | 20249 | 05:57:03 INFO - TEST-INFO | 20249: exit 0 05:57:03 INFO - TEST-OK | dromaeo_css | took 1115371ms 05:57:03 INFO - TEST-START | kraken 05:57:03 INFO - operating with platform_type : linux_ 05:57:03 INFO - Initialising browser for kraken test... 05:57:03 INFO - TEST-INFO | started process 20556 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNWkgl3/profile http://localhost:56922/getInfo.html) 05:57:07 INFO - PROCESS | 20556 | __metrics Screen width/height:1600/1200 05:57:07 INFO - PROCESS | 20556 | colorDepth:24 05:57:07 INFO - PROCESS | 20556 | Browser inner width/height: 1024/697 05:57:07 INFO - PROCESS | 20556 | __metrics 05:57:08 INFO - PROCESS | 20556 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:57:08 INFO - PROCESS | 20556 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:08 INFO - PROCESS | 20556 | console.error: PushService: 05:57:08 INFO - PROCESS | 20556 | stateChangeProcessEnqueue: Error transitioning state 05:57:08 INFO - PROCESS | 20556 | UnknownError 05:57:09 INFO - TEST-INFO | 20556: exit 0 05:57:09 INFO - Browser initialized. 05:57:09 INFO - Running cycle 1/1 for kraken test... 05:57:09 INFO - TEST-INFO | started process 20641 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNWkgl3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:21 INFO - PROCESS | 20641 | RSS: Main: 155525120 05:57:21 INFO - PROCESS | 20641 | 05:57:23 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56922/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:24 INFO - PROCESS | 20641 | RSS: Main: 203669504 05:57:24 INFO - PROCESS | 20641 | 05:57:27 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56922/tests/kraken/driver.html?testName=audio-dft) 05:57:27 INFO - PROCESS | 20641 | RSS: Main: 195440640 05:57:27 INFO - PROCESS | 20641 | 05:57:30 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56922/tests/kraken/driver.html?testName=audio-fft) 05:57:31 INFO - PROCESS | 20641 | RSS: Main: 275775488 05:57:31 INFO - PROCESS | 20641 | 05:57:33 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56922/tests/kraken/driver.html?testName=audio-oscillator) 05:57:34 INFO - PROCESS | 20641 | RSS: Main: 186122240 05:57:34 INFO - PROCESS | 20641 | 05:57:36 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56922/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:57:36 INFO - PROCESS | 20641 | RSS: Main: 191070208 05:57:36 INFO - PROCESS | 20641 | 05:57:40 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56922/tests/kraken/driver.html?testName=imaging-darkroom) 05:57:40 INFO - PROCESS | 20641 | RSS: Main: 225591296 05:57:40 INFO - PROCESS | 20641 | 05:57:44 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56922/tests/kraken/driver.html?testName=imaging-desaturate) 05:57:44 INFO - PROCESS | 20641 | RSS: Main: 226091008 05:57:44 INFO - PROCESS | 20641 | 05:57:47 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56922/tests/kraken/driver.html?testName=json-parse-financial) 05:57:48 INFO - PROCESS | 20641 | RSS: Main: 226476032 05:57:48 INFO - PROCESS | 20641 | 05:57:49 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56922/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:57:50 INFO - PROCESS | 20641 | RSS: Main: 189018112 05:57:50 INFO - PROCESS | 20641 | 05:57:51 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56922/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:57:52 INFO - PROCESS | 20641 | RSS: Main: 196829184 05:57:52 INFO - PROCESS | 20641 | 05:57:54 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56922/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:57:55 INFO - PROCESS | 20641 | RSS: Main: 193544192 05:57:55 INFO - PROCESS | 20641 | 05:57:57 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56922/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:57:58 INFO - PROCESS | 20641 | RSS: Main: 187166720 05:57:58 INFO - PROCESS | 20641 | 05:58:01 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56922/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:58:01 INFO - PROCESS | 20641 | RSS: Main: 192417792 05:58:01 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | Cycle 1(1): loaded http://localhost:56922/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56922/tests/kraken/driver.html?testName=ai-astar) 05:58:03 INFO - PROCESS | 20641 | RSS: Main: 243052544 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | __start_tp_report 05:58:03 INFO - PROCESS | 20641 | _x_x_mozilla_page_load 05:58:03 INFO - PROCESS | 20641 | _x_x_mozilla_page_load_details 05:58:03 INFO - PROCESS | 20641 | |i|pagename|runs| 05:58:03 INFO - PROCESS | 20641 | |0;ai-astar;105;112;111;111;107;114;112;113;113;111 05:58:03 INFO - PROCESS | 20641 | |1;audio-beat-detection;152;117;123;169;118;125;150;118;174;117 05:58:03 INFO - PROCESS | 20641 | |2;audio-dft;161;162;161;161;161;161;161;161;162;161 05:58:03 INFO - PROCESS | 20641 | |3;audio-fft;74;74;77;110;76;77;75;76;100;76 05:58:03 INFO - PROCESS | 20641 | |4;audio-oscillator;85;86;122;86;86;84;121;84;86;126 05:58:03 INFO - PROCESS | 20641 | |5;imaging-gaussian-blur;117;116;117;117;118;118;118;118;118;120 05:58:03 INFO - PROCESS | 20641 | |6;imaging-darkroom;146;146;147;146;147;147;148;147;147;146 05:58:03 INFO - PROCESS | 20641 | |7;imaging-desaturate;96;96;95;95;95;95;96;97;95;95 05:58:03 INFO - PROCESS | 20641 | |8;json-parse-financial;52;51;52;51;51;53;50;51;53;51 05:58:03 INFO - PROCESS | 20641 | |9;json-stringify-tinderbox;61;63;62;62;63;61;62;63;62;62 05:58:03 INFO - PROCESS | 20641 | |10;stanford-crypto-aes;80;80;79;83;79;77;79;87;84;79 05:58:03 INFO - PROCESS | 20641 | |11;stanford-crypto-ccm;126;152;122;125;123;123;123;132;146;125 05:58:03 INFO - PROCESS | 20641 | |12;stanford-crypto-pbkdf2;165;166;166;165;168;160;167;170;167;162 05:58:03 INFO - PROCESS | 20641 | |13;stanford-crypto-sha256-iterative;67;65;65;65;66;65;64;63;65;63 05:58:03 INFO - PROCESS | 20641 | __end_tp_report 05:58:03 INFO - PROCESS | 20641 | __start_cc_report 05:58:03 INFO - PROCESS | 20641 | _x_x_mozilla_cycle_collect,1644 05:58:03 INFO - PROCESS | 20641 | __end_cc_report 05:58:03 INFO - PROCESS | 20641 | __startTimestamp1471525083703__endTimestamp 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | ------- Summary: start ------- 05:58:03 INFO - PROCESS | 20641 | Number of tests: 14 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.81 (2.5%) stddev-sans-first:2.01 05:58:03 INFO - PROCESS | 20641 | Values: 105.0 112.0 111.0 111.0 107.0 114.0 112.0 113.0 113.0 111.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#1] audio-beat-detection Cycles:10 Average:136.30 Median:137.50 stddev:22.72 (16.5%) stddev-sans-first:23.37 05:58:03 INFO - PROCESS | 20641 | Values: 152.0 117.0 123.0 169.0 118.0 125.0 150.0 118.0 174.0 117.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 05:58:03 INFO - PROCESS | 20641 | Values: 161.0 162.0 161.0 161.0 161.0 161.0 161.0 161.0 162.0 161.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.00 stddev:12.65 (16.6%) stddev-sans-first:13.12 05:58:03 INFO - PROCESS | 20641 | Values: 74.0 74.0 77.0 110.0 76.0 77.0 75.0 76.0 100.0 76.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:85.50 stddev:18.28 (21.4%) stddev-sans-first:18.90 05:58:03 INFO - PROCESS | 20641 | Values: 85.0 86.0 122.0 86.0 86.0 84.0 121.0 84.0 86.0 126.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:1.06 (0.9%) stddev-sans-first:1.09 05:58:03 INFO - PROCESS | 20641 | Values: 117.0 116.0 117.0 117.0 118.0 118.0 118.0 118.0 118.0 120.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 05:58:03 INFO - PROCESS | 20641 | Values: 146.0 146.0 147.0 146.0 147.0 147.0 148.0 147.0 147.0 146.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 05:58:03 INFO - PROCESS | 20641 | Values: 96.0 96.0 95.0 95.0 95.0 95.0 96.0 97.0 95.0 95.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:0.97 (1.9%) stddev-sans-first:1.01 05:58:03 INFO - PROCESS | 20641 | Values: 52.0 51.0 52.0 51.0 51.0 53.0 50.0 51.0 53.0 51.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 05:58:03 INFO - PROCESS | 20641 | Values: 61.0 63.0 62.0 62.0 63.0 61.0 62.0 63.0 62.0 62.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.00 stddev:3.02 (3.8%) stddev-sans-first:3.19 05:58:03 INFO - PROCESS | 20641 | Values: 80.0 80.0 79.0 83.0 79.0 77.0 79.0 87.0 84.0 79.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:125.50 stddev:10.65 (8.5%) stddev-sans-first:11.21 05:58:03 INFO - PROCESS | 20641 | Values: 126.0 152.0 122.0 125.0 123.0 123.0 123.0 132.0 146.0 125.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:166.50 stddev:2.88 (1.7%) stddev-sans-first:3.04 05:58:03 INFO - PROCESS | 20641 | Values: 165.0 166.0 166.0 165.0 168.0 160.0 167.0 170.0 167.0 162.0 05:58:03 INFO - PROCESS | 20641 | 05:58:03 INFO - PROCESS | 20641 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.23 (1.9%) stddev-sans-first:1.01 05:58:03 INFO - PROCESS | 20641 | Values: 67.0 65.0 65.0 65.0 66.0 65.0 64.0 63.0 65.0 63.0 05:58:03 INFO - PROCESS | 20641 | -------- Summary: end -------- 05:58:03 INFO - PROCESS | 20641 | 05:58:04 INFO - TEST-INFO | 20641: exit 0 05:58:04 INFO - TEST-OK | kraken | took 61126ms 05:58:04 INFO - SUITE-END | took 1176s 05:58:04 INFO - Completed test suite (00:19:36) 05:58:04 INFO - javascript benchmark 05:58:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2985.492466280732, 2993.805402648826, 3008.563758255053, 3018.426498965195, 3021.471242532556, 5071.84784456466, 5125.154349532332, 5177.326947496825, 5316.707581083039, 5358.732063396829, 5206.323177986866, 5214.791408343671, 5232.973835130829, 5276.630635855492, 5309.389420216677, 4656.5110663380365, 4704.458987216462, 4752.738599377033, 4778.068276686047, 4914.1400254955415, 4867.269909513584, 4879.8780030499165, 4904.975475122619, 4906.607471402297, 4915.926261106087, 4647.976760116211, 4750.382450281476, 4812.903741925177, 4816.5068828663225, 4862.124817532843, 4756.143894099061, 4756.881077973078, 4762.333273341735, 4772.42730872294, 4860.659753817235, 4772.76136193189, 4799.9760001199775, 4801.399825021872, 4819.638527110481, 4841.612670986347, 5152.871178220537, 5212.765425555821, 5251.86870328241, 5276.182191760283, 5281.419043905167, 5686.488216060533, 5737.7131143443075, 5739.311282646109, 5753.137029445575, 5756.510696590794, 5789.710514474302, 5791.739371728282, 5798.217240672498, 5805.651660900359, 5807.448292412214, 5165.457626949154, 5318.335208098988, 5350.143976963706, 5374.086405311028, 5455.509004189642, 5849.824505264849, 5901.498372638288, 5909.763609455659, 5910.793122240787, 5910.793122240787, 5682.346530149086, 5688.345840228427, 5691.516221121168, 5693.772249110072, 5699.145128230715, 5493.340799104133, 5599.888002239933, 5633.408492108351, 5655.236543066716, 5720.8283751487525, 5648.774049037993, 5660.801871934463, 5661.179129026268, 5690.573207009491, 5696.287964004499, 5664.971675141598, 5697.03150464422, 5700.116481945222, 5702.059160238514, 5711.371749107595, 4256.212600668886, 4332.718373305725, 4338.305871060616, 4380.474343078851, 4383.7588932609015, 3836.635519625631, 3915.530136383595, 3920.0591857954455, 3934.2328245991757, 3954.0905591714068, 5398.676079435247, 5402.702851343135, 5404.162354835007, 5437.429069947678, 5453.972730136324, 5741.081426971665, 5741.913871291934, 5748.109043098243, 5753.395893431213, 5754.56840736938, 5192.7403629818355, 5341.198820176927, 5380.704061276589, 5385.75764090609, 5391.083515802323, 4933.901321973541, 4973.403191617029, 4976.726280054631, 4991.126552853236, 5004.324416203766, 5991.161237426763, 5991.88016239664, 5995.160677505153, 6006.819795406145, 6007.038873780174, 5515.09001014828, 5634.0, 5744.109663002241, 5751.482366587027, 5752.913706294493, 5950.434708702666, 5953.077273022688, 5955.493783028318, 5992.67040312787, 6002.69986500682, 5581.218629391969, 5582.49757521825, 5585.720713964367, 5589.972050139723, 5592.804251851166, 5486.1222204447095, 5650.57620678459, 5664.207011018543, 5675.233843431249, 5688.459596338341, 5633.802816901389, 5646.407127251579, 5655.462731040545, 5684.8578785529035, 5709.058005429058, 5556.472135147154, 5616.5787565931905, 5620.690862002465, 5626.1560765883805, 5629.211910332639, 5277.182036784305, 5497.670139791625, 5498.752556134904, 5529.198266251291, 5554.91667624994, 5677.489025987681, 5695.345035320991, 5722.599418040697, 5726.484616384545, 5743.3395159557185, 5493.450654934474, 5507.173923911285, 5511.559075273888, 5565.6104072714525, 5566.777328906798], "value": 5251.355940943195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.3216435017554, 606.8772770374804, 609.3388673289481, 616.3959319866531, 638.109836777695, 32171.356572868557, 34868.12829679256, 35358.0, 35370.64629353706, 47286.29070563944, 46596.0, 47030.29454558184, 47090.0, 47210.52789472104, 47234.52765472344, 8995.190432861042, 9151.222146117589, 9157.31320150988, 9164.908350916472, 9175.311851611123, 8911.420757650732, 9016.0, 9026.0, 9029.729108126767, 9036.051214622468, 8921.197092261695, 9067.909320906805, 9079.773005674879, 9094.727158185233, 9096.27229821616, 9013.504257265848, 9017.909820901772, 9069.18377346042, 9070.909290907106, 9071.59177836999, 8896.110388961051, 8989.640414383417, 8994.640214391417, 8998.86501702475, 9003.954980225057, 43777.0, 44079.33880991788, 44112.3383149253, 44215.0, 44230.0, 3818.4081467372603, 3869.361555343365, 4068.30838757409, 4202.285611446062, 4246.0, 32359.191020224447, 32510.674893251, 32511.6748832511, 33032.50451243233, 33202.50196247058, 4281.272183728774, 4330.588594083557, 4366.781660916941, 4435.955640443619, 4471.217536931024, 349417.0, 353040.0, 353086.0, 354084.0, 354095.0, 58104.4189558099, 58427.4157258422, 58462.23066539968, 58665.41334586685, 58703.70648146732, 36816.0, 37578.43632345517, 37579.62420375816, 61657.0, 61957.0, 15907.920460397623, 16316.265768040466, 16341.0, 16346.509604711877, 16364.263608137424, 12080.939595301967, 12195.268283902817, 12207.328596927255, 12209.450574724158, 12220.633380998584, 19407.010764011324, 19560.706589401172, 19596.53026023054, 19600.941901100465, 19654.803451965585, 7154.177269614061, 7230.313120253568, 7354.852902941911, 7377.225391333833, 7399.778006659809, 965.9227261819037, 1033.6020632056711, 1058.0688993685508, 1119.361963680694, 1124.814405623063, 33524.66475335264, 33653.831730841186, 33691.66308336934, 33762.49356259658, 33881.0, 1119.3899324867966, 1127.9266847654876, 1135.3415019288793, 1146.48408216303, 1215.2222577550374, 17413.216405261795, 17499.82500174982, 17577.0, 17695.646087078185, 17820.19809108593, 546960.0, 553439.0, 562115.0, 562256.0, 565505.0, 57652.0, 58015.709921450965, 58485.70757146187, 59095.0, 59136.0, 1232.8890399864056, 1322.9404676789568, 1335.7862741961435, 1361.9319034048258, 1371.1841454334656, 30867.0, 34051.4892276621, 34151.0, 34354.312913742084, 34527.79089124595, 50450.49549504458, 50747.238791418895, 50928.0, 50951.235731464796, 51113.48886511087, 427464.0, 428682.0, 429076.0, 429515.0, 429968.0, 24826.37934051591, 27803.58294625623, 29225.269368265963, 29393.971211007512, 29647.0, 43452.782736087385, 47906.85583450752, 47920.0, 48561.757191213816, 48946.021079578924, 40515.78452646425, 40744.59255407408, 41449.79275103605, 42582.36126458044, 43319.56680433155, 40375.3943690839, 40976.0, 41251.793741031106, 41666.0, 42911.0, 40826.59173408228, 41468.37797432981, 42925.78537107295, 42969.0, 43017.3547396783, 32594.0, 36831.263374732895, 37034.44448333331, 37189.282040526996, 37683.43474847934, 28490.287742806595, 28519.429611407242, 28574.57138142888, 28588.857055714587, 28975.7102428973], "value": 20236.862140604204, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.93045286621975, 381.7823840410965, 386.9748466349687, 402.3622558245181, 411.3151602581702, 17774.200160992754, 19314.90342548287, 19439.41681749546, 19480.31818886341, 19578.0, 18934.90532547335, 19184.904075479604, 19220.327288544868, 19444.125014374353, 19596.41210763676, 5557.166425036236, 5696.829095127143, 5786.508146807527, 5921.674307913063, 5926.851828704295, 5872.324682661506, 5880.382559831199, 5893.55798315126, 5896.027155519334, 5901.970490147543, 5541.667499950016, 5762.596618236725, 5807.41925807418, 5893.734781934823, 5902.557308201902, 5962.105684147369, 5991.281046274431, 5999.490043346299, 6000.6099603525845, 6004.12940123684, 5664.915026274609, 5805.36141024487, 5846.473817356458, 5849.093390524476, 5858.297004359461, 13866.098703584235, 14127.505537306248, 18730.81269187304, 23641.527169456516, 23821.880890595436, 2311.722593288816, 2435.8294919355653, 2482.726900040994, 2631.6841978962457, 2906.418716256736, 9940.701778946643, 9983.301168918179, 10075.143612792921, 10586.258961872672, 10732.463376831127, 1838.5771272607242, 1873.9505876709086, 1886.207792727058, 1905.7617797775279, 1947.47418197086, 73432.0, 82294.17705822925, 83347.0, 84830.57584712037, 85842.5707871463, 11185.440727963569, 11199.272047317061, 11264.66206013821, 11321.660350189508, 11504.25222360544, 15782.13198274083, 15878.68242635164, 15922.76115858263, 15948.441804536786, 15950.122743249001, 7695.345895598824, 7711.460197786213, 7918.28735413804, 7938.087119981161, 8017.518948863087, 7055.506114571931, 7085.681144348517, 7161.248068952789, 7317.707291708379, 7327.963360183272, 4817.9518204817505, 4842.539958703917, 4843.1524483216, 4910.558049775537, 4971.105201063843, 1967.537628657293, 1988.8707234029741, 1992.1732481020215, 2026.706127611488, 2050.4361300642445, 957.9521023948774, 978.7308490165261, 985.4727720669407, 1039.9652345915863, 1047.3297089862494, 18953.715694264596, 19578.216871325272, 19883.701744473845, 19905.303314383927, 20233.898830505754, 977.7946631207384, 1013.6705570689408, 1015.9949200253951, 1021.9029192226726, 1038.0605551975323, 10267.613872127167, 10483.528241229164, 10736.677899663022, 11180.44097795107, 11524.135689823297, 485715.0, 492615.0, 507899.0, 512962.0, 517110.0, 87113.82215444665, 92668.07331926594, 95312.52343738236, 95917.5204123975, 97601.51199243958, 1009.4094954451728, 1014.4015031131668, 1050.0706874416044, 1050.7110544600296, 1054.478033373485, 17510.212040458464, 17893.821061789735, 18369.724454133466, 18482.260709571467, 18803.0, 40855.0, 41350.0, 41812.790936046345, 42593.0, 42932.35601465918, 52682.47317526929, 58594.707026464595, 60086.0, 61414.0, 62149.0, 5551.833444996657, 5874.0, 6805.013273075476, 7731.806704832424, 8339.291160251447, 11264.493097810455, 13126.212427254395, 13160.341982900625, 13202.801957970447, 13875.79186312186, 11727.47226374798, 11742.58900938463, 11865.822012669989, 11932.76134477323, 12107.515699371927, 11424.600138995096, 11560.537578496765, 11713.1800773945, 11768.764624707286, 12213.572524961584, 11725.179237453638, 11772.17594768358, 11873.346965916957, 12008.819867701817, 12323.198992065487, 7246.565206087652, 7339.486235963432, 8060.556669383241, 8362.498250105013, 8774.122587741174, 10751.24741268135, 11709.355985420883, 11727.068232941765, 11767.941160294486, 11784.58753943608], "value": 9195.007936327624, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.1115663661974, 1502.819661640603, 1529.4876216467483, 1635.19875261122, 1770.4865588979196, 7845.411594130442, 8210.178982101786, 8339.583020848964, 8512.616932238048, 8559.614817333219, 8374.330053595713, 8511.233988940994, 8513.276371508413, 8519.701810436636, 8528.74413767588, 2357.233898982829, 2385.6779334789753, 2385.8329916905823, 2389.9761002390014, 2395.700537432821, 2307.7576854430295, 2347.0611755297846, 2354.0701422937927, 2357.57563638545, 2357.8467399619058, 2294.2314324701247, 2317.4901521665292, 2319.037599396257, 2366.5740166769974, 2370.1941339944415, 2246.5619204255013, 2300.964565945323, 2308.0767692922796, 2310.745817960023, 2316.81465482761, 2291.5875142474347, 2301.838871279011, 2304.6632952887453, 2306.054517647773, 2306.573283942476, 8338.749837504885, 8363.498190108612, 8420.0, 8451.197136272045, 8481.533515656636, 4880.780364883553, 4991.07665081961, 5038.420581633122, 5064.088464076464, 5084.51697088776, 3170.1599076244815, 3400.30293789775, 3417.6411476795074, 3442.0706409269355, 3480.0, 1071.3893080943865, 1104.7017305327597, 1111.5109351885226, 1143.2911594811187, 1146.7305183282006, 11254.155938304579, 11310.547578096866, 11312.660620181408, 11330.830037549444, 11344.376059316817, 4098.877033688935, 4247.299195632748, 4375.693701440932, 4430.113977204572, 4435.1351486459835, 9194.724158275263, 9219.400738952081, 9238.030006849318, 9241.0, 9249.16757491829, 5025.195968644999, 5032.370953630796, 5033.194688849767, 5033.270175824485, 5041.521055499722, 3967.305721498726, 4462.642988560908, 4668.883277918011, 4706.129366067288, 4726.26742854858, 3541.592716837545, 3619.3485172668647, 3674.889753307405, 3696.722745794076, 3721.7208709346905, 1097.4403054442132, 1101.862267216598, 1109.877913429522, 1119.921605487608, 1127.577158565538, 1008.3042700536606, 1012.094175712733, 1015.8120747661707, 1028.8508166315992, 1036.0727149201423, 5953.76184952597, 6005.879882402328, 6051.546134039964, 6069.15031895535, 6176.752929882844, 455.6218338778806, 467.72078365669864, 477.1030462730045, 477.8590315856761, 478.9976050119727, 4214.894627634272, 4221.261279276115, 4256.510501292391, 4297.828086876552, 4317.956820431818, 9268.629254829732, 9623.807523849484, 9638.036196380306, 9648.662296819577, 9686.740723706014, 9104.271658267322, 9306.162445379949, 9374.437533747998, 9611.855822162674, 9613.855792163124, 7649.694012239559, 7682.231776822273, 7825.217478252129, 7829.569373684502, 7851.097123830833, 5873.647581145145, 5881.852953676193, 6045.183900173421, 6104.969475152596, 6156.815295541141, 8809.339299552432, 9272.72181834546, 9303.906960930575, 9319.627214911328, 9372.0, 9093.454392736458, 9370.203532699798, 9373.953130234579, 9626.47054412014, 9627.0, 6260.624362538262, 6420.229572451336, 6433.646149461637, 7119.82200444993, 7315.743948961761, 7073.787786366417, 7799.376049916106, 8015.358771298399, 8140.063892652421, 8229.382796290422, 7144.0, 7208.171060328129, 7240.565566066053, 7280.854382912206, 7464.925350746423, 7180.964095179491, 7239.565626062453, 7303.926960730325, 7369.078865141857, 7414.888776668462, 6576.177977752781, 6764.323567643196, 6795.116634837439, 6801.18385793708, 6801.829954251183, 5053.26727624485, 5322.654027488201, 5396.325459317585, 5412.323459567554, 5433.782648694008, 5303.15149576066, 5347.251384806053, 5356.705381204071, 5528.751206195811, 5562.415946325577], "value": 4379.319949578007, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.92858068309795, 325.84848045658777, 328.6607076164629, 331.3952037531504, 332.9783564068337, 5154.922676159856, 5198.376194856618, 5209.348831396075, 5211.24436956642, 5225.921611175835, 5124.308218390516, 5137.203733421316, 5139.948600513993, 5146.639735218536, 5147.794088236466, 3743.438484227374, 3824.216035712679, 3914.4911161548957, 3921.8823435296986, 3948.170884114325, 4041.616046475595, 4044.6562042226446, 4066.7966601669946, 4075.4090656854737, 4080.9183816323657, 4018.754186202272, 4020.618041286073, 4025.0339918419513, 4026.3155263605113, 4035.435039094529, 3922.5685174630767, 3976.264391087629, 4013.8961785508927, 4041.1513582147786, 4050.8784736457956, 3586.4261718125235, 3586.677199052072, 3588.8743893963588, 3596.8381422836037, 3598.298331825295, 4994.450610432849, 4996.850094497171, 5002.874928126789, 5006.173981293082, 5007.624428167902, 4473.015936493966, 4613.215753321943, 4619.422572178478, 4624.705082576884, 4629.976850115761, 5328.307320048408, 5355.544778693816, 5358.678479291255, 5371.140617501181, 5377.193420986883, 3936.626020528045, 3943.2310699413624, 3948.9802550987347, 3949.8420063197436, 3954.3079961006997, 5409.647588102975, 5551.694656793875, 5561.555075593983, 5572.944270557283, 5590.8043218487155, 3980.7810570418906, 4024.557298697141, 4026.0, 4027.5166979962005, 4040.9797951010055, 4048.959510404917, 4064.4919385076864, 4069.7558146511305, 4069.8372065117064, 4077.4903137107863, 4556.88607784808, 4558.9772051139535, 4581.358609794632, 4585.495595484494, 4632.536746325408, 5162.070827251056, 5351.759170837322, 5367.221752845816, 5390.97304513475, 5396.4603539646505, 1390.721855628858, 1399.6710772968345, 1408.4718230663502, 1411.1321537254514, 1411.985880141206, 838.8741688746737, 881.5107615273456, 892.7544925145637, 896.4531635702216, 900.5812297281665, 5087.974560127176, 5240.9213861792105, 5247.895042099213, 5252.711100889412, 5287.603429742785, 5557.0, 5558.499735023786, 5565.3599836019375, 5566.304211973504, 5569.415211402826, 4949.208126699711, 5201.011807756514, 5249.343832020997, 5250.97374513125, 5269.894602108013, 2912.85435728217, 2940.5001149804157, 2947.3368492089107, 2949.9410011799646, 2952.985235073854, 5720.542356611461, 5737.426257374313, 5761.567882408836, 5774.797882074107, 5779.33537643168, 5564.527015203672, 5688.687122208318, 5694.604821818598, 5716.28546431696, 5740.225069615632, 5723.998300297515, 5782.5663075268685, 5801.738921748616, 5812.9709351452975, 5816.47651711348, 5324.683762563297, 5514.36584792754, 5535.612507124462, 5550.836023466747, 5555.972220138873, 5522.97238513821, 5535.7785688572, 5535.972320138373, 5548.944510554843, 5554.444555544414, 5704.429557044263, 5715.342735585295, 5725.427457254241, 5734.34055083654, 5756.42435756421, 3968.980155099206, 4039.9394009090474, 4047.1192551798613, 4054.553999060042, 4063.6139566741113, 5483.75323110453, 5496.1755736639825, 5497.890042199214, 5498.752556135064, 5501.734601041703, 5328.0, 5340.439253878442, 5348.251244825649, 5355.892882142258, 5365.651232669864, 5092.668976516515, 5359.196120581944, 5423.376311724202, 5448.482394172547, 5462.9726851365485, 5375.865603359947, 5389.326334208224, 5391.7034563099405, 5397.406285308534, 5405.702686352289, 5213.713245771519, 5268.552173065333, 5456.0, 5456.79950410909, 5460.481254280837, 5302.045631786161, 5321.201819726917, 5330.520253177233, 5356.276902618097, 5370.9731451342495], "value": 4132.337146272192, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.342763226912, 748.2330611123601, 765.3647472597744, 768.5350363030367, 782.6204290918907, 15600.375984960601, 16173.0, 16174.919125404385, 16216.75674864878, 16287.674246515062, 14977.176255305982, 15025.62435939102, 15036.323365448554, 15086.773698394505, 15086.849131508681, 656.3108735827386, 790.3874497264609, 802.2579114319246, 804.6178065418909, 805.122416565943, 795.9204079592023, 796.1719811396141, 799.2007992007992, 799.8600244957138, 801.7915342011061, 875.5884734174928, 876.5179151466668, 878.165742544582, 878.5607196401799, 879.4283715584857, 693.2686016252851, 855.7860534866284, 869.482657818597, 870.3733312015371, 870.4081224767187, 608.3156448994881, 624.0919462182559, 629.015590600713, 630.8391360203165, 632.6836581709146, 14938.402463901431, 14942.103473791607, 15113.09321440717, 15116.848831511765, 15149.091054536762, 13795.793063104062, 14129.364178611988, 14277.214753188571, 14811.259437028106, 14890.478833240893, 13352.465901363934, 13387.263700496469, 13414.86585134146, 13807.378667959965, 14260.001799874013, 4474.731516109044, 4479.350494178326, 4542.977285113553, 4659.808031498401, 4743.691660042121, 81212.50299975988, 81702.18297817005, 84070.15929840745, 84077.0, 84137.57931210304, 11516.827247591293, 11585.88414115848, 11595.420228988516, 11646.883531164749, 11854.703632409086, 22340.329790106323, 22499.66250506244, 22727.431814204778, 22755.203567875044, 22771.0, 8825.514596697243, 8909.599068041955, 8934.86597701035, 8955.373123881396, 9049.592768325441, 4733.242681170997, 4781.593564547053, 4791.401074865642, 4798.520147985243, 4798.712077275374, 3425.3320602482777, 3485.041613556242, 3498.6151523332924, 3513.3675938331344, 3521.5245941798044, 607.4927435591243, 623.5884316351277, 628.7924984755019, 629.0187307799847, 645.4707140145036, 587.5152998775941, 614.4040280927493, 649.9220093588799, 663.867226554691, 696.3002182806349, 8599.699010534601, 9160.0, 9265.11981361783, 9427.292953028413, 9612.855807162898, 600.9513399118566, 646.3375040583346, 689.5311188391842, 693.5387967001988, 694.2120693013466, 4197.559256278108, 4277.165952639206, 4419.602235798793, 4544.272916333371, 4565.885852853705, 72769.16694573456, 73950.63024684842, 74501.6274918622, 74743.0, 137730.0, 106453.4677326624, 109578.72698066961, 109676.4516177414, 112169.43915280535, 112870.43564782123, 59798.0, 59993.70003149956, 84336.0, 96140.0385996145, 98307.0, 8692.174243446863, 8701.43440676354, 8937.744906198737, 8989.640414383352, 9024.0, 54056.0, 54256.72871635616, 55698.0, 55776.0, 56289.43710562842, 76636.0, 79543.0, 82129.58935205525, 84760.0, 85015.0, 12241.877581224073, 12302.200356976768, 12335.814962775386, 12410.81383779226, 13061.08572399923, 13231.470741170631, 13236.272005039815, 13395.531156409479, 13670.11144275619, 13672.794908076188, 12391.876081239072, 12399.628011159679, 12466.189697669623, 12630.87369126297, 12984.610461686165, 11892.643220703392, 12215.45030473613, 12506.249625022529, 12545.811812822998, 12906.0, 12229.75540489203, 12460.43928023223, 12636.494540218657, 12659.746805064031, 12663.620091397273, 10300.278980471296, 10455.895441045797, 10670.786584268426, 10831.620893268699, 11011.61459348919, 8997.550122493718, 10568.630097946534, 10898.455077245948, 11010.50452729645, 11077.0], "value": 7473.1340738864665, "unit": "score"}], "name": "dromaeo_css", "value": 7137.072338160755, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 112.0, 111.0, 111.0, 107.0, 114.0, 112.0, 113.0, 113.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 123.0, 169.0, 118.0, 125.0, 150.0, 118.0, 174.0, 117.0], "value": 136.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 110.0, 76.0, 77.0, 75.0, 76.0, 100.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 122.0, 86.0, 86.0, 84.0, 121.0, 84.0, 86.0, 126.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 120.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 148.0, 147.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 97.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 51.0, 51.0, 53.0, 50.0, 51.0, 53.0, 51.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 62.0, 63.0, 61.0, 62.0, 63.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 83.0, 79.0, 77.0, 79.0, 87.0, 84.0, 79.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 152.0, 122.0, 125.0, 123.0, 123.0, 123.0, 132.0, 146.0, 125.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 166.0, 165.0, 168.0, 160.0, 167.0, 170.0, 167.0, 162.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 65.0, 66.0, 65.0, 64.0, 63.0, 65.0, 63.0], "value": 64.8, "unit": "score"}], "name": "kraken", "value": 1500.8, "alertThreshold": 2.0}]} 05:58:04 INFO - Return code: 0 05:58:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:58:05 INFO - # TBPL SUCCESS # 05:58:05 INFO - Running post-action listener: _resource_record_post_action 05:58:05 INFO - [mozharness: 2016-08-18 12:58:05.017269Z] Finished run-tests step (success) 05:58:05 INFO - Running post-run listener: _resource_record_post_run 05:58:05 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 411918336; Read time: 0; Write time: 402460 05:58:05 INFO - TinderboxPrint: CPU usage
13.0% 05:58:05 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:58:05 INFO - TinderboxPrint: I/O write bytes / time
411,918,336 / 402,460 05:58:05 INFO - TinderboxPrint: CPU idle
8,284.5 (86.9%) 05:58:05 INFO - TinderboxPrint: CPU user
1,194.5 (12.5%) 05:58:05 INFO - TinderboxPrint: Swap in / out
0 / 0 05:58:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 122843136; Read time: 0; Write time: 150612 05:58:05 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 288718848; Read time: 0; Write time: 251844 05:58:05 INFO - Running post-run listener: _upload_blobber_files 05:58:05 INFO - Blob upload gear active. 05:58:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:58:05 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 05:58:05 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'] 05:58:05 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 05:58:06 INFO - (blobuploader) - INFO - Open directory for files ... 05:58:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:58:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:58:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:17 INFO - (blobuploader) - INFO - Done attempting. 05:58:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:58:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:58:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:18 INFO - (blobuploader) - INFO - Done attempting. 05:58:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:58:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:58:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:19 INFO - (blobuploader) - INFO - Done attempting. 05:58:19 INFO - (blobuploader) - INFO - Iteration through files over. 05:58:19 INFO - Return code: 0 05:58:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:58:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:58:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee9ab091e08655dd6f333fe9957f41de917aa38d76567f63798ca01fa991b31d7fc5fa176ef6d274f2c2a1f8904fbe11852175bca6ca5d8b0fb826e3616f2a9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e47f544e0afbab5876452d0a3e7f55c78426167f2d2f31260f633c7a3ec2c782e09ebdc902bd3e806c39ab93ca74c1fdf580d2bcaf656e87cfaf39c1ead1f98", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963d5b2f3701414dce4cff39dd2780985270150efe10b4633c087b13e981f599ffcfbbb5bbaeb6d5ee45992302551e2cf5040f303f33567f7b490ea1fa005b0c"} 05:58:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:58:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:58:19 INFO - Contents: 05:58:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee9ab091e08655dd6f333fe9957f41de917aa38d76567f63798ca01fa991b31d7fc5fa176ef6d274f2c2a1f8904fbe11852175bca6ca5d8b0fb826e3616f2a9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e47f544e0afbab5876452d0a3e7f55c78426167f2d2f31260f633c7a3ec2c782e09ebdc902bd3e806c39ab93ca74c1fdf580d2bcaf656e87cfaf39c1ead1f98", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963d5b2f3701414dce4cff39dd2780985270150efe10b4633c087b13e981f599ffcfbbb5bbaeb6d5ee45992302551e2cf5040f303f33567f7b490ea1fa005b0c"} 05:58:19 INFO - Running post-run listener: copy_logs_to_upload_dir 05:58:19 INFO - Copying logs to upload dir... 05:58:19 INFO - mkdir: /builds/slave/test/build/upload/logs 05:58:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.817055 ========= master_lag: 1.60 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-18 05:58:19.797017) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 05:58:19.801060) ========= 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=52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187 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/ee9ab091e08655dd6f333fe9957f41de917aa38d76567f63798ca01fa991b31d7fc5fa176ef6d274f2c2a1f8904fbe11852175bca6ca5d8b0fb826e3616f2a9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e47f544e0afbab5876452d0a3e7f55c78426167f2d2f31260f633c7a3ec2c782e09ebdc902bd3e806c39ab93ca74c1fdf580d2bcaf656e87cfaf39c1ead1f98", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963d5b2f3701414dce4cff39dd2780985270150efe10b4633c087b13e981f599ffcfbbb5bbaeb6d5ee45992302551e2cf5040f303f33567f7b490ea1fa005b0c"} build_url:https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011261 build_url: 'https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/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/ee9ab091e08655dd6f333fe9957f41de917aa38d76567f63798ca01fa991b31d7fc5fa176ef6d274f2c2a1f8904fbe11852175bca6ca5d8b0fb826e3616f2a9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e47f544e0afbab5876452d0a3e7f55c78426167f2d2f31260f633c7a3ec2c782e09ebdc902bd3e806c39ab93ca74c1fdf580d2bcaf656e87cfaf39c1ead1f98", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963d5b2f3701414dce4cff39dd2780985270150efe10b4633c087b13e981f599ffcfbbb5bbaeb6d5ee45992302551e2cf5040f303f33567f7b490ea1fa005b0c"}' ========= master_lag: 0.59 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 05:58:20.406665) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:58:20.407031) ========= 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=52faae308a556c440401f8e5000001e8-1471523852.9899-1526246187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.39 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:58:20.805734) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 05:58:20.806077) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 05:58:20.806509) ========= ========= Total master_lag: 2.64 =========