builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1471523848.01 results: success (0) buildid: 20160817193029 builduid: a9d60d6febdf42ec92e8e6940018cd28 revision: 97a52326b06a07930216ebefa5af333271578904 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:28.006518) ========= 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:28.006985) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:28.007264) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:28.023813) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:28.024133) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 05:37:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2016-08-18 05:37:29 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126155 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:28.167640) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:28.167962) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016685 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:28.221054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 05:37:28.221416) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 05:37:30,126 truncating revision to first 12 chars 2016-08-18 05:37:30,126 Setting DEBUG logging. 2016-08-18 05:37:30,126 attempt 1/10 2016-08-18 05:37:30,126 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:31,259 unpacking tar archive at: mozilla-central-97a52326b06a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.462485 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 05:37:29.697097) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:29.697409) ========= script_repo_revision: 97a52326b06a07930216ebefa5af333271578904 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:29.697828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:29.698100) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:37:29.710018) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-18 05:37:29.710323) ========= /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=a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:37:31 INFO - MultiFileLogger online at 20160818 05:37:31 in /builds/slave/test 05:37:31 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:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:37:31 INFO - {'append_to_log': False, 05:37:31 INFO - 'base_work_dir': '/builds/slave/test', 05:37:31 INFO - 'blob_upload_branch': 'Firefox', 05:37:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:37:31 INFO - 'branch': 'Firefox', 05:37:31 INFO - 'buildbot_json_path': 'buildprops.json', 05:37:31 INFO - 'config_files': ('talos/linux_config.py',), 05:37:31 INFO - 'default_actions': ('clobber', 05:37:31 INFO - 'read-buildbot-config', 05:37:31 INFO - 'download-and-extract', 05:37:31 INFO - 'populate-webroot', 05:37:31 INFO - 'create-virtualenv', 05:37:31 INFO - 'install', 05:37:31 INFO - 'run-tests'), 05:37:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:37:31 INFO - 'download_minidump_stackwalk': True, 05:37:31 INFO - 'download_symbols': 'ondemand', 05:37:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:37:31 INFO - 'tooltool.py': '/tools/tooltool.py', 05:37:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:37:31 INFO - '/tools/misc-python/virtualenv.py')}, 05:37:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:37:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:37:31 INFO - 'installer_path': 'installer.exe', 05:37:31 INFO - 'log_level': 'info', 05:37:31 INFO - 'log_name': 'talos', 05:37:31 INFO - 'log_to_console': True, 05:37:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:37:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:37:31 INFO - 'opt_config_files': (), 05:37:31 INFO - 'pip_index': False, 05:37:31 INFO - 'sps_profile': False, 05:37:31 INFO - 'sps_profile_interval': 0, 05:37:31 INFO - 'suite': 'dromaeojs', 05:37:31 INFO - 'system_bits': '32', 05:37:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:37:31 INFO - 'title': 'talos-linux64-ix-064', 05:37:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:37:31 INFO - 'use_talos_json': True, 05:37:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:37:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:37:31 INFO - 'work_dir': 'build'} 05:37:31 INFO - [mozharness: 2016-08-18 12:37:31.706335Z] Running clobber step. 05:37:31 INFO - Running pre-action listener: _resource_record_pre_action 05:37:31 INFO - Running main action method: clobber 05:37:31 INFO - rmtree: /builds/slave/test/build 05:37:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:37:32 INFO - Running post-action listener: _resource_record_post_action 05:37:32 INFO - [mozharness: 2016-08-18 12:37:32.679104Z] Finished clobber step (success) 05:37:32 INFO - [mozharness: 2016-08-18 12:37:32.679205Z] Running read-buildbot-config step. 05:37:32 INFO - Running pre-action listener: _resource_record_pre_action 05:37:32 INFO - Running main action method: read_buildbot_config 05:37:32 INFO - Using buildbot properties: 05:37:32 INFO - { 05:37:32 INFO - "project": "", 05:37:32 INFO - "product": "firefox", 05:37:32 INFO - "script_repo_revision": "production", 05:37:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:37:32 INFO - "repository": "", 05:37:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:37:32 INFO - "buildid": "20160817193029", 05:37:32 INFO - "pgo_build": "True", 05:37:32 INFO - "basedir": "/builds/slave/test", 05:37:32 INFO - "buildnumber": 832, 05:37:32 INFO - "slavename": "talos-linux64-ix-064", 05:37:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:37:32 INFO - "platform": "ubuntu64_hw", 05:37:32 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:37:32 INFO - "branch": "mozilla-central", 05:37:32 INFO - "repo_path": "mozilla-central", 05:37:32 INFO - "revision": "97a52326b06a07930216ebefa5af333271578904", 05:37:32 INFO - "stage_platform": "linux64-pgo", 05:37:32 INFO - "builduid": "a9d60d6febdf42ec92e8e6940018cd28", 05:37:32 INFO - "slavebuilddir": "test" 05:37:32 INFO - } 05:37:32 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:32 INFO - Running post-action listener: _resource_record_post_action 05:37:32 INFO - [mozharness: 2016-08-18 12:37:32.681642Z] Finished read-buildbot-config step (success) 05:37:32 INFO - [mozharness: 2016-08-18 12:37:32.681730Z] Running download-and-extract step. 05:37:32 INFO - Running pre-action listener: _resource_record_pre_action 05:37:32 INFO - Running main action method: download_and_extract 05:37:32 INFO - mkdir: /builds/slave/test/build/tests 05:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:32 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:32 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:32 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:33 INFO - Downloaded 1361 bytes. 05:37:33 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:37:33 INFO - Using the following test package requirements: 05:37:33 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:37:33 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:37:33 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:37:33 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:33 INFO - u'jsshell-linux-x86_64.zip'], 05:37:33 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:37:33 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:37:33 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:37:33 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:37:33 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:37:33 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:33 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:37:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:37:33 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:33 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:33 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:33 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:34 INFO - Downloaded 21907829 bytes. 05:37:34 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:38 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:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:38 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:38 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:38 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:39 INFO - Downloaded 21907829 bytes. 05:37:39 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:43 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:43 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:43 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:43 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:44 INFO - Downloaded 14413981 bytes. 05:37:44 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:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:44 INFO - trying https://queue.taskcluster.net/v1/task/VFD6jNPgSjOJan7lGhS3XQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:37:44 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:44 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:45 INFO - Downloaded 59599188 bytes. 05:37:45 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:45 INFO - mkdir: /builds/slave/test/properties 05:37:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:37:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:37:45 INFO - Contents: 05:37:45 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:46 INFO - Running post-action listener: _resource_record_post_action 05:37:46 INFO - Running post-action listener: set_extra_try_arguments 05:37:46 INFO - [mozharness: 2016-08-18 12:37:46.609470Z] Finished download-and-extract step (success) 05:37:46 INFO - [mozharness: 2016-08-18 12:37:46.609767Z] Running populate-webroot step. 05:37:46 INFO - Running pre-action listener: _resource_record_pre_action 05:37:46 INFO - Running main action method: populate_webroot 05:37:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:37:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:37:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:37:46 INFO - '--tppagecycles', 05:37:46 INFO - '7'], 05:37:46 INFO - 'tests': ['tsvgm']}}, 05:37:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:37:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:37:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:37:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:37:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:46 INFO - 'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:46 INFO - 'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['damp', 'tps']}, 05:37:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:46 INFO - 'tests': ['damp', 'tps']}, 05:37:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['dromaeo_dom']}, 05:37:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['basic_compositor_video']}, 05:37:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:37:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['a11yr', 05:37:46 INFO - 'ts_paint', 05:37:46 INFO - 'tpaint', 05:37:46 INFO - 'sessionrestore', 05:37:46 INFO - 'sessionrestore_no_auto_restore', 05:37:46 INFO - 'tabpaint']}, 05:37:46 INFO - 'other-e10s': {'tests': ['a11yr', 05:37:46 INFO - 'ts_paint', 05:37:46 INFO - 'tpaint', 05:37:46 INFO - 'sessionrestore', 05:37:46 INFO - 'sessionrestore_no_auto_restore', 05:37:46 INFO - 'tabpaint']}, 05:37:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:37:46 INFO - 'ts_paint', 05:37:46 INFO - 'tpaint', 05:37:46 INFO - 'sessionrestore', 05:37:46 INFO - 'sessionrestore_no_auto_restore', 05:37:46 INFO - 'tabpaint']}, 05:37:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:37:46 INFO - 'ts_paint', 05:37:46 INFO - 'tpaint', 05:37:46 INFO - 'sessionrestore', 05:37:46 INFO - 'sessionrestore_no_auto_restore', 05:37:46 INFO - 'tabpaint']}, 05:37:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['a11yr', 05:37:46 INFO - 'ts_paint', 05:37:46 INFO - 'tpaint', 05:37:46 INFO - 'sessionrestore', 05:37:46 INFO - 'sessionrestore_no_auto_restore', 05:37:46 INFO - 'tabpaint']}, 05:37:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['a11yr', 05:37:46 INFO - 'ts_paint', 05:37:46 INFO - 'tpaint', 05:37:46 INFO - 'sessionrestore', 05:37:46 INFO - 'sessionrestore_no_auto_restore', 05:37:46 INFO - 'tabpaint']}, 05:37:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['tsvgx', 05:37:46 INFO - 'tsvgr_opacity', 05:37:46 INFO - 'tart', 05:37:46 INFO - 'tscrollx', 05:37:46 INFO - 'cart']}, 05:37:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:37:46 INFO - 'tsvgr_opacity', 05:37:46 INFO - 'tart', 05:37:46 INFO - 'tscrollx', 05:37:46 INFO - 'cart']}, 05:37:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:46 INFO - 'talos_options': ['--disable-e10s'], 05:37:46 INFO - 'tests': ['tp5o']}, 05:37:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:46 INFO - 'tests': ['tp5o']}, 05:37:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:46 INFO - 'talos_options': ['--disable-e10s', 05:37:46 INFO - '--xperf_path', 05:37:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:46 INFO - 'tests': ['tp5n']}, 05:37:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:46 INFO - 'talos_options': ['--xperf_path', 05:37:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:46 INFO - 'tests': ['tp5n']}}, 05:37:46 INFO - 'talos.zip': {'path': '', 05:37:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:37:46 INFO - Running post-action listener: _resource_record_post_action 05:37:46 INFO - [mozharness: 2016-08-18 12:37:46.631886Z] Finished populate-webroot step (success) 05:37:46 INFO - [mozharness: 2016-08-18 12:37:46.631980Z] Running create-virtualenv step. 05:37:46 INFO - Running pre-action listener: _resource_record_pre_action 05:37:46 INFO - Running main action method: create_virtualenv 05:37:46 INFO - Creating virtualenv /builds/slave/test/build/venv 05:37:46 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:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:37:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:46 INFO - Using real prefix '/usr' 05:37:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:37:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:37:49 INFO - Installing pip.................done. 05:37:49 INFO - Return code: 0 05:37:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:37:49 INFO - Using env: {'DISPLAY': ':0', 05:37:49 INFO - 'HOME': '/home/cltbld', 05:37:49 INFO - 'LANG': 'en_US.UTF-8', 05:37:49 INFO - 'LANGUAGE': 'en_US:en', 05:37:49 INFO - 'LOGNAME': 'cltbld', 05:37:49 INFO - 'MAIL': '/var/mail/cltbld', 05:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:49 INFO - 'MOZ_NO_REMOTE': '1', 05:37:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:49 INFO - 'NO_EM_RESTART': '1', 05:37:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:49 INFO - 'PWD': '/builds/slave/test', 05:37:49 INFO - 'SHELL': '/bin/bash', 05:37:49 INFO - 'SHLVL': '1', 05:37:49 INFO - 'TERM': 'linux', 05:37:49 INFO - 'TMOUT': '86400', 05:37:49 INFO - 'USER': 'cltbld', 05:37:49 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:49 INFO - '_': '/tools/buildbot/bin/python'} 05:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:49 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:49 INFO - Cleaning up... 05:37:49 INFO - Return code: 0 05:37:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:37:49 INFO - Using env: {'DISPLAY': ':0', 05:37:49 INFO - 'HOME': '/home/cltbld', 05:37:49 INFO - 'LANG': 'en_US.UTF-8', 05:37:49 INFO - 'LANGUAGE': 'en_US:en', 05:37:49 INFO - 'LOGNAME': 'cltbld', 05:37:49 INFO - 'MAIL': '/var/mail/cltbld', 05:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:49 INFO - 'MOZ_NO_REMOTE': '1', 05:37:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:49 INFO - 'NO_EM_RESTART': '1', 05:37:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:49 INFO - 'PWD': '/builds/slave/test', 05:37:49 INFO - 'SHELL': '/bin/bash', 05:37:49 INFO - 'SHLVL': '1', 05:37:49 INFO - 'TERM': 'linux', 05:37:49 INFO - 'TMOUT': '86400', 05:37:49 INFO - 'USER': 'cltbld', 05:37:49 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:49 INFO - '_': '/tools/buildbot/bin/python'} 05:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:49 INFO - Downloading/unpacking psutil>=3.1.1 05:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:37:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:51 INFO - Installing collected packages: psutil 05:37:51 INFO - Running setup.py install for psutil 05:37:51 INFO - building 'psutil._psutil_linux' extension 05:37:51 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:51 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:51 INFO - building 'psutil._psutil_posix' extension 05:37:51 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:51 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:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:52 INFO - Successfully installed psutil 05:37:52 INFO - Cleaning up... 05:37:52 INFO - Return code: 0 05:37:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:37:52 INFO - Using env: {'DISPLAY': ':0', 05:37:52 INFO - 'HOME': '/home/cltbld', 05:37:52 INFO - 'LANG': 'en_US.UTF-8', 05:37:52 INFO - 'LANGUAGE': 'en_US:en', 05:37:52 INFO - 'LOGNAME': 'cltbld', 05:37:52 INFO - 'MAIL': '/var/mail/cltbld', 05:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:52 INFO - 'MOZ_NO_REMOTE': '1', 05:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:52 INFO - 'NO_EM_RESTART': '1', 05:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:52 INFO - 'PWD': '/builds/slave/test', 05:37:52 INFO - 'SHELL': '/bin/bash', 05:37:52 INFO - 'SHLVL': '1', 05:37:52 INFO - 'TERM': 'linux', 05:37:52 INFO - 'TMOUT': '86400', 05:37:52 INFO - 'USER': 'cltbld', 05:37:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:52 INFO - '_': '/tools/buildbot/bin/python'} 05:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:37:54 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:54 INFO - Installing collected packages: mozsystemmonitor 05:37:54 INFO - Running setup.py install for mozsystemmonitor 05:37:54 INFO - Successfully installed mozsystemmonitor 05:37:54 INFO - Cleaning up... 05:37:54 INFO - Return code: 0 05:37:54 INFO - Installing jsonschema==2.5.1 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', '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 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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', 'jsonschema==2.5.1'] 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 jsonschema==2.5.1 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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 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 jsonschema==2.5.1 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 jsonschema-2.5.1-py2.py3-none-any.whl 05:37:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:37:56 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:56 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 - 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:56 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:56 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:56 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 functools32-3.2.3-2.tar.gz 05:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:37:56 INFO - warning: no files found matching '*.txt' 05:37:56 INFO - no previously-included directories found matching 'build' 05:37:56 INFO - no previously-included directories found matching 'dist' 05:37:56 INFO - no previously-included directories found matching '.git*' 05:37:56 INFO - Installing collected packages: jsonschema, functools32 05:37:56 INFO - Running setup.py install for functools32 05:37:56 INFO - warning: no files found matching '*.txt' 05:37:56 INFO - no previously-included directories found matching 'build' 05:37:56 INFO - no previously-included directories found matching 'dist' 05:37:56 INFO - no previously-included directories found matching '.git*' 05:37:56 INFO - Successfully installed jsonschema functools32 05:37:56 INFO - Cleaning up... 05:37:56 INFO - Return code: 0 05:37:56 INFO - Installing functools32==3.2.3-2 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', '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 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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', 'functools32==3.2.3-2'] 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 functools32==3.2.3-2 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:57 INFO - 'NO_EM_RESTART': '1', 05:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:57 INFO - 'PWD': '/builds/slave/test', 05:37:57 INFO - 'SHELL': '/bin/bash', 05:37:57 INFO - 'SHLVL': '1', 05:37:57 INFO - 'TERM': 'linux', 05:37:57 INFO - 'TMOUT': '86400', 05:37:57 INFO - 'USER': 'cltbld', 05:37:57 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:57 INFO - '_': '/tools/buildbot/bin/python'} 05:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:37:57 INFO - Cleaning up... 05:37:57 INFO - Return code: 0 05:37:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:37:57 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:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:57 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:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:57 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 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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:57 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:57 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:57 INFO - Using env: {'DISPLAY': ':0', 05:37:57 INFO - 'HOME': '/home/cltbld', 05:37:57 INFO - 'LANG': 'en_US.UTF-8', 05:37:57 INFO - 'LANGUAGE': 'en_US:en', 05:37:57 INFO - 'LOGNAME': 'cltbld', 05:37:57 INFO - 'MAIL': '/var/mail/cltbld', 05:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:57 INFO - 'MOZ_NO_REMOTE': '1', 05:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:57 INFO - 'NO_EM_RESTART': '1', 05:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:57 INFO - 'PWD': '/builds/slave/test', 05:37:57 INFO - 'SHELL': '/bin/bash', 05:37:57 INFO - 'SHLVL': '1', 05:37:57 INFO - 'TERM': 'linux', 05:37:57 INFO - 'TMOUT': '86400', 05:37:57 INFO - 'USER': 'cltbld', 05:37:57 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:57 INFO - '_': '/tools/buildbot/bin/python'} 05:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:57 INFO - Downloading/unpacking blobuploader==1.2.4 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:59 INFO - Downloading blobuploader-1.2.4.tar.gz 05:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:37:59 INFO - Downloading/unpacking requests==1.2.3. (from 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:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:37:59 INFO - Downloading/unpacking docopt==0.6.1 (from 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:37:59 INFO - Downloading docopt-0.6.1.tar.gz 05:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:38:00 INFO - Installing collected packages: blobuploader, requests, docopt 05:38:00 INFO - Running setup.py install for blobuploader 05:38:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:38:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:38:00 INFO - Running setup.py install for requests 05:38:00 INFO - Running setup.py install for docopt 05:38:00 INFO - Successfully installed blobuploader requests docopt 05:38:00 INFO - Cleaning up... 05:38:00 INFO - Return code: 0 05:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:00 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 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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:00 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:00 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:00 INFO - Using env: {'DISPLAY': ':0', 05:38:00 INFO - 'HOME': '/home/cltbld', 05:38:00 INFO - 'LANG': 'en_US.UTF-8', 05:38:00 INFO - 'LANGUAGE': 'en_US:en', 05:38:00 INFO - 'LOGNAME': 'cltbld', 05:38:00 INFO - 'MAIL': '/var/mail/cltbld', 05:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:00 INFO - 'MOZ_NO_REMOTE': '1', 05:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:00 INFO - 'NO_EM_RESTART': '1', 05:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:00 INFO - 'PWD': '/builds/slave/test', 05:38:00 INFO - 'SHELL': '/bin/bash', 05:38:00 INFO - 'SHLVL': '1', 05:38:00 INFO - 'TERM': 'linux', 05:38:00 INFO - 'TMOUT': '86400', 05:38:00 INFO - 'USER': 'cltbld', 05:38:00 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:00 INFO - '_': '/tools/buildbot/bin/python'} 05:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:00 INFO - Running setup.py (path:/tmp/pip-n0lrES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:00 INFO - Running setup.py (path:/tmp/pip-D12pO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:01 INFO - Running setup.py (path:/tmp/pip-bOTMZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:01 INFO - Running setup.py (path:/tmp/pip-Em0UkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:01 INFO - Running setup.py (path:/tmp/pip-hHSsZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:01 INFO - Running setup.py (path:/tmp/pip-oOK0t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:01 INFO - Running setup.py (path:/tmp/pip-BzEsCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:01 INFO - Running setup.py (path:/tmp/pip-T4YFtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:01 INFO - Running setup.py (path:/tmp/pip-32cm7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:01 INFO - Running setup.py (path:/tmp/pip-mFWGB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:01 INFO - Running setup.py (path:/tmp/pip-udnaRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:01 INFO - Running setup.py (path:/tmp/pip-Gn8AWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:01 INFO - Running setup.py (path:/tmp/pip-39WGSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:01 INFO - Running setup.py (path:/tmp/pip-Iyc1KN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:01 INFO - Running setup.py (path:/tmp/pip-_NquIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:01 INFO - Running setup.py (path:/tmp/pip-t20nPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:02 INFO - Running setup.py (path:/tmp/pip-K6tsf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:38:02 INFO - Running setup.py install for manifestparser 05:38:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Running setup.py install for mozcrash 05:38:02 INFO - Running setup.py install for mozdebug 05:38:02 INFO - Running setup.py install for mozdevice 05:38:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Running setup.py install for mozfile 05:38:02 INFO - Running setup.py install for mozhttpd 05:38:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Running setup.py install for mozinfo 05:38:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Running setup.py install for mozInstall 05:38:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:38:02 INFO - Running setup.py install for mozleak 05:38:03 INFO - Running setup.py install for mozlog 05:38:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Running setup.py install for moznetwork 05:38:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Running setup.py install for mozprocess 05:38:03 INFO - Running setup.py install for mozprofile 05:38:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Running setup.py install for mozrunner 05:38:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Running setup.py install for mozscreenshot 05:38:03 INFO - Running setup.py install for moztest 05:38:03 INFO - Running setup.py install for mozversion 05:38:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:38:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 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', '--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 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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', '--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 --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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:03 INFO - '_': '/tools/buildbot/bin/python'} 05:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:04 INFO - Running setup.py (path:/tmp/pip-9dd_5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:04 INFO - Running setup.py (path:/tmp/pip-GQKS5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:04 INFO - Running setup.py (path:/tmp/pip-5cCIHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:04 INFO - Running setup.py (path:/tmp/pip-ji_ejN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:04 INFO - Running setup.py (path:/tmp/pip-9yPMV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:04 INFO - Running setup.py (path:/tmp/pip-j1mvhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:04 INFO - Running setup.py (path:/tmp/pip-45XzQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:04 INFO - Running setup.py (path:/tmp/pip-Z5KIvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:04 INFO - Running setup.py (path:/tmp/pip-5nK8pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:04 INFO - Running setup.py (path:/tmp/pip-My8pzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:04 INFO - Running setup.py (path:/tmp/pip-DxMCez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:04 INFO - Running setup.py (path:/tmp/pip-OayRjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:05 INFO - Running setup.py (path:/tmp/pip-c9qSDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:05 INFO - Running setup.py (path:/tmp/pip-qcEx9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:05 INFO - Running setup.py (path:/tmp/pip-RtboYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:05 INFO - Running setup.py (path:/tmp/pip-ZY3kCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:05 INFO - Running setup.py (path:/tmp/pip-eqvWcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:05 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:05 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:05 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:05 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:05 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:05 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:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:05 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:05 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:05 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:05 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:05 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:05 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 - Downloading blessings-1.6.tar.gz 05:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:38:07 INFO - Installing collected packages: blessings 05:38:07 INFO - Running setup.py install for blessings 05:38:07 INFO - Successfully installed blessings 05:38:07 INFO - Cleaning up... 05:38:07 INFO - Return code: 0 05:38:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:38:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:07 INFO - Reading from file tmpfile_stdout 05:38:07 INFO - Current package versions: 05:38:07 INFO - argparse == 1.2.1 05:38:07 INFO - blessings == 1.6 05:38:07 INFO - blobuploader == 1.2.4 05:38:07 INFO - docopt == 0.6.1 05:38:07 INFO - functools32 == 3.2.3-2 05:38:07 INFO - jsonschema == 2.5.1 05:38:07 INFO - manifestparser == 1.1 05:38:07 INFO - mozInstall == 1.12 05:38:07 INFO - mozcrash == 0.17 05:38:07 INFO - mozdebug == 0.1 05:38:07 INFO - mozdevice == 0.48 05:38:07 INFO - mozfile == 1.2 05:38:07 INFO - mozhttpd == 0.7 05:38:07 INFO - mozinfo == 0.9 05:38:07 INFO - mozleak == 0.1 05:38:07 INFO - mozlog == 3.3 05:38:07 INFO - moznetwork == 0.27 05:38:07 INFO - mozprocess == 0.23 05:38:07 INFO - mozprofile == 0.28 05:38:07 INFO - mozrunner == 6.12 05:38:07 INFO - mozscreenshot == 0.1 05:38:07 INFO - mozsystemmonitor == 0.3 05:38:07 INFO - moztest == 0.7 05:38:07 INFO - mozversion == 1.4 05:38:07 INFO - psutil == 3.1.1 05:38:07 INFO - requests == 1.2.3 05:38:07 INFO - wsgiref == 0.1.2 05:38:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:07 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:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:07 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:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:07 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 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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:07 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:07 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:07 INFO - Using env: {'DISPLAY': ':0', 05:38:07 INFO - 'HOME': '/home/cltbld', 05:38:07 INFO - 'LANG': 'en_US.UTF-8', 05:38:07 INFO - 'LANGUAGE': 'en_US:en', 05:38:07 INFO - 'LOGNAME': 'cltbld', 05:38:07 INFO - 'MAIL': '/var/mail/cltbld', 05:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:07 INFO - 'MOZ_NO_REMOTE': '1', 05:38:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:07 INFO - 'NO_EM_RESTART': '1', 05:38:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:07 INFO - 'PWD': '/builds/slave/test', 05:38:07 INFO - 'SHELL': '/bin/bash', 05:38:07 INFO - 'SHLVL': '1', 05:38:07 INFO - 'TERM': 'linux', 05:38:07 INFO - 'TMOUT': '86400', 05:38:07 INFO - 'USER': 'cltbld', 05:38:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:07 INFO - '_': '/tools/buildbot/bin/python'} 05:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:07 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:07 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:07 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:07 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:07 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:07 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:07 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:07 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:07 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:07 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:07 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:07 INFO - Cleaning up... 05:38:07 INFO - Return code: 0 05:38:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:38:07 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:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:07 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:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:07 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 0x7fed2d821f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2541850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', '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:07 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:07 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:07 INFO - Using env: {'DISPLAY': ':0', 05:38:07 INFO - 'HOME': '/home/cltbld', 05:38:07 INFO - 'LANG': 'en_US.UTF-8', 05:38:07 INFO - 'LANGUAGE': 'en_US:en', 05:38:07 INFO - 'LOGNAME': 'cltbld', 05:38:07 INFO - 'MAIL': '/var/mail/cltbld', 05:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:07 INFO - 'MOZ_NO_REMOTE': '1', 05:38:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:07 INFO - 'NO_EM_RESTART': '1', 05:38:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:07 INFO - 'PWD': '/builds/slave/test', 05:38:07 INFO - 'SHELL': '/bin/bash', 05:38:07 INFO - 'SHLVL': '1', 05:38:07 INFO - 'TERM': 'linux', 05:38:07 INFO - 'TMOUT': '86400', 05:38:07 INFO - 'USER': 'cltbld', 05:38:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:07 INFO - '_': '/tools/buildbot/bin/python'} 05:38:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:38:08 INFO - Cleaning up... 05:38:08 INFO - Return code: 0 05:38:08 INFO - Running post-action listener: _resource_record_post_action 05:38:08 INFO - Running post-action listener: _start_resource_monitoring 05:38:08 INFO - Starting resource monitoring. 05:38:08 INFO - [mozharness: 2016-08-18 12:38:08.202091Z] Finished create-virtualenv step (success) 05:38:08 INFO - [mozharness: 2016-08-18 12:38:08.202599Z] Running install step. 05:38:08 INFO - Running pre-action listener: _resource_record_pre_action 05:38:08 INFO - Running main action method: install 05:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:08 INFO - Reading from file tmpfile_stdout 05:38:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:38:08 INFO - Reading from file tmpfile_stdout 05:38:08 INFO - Output received: 05:38:08 INFO - Usage: mozinstall [options] installer 05:38:08 INFO - Options: 05:38:08 INFO - -h, --help show this help message and exit 05:38:08 INFO - -d DEST, --destination=DEST 05:38:08 INFO - Directory to install application into. [default: 05:38:08 INFO - "/builds/slave/test"] 05:38:08 INFO - --app=APP Application being installed. [default: firefox] 05:38:08 INFO - mkdir: /builds/slave/test/build/application 05:38:08 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:08 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:25 INFO - Reading from file tmpfile_stdout 05:38:25 INFO - Output received: 05:38:25 INFO - /builds/slave/test/build/application/firefox/firefox 05:38:25 INFO - Running post-action listener: _resource_record_post_action 05:38:25 INFO - [mozharness: 2016-08-18 12:38:25.462632Z] Finished install step (success) 05:38:25 INFO - [mozharness: 2016-08-18 12:38:25.462879Z] Running run-tests step. 05:38:25 INFO - Running pre-action listener: _resource_record_pre_action 05:38:25 INFO - Running main action method: run_tests 05:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:38:25 INFO - Python 2.7.3 05:38:25 INFO - Return code: 0 05:38:25 INFO - grabbing minidump binary from tooltool 05:38:25 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:25 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 0x253c370>, '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 0x253f8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253fd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:38:25 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:25 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:25 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:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:25 INFO - Return code: 0 05:38:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:38:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:38:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:38:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:38:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:38:25 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-064', '--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:25 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-064 --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:25 INFO - Using env: {'DISPLAY': ':0', 05:38:25 INFO - 'HOME': '/home/cltbld', 05:38:25 INFO - 'LANG': 'en_US.UTF-8', 05:38:25 INFO - 'LANGUAGE': 'en_US:en', 05:38:25 INFO - 'LOGNAME': 'cltbld', 05:38:25 INFO - 'MAIL': '/var/mail/cltbld', 05:38:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:38:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:25 INFO - 'MOZ_NO_REMOTE': '1', 05:38:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:38:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:25 INFO - 'NO_EM_RESTART': '1', 05:38:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:25 INFO - 'PWD': '/builds/slave/test', 05:38:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:38:25 INFO - 'SHELL': '/bin/bash', 05:38:25 INFO - 'SHLVL': '1', 05:38:25 INFO - 'TERM': 'linux', 05:38:25 INFO - 'TMOUT': '86400', 05:38:25 INFO - 'USER': 'cltbld', 05:38:25 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311', 05:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:25 INFO - '_': '/tools/buildbot/bin/python'} 05:38:25 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-064', '--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:25 INFO - mozversion application_buildid: 20160817193029 05:38:25 INFO - mozversion application_changeset: 97a52326b06a07930216ebefa5af333271578904 05:38:25 INFO - mozversion application_display_name: Nightly 05:38:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:25 INFO - mozversion application_name: Firefox 05:38:25 INFO - mozversion application_remotingname: firefox 05:38:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:38:25 INFO - mozversion application_vendor: Mozilla 05:38:25 INFO - mozversion application_version: 51.0a1 05:38:25 INFO - mozversion platform_buildid: 20160817193029 05:38:25 INFO - mozversion platform_changeset: 97a52326b06a07930216ebefa5af333271578904 05:38:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:38:25 INFO - mozversion platform_version: 51.0a1 05:38:25 INFO - using testdate: 1471523905 05:38:25 INFO - actual date: 1471523905 05:38:25 INFO - starting webserver on 'localhost:45374' 05:38:25 INFO - SUITE-START | Running 2 tests 05:38:25 INFO - TEST-START | dromaeo_css 05:38:25 INFO - operating with platform_type : linux_ 05:38:25 INFO - Initialising browser for dromaeo_css test... 05:38:25 INFO - TEST-INFO | started process 26246 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqKwQR3/profile http://localhost:45374/getInfo.html) 05:38:29 INFO - PROCESS | 26246 | __metrics Screen width/height:1600/1200 05:38:29 INFO - PROCESS | 26246 | colorDepth:24 05:38:29 INFO - PROCESS | 26246 | Browser inner width/height: 1024/697 05:38:29 INFO - PROCESS | 26246 | __metrics 05:38:30 INFO - PROCESS | 26246 | 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:30 INFO - PROCESS | 26246 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:38:31 INFO - PROCESS | 26246 | console.error: PushService: 05:38:31 INFO - PROCESS | 26246 | stateChangeProcessEnqueue: Error transitioning state 05:38:31 INFO - PROCESS | 26246 | UnknownError 05:38:31 INFO - TEST-INFO | 26246: exit 0 05:38:31 INFO - Browser initialized. 05:38:31 INFO - Running cycle 1/1 for dromaeo_css test... 05:38:31 INFO - TEST-INFO | started process 26326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqKwQR3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:43 INFO - PROCESS | 26326 | RSS: Main: 155709440 05:38:43 INFO - PROCESS | 26326 | 05:39:34 INFO - PROCESS | 26326 | 1471523974838 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:44 INFO - PROCESS | 26326 | Cycle 1(1): loaded http://localhost:45374/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45374/tests/dromaeo/cssquery-ext.html) 05:41:46 INFO - PROCESS | 26326 | RSS: Main: 279957504 05:41:46 INFO - PROCESS | 26326 | 05:44:48 INFO - PROCESS | 26326 | Cycle 1(1): loaded http://localhost:45374/tests/dromaeo/cssquery-ext.html (next: http://localhost:45374/tests/dromaeo/cssquery-jquery.html) 05:44:49 INFO - PROCESS | 26326 | RSS: Main: 242049024 05:44:49 INFO - PROCESS | 26326 | 05:47:51 INFO - PROCESS | 26326 | Cycle 1(1): loaded http://localhost:45374/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45374/tests/dromaeo/cssquery-mootools.html) 05:47:51 INFO - PROCESS | 26326 | RSS: Main: 206110720 05:47:51 INFO - PROCESS | 26326 | 05:50:53 INFO - PROCESS | 26326 | Cycle 1(1): loaded http://localhost:45374/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45374/tests/dromaeo/cssquery-prototype.html) 05:50:54 INFO - PROCESS | 26326 | RSS: Main: 219430912 05:50:54 INFO - PROCESS | 26326 | 05:53:57 INFO - PROCESS | 26326 | Cycle 1(1): loaded http://localhost:45374/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45374/tests/dromaeo/cssquery-yui.html) 05:53:57 INFO - PROCESS | 26326 | RSS: Main: 186200064 05:53:57 INFO - PROCESS | 26326 | 05:57:00 INFO - PROCESS | 26326 | Cycle 1(1): loaded http://localhost:45374/tests/dromaeo/cssquery-yui.html (next: http://localhost:45374/tests/dromaeo/cssquery-dojo.html) 05:57:00 INFO - PROCESS | 26326 | RSS: Main: 278265856 05:57:00 INFO - PROCESS | 26326 | 05:57:00 INFO - PROCESS | 26326 | __start_tp_report 05:57:00 INFO - PROCESS | 26326 | _x_x_mozilla_page_load 05:57:00 INFO - PROCESS | 26326 | _x_x_mozilla_page_load_details 05:57:00 INFO - PROCESS | 26326 | |i|pagename|runs| 05:57:00 INFO - PROCESS | 26326 | |0;dojo.html;2992.640883094028;3020;3041.1028746519773;3041.1940835678547;3041.4069256494977;5053.343065401493;5119.539241468267;5262.9210561841555;5345.1447768357075;5388.568914486841;5245.291885595453;5259.263703081562;5285.207218917164;5303.469653034695;5317.707526086064;4731.455882573497;4734.550217729319;4831.396075490564;4837.951620483803;4952.554270115689;4879.438864530589;4889.584385327252;4910.803567857281;4927.137750893579;4933.654644174909;4675.041616468616;4706.7175969441605;4706.811727530895;4778.354922085508;4799.760011999386;4773.570378665902;4775.14047471455;4782.569568738795;4782.832600858988;4782.856514304576;4739.549742774431;4751;4760.309755085494;4762.476127625959;4819.807207711688;5118.59051275897;5131.794728210867;5139.871503212449;5162.380514338266;5175.818846340398;5635.3519345275;5639.661620302795;5640.294963129609;5644.125160600113;5647.237622920894;5445.53712934401;5628.549716022709;5684.204211410406;5715.628484148517;5736.512396446307;5331.733413329318;5413.783448662049;5421.457854214586;5426.158945363396;5430.647007944471;5904.7342869570975;5908.468237858613;5913.05391137416;5915.7633694652595;5916.49709774674;5380.865478363072;5414.40441551429;5494.5054945055135;5496.560275177976;5583.106702927512;5617.129344951539;5625.46558076982;5659.5755318351285;5669.773209071674;5670.404607516234;5604.915926261109;5613.747381367848;5614.9438505615235;5615.410381909841;5619.185218143428;5433.212184233264;5551.056320425457;5586.413426590231;5619.100943848965;5627.268455100811;4359.346098085312;4362.367456718758;4381.277089280296;4393.253146965024;4399.428074350314;3875.8643447479203;3885.902852428712;3904.8828535143425;3934.488516492838;3935.252302062599;5156.355455568054;5166.43169251382;5361.0618141825025;5361.08861493547;5384.165454354581;5775.249217601685;5796.391378905154;5802.738876750556;5808.186853840466;5822.737976791054;5420.457954204627;5439.211314359396;5454.400015998237;5456.863578410572;5459.344878614592;4722.598579120745;4923.901521969612;4936.284238785428;4998.475160108209;5065.341505604248;5972.671503067373;5977.850553736017;5984.371640977634;6004.699765011732;6026.548008899263;5743.74153163117;5749.195112684144;5751.3673495916255;5755.309362876482;5759.539236860957;5736.971315143398;5844.970775146097;5852.912206316993;5995.130706047686;6000.549958753199;5599.328080630187;5625.549956003428;5631.1271752878365;5660.264165658438;5671.064274394844;5655.660660360227;5695.401982791747;5699.715014249188;5705.515031222392;5708.143778433268;5293.417724050429;5513.393526712139;5570.91643625354;5583.302087239095;5592.608517403905;5587.3015873015875;5593.664380137205;5599.468050535193;5622.269105016322;5639.689817060101;5523.226748255328;5530.944690553043;5542.97228513871;5545.916811247754;5549.778008879761;5325.174597937326;5473.151661492475;5522.558195344443;5661.348944871393;5663.8867222656145;5423.7830486780085;5455.972720136374;5457.481539253765;5478.643888147258;5500.862478438071 05:57:00 INFO - PROCESS | 26326 | |1;ext.html;585.2654918077815;604.7913469852901;609.8902197604432;624.3163735709396;631.2425089892125;42528.57471425285;44202.55797442025;44215;44330.999999999956;47932;44792;45491.54508454914;45542.544574554246;45615.543844561544;45622;8835.95582022089;9115.49864757438;9116.635334586608;9116.680916167936;9144.542772861363;9007.95496022519;9129.543522823866;9130.680426185085;9130.726078217664;9132.36073474857;9160.725178244664;9202.079792020812;9203.401778884385;9221.769455763593;9226.538673066354;9085.863712044325;9138.725838224864;9144.497052662067;9144.679936202267;9147.22248608869;8929.553522323922;9008.864867027;9023.729288121367;9036.8644470333;9041.141091596288;42836.14327713459;42917;43362;43374.349384759254;43534.564654353366;3510.8244587770764;3673.155174309897;3730.1607138393915;3789.488419063419;4042.535108462506;31700.365992680017;31711.52432713511;33533.66466335354;33568.83215583931;33638.327233455195;4228.281192197333;4304.892377690583;4385;4433.312836510346;4533.341258755098;373806;375181;381421;382019;382258;63010;64163.679181603795;64200;64256;64258.35741642617;68106.31893681;68247;68449;68457.65771171212;68508.31491685119;16190;16395.42616008458;16401.58996025085;16403.097829619248;16404.589885252964;12328.32194229308;12428.689282768004;12451.25292482454;12451.75096498083;12457.128001040022;19102.33141840029;20330.898345508176;20335.59328813445;20349.491262718253;20360.49098772513;7467.887981680279;7493.66278517468;7631.580263085583;7634.541927484369;7807.033327335576;724.7899159663785;1075.198976762317;1078.4984981983416;1095.430376204385;1125.291066628019;31839.840800795846;32654.836725816695;32726.836365818017;33132.66867331344;33524.49713254303;1058.9099926506333;1103.7626910214183;1115.9051480624075;1177.6467059882002;1227.7237621535082;17171.399001034904;17211.91394043022;17231.65536689259;17307.653846922993;17384.47846564605;544236;553901;555360;555707;555976;58045.41954580485;58706.706466467396;58723.723348413856;58732.41267587355;58760.706196468745;1213.8846809553079;1330.8735670111323;1376.6489545166119;1393.811835402228;1396.9511067112603;29449.705502945555;32984.175395615304;33969.830150849084;34520;34874.30251395009;51785.482145178066;52529.47470525399;52639.73680131575;52644.736776317404;52648.473515264355;477132;481293;482344;482656;483654;43699.78150109357;44651.33023004723;45650.08699825918;46569.3014604788;48454.515454845;47556.7622161887;47629;48575;48633.270500943225;48695.75652121859;40131.59868401279;40741.59258407496;41057.794711026254;41717.58282417137;43285.35071973986;41279;41712;41737.02062340524;42289;42667;41266;41720.3741943865;42015.579844201166;42440.57559424366;42749;33729.83135084309;37390.25219495541;37549.81225093857;38113.428298576095;38672.22655546817;28724;29235.7076429233;29254.853725731235;29409.411811764072;29444.705552944197 05:57:00 INFO - PROCESS | 26326 | |2;jquery.html;360.2003552114305;364.1133839101786;379.5501185471499;387.8119112230569;407.49877650489896;14304.785428218573;18625.161867715953;19499.902500487497;19630.312939047137;20841;19076;19617.411477655656;19959.201631934742;19974.201031958703;20072.197112115497;5457.808976685807;5660.320761508625;5724.227229324029;5725.398833122525;5730.7707691692285;5452.59105567084;5586.776528938837;5611.551075913917;5643.435656434344;5644.604877658566;5592.245046918675;5612.354579223401;5612.803551875685;5613.298337707786;5624.943750562504;5600.635958662694;5641.351244606882;5892.23400957877;5924.348321684612;5996.130561068664;5631;5788.18965344852;5795.652260864362;5812.622179558315;5820.417958204146;13544.59366219015;13957.232352220624;13957.511487097903;13974.790378144337;14589.927050364786;2239.798418142375;2281.235786011688;2406.3984003999;2496.0889275414524;2917.197770613077;9905.108540231413;10017.69946901594;10094.242931780145;10504.947475262652;10571.312864663772;1845.3817970979737;1890.5273681579606;1916.8274855263094;1948.3180886689545;2005.7392538969987;80364.59817700874;81838;82669;85181;86378;8809.823803523894;9284.396514226553;9371.34400591952;9396.906030939603;11231.775364492827;13273;13273.137246078973;13275.137116087424;13370.264635445144;13387.933060334832;7307.123145222501;7559.962200188964;7606.619669016527;7810.336121429628;7849.175836537195;7565.546067235873;7595;7691.846163076707;7772;7829.256220659028;4016.6184212499766;4021.678265738734;4060.5330387005283;4131.752094874317;4240.173166232555;1997.5605366819277;2066.52243645791;2076.480879780055;2086.3949454657977;2107.6522373808457;876.1501343696605;959.9376040557481;1048.2766890845292;1099.3019432660158;1115.2583531951325;18471.16879740415;19913.900430497753;19920.3027894023;20033.499162521053;20188;948.6347756113807;956.5456408206186;971.1308379000754;981.3915372469115;1001.3191030099457;10443;10877.347359158475;10992.560297588007;10998.615048473264;11038.227324087236;375882;381038.0948095242;502700;503495;506479;97696.51151744196;97975.51012244893;99485.50257248667;100722;103261;1029.0481304793036;1045.1168762395764;1059.1791361694748;1074.9032587067202;1092.8797832238447;17474.82525174732;17717.291308348038;18390.1724422404;18619;18927;43161.56838431575;43426.56573434225;43446.34830477609;44532.332015020445;44613.330800038675;61784.69107654433;61958.69020654868;62048;62112.68943655253;63788.3621163801;8262.83474330522;8343.707970221014;8354.958225209079;8457.957710211409;8833.602487887933;11658.53365865356;13038.348082595643;13180.736385272432;13183.14309569875;13246.271455070064;11125.3324800509;11287.153463490435;11445.141614378788;11490.88509114898;11842.703932401759;7665.808354791175;11238.606648767254;11321;11453.19827612059;11599.8260026098;10370.688879333633;11604.245724027911;11710.707232318919;11893.286402815858;11902.761944760883;8622.956885215533;8678.175573320525;8767.693130740394;8773.912260877309;9051.14014168653;11545.82681259765;11700.414979250832;11840.70398240051;11859.822102668639;12291.815622765844 05:57:00 INFO - PROCESS | 26326 | |3;mootools.html;1332.560255115812;1459.7591397419424;1475.1444162385815;1606.8955517891336;1742.6950283700357;7978.760637180887;8408.45345052571;8437.999999999993;8601.741947741577;8618.870716939251;8444.113368096338;8531.146885311466;8542.402031857773;8554.315654747621;8556.871646925285;2320.524292520029;2344.191254017361;2345.296411076679;2346.756219203825;2347.788699017088;2268.943276418086;2314.36355002374;2324.8140148788057;2337.240396871015;2339.087755775249;2263.3096905443917;2281.0761641535205;2286.7598902115287;2350.7296660883962;2351.318117745852;2262.8981695823727;2270.9318720438414;2283.1209984156135;2285.1430713482446;2285.3600991722346;2282.4179834142356;2286.039863257436;2286.302677683306;2287.1308902616897;2287.668288098233;8194.139615340422;8345.165483451667;8487.321014318839;8489.74530764078;8495.83008339836;4850.53919877611;4974.925376119325;5042.890564075898;5044.016416798726;5045.747712614355;3309.9172520686934;3425.4519276915576;3489.9127521812156;3489.930201395983;3542.946855797165;1125.774845030989;1149.4482648328765;1151.4012713389075;1164.126904821384;1174.3247632611196;11418.486168122454;11508.597199098074;11546.134039947037;11550.768984620261;11589.942050289694;4166.979165104155;4354.216241076637;4360.171567402247;4501.707389019703;4516.209663308908;9999.600015999424;10000.399976001463;10009.249306302056;10015.148712359385;10019.248556358303;4969.950300496948;4972.825951091694;4979.103761322925;4979.228219625964;4984.451710311862;3929.096307849211;4478.39541661878;4583.587477127075;4609.631229501631;4635.351050852883;3710.350688629479;3782.962170378316;3820.84716611338;3894.3768996960916;3905.6094390560716;1233.3216730798204;1247.607003793802;1259.250745806244;1264.8861602455822;1273.3824095313767;916.445550441973;997.8602995580625;1000.099910080933;1001.3491230700104;1023.9948800255951;5565.3599836019375;5818;6018.428249316308;6081.7871374502565;6145.9692701536205;461.26198083067493;470.85638880141886;471.8207081309149;476.89508308172145;479.88242880493806;4087.795610219477;4089.3048181809163;4107.199096176277;4116.58834116586;4116.58834116592;9907.356021858555;10332.948335258276;10338.069573738248;10342.77630309487;10343.27597068198;10121.59513619447;10147.797044059225;10186.796264074825;10210.591576336863;10225.74435639115;7485.288897554724;7770.261825126604;7776.766696998986;7794.688212471551;7867.881981770278;5859.765609375748;6071;6073.240844894388;6092.664903430176;6107.84730381744;9320.580573874327;9434.764130896783;9443.858342124737;9453.385529940808;9510.95244523773;9337.673181438617;9513.857292140761;9543.809123817346;9651.469169195761;9679.209346270962;7230.45771567124;7359;7417.592032438268;7423.517471364344;7427.591482468516;8128.9593552031865;8166.224208700164;8185.590720463834;8199.303059240032;8204.917950820654;7273.454490913097;7310.817229569303;7479.723341792481;7509.061367329084;7559.584222867795;7110.786676399716;7152.213256541881;7247.8550428990075;7251.238619945041;7349.706011759684;6622.933770662232;6755.121834161528;6764.188297404343;6764.932350676627;6765.8985115224295;4869.829555965524;5050;5117.334746482933;5204.8178313758835;5235.581153507786;5003.124453220599;5044;5175.197844334134;5228.869278268074;5318.282031925795 05:57:00 INFO - PROCESS | 26326 | |4;prototype.html;316.25048634735697;322.34242146022115;329.2361720807727;329.319954294382;337.0747298665163;5016.573591244749;5171.948280517194;5178.249153872686;5196.610254230929;5208;5072.188449848025;5086.389633244016;5119.667221630601;5162.909021846149;5175.456577059411;4057.1479989202303;4061.289274376987;4078.327076032466;4080.5715399882956;4099.467069281004;3766.7928263945473;3907.7655340679507;4049.3116170250983;4072.9185416291657;4091.468109145807;4026.758394496325;4044.9595504045024;4050.886006348263;4052.49343832021;4063.6545893599077;4044.3326851069537;4048.67610591152;4049.777262250575;4055.168690418457;4057.3711074783455;3592.371335016388;3602.6937710294656;3620.855165793365;3625.23869987303;3633.2551826875692;4868.788831480699;4956.5786908112495;4956.63692484566;4958.504149585049;4964.801407943678;4581.3815134956685;4583.175028494869;4583.30417745433;4605.282883098987;4623.375844261015;5325.8934821303355;5332.307184871654;5337.359516857963;5344.6793192408195;5370.69060304073;3836.2135762168687;3904.824282907276;3929.607039296076;3941.664958478504;3949.637375105584;5500.157447255175;5500.889982200333;5508.14623733322;5515;5518.19986102017;4009.558948515661;4028.274910516135;4031.9395209071886;4043.3732771420478;4043.534993475729;4007.73949693269;4060.837566497307;4067.91864162721;4077.531083925327;4130.56629053951;4560.674056231346;4575.9084818303445;4602.125596136723;4606.401167848226;4612.723236605747;5334.0665383557725;5383.2194331821665;5394.514493695587;5423.620346575781;5447.809326673548;1251.7559075980278;1276.6233960981533;1389.145675409636;1390.1242217402971;1416.4546649540002;968.6319198704588;982.6020461713018;989.747614358334;991.6033586565432;994.9651762188289;5112.284280200774;5121.615878809104;5144.819931302386;5155.659528522611;5171.198464238049;5290.93653475145;5448.83653490396;5484.287042684506;5488.313960754906;5500.559955203654;5072.6195535335;5088.92366614501;5099;5112;5119.97440012805;2543.974560254411;2549.2607143928426;2552.2215724203925;2566.345581876647;2571.717111117813;4695;4957.578605818474;4965.453800081988;4965.627577931597;4992.233969764928;4888.462269150391;4906;4907.730074845917;4929.433115191727;4935.654504184745;4979.651424400329;4988.276699878497;4993.725345106054;4994.1759609663995;5025.195968644999;4544.386507821402;4755.952440475551;4810.831620893252;4832.347633069631;4834.95165048345;4763.308730567219;4765.142274390643;4772.1627173537745;4781.39005051198;4792.305115758265;4994.876152865576;4995.400551933792;5001.024800163933;5022;5022.815075442194;3466.7053300468733;3630.419132938717;3633.128938596572;3661.2494438640547;3740.2519496100344;5379.838604841861;5391.137418013099;5412.648177868426;5440.102383106743;5446.564274858081;5213.739313034409;5261.947380526146;5277.577793776565;5285.497877701612;5307.681539107666;5100.923486147785;5276.39321478035;5283.920741188808;5310.442403547727;5354.54486368663;5288.233206185158;5292.930100541754;5307.9734601326745;5309.415964244007;5321.521063104339;5401;5416.945830541644;5417.972910135424;5418.268533747893;5428.7285635718845;5182.870428239325;5296.152615581488;5298.178782288752;5324.6006549508165;5326.508577598279 05:57:00 INFO - PROCESS | 26326 | |5;yui.html;709.5813470052668;727.0330460487551;737.4985010193067;746.7647690977342;746.8207630168757;14405.711885762292;15393.538193854174;15417.306221220044;15498.845011549882;15520.456784012576;15469.84530154698;15704.921475392635;15711.842881571181;15715.057096574214;15720.213989300546;655.9114519539849;779.6998155710064;798.3094623150976;801.8957535520374;803.9959800200991;812.8943237379133;814.0963530481162;816.9387295952797;816.9836603267931;817.513579420247;870.8563087090622;871.8430682477151;872.4067634008871;872.5331947408138;872.8167084912176;683.5420268420146;821.117298903681;846.3356265331685;852.8507511185579;864.9264812490945;624.7095100778115;625.3496363781661;625.4433554136822;625.7653379982506;625.8967270400378;14048.648783780487;14218.28908554568;14223.573292801233;14226.715465690731;14229.359678814479;14374.281285935662;14598.489052883204;14738;14764.778528322084;14780.926095369454;13718.588442346645;13743.656408589864;13865.653358666013;14033.438662453489;14593.343299551545;4419.469663640384;4420.160169567772;4444.511103778582;4448.977755111204;4515.029268707211;85065;85857.57071214604;86645.56677216636;86686.56656716739;89235.55382223047;10980.835287470694;11350.205485616092;11411.201215914807;11694.35681037534;12249.448774804978;21614;21863.234786782385;21876.562468750537;21891.562168756853;21952;8487.533185674723;8603.314774437382;8646.52444115567;8765.561721913879;8989.46063236208;4803.231482962709;4874.926876096862;4890.1931181355385;4980.738434384247;5025.924611130836;3553.6091029986683;3694.9630503695157;3699.6855267302044;3711.0907827582414;3712.350338690718;580.9070548712185;587.7531436796555;595.7289433307834;602.3825578781696;621.9253689557282;593.970301484924;621.487272999777;632.4971647540217;680.5644387591946;705.3087973785719;9614.375065620712;9630.759231019281;9852;9900.000998990927;10032.79934401308;564.7035306464057;624.6064979063252;632.6393955445352;687.0244253160524;689.2521614048713;4440.489343725449;4822.927656085161;4837.177679794443;4840.806367745251;4903.117438861039;125648;126095.36952315363;127412;128440.35779821228;133386.33306833403;86501;108585;111382;116517;119152.40423797826;95820.52089739646;97365;98710.50644746874;99188.50405748069;100775;8718.520481373585;8981.326400520118;9024.819503610022;9040.41237319572;9049;55296.44703553074;55621.1656825156;55807.16289255583;58090.4190958085;59056.409435905094;76237.6188119078;77937.61031194808;87442;87720;87745;11764;11853.940730296637;11933.403329833647;12142;12394.442250098587;13293.667658308234;13329.400176992252;13443.865561344652;13787.931060344634;13899;12119.454624542093;12324.32216228116;12736.299503527394;12876.613701588967;13186.80219796723;12411.503539858304;13038;13046.54337098197;13111.803322950354;13134.343282836011;12418.75162496727;12423;12454.333113732919;12457.626271211879;12567.120301578801;9933.552990115317;10100.84848727255;10465.476726163814;10602.526821425417;10784.676459706221;10229.232807539316;10754.946225268823;10961.232713709964;10964.506597203304;10980.615678451215 05:57:00 INFO - PROCESS | 26326 | __end_tp_report 05:57:00 INFO - PROCESS | 26326 | __start_cc_report 05:57:00 INFO - PROCESS | 26326 | _x_x_mozilla_cycle_collect,1103 05:57:00 INFO - PROCESS | 26326 | __end_cc_report 05:57:00 INFO - PROCESS | 26326 | __startTimestamp1471525020988__endTimestamp 05:57:00 INFO - PROCESS | 26326 | 05:57:00 INFO - PROCESS | 26326 | ------- Summary: start ------- 05:57:00 INFO - PROCESS | 26326 | Number of tests: 6 05:57:00 INFO - PROCESS | 26326 | 05:57:00 INFO - PROCESS | 26326 | [#0] dojo.html Cycles:165 Average:5271.33 Median:5457.48 stddev:611.25 (11.2%) stddev-sans-first:586.40 05:57:00 INFO - PROCESS | 26326 | Values: 2992.6 3020.0 3041.1 3041.2 3041.4 5053.3 5119.5 5262.9 5345.1 5388.6 5245.3 5259.3 5285.2 5303.5 5317.7 4731.5 4734.6 4831.4 4838.0 4952.6 4879.4 4889.6 4910.8 4927.1 4933.7 4675.0 4706.7 4706.8 4778.4 4799.8 4773.6 4775.1 4782.6 4782.8 4782.9 4739.5 4751.0 4760.3 4762.5 4819.8 5118.6 5131.8 5139.9 5162.4 5175.8 5635.4 5639.7 5640.3 5644.1 5647.2 5445.5 5628.5 5684.2 5715.6 5736.5 5331.7 5413.8 5421.5 5426.2 5430.6 5904.7 5908.5 5913.1 5915.8 5916.5 5380.9 5414.4 5494.5 5496.6 5583.1 5617.1 5625.5 5659.6 5669.8 5670.4 5604.9 5613.7 5614.9 5615.4 5619.2 5433.2 5551.1 5586.4 5619.1 5627.3 4359.3 4362.4 4381.3 4393.3 4399.4 3875.9 3885.9 3904.9 3934.5 3935.3 5156.4 5166.4 5361.1 5361.1 5384.2 5775.2 5796.4 5802.7 5808.2 5822.7 5420.5 5439.2 5454.4 5456.9 5459.3 4722.6 4923.9 4936.3 4998.5 5065.3 5972.7 5977.9 5984.4 6004.7 6026.5 5743.7 5749.2 5751.4 5755.3 5759.5 5737.0 5845.0 5852.9 5995.1 6000.5 5599.3 5625.5 5631.1 5660.3 5671.1 5655.7 5695.4 5699.7 5705.5 5708.1 5293.4 5513.4 5570.9 5583.3 5592.6 5587.3 5593.7 5599.5 5622.3 5639.7 5523.2 5530.9 5543.0 5545.9 5549.8 5325.2 5473.2 5522.6 5661.3 5663.9 5423.8 5456.0 5457.5 5478.6 5500.9 05:57:00 INFO - PROCESS | 26326 | 05:57:00 INFO - PROCESS | 26326 | [#1] ext.html Cycles:180 Average:64054.90 Median:43454.46 stddev:126314.02 (290.7%) stddev-sans-first:126578.47 05:57:00 INFO - PROCESS | 26326 | Values: 585.3 604.8 609.9 624.3 631.2 42528.6 44202.6 44215.0 44331.0 47932.0 44792.0 45491.5 45542.5 45615.5 45622.0 8836.0 9115.5 9116.6 9116.7 9144.5 9008.0 9129.5 9130.7 9130.7 9132.4 9160.7 9202.1 9203.4 9221.8 9226.5 9085.9 9138.7 9144.5 9144.7 9147.2 8929.6 9008.9 9023.7 9036.9 9041.1 42836.1 42917.0 43362.0 43374.3 43534.6 3510.8 3673.2 3730.2 3789.5 4042.5 31700.4 31711.5 33533.7 33568.8 33638.3 4228.3 4304.9 4385.0 4433.3 4533.3 373806.0 375181.0 381421.0 382019.0 382258.0 63010.0 64163.7 64200.0 64256.0 64258.4 68106.3 68247.0 68449.0 68457.7 68508.3 16190.0 16395.4 16401.6 16403.1 16404.6 12328.3 12428.7 12451.3 12451.8 12457.1 19102.3 20330.9 20335.6 20349.5 20360.5 7467.9 7493.7 7631.6 7634.5 7807.0 724.8 1075.2 1078.5 1095.4 1125.3 31839.8 32654.8 32726.8 33132.7 33524.5 1058.9 1103.8 1115.9 1177.6 1227.7 17171.4 17211.9 17231.7 17307.7 17384.5 544236.0 553901.0 555360.0 555707.0 555976.0 58045.4 58706.7 58723.7 58732.4 58760.7 1213.9 1330.9 1376.6 1393.8 1397.0 29449.7 32984.2 33969.8 34520.0 34874.3 51785.5 52529.5 52639.7 52644.7 52648.5 477132.0 481293.0 482344.0 482656.0 483654.0 43699.8 44651.3 45650.1 46569.3 48454.5 47556.8 47629.0 48575.0 48633.3 48695.8 40131.6 40741.6 41057.8 41717.6 43285.4 41279.0 41712.0 41737.0 42289.0 42667.0 41266.0 41720.4 42015.6 42440.6 42749.0 33729.8 37390.3 37549.8 38113.4 38672.2 28724.0 29235.7 29254.9 29409.4 29444.7 05:57:00 INFO - PROCESS | 26326 | 05:57:00 INFO - PROCESS | 26326 | [#2] jquery.html Cycles:180 Average:28076.51 Median:362.16 stddev:76128.73 (21020.9%) stddev-sans-first:76313.85 05:57:01 INFO - PROCESS | 26326 | Values: 360.2 364.1 379.6 387.8 407.5 14304.8 18625.2 19499.9 19630.3 20841.0 19076.0 19617.4 19959.2 19974.2 20072.2 5457.8 5660.3 5724.2 5725.4 5730.8 5452.6 5586.8 5611.6 5643.4 5644.6 5592.2 5612.4 5612.8 5613.3 5624.9 5600.6 5641.4 5892.2 5924.3 5996.1 5631.0 5788.2 5795.7 5812.6 5820.4 13544.6 13957.2 13957.5 13974.8 14589.9 2239.8 2281.2 2406.4 2496.1 2917.2 9905.1 10017.7 10094.2 10504.9 10571.3 1845.4 1890.5 1916.8 1948.3 2005.7 80364.6 81838.0 82669.0 85181.0 86378.0 8809.8 9284.4 9371.3 9396.9 11231.8 13273.0 13273.1 13275.1 13370.3 13387.9 7307.1 7560.0 7606.6 7810.3 7849.2 7565.5 7595.0 7691.8 7772.0 7829.3 4016.6 4021.7 4060.5 4131.8 4240.2 1997.6 2066.5 2076.5 2086.4 2107.7 876.2 959.9 1048.3 1099.3 1115.3 18471.2 19913.9 19920.3 20033.5 20188.0 948.6 956.5 971.1 981.4 1001.3 10443.0 10877.3 10992.6 10998.6 11038.2 375882.0 381038.1 502700.0 503495.0 506479.0 97696.5 97975.5 99485.5 100722.0 103261.0 1029.0 1045.1 1059.2 1074.9 1092.9 17474.8 17717.3 18390.2 18619.0 18927.0 43161.6 43426.6 43446.3 44532.3 44613.3 61784.7 61958.7 62048.0 62112.7 63788.4 8262.8 8343.7 8355.0 8458.0 8833.6 11658.5 13038.3 13180.7 13183.1 13246.3 11125.3 11287.2 11445.1 11490.9 11842.7 7665.8 11238.6 11321.0 11453.2 11599.8 10370.7 11604.2 11710.7 11893.3 11902.8 8623.0 8678.2 8767.7 8773.9 9051.1 11545.8 11700.4 11840.7 11859.8 12291.8 05:57:01 INFO - PROCESS | 26326 | 05:57:01 INFO - PROCESS | 26326 | [#3] mootools.html Cycles:180 Average:5562.76 Median:2665.21 stddev:3104.66 (116.5%) stddev-sans-first:3097.09 05:57:01 INFO - PROCESS | 26326 | Values: 1332.6 1459.8 1475.1 1606.9 1742.7 7978.8 8408.5 8438.0 8601.7 8618.9 8444.1 8531.1 8542.4 8554.3 8556.9 2320.5 2344.2 2345.3 2346.8 2347.8 2268.9 2314.4 2324.8 2337.2 2339.1 2263.3 2281.1 2286.8 2350.7 2351.3 2262.9 2270.9 2283.1 2285.1 2285.4 2282.4 2286.0 2286.3 2287.1 2287.7 8194.1 8345.2 8487.3 8489.7 8495.8 4850.5 4974.9 5042.9 5044.0 5045.7 3309.9 3425.5 3489.9 3489.9 3542.9 1125.8 1149.4 1151.4 1164.1 1174.3 11418.5 11508.6 11546.1 11550.8 11589.9 4167.0 4354.2 4360.2 4501.7 4516.2 9999.6 10000.4 10009.2 10015.1 10019.2 4970.0 4972.8 4979.1 4979.2 4984.5 3929.1 4478.4 4583.6 4609.6 4635.4 3710.4 3783.0 3820.8 3894.4 3905.6 1233.3 1247.6 1259.3 1264.9 1273.4 916.4 997.9 1000.1 1001.3 1024.0 5565.4 5818.0 6018.4 6081.8 6146.0 461.3 470.9 471.8 476.9 479.9 4087.8 4089.3 4107.2 4116.6 4116.6 9907.4 10332.9 10338.1 10342.8 10343.3 10121.6 10147.8 10186.8 10210.6 10225.7 7485.3 7770.3 7776.8 7794.7 7867.9 5859.8 6071.0 6073.2 6092.7 6107.8 9320.6 9434.8 9443.9 9453.4 9511.0 9337.7 9513.9 9543.8 9651.5 9679.2 7230.5 7359.0 7417.6 7423.5 7427.6 8129.0 8166.2 8185.6 8199.3 8204.9 7273.5 7310.8 7479.7 7509.1 7559.6 7110.8 7152.2 7247.9 7251.2 7349.7 6622.9 6755.1 6764.2 6764.9 6765.9 4869.8 5050.0 5117.3 5204.8 5235.6 5003.1 5044.0 5175.2 5228.9 5318.3 05:57:01 INFO - PROCESS | 26326 | 05:57:01 INFO - PROCESS | 26326 | [#4] prototype.html Cycles:180 Average:4396.45 Median:4961.65 stddev:1258.23 (25.4%) stddev-sans-first:1223.92 05:57:01 INFO - PROCESS | 26326 | Values: 316.3 322.3 329.2 329.3 337.1 5016.6 5171.9 5178.2 5196.6 5208.0 5072.2 5086.4 5119.7 5162.9 5175.5 4057.1 4061.3 4078.3 4080.6 4099.5 3766.8 3907.8 4049.3 4072.9 4091.5 4026.8 4045.0 4050.9 4052.5 4063.7 4044.3 4048.7 4049.8 4055.2 4057.4 3592.4 3602.7 3620.9 3625.2 3633.3 4868.8 4956.6 4956.6 4958.5 4964.8 4581.4 4583.2 4583.3 4605.3 4623.4 5325.9 5332.3 5337.4 5344.7 5370.7 3836.2 3904.8 3929.6 3941.7 3949.6 5500.2 5500.9 5508.1 5515.0 5518.2 4009.6 4028.3 4031.9 4043.4 4043.5 4007.7 4060.8 4067.9 4077.5 4130.6 4560.7 4575.9 4602.1 4606.4 4612.7 5334.1 5383.2 5394.5 5423.6 5447.8 1251.8 1276.6 1389.1 1390.1 1416.5 968.6 982.6 989.7 991.6 995.0 5112.3 5121.6 5144.8 5155.7 5171.2 5290.9 5448.8 5484.3 5488.3 5500.6 5072.6 5088.9 5099.0 5112.0 5120.0 2544.0 2549.3 2552.2 2566.3 2571.7 4695.0 4957.6 4965.5 4965.6 4992.2 4888.5 4906.0 4907.7 4929.4 4935.7 4979.7 4988.3 4993.7 4994.2 5025.2 4544.4 4756.0 4810.8 4832.3 4835.0 4763.3 4765.1 4772.2 4781.4 4792.3 4994.9 4995.4 5001.0 5022.0 5022.8 3466.7 3630.4 3633.1 3661.2 3740.3 5379.8 5391.1 5412.6 5440.1 5446.6 5213.7 5261.9 5277.6 5285.5 5307.7 5100.9 5276.4 5283.9 5310.4 5354.5 5288.2 5292.9 5308.0 5309.4 5321.5 5401.0 5416.9 5418.0 5418.3 5428.7 5182.9 5296.2 5298.2 5324.6 5326.5 05:57:01 INFO - PROCESS | 26326 | 05:57:01 INFO - PROCESS | 26326 | [#5] yui.html Cycles:180 Average:22377.15 Median:4659.13 stddev:33754.02 (724.5%) stddev-sans-first:33809.50 05:57:01 INFO - PROCESS | 26326 | Values: 709.6 727.0 737.5 746.8 746.8 14405.7 15393.5 15417.3 15498.8 15520.5 15469.8 15704.9 15711.8 15715.1 15720.2 655.9 779.7 798.3 801.9 804.0 812.9 814.1 816.9 817.0 817.5 870.9 871.8 872.4 872.5 872.8 683.5 821.1 846.3 852.9 864.9 624.7 625.3 625.4 625.8 625.9 14048.6 14218.3 14223.6 14226.7 14229.4 14374.3 14598.5 14738.0 14764.8 14780.9 13718.6 13743.7 13865.7 14033.4 14593.3 4419.5 4420.2 4444.5 4449.0 4515.0 85065.0 85857.6 86645.6 86686.6 89235.6 10980.8 11350.2 11411.2 11694.4 12249.4 21614.0 21863.2 21876.6 21891.6 21952.0 8487.5 8603.3 8646.5 8765.6 8989.5 4803.2 4874.9 4890.2 4980.7 5025.9 3553.6 3695.0 3699.7 3711.1 3712.4 580.9 587.8 595.7 602.4 621.9 594.0 621.5 632.5 680.6 705.3 9614.4 9630.8 9852.0 9900.0 10032.8 564.7 624.6 632.6 687.0 689.3 4440.5 4822.9 4837.2 4840.8 4903.1 125648.0 126095.4 127412.0 128440.4 133386.3 86501.0 108585.0 111382.0 116517.0 119152.4 95820.5 97365.0 98710.5 99188.5 100775.0 8718.5 8981.3 9024.8 9040.4 9049.0 55296.4 55621.2 55807.2 58090.4 59056.4 76237.6 77937.6 87442.0 87720.0 87745.0 11764.0 11853.9 11933.4 12142.0 12394.4 13293.7 13329.4 13443.9 13787.9 13899.0 12119.5 12324.3 12736.3 12876.6 13186.8 12411.5 13038.0 13046.5 13111.8 13134.3 12418.8 12423.0 12454.3 12457.6 12567.1 9933.6 10100.8 10465.5 10602.5 10784.7 10229.2 10754.9 10961.2 10964.5 10980.6 05:57:01 INFO - PROCESS | 26326 | -------- Summary: end -------- 05:57:01 INFO - PROCESS | 26326 | 05:57:01 INFO - TEST-INFO | 26326: exit 0 05:57:01 INFO - TEST-OK | dromaeo_css | took 1116075ms 05:57:01 INFO - TEST-START | kraken 05:57:01 INFO - operating with platform_type : linux_ 05:57:01 INFO - Initialising browser for kraken test... 05:57:01 INFO - TEST-INFO | started process 26625 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnFSXrJ/profile http://localhost:45374/getInfo.html) 05:57:06 INFO - PROCESS | 26625 | __metrics Screen width/height:1600/1200 05:57:06 INFO - PROCESS | 26625 | colorDepth:24 05:57:06 INFO - PROCESS | 26625 | Browser inner width/height: 1024/697 05:57:06 INFO - PROCESS | 26625 | __metrics 05:57:06 INFO - PROCESS | 26625 | 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:06 INFO - PROCESS | 26625 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:07 INFO - PROCESS | 26625 | console.error: PushService: 05:57:07 INFO - PROCESS | 26625 | stateChangeProcessEnqueue: Error transitioning state 05:57:07 INFO - PROCESS | 26625 | UnknownError 05:57:08 INFO - TEST-INFO | 26625: exit 0 05:57:08 INFO - Browser initialized. 05:57:08 INFO - Running cycle 1/1 for kraken test... 05:57:08 INFO - TEST-INFO | started process 26705 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnFSXrJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:19 INFO - PROCESS | 26705 | RSS: Main: 156504064 05:57:19 INFO - PROCESS | 26705 | 05:57:22 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45374/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:23 INFO - PROCESS | 26705 | RSS: Main: 204410880 05:57:23 INFO - PROCESS | 26705 | 05:57:25 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45374/tests/kraken/driver.html?testName=audio-dft) 05:57:26 INFO - PROCESS | 26705 | RSS: Main: 196190208 05:57:26 INFO - PROCESS | 26705 | 05:57:29 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45374/tests/kraken/driver.html?testName=audio-fft) 05:57:30 INFO - PROCESS | 26705 | RSS: Main: 276242432 05:57:30 INFO - PROCESS | 26705 | 05:57:32 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45374/tests/kraken/driver.html?testName=audio-oscillator) 05:57:32 INFO - PROCESS | 26705 | RSS: Main: 185716736 05:57:32 INFO - PROCESS | 26705 | 05:57:35 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45374/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:57:35 INFO - PROCESS | 26705 | RSS: Main: 191320064 05:57:35 INFO - PROCESS | 26705 | 05:57:38 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45374/tests/kraken/driver.html?testName=imaging-darkroom) 05:57:39 INFO - PROCESS | 26705 | RSS: Main: 227401728 05:57:39 INFO - PROCESS | 26705 | 05:57:42 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45374/tests/kraken/driver.html?testName=imaging-desaturate) 05:57:43 INFO - PROCESS | 26705 | RSS: Main: 226705408 05:57:43 INFO - PROCESS | 26705 | 05:57:46 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45374/tests/kraken/driver.html?testName=json-parse-financial) 05:57:46 INFO - PROCESS | 26705 | RSS: Main: 226152448 05:57:46 INFO - PROCESS | 26705 | 05:57:48 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45374/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:57:48 INFO - PROCESS | 26705 | RSS: Main: 188940288 05:57:48 INFO - PROCESS | 26705 | 05:57:50 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45374/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:57:51 INFO - PROCESS | 26705 | RSS: Main: 197390336 05:57:51 INFO - PROCESS | 26705 | 05:57:53 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45374/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:57:54 INFO - PROCESS | 26705 | RSS: Main: 194158592 05:57:54 INFO - PROCESS | 26705 | 05:57:56 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45374/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:57:57 INFO - PROCESS | 26705 | RSS: Main: 189472768 05:57:57 INFO - PROCESS | 26705 | 05:57:59 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45374/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:58:00 INFO - PROCESS | 26705 | RSS: Main: 192204800 05:58:00 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | Cycle 1(1): loaded http://localhost:45374/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45374/tests/kraken/driver.html?testName=ai-astar) 05:58:02 INFO - PROCESS | 26705 | RSS: Main: 242966528 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | __start_tp_report 05:58:02 INFO - PROCESS | 26705 | _x_x_mozilla_page_load 05:58:02 INFO - PROCESS | 26705 | _x_x_mozilla_page_load_details 05:58:02 INFO - PROCESS | 26705 | |i|pagename|runs| 05:58:02 INFO - PROCESS | 26705 | |0;ai-astar;104;112;112;113;107;112;110;112;112;110 05:58:02 INFO - PROCESS | 26705 | |1;audio-beat-detection;143;118;120;184;120;118;154;122;152;117 05:58:02 INFO - PROCESS | 26705 | |2;audio-dft;161;161;160;161;161;163;164;162;161;161 05:58:02 INFO - PROCESS | 26705 | |3;audio-fft;75;74;75;114;76;75;75;76;109;76 05:58:02 INFO - PROCESS | 26705 | |4;audio-oscillator;85;85;130;84;84;85;129;85;85;124 05:58:02 INFO - PROCESS | 26705 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;118;116;116 05:58:02 INFO - PROCESS | 26705 | |6;imaging-darkroom;145;146;147;147;146;145;146;148;146;147 05:58:02 INFO - PROCESS | 26705 | |7;imaging-desaturate;95;95;95;95;95;96;95;96;95;96 05:58:02 INFO - PROCESS | 26705 | |8;json-parse-financial;53;51;55;52;50;53;53;49;54;51 05:58:02 INFO - PROCESS | 26705 | |9;json-stringify-tinderbox;63;62;62;62;62;61;62;62;62;62 05:58:02 INFO - PROCESS | 26705 | |10;stanford-crypto-aes;79;80;84;79;87;80;78;78;77;78 05:58:02 INFO - PROCESS | 26705 | |11;stanford-crypto-ccm;125;130;122;124;126;123;124;124;139;127 05:58:02 INFO - PROCESS | 26705 | |12;stanford-crypto-pbkdf2;163;166;161;161;165;168;162;165;166;164 05:58:02 INFO - PROCESS | 26705 | |13;stanford-crypto-sha256-iterative;66;65;64;66;66;64;63;64;64;69 05:58:02 INFO - PROCESS | 26705 | __end_tp_report 05:58:02 INFO - PROCESS | 26705 | __start_cc_report 05:58:02 INFO - PROCESS | 26705 | _x_x_mozilla_cycle_collect,1673 05:58:02 INFO - PROCESS | 26705 | __end_cc_report 05:58:02 INFO - PROCESS | 26705 | __startTimestamp1471525082487__endTimestamp 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | ------- Summary: start ------- 05:58:02 INFO - PROCESS | 26705 | Number of tests: 14 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#0] ai-astar Cycles:10 Average:110.40 Median:112.00 stddev:2.84 (2.5%) stddev-sans-first:1.83 05:58:02 INFO - PROCESS | 26705 | Values: 104.0 112.0 112.0 113.0 107.0 112.0 110.0 112.0 112.0 110.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:132.50 stddev:22.70 (17.1%) stddev-sans-first:23.88 05:58:02 INFO - PROCESS | 26705 | Values: 143.0 118.0 120.0 184.0 120.0 118.0 154.0 122.0 152.0 117.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.00 stddev:1.18 (0.7%) stddev-sans-first:1.24 05:58:02 INFO - PROCESS | 26705 | Values: 161.0 161.0 160.0 161.0 161.0 163.0 164.0 162.0 161.0 161.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#3] audio-fft Cycles:10 Average:82.50 Median:75.00 stddev:15.34 (20.5%) stddev-sans-first:16.03 05:58:02 INFO - PROCESS | 26705 | Values: 75.0 74.0 75.0 114.0 76.0 75.0 75.0 76.0 109.0 76.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:85.00 stddev:20.81 (24.5%) stddev-sans-first:21.56 05:58:02 INFO - PROCESS | 26705 | Values: 85.0 85.0 130.0 84.0 84.0 85.0 129.0 85.0 85.0 124.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:58:02 INFO - PROCESS | 26705 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 116.0 116.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.95 (0.6%) stddev-sans-first:0.88 05:58:02 INFO - PROCESS | 26705 | Values: 145.0 146.0 147.0 147.0 146.0 145.0 146.0 148.0 146.0 147.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 05:58:02 INFO - PROCESS | 26705 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 96.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:53.00 stddev:1.85 (3.5%) stddev-sans-first:1.94 05:58:02 INFO - PROCESS | 26705 | Values: 53.0 51.0 55.0 52.0 50.0 53.0 53.0 49.0 54.0 51.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.47 (0.8%) stddev-sans-first:0.33 05:58:02 INFO - PROCESS | 26705 | Values: 63.0 62.0 62.0 62.0 62.0 61.0 62.0 62.0 62.0 62.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.50 stddev:3.13 (3.9%) stddev-sans-first:3.30 05:58:02 INFO - PROCESS | 26705 | Values: 79.0 80.0 84.0 79.0 87.0 80.0 78.0 78.0 77.0 78.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:125.50 stddev:4.97 (4.0%) stddev-sans-first:5.25 05:58:02 INFO - PROCESS | 26705 | Values: 125.0 130.0 122.0 124.0 126.0 123.0 124.0 124.0 139.0 127.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:165.00 stddev:2.33 (1.4%) stddev-sans-first:2.44 05:58:02 INFO - PROCESS | 26705 | Values: 163.0 166.0 161.0 161.0 165.0 168.0 162.0 165.0 166.0 164.0 05:58:02 INFO - PROCESS | 26705 | 05:58:02 INFO - PROCESS | 26705 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:1.73 (2.6%) stddev-sans-first:1.80 05:58:02 INFO - PROCESS | 26705 | Values: 66.0 65.0 64.0 66.0 66.0 64.0 63.0 64.0 64.0 69.0 05:58:02 INFO - PROCESS | 26705 | -------- Summary: end -------- 05:58:02 INFO - PROCESS | 26705 | 05:58:03 INFO - TEST-INFO | 26705: exit 0 05:58:03 INFO - TEST-OK | kraken | took 61742ms 05:58:03 INFO - SUITE-END | took 1177s 05:58:03 INFO - Completed test suite (00:19:37) 05:58:03 INFO - javascript benchmark 05:58:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2992.640883094028, 3020.0, 3041.1028746519773, 3041.1940835678547, 3041.4069256494977, 5053.343065401493, 5119.539241468267, 5262.9210561841555, 5345.1447768357075, 5388.568914486841, 5245.291885595453, 5259.263703081562, 5285.207218917164, 5303.469653034695, 5317.707526086064, 4731.455882573497, 4734.550217729319, 4831.396075490564, 4837.951620483803, 4952.554270115689, 4879.438864530589, 4889.584385327252, 4910.803567857281, 4927.137750893579, 4933.654644174909, 4675.041616468616, 4706.7175969441605, 4706.811727530895, 4778.354922085508, 4799.760011999386, 4773.570378665902, 4775.14047471455, 4782.569568738795, 4782.832600858988, 4782.856514304576, 4739.549742774431, 4751.0, 4760.309755085494, 4762.476127625959, 4819.807207711688, 5118.59051275897, 5131.794728210867, 5139.871503212449, 5162.380514338266, 5175.818846340398, 5635.3519345275, 5639.661620302795, 5640.294963129609, 5644.125160600113, 5647.237622920894, 5445.53712934401, 5628.549716022709, 5684.204211410406, 5715.628484148517, 5736.512396446307, 5331.733413329318, 5413.783448662049, 5421.457854214586, 5426.158945363396, 5430.647007944471, 5904.7342869570975, 5908.468237858613, 5913.05391137416, 5915.7633694652595, 5916.49709774674, 5380.865478363072, 5414.40441551429, 5494.5054945055135, 5496.560275177976, 5583.106702927512, 5617.129344951539, 5625.46558076982, 5659.5755318351285, 5669.773209071674, 5670.404607516234, 5604.915926261109, 5613.747381367848, 5614.9438505615235, 5615.410381909841, 5619.185218143428, 5433.212184233264, 5551.056320425457, 5586.413426590231, 5619.100943848965, 5627.268455100811, 4359.346098085312, 4362.367456718758, 4381.277089280296, 4393.253146965024, 4399.428074350314, 3875.8643447479203, 3885.902852428712, 3904.8828535143425, 3934.488516492838, 3935.252302062599, 5156.355455568054, 5166.43169251382, 5361.0618141825025, 5361.08861493547, 5384.165454354581, 5775.249217601685, 5796.391378905154, 5802.738876750556, 5808.186853840466, 5822.737976791054, 5420.457954204627, 5439.211314359396, 5454.400015998237, 5456.863578410572, 5459.344878614592, 4722.598579120745, 4923.901521969612, 4936.284238785428, 4998.475160108209, 5065.341505604248, 5972.671503067373, 5977.850553736017, 5984.371640977634, 6004.699765011732, 6026.548008899263, 5743.74153163117, 5749.195112684144, 5751.3673495916255, 5755.309362876482, 5759.539236860957, 5736.971315143398, 5844.970775146097, 5852.912206316993, 5995.130706047686, 6000.549958753199, 5599.328080630187, 5625.549956003428, 5631.1271752878365, 5660.264165658438, 5671.064274394844, 5655.660660360227, 5695.401982791747, 5699.715014249188, 5705.515031222392, 5708.143778433268, 5293.417724050429, 5513.393526712139, 5570.91643625354, 5583.302087239095, 5592.608517403905, 5587.3015873015875, 5593.664380137205, 5599.468050535193, 5622.269105016322, 5639.689817060101, 5523.226748255328, 5530.944690553043, 5542.97228513871, 5545.916811247754, 5549.778008879761, 5325.174597937326, 5473.151661492475, 5522.558195344443, 5661.348944871393, 5663.8867222656145, 5423.7830486780085, 5455.972720136374, 5457.481539253765, 5478.643888147258, 5500.862478438071], "value": 5229.3659508508335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.2654918077815, 604.7913469852901, 609.8902197604432, 624.3163735709396, 631.2425089892125, 42528.57471425285, 44202.55797442025, 44215.0, 44330.999999999956, 47932.0, 44792.0, 45491.54508454914, 45542.544574554246, 45615.543844561544, 45622.0, 8835.95582022089, 9115.49864757438, 9116.635334586608, 9116.680916167936, 9144.542772861363, 9007.95496022519, 9129.543522823866, 9130.680426185085, 9130.726078217664, 9132.36073474857, 9160.725178244664, 9202.079792020812, 9203.401778884385, 9221.769455763593, 9226.538673066354, 9085.863712044325, 9138.725838224864, 9144.497052662067, 9144.679936202267, 9147.22248608869, 8929.553522323922, 9008.864867027, 9023.729288121367, 9036.8644470333, 9041.141091596288, 42836.14327713459, 42917.0, 43362.0, 43374.349384759254, 43534.564654353366, 3510.8244587770764, 3673.155174309897, 3730.1607138393915, 3789.488419063419, 4042.535108462506, 31700.365992680017, 31711.52432713511, 33533.66466335354, 33568.83215583931, 33638.327233455195, 4228.281192197333, 4304.892377690583, 4385.0, 4433.312836510346, 4533.341258755098, 373806.0, 375181.0, 381421.0, 382019.0, 382258.0, 63010.0, 64163.679181603795, 64200.0, 64256.0, 64258.35741642617, 68106.31893681, 68247.0, 68449.0, 68457.65771171212, 68508.31491685119, 16190.0, 16395.42616008458, 16401.58996025085, 16403.097829619248, 16404.589885252964, 12328.32194229308, 12428.689282768004, 12451.25292482454, 12451.75096498083, 12457.128001040022, 19102.33141840029, 20330.898345508176, 20335.59328813445, 20349.491262718253, 20360.49098772513, 7467.887981680279, 7493.66278517468, 7631.580263085583, 7634.541927484369, 7807.033327335576, 724.7899159663785, 1075.198976762317, 1078.4984981983416, 1095.430376204385, 1125.291066628019, 31839.840800795846, 32654.836725816695, 32726.836365818017, 33132.66867331344, 33524.49713254303, 1058.9099926506333, 1103.7626910214183, 1115.9051480624075, 1177.6467059882002, 1227.7237621535082, 17171.399001034904, 17211.91394043022, 17231.65536689259, 17307.653846922993, 17384.47846564605, 544236.0, 553901.0, 555360.0, 555707.0, 555976.0, 58045.41954580485, 58706.706466467396, 58723.723348413856, 58732.41267587355, 58760.706196468745, 1213.8846809553079, 1330.8735670111323, 1376.6489545166119, 1393.811835402228, 1396.9511067112603, 29449.705502945555, 32984.175395615304, 33969.830150849084, 34520.0, 34874.30251395009, 51785.482145178066, 52529.47470525399, 52639.73680131575, 52644.736776317404, 52648.473515264355, 477132.0, 481293.0, 482344.0, 482656.0, 483654.0, 43699.78150109357, 44651.33023004723, 45650.08699825918, 46569.3014604788, 48454.515454845, 47556.7622161887, 47629.0, 48575.0, 48633.270500943225, 48695.75652121859, 40131.59868401279, 40741.59258407496, 41057.794711026254, 41717.58282417137, 43285.35071973986, 41279.0, 41712.0, 41737.02062340524, 42289.0, 42667.0, 41266.0, 41720.3741943865, 42015.579844201166, 42440.57559424366, 42749.0, 33729.83135084309, 37390.25219495541, 37549.81225093857, 38113.428298576095, 38672.22655546817, 28724.0, 29235.7076429233, 29254.853725731235, 29409.411811764072, 29444.705552944197], "value": 20986.297052343016, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.2003552114305, 364.1133839101786, 379.5501185471499, 387.8119112230569, 407.49877650489896, 14304.785428218573, 18625.161867715953, 19499.902500487497, 19630.312939047137, 20841.0, 19076.0, 19617.411477655656, 19959.201631934742, 19974.201031958703, 20072.197112115497, 5457.808976685807, 5660.320761508625, 5724.227229324029, 5725.398833122525, 5730.7707691692285, 5452.59105567084, 5586.776528938837, 5611.551075913917, 5643.435656434344, 5644.604877658566, 5592.245046918675, 5612.354579223401, 5612.803551875685, 5613.298337707786, 5624.943750562504, 5600.635958662694, 5641.351244606882, 5892.23400957877, 5924.348321684612, 5996.130561068664, 5631.0, 5788.18965344852, 5795.652260864362, 5812.622179558315, 5820.417958204146, 13544.59366219015, 13957.232352220624, 13957.511487097903, 13974.790378144337, 14589.927050364786, 2239.798418142375, 2281.235786011688, 2406.3984003999, 2496.0889275414524, 2917.197770613077, 9905.108540231413, 10017.69946901594, 10094.242931780145, 10504.947475262652, 10571.312864663772, 1845.3817970979737, 1890.5273681579606, 1916.8274855263094, 1948.3180886689545, 2005.7392538969987, 80364.59817700874, 81838.0, 82669.0, 85181.0, 86378.0, 8809.823803523894, 9284.396514226553, 9371.34400591952, 9396.906030939603, 11231.775364492827, 13273.0, 13273.137246078973, 13275.137116087424, 13370.264635445144, 13387.933060334832, 7307.123145222501, 7559.962200188964, 7606.619669016527, 7810.336121429628, 7849.175836537195, 7565.546067235873, 7595.0, 7691.846163076707, 7772.0, 7829.256220659028, 4016.6184212499766, 4021.678265738734, 4060.5330387005283, 4131.752094874317, 4240.173166232555, 1997.5605366819277, 2066.52243645791, 2076.480879780055, 2086.3949454657977, 2107.6522373808457, 876.1501343696605, 959.9376040557481, 1048.2766890845292, 1099.3019432660158, 1115.2583531951325, 18471.16879740415, 19913.900430497753, 19920.3027894023, 20033.499162521053, 20188.0, 948.6347756113807, 956.5456408206186, 971.1308379000754, 981.3915372469115, 1001.3191030099457, 10443.0, 10877.347359158475, 10992.560297588007, 10998.615048473264, 11038.227324087236, 375882.0, 381038.0948095242, 502700.0, 503495.0, 506479.0, 97696.51151744196, 97975.51012244893, 99485.50257248667, 100722.0, 103261.0, 1029.0481304793036, 1045.1168762395764, 1059.1791361694748, 1074.9032587067202, 1092.8797832238447, 17474.82525174732, 17717.291308348038, 18390.1724422404, 18619.0, 18927.0, 43161.56838431575, 43426.56573434225, 43446.34830477609, 44532.332015020445, 44613.330800038675, 61784.69107654433, 61958.69020654868, 62048.0, 62112.68943655253, 63788.3621163801, 8262.83474330522, 8343.707970221014, 8354.958225209079, 8457.957710211409, 8833.602487887933, 11658.53365865356, 13038.348082595643, 13180.736385272432, 13183.14309569875, 13246.271455070064, 11125.3324800509, 11287.153463490435, 11445.141614378788, 11490.88509114898, 11842.703932401759, 7665.808354791175, 11238.606648767254, 11321.0, 11453.19827612059, 11599.8260026098, 10370.688879333633, 11604.245724027911, 11710.707232318919, 11893.286402815858, 11902.761944760883, 8622.956885215533, 8678.175573320525, 8767.693130740394, 8773.912260877309, 9051.14014168653, 11545.82681259765, 11700.414979250832, 11840.70398240051, 11859.822102668639, 12291.815622765844], "value": 9016.873184693826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.560255115812, 1459.7591397419424, 1475.1444162385815, 1606.8955517891336, 1742.6950283700357, 7978.760637180887, 8408.45345052571, 8437.999999999993, 8601.741947741577, 8618.870716939251, 8444.113368096338, 8531.146885311466, 8542.402031857773, 8554.315654747621, 8556.871646925285, 2320.524292520029, 2344.191254017361, 2345.296411076679, 2346.756219203825, 2347.788699017088, 2268.943276418086, 2314.36355002374, 2324.8140148788057, 2337.240396871015, 2339.087755775249, 2263.3096905443917, 2281.0761641535205, 2286.7598902115287, 2350.7296660883962, 2351.318117745852, 2262.8981695823727, 2270.9318720438414, 2283.1209984156135, 2285.1430713482446, 2285.3600991722346, 2282.4179834142356, 2286.039863257436, 2286.302677683306, 2287.1308902616897, 2287.668288098233, 8194.139615340422, 8345.165483451667, 8487.321014318839, 8489.74530764078, 8495.83008339836, 4850.53919877611, 4974.925376119325, 5042.890564075898, 5044.016416798726, 5045.747712614355, 3309.9172520686934, 3425.4519276915576, 3489.9127521812156, 3489.930201395983, 3542.946855797165, 1125.774845030989, 1149.4482648328765, 1151.4012713389075, 1164.126904821384, 1174.3247632611196, 11418.486168122454, 11508.597199098074, 11546.134039947037, 11550.768984620261, 11589.942050289694, 4166.979165104155, 4354.216241076637, 4360.171567402247, 4501.707389019703, 4516.209663308908, 9999.600015999424, 10000.399976001463, 10009.249306302056, 10015.148712359385, 10019.248556358303, 4969.950300496948, 4972.825951091694, 4979.103761322925, 4979.228219625964, 4984.451710311862, 3929.096307849211, 4478.39541661878, 4583.587477127075, 4609.631229501631, 4635.351050852883, 3710.350688629479, 3782.962170378316, 3820.84716611338, 3894.3768996960916, 3905.6094390560716, 1233.3216730798204, 1247.607003793802, 1259.250745806244, 1264.8861602455822, 1273.3824095313767, 916.445550441973, 997.8602995580625, 1000.099910080933, 1001.3491230700104, 1023.9948800255951, 5565.3599836019375, 5818.0, 6018.428249316308, 6081.7871374502565, 6145.9692701536205, 461.26198083067493, 470.85638880141886, 471.8207081309149, 476.89508308172145, 479.88242880493806, 4087.795610219477, 4089.3048181809163, 4107.199096176277, 4116.58834116586, 4116.58834116592, 9907.356021858555, 10332.948335258276, 10338.069573738248, 10342.77630309487, 10343.27597068198, 10121.59513619447, 10147.797044059225, 10186.796264074825, 10210.591576336863, 10225.74435639115, 7485.288897554724, 7770.261825126604, 7776.766696998986, 7794.688212471551, 7867.881981770278, 5859.765609375748, 6071.0, 6073.240844894388, 6092.664903430176, 6107.84730381744, 9320.580573874327, 9434.764130896783, 9443.858342124737, 9453.385529940808, 9510.95244523773, 9337.673181438617, 9513.857292140761, 9543.809123817346, 9651.469169195761, 9679.209346270962, 7230.45771567124, 7359.0, 7417.592032438268, 7423.517471364344, 7427.591482468516, 8128.9593552031865, 8166.224208700164, 8185.590720463834, 8199.303059240032, 8204.917950820654, 7273.454490913097, 7310.817229569303, 7479.723341792481, 7509.061367329084, 7559.584222867795, 7110.786676399716, 7152.213256541881, 7247.8550428990075, 7251.238619945041, 7349.706011759684, 6622.933770662232, 6755.121834161528, 6764.188297404343, 6764.932350676627, 6765.8985115224295, 4869.829555965524, 5050.0, 5117.334746482933, 5204.8178313758835, 5235.581153507786, 5003.124453220599, 5044.0, 5175.197844334134, 5228.869278268074, 5318.282031925795], "value": 4427.1886184555, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.25048634735697, 322.34242146022115, 329.2361720807727, 329.319954294382, 337.0747298665163, 5016.573591244749, 5171.948280517194, 5178.249153872686, 5196.610254230929, 5208.0, 5072.188449848025, 5086.389633244016, 5119.667221630601, 5162.909021846149, 5175.456577059411, 4057.1479989202303, 4061.289274376987, 4078.327076032466, 4080.5715399882956, 4099.467069281004, 3766.7928263945473, 3907.7655340679507, 4049.3116170250983, 4072.9185416291657, 4091.468109145807, 4026.758394496325, 4044.9595504045024, 4050.886006348263, 4052.49343832021, 4063.6545893599077, 4044.3326851069537, 4048.67610591152, 4049.777262250575, 4055.168690418457, 4057.3711074783455, 3592.371335016388, 3602.6937710294656, 3620.855165793365, 3625.23869987303, 3633.2551826875692, 4868.788831480699, 4956.5786908112495, 4956.63692484566, 4958.504149585049, 4964.801407943678, 4581.3815134956685, 4583.175028494869, 4583.30417745433, 4605.282883098987, 4623.375844261015, 5325.8934821303355, 5332.307184871654, 5337.359516857963, 5344.6793192408195, 5370.69060304073, 3836.2135762168687, 3904.824282907276, 3929.607039296076, 3941.664958478504, 3949.637375105584, 5500.157447255175, 5500.889982200333, 5508.14623733322, 5515.0, 5518.19986102017, 4009.558948515661, 4028.274910516135, 4031.9395209071886, 4043.3732771420478, 4043.534993475729, 4007.73949693269, 4060.837566497307, 4067.91864162721, 4077.531083925327, 4130.56629053951, 4560.674056231346, 4575.9084818303445, 4602.125596136723, 4606.401167848226, 4612.723236605747, 5334.0665383557725, 5383.2194331821665, 5394.514493695587, 5423.620346575781, 5447.809326673548, 1251.7559075980278, 1276.6233960981533, 1389.145675409636, 1390.1242217402971, 1416.4546649540002, 968.6319198704588, 982.6020461713018, 989.747614358334, 991.6033586565432, 994.9651762188289, 5112.284280200774, 5121.615878809104, 5144.819931302386, 5155.659528522611, 5171.198464238049, 5290.93653475145, 5448.83653490396, 5484.287042684506, 5488.313960754906, 5500.559955203654, 5072.6195535335, 5088.92366614501, 5099.0, 5112.0, 5119.97440012805, 2543.974560254411, 2549.2607143928426, 2552.2215724203925, 2566.345581876647, 2571.717111117813, 4695.0, 4957.578605818474, 4965.453800081988, 4965.627577931597, 4992.233969764928, 4888.462269150391, 4906.0, 4907.730074845917, 4929.433115191727, 4935.654504184745, 4979.651424400329, 4988.276699878497, 4993.725345106054, 4994.1759609663995, 5025.195968644999, 4544.386507821402, 4755.952440475551, 4810.831620893252, 4832.347633069631, 4834.95165048345, 4763.308730567219, 4765.142274390643, 4772.1627173537745, 4781.39005051198, 4792.305115758265, 4994.876152865576, 4995.400551933792, 5001.024800163933, 5022.0, 5022.815075442194, 3466.7053300468733, 3630.419132938717, 3633.128938596572, 3661.2494438640547, 3740.2519496100344, 5379.838604841861, 5391.137418013099, 5412.648177868426, 5440.102383106743, 5446.564274858081, 5213.739313034409, 5261.947380526146, 5277.577793776565, 5285.497877701612, 5307.681539107666, 5100.923486147785, 5276.39321478035, 5283.920741188808, 5310.442403547727, 5354.54486368663, 5288.233206185158, 5292.930100541754, 5307.9734601326745, 5309.415964244007, 5321.521063104339, 5401.0, 5416.945830541644, 5417.972910135424, 5418.268533747893, 5428.7285635718845, 5182.870428239325, 5296.152615581488, 5298.178782288752, 5324.6006549508165, 5326.508577598279], "value": 4002.933477151787, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.5813470052668, 727.0330460487551, 737.4985010193067, 746.7647690977342, 746.8207630168757, 14405.711885762292, 15393.538193854174, 15417.306221220044, 15498.845011549882, 15520.456784012576, 15469.84530154698, 15704.921475392635, 15711.842881571181, 15715.057096574214, 15720.213989300546, 655.9114519539849, 779.6998155710064, 798.3094623150976, 801.8957535520374, 803.9959800200991, 812.8943237379133, 814.0963530481162, 816.9387295952797, 816.9836603267931, 817.513579420247, 870.8563087090622, 871.8430682477151, 872.4067634008871, 872.5331947408138, 872.8167084912176, 683.5420268420146, 821.117298903681, 846.3356265331685, 852.8507511185579, 864.9264812490945, 624.7095100778115, 625.3496363781661, 625.4433554136822, 625.7653379982506, 625.8967270400378, 14048.648783780487, 14218.28908554568, 14223.573292801233, 14226.715465690731, 14229.359678814479, 14374.281285935662, 14598.489052883204, 14738.0, 14764.778528322084, 14780.926095369454, 13718.588442346645, 13743.656408589864, 13865.653358666013, 14033.438662453489, 14593.343299551545, 4419.469663640384, 4420.160169567772, 4444.511103778582, 4448.977755111204, 4515.029268707211, 85065.0, 85857.57071214604, 86645.56677216636, 86686.56656716739, 89235.55382223047, 10980.835287470694, 11350.205485616092, 11411.201215914807, 11694.35681037534, 12249.448774804978, 21614.0, 21863.234786782385, 21876.562468750537, 21891.562168756853, 21952.0, 8487.533185674723, 8603.314774437382, 8646.52444115567, 8765.561721913879, 8989.46063236208, 4803.231482962709, 4874.926876096862, 4890.1931181355385, 4980.738434384247, 5025.924611130836, 3553.6091029986683, 3694.9630503695157, 3699.6855267302044, 3711.0907827582414, 3712.350338690718, 580.9070548712185, 587.7531436796555, 595.7289433307834, 602.3825578781696, 621.9253689557282, 593.970301484924, 621.487272999777, 632.4971647540217, 680.5644387591946, 705.3087973785719, 9614.375065620712, 9630.759231019281, 9852.0, 9900.000998990927, 10032.79934401308, 564.7035306464057, 624.6064979063252, 632.6393955445352, 687.0244253160524, 689.2521614048713, 4440.489343725449, 4822.927656085161, 4837.177679794443, 4840.806367745251, 4903.117438861039, 125648.0, 126095.36952315363, 127412.0, 128440.35779821228, 133386.33306833403, 86501.0, 108585.0, 111382.0, 116517.0, 119152.40423797826, 95820.52089739646, 97365.0, 98710.50644746874, 99188.50405748069, 100775.0, 8718.520481373585, 8981.326400520118, 9024.819503610022, 9040.41237319572, 9049.0, 55296.44703553074, 55621.1656825156, 55807.16289255583, 58090.4190958085, 59056.409435905094, 76237.6188119078, 77937.61031194808, 87442.0, 87720.0, 87745.0, 11764.0, 11853.940730296637, 11933.403329833647, 12142.0, 12394.442250098587, 13293.667658308234, 13329.400176992252, 13443.865561344652, 13787.931060344634, 13899.0, 12119.454624542093, 12324.32216228116, 12736.299503527394, 12876.613701588967, 13186.80219796723, 12411.503539858304, 13038.0, 13046.54337098197, 13111.803322950354, 13134.343282836011, 12418.75162496727, 12423.0, 12454.333113732919, 12457.626271211879, 12567.120301578801, 9933.552990115317, 10100.84848727255, 10465.476726163814, 10602.526821425417, 10784.676459706221, 10229.232807539316, 10754.946225268823, 10961.232713709964, 10964.506597203304, 10980.615678451215], "value": 7613.371836069201, "unit": "score"}], "name": "dromaeo_css", "value": 7149.2840122921525, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 113.0, 107.0, 112.0, 110.0, 112.0, 112.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 120.0, 184.0, 120.0, 118.0, 154.0, 122.0, 152.0, 117.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 163.0, 164.0, 162.0, 161.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 114.0, 76.0, 75.0, 75.0, 76.0, 109.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 130.0, 84.0, 84.0, 85.0, 129.0, 85.0, 85.0, 124.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 116.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 148.0, 146.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 55.0, 52.0, 50.0, 53.0, 53.0, 49.0, 54.0, 51.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 84.0, 79.0, 87.0, 80.0, 78.0, 78.0, 77.0, 78.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 130.0, 122.0, 124.0, 126.0, 123.0, 124.0, 124.0, 139.0, 127.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 161.0, 161.0, 165.0, 168.0, 162.0, 165.0, 166.0, 164.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 66.0, 66.0, 64.0, 63.0, 64.0, 64.0, 69.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1495.1, "alertThreshold": 2.0}]} 05:58:03 INFO - Return code: 0 05:58:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:58:03 INFO - # TBPL SUCCESS # 05:58:03 INFO - Running post-action listener: _resource_record_post_action 05:58:03 INFO - [mozharness: 2016-08-18 12:58:03.784481Z] Finished run-tests step (success) 05:58:03 INFO - Running post-run listener: _resource_record_post_run 05:58:03 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 8192; Write bytes: 406552576; Read time: 220; Write time: 255308 05:58:03 INFO - TinderboxPrint: CPU usage
13.1% 05:58:03 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 220 05:58:03 INFO - TinderboxPrint: I/O write bytes / time
406,552,576 / 255,308 05:58:03 INFO - TinderboxPrint: CPU idle
8,292.5 (86.9%) 05:58:03 INFO - TinderboxPrint: CPU user
1,198.7 (12.6%) 05:58:03 INFO - TinderboxPrint: Swap in / out
0 / 0 05:58:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118013952; Read time: 0; Write time: 105300 05:58:03 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288083968; Read time: 204; Write time: 150004 05:58:04 INFO - Running post-run listener: _upload_blobber_files 05:58:04 INFO - Blob upload gear active. 05:58:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:58:04 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:04 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:04 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:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:58:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:58:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:58:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:05 INFO - (blobuploader) - INFO - Done attempting. 05:58:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:58:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:58:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:10 INFO - (blobuploader) - INFO - Done attempting. 05:58:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:58:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:58:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:11 INFO - (blobuploader) - INFO - Done attempting. 05:58:11 INFO - (blobuploader) - INFO - Iteration through files over. 05:58:11 INFO - Return code: 0 05:58:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:58:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:58:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d158ce684e485e5ebb74291689f968326e7f1bd1a89e232d0d8d37c48d8876a1148ba9e1d442b098a7d9552837b2b3efd1075f692bebc13aee550c27105e2105", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a645b60096e0f1b7ed4875190ff34e81eab871d96095e9c71338415fe678b3d8ab0145707a10ffbcd145ee74df83f432a9ceb06adca8c9105191f0083ad816cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ccebd37ed225c05b29e24a9fc4ead800bdda5a1282d368d3b1e11bf7fac17cacdf8e0bca105a10b5dbfb92b477bbd8da663e83f4064f54ee6cc98186737166e"} 05:58:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:58:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:58:11 INFO - Contents: 05:58:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d158ce684e485e5ebb74291689f968326e7f1bd1a89e232d0d8d37c48d8876a1148ba9e1d442b098a7d9552837b2b3efd1075f692bebc13aee550c27105e2105", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a645b60096e0f1b7ed4875190ff34e81eab871d96095e9c71338415fe678b3d8ab0145707a10ffbcd145ee74df83f432a9ceb06adca8c9105191f0083ad816cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ccebd37ed225c05b29e24a9fc4ead800bdda5a1282d368d3b1e11bf7fac17cacdf8e0bca105a10b5dbfb92b477bbd8da663e83f4064f54ee6cc98186737166e"} 05:58:11 INFO - Running post-run listener: copy_logs_to_upload_dir 05:58:11 INFO - Copying logs to upload dir... 05:58:11 INFO - mkdir: /builds/slave/test/build/upload/logs 05:58:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.433760 ========= master_lag: 0.84 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-18 05:58:10.987298) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 05:58:10.993143) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311 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/d158ce684e485e5ebb74291689f968326e7f1bd1a89e232d0d8d37c48d8876a1148ba9e1d442b098a7d9552837b2b3efd1075f692bebc13aee550c27105e2105", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a645b60096e0f1b7ed4875190ff34e81eab871d96095e9c71338415fe678b3d8ab0145707a10ffbcd145ee74df83f432a9ceb06adca8c9105191f0083ad816cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ccebd37ed225c05b29e24a9fc4ead800bdda5a1282d368d3b1e11bf7fac17cacdf8e0bca105a10b5dbfb92b477bbd8da663e83f4064f54ee6cc98186737166e"} 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.011216 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/d158ce684e485e5ebb74291689f968326e7f1bd1a89e232d0d8d37c48d8876a1148ba9e1d442b098a7d9552837b2b3efd1075f692bebc13aee550c27105e2105", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a645b60096e0f1b7ed4875190ff34e81eab871d96095e9c71338415fe678b3d8ab0145707a10ffbcd145ee74df83f432a9ceb06adca8c9105191f0083ad816cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ccebd37ed225c05b29e24a9fc4ead800bdda5a1282d368d3b1e11bf7fac17cacdf8e0bca105a10b5dbfb92b477bbd8da663e83f4064f54ee6cc98186737166e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 05:58:11.021128) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:58:11.025062) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1471523849.40801-1602054311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:58:11.082953) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 05:58:11.083261) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 05:58:11.088016) ========= ========= Total master_lag: 0.98 =========