builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-022 starttime: 1472626359.48 results: success (0) buildid: 20160830223321 builduid: 6cda6d6e93e4438689c319b5caf94e77 revision: de41b9a9ba4fa3d902d7ff9e39b90e83a6133c5c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.480020) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.480420) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.480714) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.504784) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.505071) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 23:52:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.3M=0s 2016-08-30 23:52:40 (40.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122591 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.656720) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.657012) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023023 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.706243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:39.706578) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev de41b9a9ba4fa3d902d7ff9e39b90e83a6133c5c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev de41b9a9ba4fa3d902d7ff9e39b90e83a6133c5c --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=9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 23:52:40,234 truncating revision to first 12 chars 2016-08-30 23:52:40,234 Setting DEBUG logging. 2016-08-30 23:52:40,234 attempt 1/10 2016-08-30 23:52:40,234 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/de41b9a9ba4f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 23:52:40,513 unpacking tar archive at: autoland-de41b9a9ba4f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.566595 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:40.297471) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:40.297983) ========= script_repo_revision: de41b9a9ba4fa3d902d7ff9e39b90e83a6133c5c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:40.299018) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:40.299318) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 23:52:40.312193) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-08-30 23:52:40.312463) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:52:40 INFO - MultiFileLogger online at 20160830 23:52:40 in /builds/slave/test 23:52:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 23:52:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:52:40 INFO - {'append_to_log': False, 23:52:40 INFO - 'base_work_dir': '/builds/slave/test', 23:52:40 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 23:52:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:52:40 INFO - 'branch': 'Autoland-Non-PGO', 23:52:40 INFO - 'buildbot_json_path': 'buildprops.json', 23:52:40 INFO - 'config_files': ('talos/linux_config.py',), 23:52:40 INFO - 'default_actions': ('clobber', 23:52:40 INFO - 'read-buildbot-config', 23:52:40 INFO - 'download-and-extract', 23:52:40 INFO - 'populate-webroot', 23:52:40 INFO - 'create-virtualenv', 23:52:40 INFO - 'install', 23:52:40 INFO - 'run-tests'), 23:52:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:52:40 INFO - 'download_minidump_stackwalk': True, 23:52:40 INFO - 'download_symbols': 'ondemand', 23:52:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:52:40 INFO - 'tooltool.py': '/tools/tooltool.py', 23:52:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:52:40 INFO - '/tools/misc-python/virtualenv.py')}, 23:52:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:52:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:52:40 INFO - 'installer_path': 'installer.exe', 23:52:40 INFO - 'log_level': 'info', 23:52:40 INFO - 'log_name': 'talos', 23:52:40 INFO - 'log_to_console': True, 23:52:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:52:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:52:40 INFO - 'opt_config_files': (), 23:52:40 INFO - 'pip_index': False, 23:52:40 INFO - 'sps_profile': False, 23:52:40 INFO - 'sps_profile_interval': 0, 23:52:40 INFO - 'suite': 'dromaeojs', 23:52:40 INFO - 'system_bits': '32', 23:52:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:52:40 INFO - 'title': 'talos-linux64-ix-022', 23:52:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:52:40 INFO - 'use_talos_json': True, 23:52:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:52:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:52:40 INFO - 'work_dir': 'build'} 23:52:40 INFO - [mozharness: 2016-08-31 06:52:40.931978Z] Running clobber step. 23:52:40 INFO - Running pre-action listener: _resource_record_pre_action 23:52:40 INFO - Running main action method: clobber 23:52:40 INFO - rmtree: /builds/slave/test/build 23:52:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:52:41 INFO - Running post-action listener: _resource_record_post_action 23:52:41 INFO - [mozharness: 2016-08-31 06:52:41.902522Z] Finished clobber step (success) 23:52:41 INFO - [mozharness: 2016-08-31 06:52:41.902622Z] Running read-buildbot-config step. 23:52:41 INFO - Running pre-action listener: _resource_record_pre_action 23:52:41 INFO - Running main action method: read_buildbot_config 23:52:41 INFO - Using buildbot properties: 23:52:41 INFO - { 23:52:41 INFO - "project": "", 23:52:41 INFO - "product": "firefox", 23:52:41 INFO - "script_repo_revision": "production", 23:52:41 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 23:52:41 INFO - "repository": "", 23:52:41 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 23:52:41 INFO - "buildid": "20160830223321", 23:52:41 INFO - "pgo_build": "False", 23:52:41 INFO - "basedir": "/builds/slave/test", 23:52:41 INFO - "buildnumber": 392, 23:52:41 INFO - "slavename": "talos-linux64-ix-022", 23:52:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:52:41 INFO - "platform": "ubuntu64_hw", 23:52:41 INFO - "scheduler": "tests-autoland-linux64-talos", 23:52:41 INFO - "branch": "autoland", 23:52:41 INFO - "repo_path": "integration/autoland", 23:52:41 INFO - "revision": "de41b9a9ba4fa3d902d7ff9e39b90e83a6133c5c", 23:52:41 INFO - "stage_platform": "linux64", 23:52:41 INFO - "builduid": "6cda6d6e93e4438689c319b5caf94e77", 23:52:41 INFO - "slavebuilddir": "test" 23:52:41 INFO - } 23:52:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 23:52:41 INFO - Running post-action listener: _resource_record_post_action 23:52:41 INFO - [mozharness: 2016-08-31 06:52:41.905154Z] Finished read-buildbot-config step (success) 23:52:41 INFO - [mozharness: 2016-08-31 06:52:41.905245Z] Running download-and-extract step. 23:52:41 INFO - Running pre-action listener: _resource_record_pre_action 23:52:41 INFO - Running main action method: download_and_extract 23:52:41 INFO - mkdir: /builds/slave/test/build/tests 23:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:52:41 INFO - trying https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:52:41 INFO - Downloading https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:42 INFO - Downloaded 1361 bytes. 23:52:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:52:42 INFO - Using the following test package requirements: 23:52:42 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:52:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:52:42 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:52:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:52:42 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:52:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:52:42 INFO - u'jsshell-linux-x86_64.zip'], 23:52:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:52:42 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:52:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:52:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:52:42 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:52:42 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:52:42 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:52:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:52:42 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:52:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:52:42 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:52:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:52:42 INFO - trying https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:52:42 INFO - Downloading https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:43 INFO - Downloaded 20326483 bytes. 23:52:43 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 23:52:47 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 23:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:52:47 INFO - trying https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:52:47 INFO - Downloading https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:48 INFO - Downloaded 20326483 bytes. 23:52:48 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 23:52:53 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')]} 23:52:53 INFO - trying https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 23:52:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:54 INFO - Downloaded 14413998 bytes. 23:52:54 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 23:52: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')]} 23:52:54 INFO - trying https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:52:54 INFO - Downloading https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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 23:52:57 INFO - Downloaded 55861207 bytes. 23:52:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:52:57 INFO - mkdir: /builds/slave/test/properties 23:52:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:52:57 INFO - Writing to file /builds/slave/test/properties/build_url 23:52:57 INFO - Contents: 23:52:57 INFO - build_url:https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:52:57 INFO - Running post-action listener: _resource_record_post_action 23:52:57 INFO - Running post-action listener: set_extra_try_arguments 23:52:57 INFO - [mozharness: 2016-08-31 06:52:57.709669Z] Finished download-and-extract step (success) 23:52:57 INFO - [mozharness: 2016-08-31 06:52:57.709896Z] Running populate-webroot step. 23:52:57 INFO - Running pre-action listener: _resource_record_pre_action 23:52:57 INFO - Running main action method: populate_webroot 23:52:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:52:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:52:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:52:57 INFO - '--tppagecycles', 23:52:57 INFO - '7'], 23:52:57 INFO - 'tests': ['tsvgm']}}, 23:52:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:52:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:52:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:52:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:52:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:57 INFO - 'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:52:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:52:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:57 INFO - 'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['damp', 'tps']}, 23:52:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:57 INFO - 'tests': ['damp', 'tps']}, 23:52:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['dromaeo_dom']}, 23:52:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:52:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['basic_compositor_video']}, 23:52:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:52:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other-e10s': {'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['tsvgx', 23:52:57 INFO - 'tsvgr_opacity', 23:52:57 INFO - 'tart', 23:52:57 INFO - 'tscrollx', 23:52:57 INFO - 'cart']}, 23:52:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:52:57 INFO - 'tsvgr_opacity', 23:52:57 INFO - 'tart', 23:52:57 INFO - 'tscrollx', 23:52:57 INFO - 'cart']}, 23:52:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:57 INFO - 'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['tp5o']}, 23:52:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:57 INFO - 'tests': ['tp5o']}, 23:52:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:57 INFO - 'talos_options': ['--disable-e10s', 23:52:57 INFO - '--xperf_path', 23:52:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:52:57 INFO - 'tests': ['tp5n']}, 23:52:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:57 INFO - 'talos_options': ['--xperf_path', 23:52:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:52:57 INFO - 'tests': ['tp5n']}}, 23:52:57 INFO - 'talos.zip': {'path': '', 23:52:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:52:57 INFO - Running post-action listener: _resource_record_post_action 23:52:57 INFO - [mozharness: 2016-08-31 06:52:57.729213Z] Finished populate-webroot step (success) 23:52:57 INFO - [mozharness: 2016-08-31 06:52:57.729303Z] Running create-virtualenv step. 23:52:57 INFO - Running pre-action listener: _resource_record_pre_action 23:52:57 INFO - Running main action method: create_virtualenv 23:52:57 INFO - Creating virtualenv /builds/slave/test/build/venv 23:52:57 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 23:52:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:52:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:52:57 INFO - Using real prefix '/usr' 23:52:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:52:58 INFO - Installing distribute.............................................................................................................................................................................................done. 23:53:00 INFO - Installing pip.................done. 23:53:00 INFO - Return code: 0 23:53:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:53: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')]} 23:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53: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')]} 23:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:00 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:00 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 23:53:00 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 23:53:00 INFO - Using env: {'DISPLAY': ':0', 23:53:00 INFO - 'HOME': '/home/cltbld', 23:53:00 INFO - 'LANG': 'en_US.UTF-8', 23:53:00 INFO - 'LANGUAGE': 'en_US:en', 23:53:00 INFO - 'LOGNAME': 'cltbld', 23:53:00 INFO - 'MAIL': '/var/mail/cltbld', 23:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:00 INFO - 'MOZ_NO_REMOTE': '1', 23:53:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:00 INFO - 'NO_EM_RESTART': '1', 23:53:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:00 INFO - 'PWD': '/builds/slave/test', 23:53:00 INFO - 'SHELL': '/bin/bash', 23:53:00 INFO - 'SHLVL': '1', 23:53:00 INFO - 'TERM': 'linux', 23:53:00 INFO - 'TMOUT': '86400', 23:53:00 INFO - 'USER': 'cltbld', 23:53:00 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:00 INFO - '_': '/tools/buildbot/bin/python'} 23:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:00 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 23:53:00 INFO - Cleaning up... 23:53:00 INFO - Return code: 0 23:53:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:53: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')]} 23:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53: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')]} 23:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:00 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:00 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 23:53:00 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 23:53:00 INFO - Using env: {'DISPLAY': ':0', 23:53:00 INFO - 'HOME': '/home/cltbld', 23:53:00 INFO - 'LANG': 'en_US.UTF-8', 23:53:00 INFO - 'LANGUAGE': 'en_US:en', 23:53:00 INFO - 'LOGNAME': 'cltbld', 23:53:00 INFO - 'MAIL': '/var/mail/cltbld', 23:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:00 INFO - 'MOZ_NO_REMOTE': '1', 23:53:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:00 INFO - 'NO_EM_RESTART': '1', 23:53:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:00 INFO - 'PWD': '/builds/slave/test', 23:53:00 INFO - 'SHELL': '/bin/bash', 23:53:00 INFO - 'SHLVL': '1', 23:53:00 INFO - 'TERM': 'linux', 23:53:00 INFO - 'TMOUT': '86400', 23:53:00 INFO - 'USER': 'cltbld', 23:53:00 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:00 INFO - '_': '/tools/buildbot/bin/python'} 23:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:00 INFO - Downloading/unpacking psutil>=3.1.1 23:53:00 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 23:53:00 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 23:53:00 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 23:53:00 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 23:53:00 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 23:53:00 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 23:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:53:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:53:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:53:02 INFO - Installing collected packages: psutil 23:53:02 INFO - Running setup.py install for psutil 23:53:02 INFO - building 'psutil._psutil_linux' extension 23:53:02 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 23:53:02 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 23:53:03 INFO - building 'psutil._psutil_posix' extension 23:53:03 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 23:53:03 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 23:53:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:53:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:53:03 INFO - Successfully installed psutil 23:53:03 INFO - Cleaning up... 23:53:03 INFO - Return code: 0 23:53:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:53: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')]} 23:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53: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')]} 23:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:03 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:03 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 23:53:03 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 23:53:03 INFO - Using env: {'DISPLAY': ':0', 23:53:03 INFO - 'HOME': '/home/cltbld', 23:53:03 INFO - 'LANG': 'en_US.UTF-8', 23:53:03 INFO - 'LANGUAGE': 'en_US:en', 23:53:03 INFO - 'LOGNAME': 'cltbld', 23:53:03 INFO - 'MAIL': '/var/mail/cltbld', 23:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:03 INFO - 'MOZ_NO_REMOTE': '1', 23:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:03 INFO - 'NO_EM_RESTART': '1', 23:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:03 INFO - 'PWD': '/builds/slave/test', 23:53:03 INFO - 'SHELL': '/bin/bash', 23:53:03 INFO - 'SHLVL': '1', 23:53:03 INFO - 'TERM': 'linux', 23:53:03 INFO - 'TMOUT': '86400', 23:53:03 INFO - 'USER': 'cltbld', 23:53:03 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:03 INFO - '_': '/tools/buildbot/bin/python'} 23:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:53:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:53:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:53:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:53:05 INFO - Installing collected packages: mozsystemmonitor 23:53:05 INFO - Running setup.py install for mozsystemmonitor 23:53:05 INFO - Successfully installed mozsystemmonitor 23:53:05 INFO - Cleaning up... 23:53:05 INFO - Return code: 0 23:53:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:53:05 INFO - Using env: {'DISPLAY': ':0', 23:53:05 INFO - 'HOME': '/home/cltbld', 23:53:05 INFO - 'LANG': 'en_US.UTF-8', 23:53:05 INFO - 'LANGUAGE': 'en_US:en', 23:53:05 INFO - 'LOGNAME': 'cltbld', 23:53:05 INFO - 'MAIL': '/var/mail/cltbld', 23:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:05 INFO - 'MOZ_NO_REMOTE': '1', 23:53:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:05 INFO - 'NO_EM_RESTART': '1', 23:53:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:05 INFO - 'PWD': '/builds/slave/test', 23:53:05 INFO - 'SHELL': '/bin/bash', 23:53:05 INFO - 'SHLVL': '1', 23:53:05 INFO - 'TERM': 'linux', 23:53:05 INFO - 'TMOUT': '86400', 23:53:05 INFO - 'USER': 'cltbld', 23:53:05 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:05 INFO - '_': '/tools/buildbot/bin/python'} 23:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:05 INFO - Downloading/unpacking jsonschema==2.5.1 23:53: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 23:53: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 23:53: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 23:53: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 23:53: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 23:53: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 23:53:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:53:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:53:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:53:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:53:07 INFO - Downloading functools32-3.2.3-2.tar.gz 23:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:53:07 INFO - warning: no files found matching '*.txt' 23:53:07 INFO - no previously-included directories found matching 'build' 23:53:07 INFO - no previously-included directories found matching 'dist' 23:53:07 INFO - no previously-included directories found matching '.git*' 23:53:08 INFO - Installing collected packages: jsonschema, functools32 23:53:08 INFO - Running setup.py install for functools32 23:53:08 INFO - warning: no files found matching '*.txt' 23:53:08 INFO - no previously-included directories found matching 'build' 23:53:08 INFO - no previously-included directories found matching 'dist' 23:53:08 INFO - no previously-included directories found matching '.git*' 23:53:08 INFO - Successfully installed jsonschema functools32 23:53:08 INFO - Cleaning up... 23:53:08 INFO - Return code: 0 23:53:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:08 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:08 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 23:53:08 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 23:53:08 INFO - Using env: {'DISPLAY': ':0', 23:53:08 INFO - 'HOME': '/home/cltbld', 23:53:08 INFO - 'LANG': 'en_US.UTF-8', 23:53:08 INFO - 'LANGUAGE': 'en_US:en', 23:53:08 INFO - 'LOGNAME': 'cltbld', 23:53:08 INFO - 'MAIL': '/var/mail/cltbld', 23:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:08 INFO - 'MOZ_NO_REMOTE': '1', 23:53:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:08 INFO - 'NO_EM_RESTART': '1', 23:53:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:08 INFO - 'PWD': '/builds/slave/test', 23:53:08 INFO - 'SHELL': '/bin/bash', 23:53:08 INFO - 'SHLVL': '1', 23:53:08 INFO - 'TERM': 'linux', 23:53:08 INFO - 'TMOUT': '86400', 23:53:08 INFO - 'USER': 'cltbld', 23:53:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:08 INFO - '_': '/tools/buildbot/bin/python'} 23:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:53:08 INFO - Cleaning up... 23:53:08 INFO - Return code: 0 23:53:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:08 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:08 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 23:53:08 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 23:53:08 INFO - Using env: {'DISPLAY': ':0', 23:53:08 INFO - 'HOME': '/home/cltbld', 23:53:08 INFO - 'LANG': 'en_US.UTF-8', 23:53:08 INFO - 'LANGUAGE': 'en_US:en', 23:53:08 INFO - 'LOGNAME': 'cltbld', 23:53:08 INFO - 'MAIL': '/var/mail/cltbld', 23:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:08 INFO - 'MOZ_NO_REMOTE': '1', 23:53:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:08 INFO - 'NO_EM_RESTART': '1', 23:53:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:08 INFO - 'PWD': '/builds/slave/test', 23:53:08 INFO - 'SHELL': '/bin/bash', 23:53:08 INFO - 'SHLVL': '1', 23:53:08 INFO - 'TERM': 'linux', 23:53:08 INFO - 'TMOUT': '86400', 23:53:08 INFO - 'USER': 'cltbld', 23:53:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:08 INFO - '_': '/tools/buildbot/bin/python'} 23:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:08 INFO - Downloading/unpacking blobuploader==1.2.4 23:53:08 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 23:53:08 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 23:53:08 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 23:53:08 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 23:53:08 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 23:53:08 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 23:53:10 INFO - Downloading blobuploader-1.2.4.tar.gz 23:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:53:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:53:10 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 23:53:10 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 23:53:10 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 23:53:10 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 23:53:10 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 23:53:10 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 23:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:53:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:53:10 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 23:53:10 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 23:53:10 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 23:53:10 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 23:53:10 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 23:53:10 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 23:53:11 INFO - Downloading docopt-0.6.1.tar.gz 23:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:53:11 INFO - Installing collected packages: blobuploader, requests, docopt 23:53:11 INFO - Running setup.py install for blobuploader 23:53:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:53:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:53:11 INFO - Running setup.py install for requests 23:53:11 INFO - Running setup.py install for docopt 23:53:11 INFO - Successfully installed blobuploader requests docopt 23:53:11 INFO - Cleaning up... 23:53:11 INFO - Return code: 0 23:53:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:53:11 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')]} 23:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:11 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')]} 23:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:11 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:11 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 23:53:11 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 23:53:11 INFO - Using env: {'DISPLAY': ':0', 23:53:11 INFO - 'HOME': '/home/cltbld', 23:53:11 INFO - 'LANG': 'en_US.UTF-8', 23:53:11 INFO - 'LANGUAGE': 'en_US:en', 23:53:11 INFO - 'LOGNAME': 'cltbld', 23:53:11 INFO - 'MAIL': '/var/mail/cltbld', 23:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:11 INFO - 'MOZ_NO_REMOTE': '1', 23:53:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:11 INFO - 'NO_EM_RESTART': '1', 23:53:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:11 INFO - 'PWD': '/builds/slave/test', 23:53:11 INFO - 'SHELL': '/bin/bash', 23:53:11 INFO - 'SHLVL': '1', 23:53:11 INFO - 'TERM': 'linux', 23:53:11 INFO - 'TMOUT': '86400', 23:53:11 INFO - 'USER': 'cltbld', 23:53:11 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:11 INFO - '_': '/tools/buildbot/bin/python'} 23:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:53:12 INFO - Running setup.py (path:/tmp/pip-bhkogo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:53:12 INFO - Running setup.py (path:/tmp/pip-1lkTzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:53:12 INFO - Running setup.py (path:/tmp/pip-QUPmJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:53:12 INFO - Running setup.py (path:/tmp/pip-wEXwhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:53:12 INFO - Running setup.py (path:/tmp/pip-W02x1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:53:12 INFO - Running setup.py (path:/tmp/pip-l5BIAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:53:12 INFO - Running setup.py (path:/tmp/pip-A_pHVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:53:12 INFO - Running setup.py (path:/tmp/pip-c4V_un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:53:12 INFO - Running setup.py (path:/tmp/pip-ZVrfv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:53:12 INFO - Running setup.py (path:/tmp/pip-y6XBW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:53:12 INFO - Running setup.py (path:/tmp/pip-FcneZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:53:12 INFO - Running setup.py (path:/tmp/pip-QmxoPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:53:12 INFO - Running setup.py (path:/tmp/pip-gmnYMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:53:13 INFO - Running setup.py (path:/tmp/pip-KPHauC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:53:13 INFO - Running setup.py (path:/tmp/pip-_Ulg4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:53:13 INFO - Running setup.py (path:/tmp/pip-5djLXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:53:13 INFO - Running setup.py (path:/tmp/pip-p65M3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:53:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:53:13 INFO - Running setup.py install for manifestparser 23:53:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:53:13 INFO - Running setup.py install for mozcrash 23:53:13 INFO - Running setup.py install for mozdebug 23:53:13 INFO - Running setup.py install for mozdevice 23:53:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:53:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:53:13 INFO - Running setup.py install for mozfile 23:53:13 INFO - Running setup.py install for mozhttpd 23:53:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:53:13 INFO - Running setup.py install for mozinfo 23:53:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Running setup.py install for mozInstall 23:53:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Running setup.py install for mozleak 23:53:14 INFO - Running setup.py install for mozlog 23:53:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Running setup.py install for moznetwork 23:53:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Running setup.py install for mozprocess 23:53:14 INFO - Running setup.py install for mozprofile 23:53:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Running setup.py install for mozrunner 23:53:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:53:14 INFO - Running setup.py install for mozscreenshot 23:53:14 INFO - Running setup.py install for moztest 23:53:14 INFO - Running setup.py install for mozversion 23:53:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:53:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:53:15 INFO - Cleaning up... 23:53:15 INFO - Return code: 0 23:53:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:53:15 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')]} 23:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:15 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')]} 23:53:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:15 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:15 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 23:53:15 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 23:53:15 INFO - Using env: {'DISPLAY': ':0', 23:53:15 INFO - 'HOME': '/home/cltbld', 23:53:15 INFO - 'LANG': 'en_US.UTF-8', 23:53:15 INFO - 'LANGUAGE': 'en_US:en', 23:53:15 INFO - 'LOGNAME': 'cltbld', 23:53:15 INFO - 'MAIL': '/var/mail/cltbld', 23:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:15 INFO - 'MOZ_NO_REMOTE': '1', 23:53:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:15 INFO - 'NO_EM_RESTART': '1', 23:53:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:15 INFO - 'PWD': '/builds/slave/test', 23:53:15 INFO - 'SHELL': '/bin/bash', 23:53:15 INFO - 'SHLVL': '1', 23:53:15 INFO - 'TERM': 'linux', 23:53:15 INFO - 'TMOUT': '86400', 23:53:15 INFO - 'USER': 'cltbld', 23:53:15 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:15 INFO - '_': '/tools/buildbot/bin/python'} 23:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:53:15 INFO - Running setup.py (path:/tmp/pip-1UQXV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:53:15 INFO - Running setup.py (path:/tmp/pip-Z0V2DP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:53:15 INFO - Running setup.py (path:/tmp/pip-1UWtiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:53:15 INFO - Running setup.py (path:/tmp/pip-1WOrFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:53:15 INFO - Running setup.py (path:/tmp/pip-IYLhsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:53:15 INFO - Running setup.py (path:/tmp/pip-YZwcFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:53:15 INFO - Running setup.py (path:/tmp/pip-413Zwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:53:15 INFO - Running setup.py (path:/tmp/pip-Dqo9v5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:53:15 INFO - Running setup.py (path:/tmp/pip-VcnbbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:53:15 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)) 23:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:53:15 INFO - Running setup.py (path:/tmp/pip-0Z__4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:53:16 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)) 23:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:53:16 INFO - Running setup.py (path:/tmp/pip-Pw6lG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:53:16 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)) 23:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:53:16 INFO - Running setup.py (path:/tmp/pip-bfi7dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:53:16 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)) 23:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:53:16 INFO - Running setup.py (path:/tmp/pip-Fbimec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:53:16 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)) 23:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:53:16 INFO - Running setup.py (path:/tmp/pip-NgJjKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:53:16 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)) 23:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:53:16 INFO - Running setup.py (path:/tmp/pip-OkNL4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:53:16 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)) 23:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:53:16 INFO - Running setup.py (path:/tmp/pip-FpkrwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:53:16 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)) 23:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:53:16 INFO - Running setup.py (path:/tmp/pip-Y71kgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:53:16 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)) 23:53:16 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)) 23:53:16 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)) 23:53:16 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)) 23:53:16 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)) 23:53:16 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)) 23:53:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:53:16 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 23:53:16 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 23:53:16 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 23:53:16 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 23:53:16 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 23:53:16 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 23:53:18 INFO - Downloading blessings-1.6.tar.gz 23:53:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:53:18 INFO - Installing collected packages: blessings 23:53:18 INFO - Running setup.py install for blessings 23:53:18 INFO - Successfully installed blessings 23:53:18 INFO - Cleaning up... 23:53:18 INFO - Return code: 0 23:53:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:53:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:53:18 INFO - Reading from file tmpfile_stdout 23:53:18 INFO - Current package versions: 23:53:18 INFO - argparse == 1.2.1 23:53:18 INFO - blessings == 1.6 23:53:18 INFO - blobuploader == 1.2.4 23:53:18 INFO - docopt == 0.6.1 23:53:18 INFO - functools32 == 3.2.3-2 23:53:18 INFO - jsonschema == 2.5.1 23:53:18 INFO - manifestparser == 1.1 23:53:18 INFO - mozInstall == 1.12 23:53:18 INFO - mozcrash == 0.17 23:53:18 INFO - mozdebug == 0.1 23:53:18 INFO - mozdevice == 0.48 23:53:18 INFO - mozfile == 1.2 23:53:18 INFO - mozhttpd == 0.7 23:53:18 INFO - mozinfo == 0.9 23:53:18 INFO - mozleak == 0.1 23:53:18 INFO - mozlog == 3.3 23:53:18 INFO - moznetwork == 0.27 23:53:18 INFO - mozprocess == 0.23 23:53:18 INFO - mozprofile == 0.28 23:53:18 INFO - mozrunner == 6.12 23:53:18 INFO - mozscreenshot == 0.1 23:53:18 INFO - mozsystemmonitor == 0.3 23:53:18 INFO - moztest == 0.7 23:53:18 INFO - mozversion == 1.4 23:53:18 INFO - psutil == 3.1.1 23:53:18 INFO - requests == 1.2.3 23:53:18 INFO - wsgiref == 0.1.2 23:53:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:53:18 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')]} 23:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:18 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')]} 23:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:18 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:18 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 23:53:18 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 23:53:18 INFO - Using env: {'DISPLAY': ':0', 23:53:18 INFO - 'HOME': '/home/cltbld', 23:53:18 INFO - 'LANG': 'en_US.UTF-8', 23:53:18 INFO - 'LANGUAGE': 'en_US:en', 23:53:18 INFO - 'LOGNAME': 'cltbld', 23:53:18 INFO - 'MAIL': '/var/mail/cltbld', 23:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:18 INFO - 'MOZ_NO_REMOTE': '1', 23:53:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:18 INFO - 'NO_EM_RESTART': '1', 23:53:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:18 INFO - 'PWD': '/builds/slave/test', 23:53:18 INFO - 'SHELL': '/bin/bash', 23:53:18 INFO - 'SHLVL': '1', 23:53:18 INFO - 'TERM': 'linux', 23:53:18 INFO - 'TMOUT': '86400', 23:53:18 INFO - 'USER': 'cltbld', 23:53:18 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:18 INFO - '_': '/tools/buildbot/bin/python'} 23:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 INFO - Cleaning up... 23:53:19 INFO - Return code: 0 23:53:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:53:19 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')]} 23:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:19 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')]} 23:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:53:19 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 0x7f033a80ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2668230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', '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 23:53:19 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 23:53:19 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 23:53:19 INFO - Using env: {'DISPLAY': ':0', 23:53:19 INFO - 'HOME': '/home/cltbld', 23:53:19 INFO - 'LANG': 'en_US.UTF-8', 23:53:19 INFO - 'LANGUAGE': 'en_US:en', 23:53:19 INFO - 'LOGNAME': 'cltbld', 23:53:19 INFO - 'MAIL': '/var/mail/cltbld', 23:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:19 INFO - 'MOZ_NO_REMOTE': '1', 23:53:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:19 INFO - 'NO_EM_RESTART': '1', 23:53:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:19 INFO - 'PWD': '/builds/slave/test', 23:53:19 INFO - 'SHELL': '/bin/bash', 23:53:19 INFO - 'SHLVL': '1', 23:53:19 INFO - 'TERM': 'linux', 23:53:19 INFO - 'TMOUT': '86400', 23:53:19 INFO - 'USER': 'cltbld', 23:53:19 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:19 INFO - '_': '/tools/buildbot/bin/python'} 23:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:53:19 INFO - Cleaning up... 23:53:19 INFO - Return code: 0 23:53:19 INFO - Running post-action listener: _resource_record_post_action 23:53:19 INFO - Running post-action listener: _start_resource_monitoring 23:53:19 INFO - Starting resource monitoring. 23:53:19 INFO - [mozharness: 2016-08-31 06:53:19.350316Z] Finished create-virtualenv step (success) 23:53:19 INFO - [mozharness: 2016-08-31 06:53:19.350822Z] Running install step. 23:53:19 INFO - Running pre-action listener: _resource_record_pre_action 23:53:19 INFO - Running main action method: install 23:53:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:53:19 INFO - Reading from file tmpfile_stdout 23:53:19 INFO - Detecting whether we're running mozinstall >=1.0... 23:53:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:53:19 INFO - Reading from file tmpfile_stdout 23:53:19 INFO - Output received: 23:53:19 INFO - Usage: mozinstall [options] installer 23:53:19 INFO - Options: 23:53:19 INFO - -h, --help show this help message and exit 23:53:19 INFO - -d DEST, --destination=DEST 23:53:19 INFO - Directory to install application into. [default: 23:53:19 INFO - "/builds/slave/test"] 23:53:19 INFO - --app=APP Application being installed. [default: firefox] 23:53:19 INFO - mkdir: /builds/slave/test/build/application 23:53:19 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'] 23:53:19 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 23:53:35 INFO - Reading from file tmpfile_stdout 23:53:35 INFO - Output received: 23:53:35 INFO - /builds/slave/test/build/application/firefox/firefox 23:53:35 INFO - Running post-action listener: _resource_record_post_action 23:53:35 INFO - [mozharness: 2016-08-31 06:53:35.791123Z] Finished install step (success) 23:53:35 INFO - [mozharness: 2016-08-31 06:53:35.791471Z] Running run-tests step. 23:53:35 INFO - Running pre-action listener: _resource_record_pre_action 23:53:35 INFO - Running main action method: run_tests 23:53:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:53:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:53:35 INFO - Python 2.7.3 23:53:35 INFO - Return code: 0 23:53:35 INFO - grabbing minidump binary from tooltool 23:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:35 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 0x2662090>, '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 0x26659b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2665e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:53:35 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 23:53:35 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 23:53:35 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 23:53:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:53:35 INFO - Return code: 0 23:53:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:53:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:53:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:53:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:53:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:53:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 23:53:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 23:53:35 INFO - Using env: {'DISPLAY': ':0', 23:53:35 INFO - 'HOME': '/home/cltbld', 23:53:35 INFO - 'LANG': 'en_US.UTF-8', 23:53:35 INFO - 'LANGUAGE': 'en_US:en', 23:53:35 INFO - 'LOGNAME': 'cltbld', 23:53:35 INFO - 'MAIL': '/var/mail/cltbld', 23:53:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:53:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:35 INFO - 'MOZ_NO_REMOTE': '1', 23:53:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:53:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:53:35 INFO - 'NO_EM_RESTART': '1', 23:53:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:35 INFO - 'PWD': '/builds/slave/test', 23:53:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:53:35 INFO - 'SHELL': '/bin/bash', 23:53:35 INFO - 'SHLVL': '1', 23:53:35 INFO - 'TERM': 'linux', 23:53:35 INFO - 'TMOUT': '86400', 23:53:35 INFO - 'USER': 'cltbld', 23:53:35 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178', 23:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:35 INFO - '_': '/tools/buildbot/bin/python'} 23:53:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 23:53:36 INFO - mozversion application_buildid: 20160830223321 23:53:36 INFO - mozversion application_changeset: de41b9a9ba4fa3d902d7ff9e39b90e83a6133c5c 23:53:36 INFO - mozversion application_display_name: Nightly 23:53:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:53:36 INFO - mozversion application_name: Firefox 23:53:36 INFO - mozversion application_remotingname: firefox 23:53:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:53:36 INFO - mozversion application_vendor: Mozilla 23:53:36 INFO - mozversion application_version: 51.0a1 23:53:36 INFO - mozversion platform_buildid: 20160830223321 23:53:36 INFO - mozversion platform_changeset: de41b9a9ba4fa3d902d7ff9e39b90e83a6133c5c 23:53:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:53:36 INFO - mozversion platform_version: 51.0a1 23:53:36 INFO - using testdate: 1472626416 23:53:36 INFO - actual date: 1472626416 23:53:36 INFO - starting webserver on 'localhost:48569' 23:53:36 INFO - SUITE-START | Running 2 tests 23:53:36 INFO - TEST-START | dromaeo_css 23:53:36 INFO - operating with platform_type : linux_ 23:53:36 INFO - Initialising browser for dromaeo_css test... 23:53:36 INFO - TEST-INFO | started process 15240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJx0OG0/profile http://localhost:48569/getInfo.html) 23:53:40 INFO - PROCESS | 15240 | __metrics Screen width/height:1600/1200 23:53:40 INFO - PROCESS | 15240 | colorDepth:24 23:53:40 INFO - PROCESS | 15240 | Browser inner width/height: 1024/697 23:53:40 INFO - PROCESS | 15240 | __metrics 23:53:41 INFO - PROCESS | 15240 | 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" 23:53:41 INFO - PROCESS | 15240 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:53:41 INFO - PROCESS | 15240 | console.error: PushService: 23:53:42 INFO - PROCESS | 15240 | stateChangeProcessEnqueue: Error transitioning state 23:53:42 INFO - PROCESS | 15240 | UnknownError 23:53:42 INFO - TEST-INFO | 15240: exit 0 23:53:42 INFO - Browser initialized. 23:53:42 INFO - Running cycle 1/1 for dromaeo_css test... 23:53:42 INFO - TEST-INFO | started process 15321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJx0OG0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:53:54 INFO - PROCESS | 15321 | RSS: Main: 181813248 23:53:54 INFO - PROCESS | 15321 | 23:54:46 INFO - PROCESS | 15321 | 1472626486220 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] 23:56:58 INFO - PROCESS | 15321 | Cycle 1(1): loaded http://localhost:48569/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48569/tests/dromaeo/cssquery-ext.html) 23:57:00 INFO - PROCESS | 15321 | RSS: Main: 307978240 23:57:00 INFO - PROCESS | 15321 | 00:00:03 INFO - PROCESS | 15321 | Cycle 1(1): loaded http://localhost:48569/tests/dromaeo/cssquery-ext.html (next: http://localhost:48569/tests/dromaeo/cssquery-jquery.html) 00:00:03 INFO - PROCESS | 15321 | RSS: Main: 274436096 00:00:03 INFO - PROCESS | 15321 | 00:03:06 INFO - PROCESS | 15321 | Cycle 1(1): loaded http://localhost:48569/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48569/tests/dromaeo/cssquery-mootools.html) 00:03:06 INFO - PROCESS | 15321 | RSS: Main: 220459008 00:03:06 INFO - PROCESS | 15321 | 00:06:09 INFO - PROCESS | 15321 | Cycle 1(1): loaded http://localhost:48569/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48569/tests/dromaeo/cssquery-prototype.html) 00:06:09 INFO - PROCESS | 15321 | RSS: Main: 219697152 00:06:09 INFO - PROCESS | 15321 | 00:09:12 INFO - PROCESS | 15321 | Cycle 1(1): loaded http://localhost:48569/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48569/tests/dromaeo/cssquery-yui.html) 00:09:13 INFO - PROCESS | 15321 | RSS: Main: 219635712 00:09:13 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | Cycle 1(1): loaded http://localhost:48569/tests/dromaeo/cssquery-yui.html (next: http://localhost:48569/tests/dromaeo/cssquery-dojo.html) 00:12:16 INFO - PROCESS | 15321 | RSS: Main: 308232192 00:12:16 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | __start_tp_report 00:12:16 INFO - PROCESS | 15321 | _x_x_mozilla_page_load 00:12:16 INFO - PROCESS | 15321 | _x_x_mozilla_page_load_details 00:12:16 INFO - PROCESS | 15321 | |i|pagename|runs| 00:12:16 INFO - PROCESS | 15321 | |0;dojo.html;2582.5867861142196;2589.3656054266703;2605.7394260573947;2637.5120602688503;2646.801489888259;4522.118186953538;4592.632589392849;4596.770161491924;4663.1839428100075;4718.598919091877;4620.630349572035;4621.58405743483;4631.8147274109;4651.441826980758;4690.460597031334;4221.113566151112;4254.787260636971;4293.098449325646;4356.695031347807;4459.576340247671;4366.781660916958;4392.231359512072;4405.933910991338;4419.756913369763;4436.600705936465;4184.28867092595;4223.1975924574235;4241.554636763126;4293.334533147367;4309.202797482444;4252.043290259698;4271.530131685512;4271.8077686503875;4362.345648152803;4373.037931655031;4276.337167739005;4282.015136518587;4298.828046878121;4328.696991210617;4350.8912277193;4536.818527258905;4553.590176884063;4620.630349572026;4672.626189904833;4716.127516409475;4905.877353066202;4926.0394223126505;4932.309476673233;4936.703797772145;5014.222795466709;4921.753912304371;4933.876653083666;4954.975225123887;4959.528844759742;4992.151334273182;4568.063546972929;4610.515895830957;4615.2384856498975;4632.97683511587;4663.440387153563;5117.539421452013;5117.5394214520875;5141.948580514222;5162.793488260502;5188.247704082887;4907.705537667752;4908.435529914105;4918.827841025547;4920.6309526785635;4976.2286845539;4908.410990681141;4922.581580565692;4970.502949705001;4986.351774269322;5008.1486147355035;4959.776810043557;4960.627952903546;4961.05739909416;4984.626153038536;5007.324011258506;4941.950580494221;4951.059298733229;4951.084049450863;4953.6037117030555;5007.223880298559;4118.5057793065025;4162.458880345596;4165.500139983161;4244.172386384687;4291.184674911757;3713.313037088147;3723.8696645617274;3736.5142531470733;3736.869209577652;3740.3080430120513;4196.391523229115;4200.28595138821;4227.196832601796;4240.660747140221;4290.635295999875;4925.9014819704125;4926.926096108561;4930.654854160174;4982.7508624569355;4991.775370108354;4234.195502854509;4237.978810105929;4239.851605193803;4284.592963668447;4285.271503844354;4362.520122786491;4420.778961051935;4422.402975598253;4438.201123797748;4492.124035813051;4665.790039448165;4668.066386722601;4671.953280467152;4715.858524244278;4719.882002949885;4916.8770780730765;4958.801647934042;4959.429665588504;4964.3298154748645;5024.145895197898;4621.953780462152;4631.189541830099;4633.559811817872;4662.114198302312;4664.766761661971;4909.042736666459;4933.55597996182;4936.605071594336;4982.252662100714;4997.3253610762085;4987.975060124676;4997.925031124463;5000.099982003274;5005.549500544968;5069.391672999116;4689.366935463669;4724.385829842236;4737.076270127292;4862.465128835896;4931.457539670705;4795.736234507136;4803.855884323476;4804.25534042224;4810.446798618063;4816.975915120402;4689.859304220879;4697.530246975275;4719.05618876233;4791.9281210782565;4806.2310030394965;4823.975880120577;4825.30033145199;4835.105505481497;4844.103840789465;4896.6327525436445;4737.9052418952115;4750.5249475052215;4751.382320298339;4751.976240118777;4753.168195565693 00:12:16 INFO - PROCESS | 15321 | |1;ext.html;549.8625343664085;556.7661582135507;558.0346001417547;569.0042425754929;573.2347316332696;27811.165665030057;38073;39300.41049384262;39463.210735785266;43300;39999.80000099995;40276;40400.79799601005;40409.39385909214;40417;7539.773806785792;7727.227277272284;7727.995360603142;7735.187805280449;7735.4585179037485;7610;7694;7701.998740163771;7703.114141873672;7724.575148366837;7673.23267673231;7710.807229819271;7716.151223365426;7724.188960159158;7726.227377262257;7665.731699390522;7699.846003079963;7701.306882380557;7709.653065612061;7713.49862258957;7620.466567340289;7683.039620047494;7690.5770182639935;7690.653920573588;7691.769246922601;37936.62063379359;38285.42571861424;38289.042773930596;38333.80833095845;38338.23323533514;2661.733826617342;2703;2753.600727894444;2787.707290734484;3082.8920987765546;28356.574651380244;28391.290217744514;28427.431451371063;28662.426751464853;28881.71118288832;3325.068980685412;3331.7358573453885;3381.9830900845586;3409.4203985322556;3429.416999110157;249303;250744.99999999817;251619;252621;253688;53559;53952;53980.73009634927;54045.1893221602;54045.45954540404;47039.529604703515;47377;47823.282650760266;48413.27380089302;57360.71319643375;13357.866421335857;13362.465501380031;13382.197068175941;13422.530211442749;13620.114692544952;10114.039166279192;10122.291439599157;10133;10133;10139.391636501834;15631.765523517157;17395;17908.46274611764;17946.730799037763;17949.102544872705;6795.830104247335;6828.970907164797;6929.341712537301;6968.95465680152;6969.1985421676145;758.6396461680657;805.715795719131;824.814416756237;836.7364280251701;944.0182210501139;26289.73710262911;26999.190024299303;27463;27486.862565687043;27904.44191116207;846.4498076250486;872.075599864146;877.591919757316;906.6943601214231;944.390867890207;13320.733585328433;14367.425302987764;14431.96845947733;14672.706545869236;14707.55877323682;402235;405646;406324;406480;406544.6727663605;48947.51052489429;49383.50616493861;49400.75299623479;49413;49427.50572494301;1143.5711608146944;1155.6013175454455;1157.3750174905456;1172.038928078967;1177.5054477119631;25877;26468.338291542867;26474.470510589294;26751;27211.59182612302;42405;42949;42977.3553396693;42982;43017;350684;353924;355127;355321;355362;21122.788772112697;21492;25344.366390840376;25596;25672.743272567037;40851.59148408597;41172.588274116875;41235.81462780607;41252.38121428121;41743.373849391675;34693;35190.64809351976;35337.82331088328;35561.82219088888;35993.10017249485;34913.30173396569;35207.47188792221;35264.647353527165;35454;35624.46563301604;34535;34809.65190348165;34980.47529287012;35942.281154376244;37927.81036094802;28182.43635127327;30498.390032199677;30534.08397748071;30845.845770771004;30995;24933.376665583506;25169.748302516742;25215.49569008573;25503.24273478929;25595 00:12:16 INFO - PROCESS | 15321 | |2;jquery.html;326.9051974927271;345.16297977404804;350.6703698523388;354.28257777999545;373.2479054704769;12046;15805.130717810516;16161.75757363639;17756.733648995276;17917.7312340315;13775.65560860979;13942.930285348584;15717.764233536505;16105.436309729163;16225.188740562984;4299.978500107495;4442.4224850769515;4445.622122119607;5022.522860328263;5268;3351.7151042161445;5326.334208223972;5355.437679043703;5357.4374690657505;5359.624826262163;5018.623603229754;5246.711430871301;5291.047611429941;5345.946540534584;5349.866253343678;5162.92255616166;5328.653637513549;5336.092864213074;5338.919916201241;5340.172273297645;5196.896062078775;5201.167813149916;5201.24581935621;5202.791888324463;5207.791688332462;11469.713257168554;11581.76836463266;11618.767624647544;11654.825177622342;11712.824307635392;1734.4363081998274;1783.8305360990814;1810.2849374497;1911.4170178095676;2390.1992832401165;8263.380246481538;8634.26608738258;8935.240504557121;9107;9112.134347237003;1660.8671306295466;1736.966504929565;1740.582260257529;1749.2215963896072;1759.8944063356112;67656.32343676599;69535.65232173857;70287.29712702859;70843;71354.64322678406;9729.4648794317;9751.3661611995;9849.950750246346;10018.799624007479;10158.511164764532;12424.254544727346;12462;12472.563460279029;12519.624411267676;12536.686582835502;6780.288069752703;6791.6077204173025;6877.724891004404;6987.615681137485;7236.493445458873;6743.2919543448215;6818.4886133540185;6981.511294209458;6994.825129371705;6998.545094568939;3879.4956655634587;3927.7446965947124;4029.576894426043;4062.1672557125717;4087.46862907826;1726.818684038183;1763.3828160143846;1769.6283780406259;1792.542901560094;1793.6189134366725;739.7780665800238;755.4938191411767;823.8681810910225;832.9375296852639;873.863976830131;16352.75470867938;16457.506274811774;16824.411145609844;17143.57141071483;17552.561185970197;765.261522630653;801.2788490358607;807.8384323135397;825.3438516379373;833.2167762303416;8077.515349079074;9122.992941552848;9343.71968840936;9460.24318054554;9498.479111979945;290133;295684;295939;400032;401814;69969.30030699627;76953.61523192348;78932;80859;81912.18087819045;892.638481415028;935.1770442011123;939.5067589515559;940.9764755881158;944.0323668239971;15317.77023344673;15491.30289136969;16189.75715364294;16390.426335078217;16426.917865410596;35733.28533429265;36055.45916811198;36519.81740091283;36969.81515092407;37325.4401183977;51210.48789512057;52318;52442.73778631083;52563;52937;7194.244604316471;7244.058272424551;7290.854182916418;7339.119305683352;7478.140013898471;10884.836727448936;11799.52801887915;11983.460744266704;12032.09759268041;12326.81509777336;5136.049830781318;6057.727402266997;9890.505474726378;10090.798184036425;10285.177185824967;10184.287099902936;10372.896271037192;10476.842847357448;10572.41851698164;10852.077573406348;10264.64073757415;10319.94840025825;10522.473876306307;10865.565377385132;10914.181436392144;7080.822979425556;7320.084989376328;7356.190819009797;7636.389088872766;7703.884441733267;8537.099807937706;9339.439633622005;10049.799004019733;10279.126274266771;10560.313579617301 00:12:16 INFO - PROCESS | 15321 | |3;mootools.html;1219.859716132645;1300.902432317576;1351.1015174908687;1388.4446221511394;1656.478209364051;6971.372576468117;7293.708251669934;7332.340089391961;7541.84916301675;7542.8114297142465;7368.778936631897;7447.106347238326;7454.180040195574;7464.365528930034;7473.701051957915;1840.5030641726726;2060.5157787919825;2148.881811500371;2177.4012146659716;2178.2049551913515;2181.6073106840763;2200.185931205456;2206.051397898903;2206.6248737714545;2211.3587059752654;2112.725345705056;2121.724175857144;2142.1324363632675;2143.517708515579;2176.3688530326185;2032.9898350508154;2115.7778433264593;2119.057019626267;2128.9041993110345;2132.0086159935627;2074.9273775417787;2085.6350138725816;2085.989570052155;2088.9477763055893;2092.3618296419586;7416.703331866719;7458.664360103754;7467.178610352857;7469.439792015566;7474.327310542078;4297.247981603207;4423.491298500681;4431.379606855043;4431.468223813163;4441.267190913495;2794.7345002224756;2919.138854038064;2925.7220564046384;2929.663088744779;2930.8388038657863;910.4901255296988;946.7017889364897;960.7357976556433;967.086103632068;974.7758015656368;9460.148586627236;9533.618655253851;9611.759706007406;9615.807683846284;9619.470929098898;3587.9953612987883;3741.569719482294;3742.1954279829965;3858.9421158682644;3863.188730366598;7776.377889768805;7787.182345853604;7788.922110778933;7800.375969922393;7804.219578042151;4319.503257125471;4320.049589090395;4321.092570560155;4335.393044973706;4336.956630433655;3419.076849250738;3886.106195575034;4042.0703238255364;4044.0496483326397;4059.7361171523758;3225.483922572377;3227.451333273302;3317.502374643823;3348.363810875926;3385.6288203277713;980.2403137568299;983.7343917142474;987.5555999800118;992.8957459466797;1004.3020101029861;599.7690889007677;675.5912672832964;710.6837457331438;726.6366816591704;748.9325960663457;4562.498125206224;4579.702319349231;4621.976890115528;4707.835225767081;4757.3577567028005;387.43628021229046;395.0577871775835;397.3344647715066;399.41884557968086;400.21957183492304;3138.3409484008157;3190.4097741917353;3195.6964088411564;3248.951265731016;3256.2673398485153;8658.913410865936;8998.685046023358;9044.231240344512;9057.81884362309;9068.818623627623;9060.95469522648;9064.365494415328;9069.59186836594;9072.637094516145;9076.636934522545;6863.347981941708;6874.381305682513;6874.553154044971;6880.965595172092;6919.584824910521;4647.604953578983;4753.453352864327;4780.713157210578;4919.532644398776;4931.7040977541465;7678.155402905788;8012.799680008046;8184.468009579358;8184.631691574013;8193.09875913661;8095.473794203358;8224;8389.11914248995;8392.076871544248;8400;6290.811275661737;6821.3519715626935;6992.685329160098;6996.440284777307;7005.684744186626;6499.057636642566;6666.266710661726;6720.025596288413;6728.125343705287;6871.484638652021;5726.713664316684;5802.274715660543;5984.461398474158;6101.725422355916;6308.747650093945;6053;6053.697315134314;6093.695315234309;6118.051701986199;6164.106204600397;5587.385387607279;5628.324601047901;5642.379338272704;5643.153526970823;5860.062390017577;4247.5752424757275;4269.509006464296;4279.015826359892;4390.495093064211;4524;4316.330968699856;4415.22733521626;4447.399601053946;4534.093181363674;4621.537846215351 00:12:16 INFO - PROCESS | 15321 | |4;prototype.html;263.2287397924082;266.8572313812111;267.2730173926918;267.85803524132206;271.4218714138884;4727.432708075027;4760.381150450441;4786.688865223767;4791.880202994923;4800.591949684277;4697.788599513022;4709.2936059591075;4721.315409265654;4731.810727570893;4739.194336962716;3500.2824420993634;3574.535310409643;3716.2753263113705;3719.628037196279;3726.2547490501875;3672.192117734108;3675.9448608270895;3686.907827304312;3725.1618385863235;3740.532433445819;3683.594804571495;3690.431566584191;3691.593924668284;3698.2418604186078;3701.666849983501;3511.94732079019;3524.506569080331;3703.611120832314;3707.4809526666154;3713.6100709425664;3170.524421336795;3184.251700850298;3191.186247506871;3194.984025079883;3201.5837941067743;4537.72943575796;4541.43232095988;4551.340055691906;4558.247889098295;4581.518940511265;4142.5857414258635;4146.958530414688;4154.376843473473;4162.542120366757;4170.895727606834;4729.59798417135;4884.389451318585;4898.403845961646;4941.369348115113;4954.851354459372;3525.665061819123;3528.153243221634;3530.6116327203986;3534.169470174507;3547;5176.715280659562;5190.429052804151;5193.584513238932;5216.400113986902;5248.8425347239645;3311.519829624691;3496.3881320768764;3513.7540372173703;3584.4981702561663;3598.2623562169692;3549.8285565763235;3559.2703495783303;3564.7326450516316;3574.517440145547;3574.5174401455993;4117.197146556453;4119.8970025749595;4127.607877251657;4131.421600975866;4132.524759652619;4964.826231081895;4995.550400463903;5019.573336266385;5024.723640199751;5039.571636410946;1161.2684009074399;1212.8180772884139;1222.2116734706065;1233.685410220406;1236.9876301237052;756.3171942428163;771.3482107619053;782.7690831204808;783.6591082878966;788.2944764435798;3947.3092208863336;4247.193033323659;4333.9133217335475;4426.424564806619;4439.64482841372;5090.6691065081395;5113.642045056885;5141.742912854343;5163.66436181647;5168.870778230575;4414.543200743747;4432.711873728262;4438.467383913951;4458.487273963471;4460.330950357472;2596.4287856671504;2681.6647919010124;2693.5151672699103;2697.689765676933;2708.864556772193;4823.263625094963;4823.710577365369;4835;4869.104716227004;4879.634027447955;5247;5269.235960785742;5279.683219006795;5279.947200528022;5295.947040529622;4709.317149013443;4790.928136077961;4802.079168332625;4833.758312084311;4860.878478038077;5135.432283858071;5142.357205349332;5163.251328557413;5168.896622067462;5183.766730497061;5059.87350316245;5070.746462676925;5080.517350851663;5110.540051395392;5118.97440512795;4650.441946966385;4858.3441235432765;4866.318715379779;4876.513292995525;4879.926801098058;3540.433530635086;3549.627289134707;3552.105034396958;3553.200529880756;3553.9466907995884;5125.487451254845;5140.665856719302;5141.357330333708;5160.587153027824;5217.478252174752;4759.262314341283;5002.849914502571;5004.3494345734825;5046.6719663221775;5067.797288108434;5017.397912250553;5045.924311135409;5050.898982020412;5062.873428164326;5065.189569668835;5023.673461225008;5025.0954828129825;5029.9245511318095;5035.244713293036;5041.596672266137;4842.612590992782;4927.605791536596;5058.9494105058475;5067.087924173684;5101.540861322499;4850.248211527219;4922.4585295616735;4978.601711863114;4979.477154898684;4980.825671101494 00:12:16 INFO - PROCESS | 15321 | |5;yui.html;575.5866079522095;610.6427739772233;647.2394935950257;655.4592461219495;665.550753241562;12817.679558011043;12955.805662915063;13820.308984550757;13870.930645346773;13875.098118622294;13185.340732963337;13488.46046158155;13501.459941602348;13516;13519.188848669086;595.0598055072975;723.1322413104269;739.4047791527818;740.5482655580092;741.218014994181;718.5185925429948;738.9408847292203;739.693027393631;744.9702011919517;746.6789280609336;784.352908850198;788.1566723605744;788.7791418402857;790.4822341366386;790.5059337913804;618.7679620142446;784.9803754906116;785.8506883692108;786.0057027859733;786.0606575142707;549.1844610753028;562.1679913727666;563.1749487001522;564.4947771744266;564.5963136357483;12476.18904771187;12508.624741257778;12574.182678125984;12578;12622.621321360373;9424.858627120599;9691.27315451344;9734.805303893883;9965;10188.184945204366;9183.357164998453;9345.672901448484;10736.570537178504;10847.620333288376;10847.891521084846;4017.2969730297373;4074.7147699661036;4129;4221.97889010553;4284.036091879302;50947.23579146316;53005.46994530082;53117.2032419514;58297.41702582962;58583;9457.38526995743;9734.902650973541;9971.40171589707;9989.051040151175;10032.1472674822;18129.274829006958;18146.637067258845;18150;18154;18155;6889.173299204128;6921.93078069223;6943.340382663639;7067.540609860343;7259.165196002422;3494.305261947953;3561.5191949086543;3575.6424357564038;3608.1881576645046;3643.4352675335367;2799.342154593669;2918.1537354167476;2939.76481881449;2952.5423559348337;2963.2443726849515;500.87227756921754;502.5502175552933;505.1463027483541;510.9540141387293;512.4081685653076;474.06498352154597;481.1171500296924;494.4659767451144;499.1140725212704;506.0940915760747;7243.492955493065;7569.848603028019;7690.615469226516;7790.805229869298;7804.804879878048;426.1753506964034;456.6597884576012;488.74585215688353;508.5423119192756;512.6872607709295;3567.714582833367;3628.9637103628625;3672.394054980898;3767.7174211933666;3806.5241844769403;104434;105704;106524.46737766262;107198.46400768102;111037.44481277703;50011;60702;81811;87144;88689;78775;79008.99999999885;79203.60398197972;79394;79873;6996.650167491503;7130.2869713028285;7168.211496735251;7194.712211511481;7224.855502889807;45024.324635129844;46307.3053904185;46854.76572617115;47256;48729.75635121943;59579.404205957384;66895;68871.31128688648;69388.63253460718;70257.64871175612;9230.400023998418;9337.813243735223;9342.439453632804;9347.813043738952;9544.379615324982;10569.577216911237;10620.256582039183;10733;10780.676579702622;11715;9312.301577381859;9439.669611563562;9485.857712134186;9520.714378568655;9798.75503112428;9355.251579873458;9431.952840235756;9605.711828645151;9612.66355677548;10103.949480252551;9192.494412807093;9200.723978280663;9279.62881484733;10275.691729248134;10573.04842564173;7939.358066938712;7998.280154786097;8087.272145506932;8149.7147599833725;8771.210591046836;6685.699143538455;6902.654867256718;7340.009098771598;7419.925800741924;7459.850802984019 00:12:16 INFO - PROCESS | 15321 | __end_tp_report 00:12:16 INFO - PROCESS | 15321 | __start_cc_report 00:12:16 INFO - PROCESS | 15321 | _x_x_mozilla_cycle_collect,1192 00:12:16 INFO - PROCESS | 15321 | __end_cc_report 00:12:16 INFO - PROCESS | 15321 | __startTimestamp1472627536506__endTimestamp 00:12:16 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | ------- Summary: start ------- 00:12:16 INFO - PROCESS | 15321 | Number of tests: 6 00:12:16 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | [#0] dojo.html Cycles:165 Average:4592.19 Median:4690.46 stddev:475.24 (10.1%) stddev-sans-first:449.79 00:12:16 INFO - PROCESS | 15321 | Values: 2582.6 2589.4 2605.7 2637.5 2646.8 4522.1 4592.6 4596.8 4663.2 4718.6 4620.6 4621.6 4631.8 4651.4 4690.5 4221.1 4254.8 4293.1 4356.7 4459.6 4366.8 4392.2 4405.9 4419.8 4436.6 4184.3 4223.2 4241.6 4293.3 4309.2 4252.0 4271.5 4271.8 4362.3 4373.0 4276.3 4282.0 4298.8 4328.7 4350.9 4536.8 4553.6 4620.6 4672.6 4716.1 4905.9 4926.0 4932.3 4936.7 5014.2 4921.8 4933.9 4955.0 4959.5 4992.2 4568.1 4610.5 4615.2 4633.0 4663.4 5117.5 5117.5 5141.9 5162.8 5188.2 4907.7 4908.4 4918.8 4920.6 4976.2 4908.4 4922.6 4970.5 4986.4 5008.1 4959.8 4960.6 4961.1 4984.6 5007.3 4942.0 4951.1 4951.1 4953.6 5007.2 4118.5 4162.5 4165.5 4244.2 4291.2 3713.3 3723.9 3736.5 3736.9 3740.3 4196.4 4200.3 4227.2 4240.7 4290.6 4925.9 4926.9 4930.7 4982.8 4991.8 4234.2 4238.0 4239.9 4284.6 4285.3 4362.5 4420.8 4422.4 4438.2 4492.1 4665.8 4668.1 4672.0 4715.9 4719.9 4916.9 4958.8 4959.4 4964.3 5024.1 4622.0 4631.2 4633.6 4662.1 4664.8 4909.0 4933.6 4936.6 4982.3 4997.3 4988.0 4997.9 5000.1 5005.5 5069.4 4689.4 4724.4 4737.1 4862.5 4931.5 4795.7 4803.9 4804.3 4810.4 4817.0 4689.9 4697.5 4719.1 4791.9 4806.2 4824.0 4825.3 4835.1 4844.1 4896.6 4737.9 4750.5 4751.4 4752.0 4753.2 00:12:16 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | [#1] ext.html Cycles:180 Average:48398.98 Median:37932.22 stddev:90611.33 (238.9%) stddev-sans-first:90794.30 00:12:16 INFO - PROCESS | 15321 | Values: 549.9 556.8 558.0 569.0 573.2 27811.2 38073.0 39300.4 39463.2 43300.0 39999.8 40276.0 40400.8 40409.4 40417.0 7539.8 7727.2 7728.0 7735.2 7735.5 7610.0 7694.0 7702.0 7703.1 7724.6 7673.2 7710.8 7716.2 7724.2 7726.2 7665.7 7699.8 7701.3 7709.7 7713.5 7620.5 7683.0 7690.6 7690.7 7691.8 37936.6 38285.4 38289.0 38333.8 38338.2 2661.7 2703.0 2753.6 2787.7 3082.9 28356.6 28391.3 28427.4 28662.4 28881.7 3325.1 3331.7 3382.0 3409.4 3429.4 249303.0 250745.0 251619.0 252621.0 253688.0 53559.0 53952.0 53980.7 54045.2 54045.5 47039.5 47377.0 47823.3 48413.3 57360.7 13357.9 13362.5 13382.2 13422.5 13620.1 10114.0 10122.3 10133.0 10133.0 10139.4 15631.8 17395.0 17908.5 17946.7 17949.1 6795.8 6829.0 6929.3 6969.0 6969.2 758.6 805.7 824.8 836.7 944.0 26289.7 26999.2 27463.0 27486.9 27904.4 846.4 872.1 877.6 906.7 944.4 13320.7 14367.4 14432.0 14672.7 14707.6 402235.0 405646.0 406324.0 406480.0 406544.7 48947.5 49383.5 49400.8 49413.0 49427.5 1143.6 1155.6 1157.4 1172.0 1177.5 25877.0 26468.3 26474.5 26751.0 27211.6 42405.0 42949.0 42977.4 42982.0 43017.0 350684.0 353924.0 355127.0 355321.0 355362.0 21122.8 21492.0 25344.4 25596.0 25672.7 40851.6 41172.6 41235.8 41252.4 41743.4 34693.0 35190.6 35337.8 35561.8 35993.1 34913.3 35207.5 35264.6 35454.0 35624.5 34535.0 34809.7 34980.5 35942.3 37927.8 28182.4 30498.4 30534.1 30845.8 30995.0 24933.4 25169.7 25215.5 25503.2 25595.0 00:12:16 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | [#2] jquery.html Cycles:180 Average:22395.11 Median:4732.12 stddev:56817.54 (1200.7%) stddev-sans-first:56952.77 00:12:16 INFO - PROCESS | 15321 | Values: 326.9 345.2 350.7 354.3 373.2 12046.0 15805.1 16161.8 17756.7 17917.7 13775.7 13942.9 15717.8 16105.4 16225.2 4300.0 4442.4 4445.6 5022.5 5268.0 3351.7 5326.3 5355.4 5357.4 5359.6 5018.6 5246.7 5291.0 5345.9 5349.9 5162.9 5328.7 5336.1 5338.9 5340.2 5196.9 5201.2 5201.2 5202.8 5207.8 11469.7 11581.8 11618.8 11654.8 11712.8 1734.4 1783.8 1810.3 1911.4 2390.2 8263.4 8634.3 8935.2 9107.0 9112.1 1660.9 1737.0 1740.6 1749.2 1759.9 67656.3 69535.7 70287.3 70843.0 71354.6 9729.5 9751.4 9850.0 10018.8 10158.5 12424.3 12462.0 12472.6 12519.6 12536.7 6780.3 6791.6 6877.7 6987.6 7236.5 6743.3 6818.5 6981.5 6994.8 6998.5 3879.5 3927.7 4029.6 4062.2 4087.5 1726.8 1763.4 1769.6 1792.5 1793.6 739.8 755.5 823.9 832.9 873.9 16352.8 16457.5 16824.4 17143.6 17552.6 765.3 801.3 807.8 825.3 833.2 8077.5 9123.0 9343.7 9460.2 9498.5 290133.0 295684.0 295939.0 400032.0 401814.0 69969.3 76953.6 78932.0 80859.0 81912.2 892.6 935.2 939.5 941.0 944.0 15317.8 15491.3 16189.8 16390.4 16426.9 35733.3 36055.5 36519.8 36969.8 37325.4 51210.5 52318.0 52442.7 52563.0 52937.0 7194.2 7244.1 7290.9 7339.1 7478.1 10884.8 11799.5 11983.5 12032.1 12326.8 5136.0 6057.7 9890.5 10090.8 10285.2 10184.3 10372.9 10476.8 10572.4 10852.1 10264.6 10319.9 10522.5 10865.6 10914.2 7080.8 7320.1 7356.2 7636.4 7703.9 8537.1 9339.4 10049.8 10279.1 10560.3 00:12:16 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | [#3] mootools.html Cycles:180 Average:4755.83 Median:5259.54 stddev:2647.54 (50.3%) stddev-sans-first:2641.63 00:12:16 INFO - PROCESS | 15321 | Values: 1219.9 1300.9 1351.1 1388.4 1656.5 6971.4 7293.7 7332.3 7541.8 7542.8 7368.8 7447.1 7454.2 7464.4 7473.7 1840.5 2060.5 2148.9 2177.4 2178.2 2181.6 2200.2 2206.1 2206.6 2211.4 2112.7 2121.7 2142.1 2143.5 2176.4 2033.0 2115.8 2119.1 2128.9 2132.0 2074.9 2085.6 2086.0 2088.9 2092.4 7416.7 7458.7 7467.2 7469.4 7474.3 4297.2 4423.5 4431.4 4431.5 4441.3 2794.7 2919.1 2925.7 2929.7 2930.8 910.5 946.7 960.7 967.1 974.8 9460.1 9533.6 9611.8 9615.8 9619.5 3588.0 3741.6 3742.2 3858.9 3863.2 7776.4 7787.2 7788.9 7800.4 7804.2 4319.5 4320.0 4321.1 4335.4 4337.0 3419.1 3886.1 4042.1 4044.0 4059.7 3225.5 3227.5 3317.5 3348.4 3385.6 980.2 983.7 987.6 992.9 1004.3 599.8 675.6 710.7 726.6 748.9 4562.5 4579.7 4622.0 4707.8 4757.4 387.4 395.1 397.3 399.4 400.2 3138.3 3190.4 3195.7 3249.0 3256.3 8658.9 8998.7 9044.2 9057.8 9068.8 9061.0 9064.4 9069.6 9072.6 9076.6 6863.3 6874.4 6874.6 6881.0 6919.6 4647.6 4753.5 4780.7 4919.5 4931.7 7678.2 8012.8 8184.5 8184.6 8193.1 8095.5 8224.0 8389.1 8392.1 8400.0 6290.8 6821.4 6992.7 6996.4 7005.7 6499.1 6666.3 6720.0 6728.1 6871.5 5726.7 5802.3 5984.5 6101.7 6308.7 6053.0 6053.7 6093.7 6118.1 6164.1 5587.4 5628.3 5642.4 5643.2 5860.1 4247.6 4269.5 4279.0 4390.5 4524.0 4316.3 4415.2 4447.4 4534.1 4621.5 00:12:16 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | [#4] prototype.html Cycles:180 Average:4121.40 Median:4728.52 stddev:1230.31 (26.0%) stddev-sans-first:1199.20 00:12:16 INFO - PROCESS | 15321 | Values: 263.2 266.9 267.3 267.9 271.4 4727.4 4760.4 4786.7 4791.9 4800.6 4697.8 4709.3 4721.3 4731.8 4739.2 3500.3 3574.5 3716.3 3719.6 3726.3 3672.2 3675.9 3686.9 3725.2 3740.5 3683.6 3690.4 3691.6 3698.2 3701.7 3511.9 3524.5 3703.6 3707.5 3713.6 3170.5 3184.3 3191.2 3195.0 3201.6 4537.7 4541.4 4551.3 4558.2 4581.5 4142.6 4147.0 4154.4 4162.5 4170.9 4729.6 4884.4 4898.4 4941.4 4954.9 3525.7 3528.2 3530.6 3534.2 3547.0 5176.7 5190.4 5193.6 5216.4 5248.8 3311.5 3496.4 3513.8 3584.5 3598.3 3549.8 3559.3 3564.7 3574.5 3574.5 4117.2 4119.9 4127.6 4131.4 4132.5 4964.8 4995.6 5019.6 5024.7 5039.6 1161.3 1212.8 1222.2 1233.7 1237.0 756.3 771.3 782.8 783.7 788.3 3947.3 4247.2 4333.9 4426.4 4439.6 5090.7 5113.6 5141.7 5163.7 5168.9 4414.5 4432.7 4438.5 4458.5 4460.3 2596.4 2681.7 2693.5 2697.7 2708.9 4823.3 4823.7 4835.0 4869.1 4879.6 5247.0 5269.2 5279.7 5279.9 5295.9 4709.3 4790.9 4802.1 4833.8 4860.9 5135.4 5142.4 5163.3 5168.9 5183.8 5059.9 5070.7 5080.5 5110.5 5119.0 4650.4 4858.3 4866.3 4876.5 4879.9 3540.4 3549.6 3552.1 3553.2 3553.9 5125.5 5140.7 5141.4 5160.6 5217.5 4759.3 5002.8 5004.3 5046.7 5067.8 5017.4 5045.9 5050.9 5062.9 5065.2 5023.7 5025.1 5029.9 5035.2 5041.6 4842.6 4927.6 5058.9 5067.1 5101.5 4850.2 4922.5 4978.6 4979.5 4980.8 00:12:16 INFO - PROCESS | 15321 | 00:12:16 INFO - PROCESS | 15321 | [#5] yui.html Cycles:180 Average:17282.08 Median:30087.23 stddev:26063.64 (86.6%) stddev-sans-first:26106.57 00:12:16 INFO - PROCESS | 15321 | Values: 575.6 610.6 647.2 655.5 665.6 12817.7 12955.8 13820.3 13870.9 13875.1 13185.3 13488.5 13501.5 13516.0 13519.2 595.1 723.1 739.4 740.5 741.2 718.5 738.9 739.7 745.0 746.7 784.4 788.2 788.8 790.5 790.5 618.8 785.0 785.9 786.0 786.1 549.2 562.2 563.2 564.5 564.6 12476.2 12508.6 12574.2 12578.0 12622.6 9424.9 9691.3 9734.8 9965.0 10188.2 9183.4 9345.7 10736.6 10847.6 10847.9 4017.3 4074.7 4129.0 4222.0 4284.0 50947.2 53005.5 53117.2 58297.4 58583.0 9457.4 9734.9 9971.4 9989.1 10032.1 18129.3 18146.6 18150.0 18154.0 18155.0 6889.2 6921.9 6943.3 7067.5 7259.2 3494.3 3561.5 3575.6 3608.2 3643.4 2799.3 2918.2 2939.8 2952.5 2963.2 500.9 502.6 505.1 511.0 512.4 474.1 481.1 494.5 499.1 506.1 7243.5 7569.8 7690.6 7790.8 7804.8 426.2 456.7 488.7 508.5 512.7 3567.7 3629.0 3672.4 3767.7 3806.5 104434.0 105704.0 106524.5 107198.5 111037.4 50011.0 60702.0 81811.0 87144.0 88689.0 78775.0 79009.0 79203.6 79394.0 79873.0 6996.7 7130.3 7168.2 7194.7 7224.9 45024.3 46307.3 46854.8 47256.0 48729.8 59579.4 66895.0 68871.3 69388.6 70257.6 9230.4 9337.8 9342.4 9347.8 9544.4 10569.6 10620.3 10733.0 10780.7 11715.0 9312.3 9439.7 9485.9 9520.7 9798.8 9355.3 9432.0 9605.7 9612.7 10103.9 9192.5 9200.7 9279.6 10275.7 10573.0 7939.4 7998.3 8087.3 8149.7 8771.2 6685.7 6902.7 7340.0 7419.9 7459.9 00:12:16 INFO - PROCESS | 15321 | -------- Summary: end -------- 00:12:16 INFO - PROCESS | 15321 | 00:12:17 INFO - TEST-INFO | 15321: exit 0 00:12:17 INFO - TEST-OK | dromaeo_css | took 1121441ms 00:12:17 INFO - TEST-START | kraken 00:12:17 INFO - operating with platform_type : linux_ 00:12:17 INFO - Initialising browser for kraken test... 00:12:17 INFO - TEST-INFO | started process 15617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgsIL4N/profile http://localhost:48569/getInfo.html) 00:12:22 INFO - PROCESS | 15617 | __metrics Screen width/height:1600/1200 00:12:22 INFO - PROCESS | 15617 | colorDepth:24 00:12:22 INFO - PROCESS | 15617 | Browser inner width/height: 1024/697 00:12:22 INFO - PROCESS | 15617 | __metrics 00:12:22 INFO - PROCESS | 15617 | 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" 00:12:22 INFO - PROCESS | 15617 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:12:23 INFO - PROCESS | 15617 | console.error: PushService: 00:12:23 INFO - PROCESS | 15617 | stateChangeProcessEnqueue: Error transitioning state 00:12:23 INFO - PROCESS | 15617 | UnknownError 00:12:24 INFO - TEST-INFO | 15617: exit 0 00:12:24 INFO - Browser initialized. 00:12:24 INFO - Running cycle 1/1 for kraken test... 00:12:24 INFO - TEST-INFO | started process 15699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgsIL4N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:12:36 INFO - PROCESS | 15699 | RSS: Main: 182181888 00:12:36 INFO - PROCESS | 15699 | 00:12:38 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48569/tests/kraken/driver.html?testName=audio-beat-detection) 00:12:39 INFO - PROCESS | 15699 | RSS: Main: 235393024 00:12:39 INFO - PROCESS | 15699 | 00:12:42 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48569/tests/kraken/driver.html?testName=audio-dft) 00:12:43 INFO - PROCESS | 15699 | RSS: Main: 225955840 00:12:43 INFO - PROCESS | 15699 | 00:12:46 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48569/tests/kraken/driver.html?testName=audio-fft) 00:12:47 INFO - PROCESS | 15699 | RSS: Main: 303976448 00:12:47 INFO - PROCESS | 15699 | 00:12:49 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48569/tests/kraken/driver.html?testName=audio-oscillator) 00:12:49 INFO - PROCESS | 15699 | RSS: Main: 213291008 00:12:49 INFO - PROCESS | 15699 | 00:12:52 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48569/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:12:52 INFO - PROCESS | 15699 | RSS: Main: 215076864 00:12:52 INFO - PROCESS | 15699 | 00:12:55 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48569/tests/kraken/driver.html?testName=imaging-darkroom) 00:12:56 INFO - PROCESS | 15699 | RSS: Main: 253407232 00:12:56 INFO - PROCESS | 15699 | 00:12:59 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48569/tests/kraken/driver.html?testName=imaging-desaturate) 00:13:00 INFO - PROCESS | 15699 | RSS: Main: 247341056 00:13:00 INFO - PROCESS | 15699 | 00:13:03 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48569/tests/kraken/driver.html?testName=json-parse-financial) 00:13:04 INFO - PROCESS | 15699 | RSS: Main: 250839040 00:13:04 INFO - PROCESS | 15699 | 00:13:05 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48569/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:13:06 INFO - PROCESS | 15699 | RSS: Main: 214224896 00:13:06 INFO - PROCESS | 15699 | 00:13:08 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48569/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:13:09 INFO - PROCESS | 15699 | RSS: Main: 222326784 00:13:09 INFO - PROCESS | 15699 | 00:13:11 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48569/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:13:11 INFO - PROCESS | 15699 | RSS: Main: 222834688 00:13:11 INFO - PROCESS | 15699 | 00:13:14 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48569/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:13:15 INFO - PROCESS | 15699 | RSS: Main: 224542720 00:13:15 INFO - PROCESS | 15699 | 00:13:18 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48569/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:13:18 INFO - PROCESS | 15699 | RSS: Main: 221560832 00:13:18 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | Cycle 1(1): loaded http://localhost:48569/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48569/tests/kraken/driver.html?testName=ai-astar) 00:13:20 INFO - PROCESS | 15699 | RSS: Main: 272109568 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | __start_tp_report 00:13:20 INFO - PROCESS | 15699 | _x_x_mozilla_page_load 00:13:20 INFO - PROCESS | 15699 | _x_x_mozilla_page_load_details 00:13:20 INFO - PROCESS | 15699 | |i|pagename|runs| 00:13:20 INFO - PROCESS | 15699 | |0;ai-astar;113;115;131;113;113;113;113;114;114;114 00:13:20 INFO - PROCESS | 15699 | |1;audio-beat-detection;153;127;155;185;123;149;153;125;191;125 00:13:20 INFO - PROCESS | 15699 | |2;audio-dft;162;160;162;161;161;165;161;162;161;162 00:13:20 INFO - PROCESS | 15699 | |3;audio-fft;78;75;75;181;78;78;75;75;165;76 00:13:20 INFO - PROCESS | 15699 | |4;audio-oscillator;84;86;150;85;85;85;145;83;84;141 00:13:20 INFO - PROCESS | 15699 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 00:13:20 INFO - PROCESS | 15699 | |6;imaging-darkroom;146;147;146;146;146;146;147;147;147;146 00:13:20 INFO - PROCESS | 15699 | |7;imaging-desaturate;95;96;94;95;95;95;95;95;95;96 00:13:20 INFO - PROCESS | 15699 | |8;json-parse-financial;64;63;65;64;65;67;70;66;64;65 00:13:20 INFO - PROCESS | 15699 | |9;json-stringify-tinderbox;67;66;66;66;67;66;67;65;69;67 00:13:20 INFO - PROCESS | 15699 | |10;stanford-crypto-aes;85;87;90;81;82;81;83;82;81;82 00:13:20 INFO - PROCESS | 15699 | |11;stanford-crypto-ccm;136;132;132;132;131;129;129;132;173;146 00:13:20 INFO - PROCESS | 15699 | |12;stanford-crypto-pbkdf2;173;174;179;171;177;172;173;178;179;177 00:13:20 INFO - PROCESS | 15699 | |13;stanford-crypto-sha256-iterative;70;69;69;69;73;69;69;71;67;67 00:13:20 INFO - PROCESS | 15699 | __end_tp_report 00:13:20 INFO - PROCESS | 15699 | __start_cc_report 00:13:20 INFO - PROCESS | 15699 | _x_x_mozilla_cycle_collect,1987 00:13:20 INFO - PROCESS | 15699 | __end_cc_report 00:13:20 INFO - PROCESS | 15699 | __startTimestamp1472627600919__endTimestamp 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | ------- Summary: start ------- 00:13:20 INFO - PROCESS | 15699 | Number of tests: 14 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#0] ai-astar Cycles:10 Average:115.30 Median:114.00 stddev:5.56 (4.9%) stddev-sans-first:5.83 00:13:20 INFO - PROCESS | 15699 | Values: 113.0 115.0 131.0 113.0 113.0 113.0 113.0 114.0 114.0 114.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#1] audio-beat-detection Cycles:10 Average:148.60 Median:153.00 stddev:24.58 (16.1%) stddev-sans-first:26.02 00:13:20 INFO - PROCESS | 15699 | Values: 153.0 127.0 155.0 185.0 123.0 149.0 153.0 125.0 191.0 125.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.34 (0.8%) stddev-sans-first:1.41 00:13:20 INFO - PROCESS | 15699 | Values: 162.0 160.0 162.0 161.0 161.0 165.0 161.0 162.0 161.0 162.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#3] audio-fft Cycles:10 Average:95.60 Median:75.50 stddev:40.99 (54.3%) stddev-sans-first:42.98 00:13:20 INFO - PROCESS | 15699 | Values: 78.0 75.0 75.0 181.0 78.0 78.0 75.0 75.0 165.0 76.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:84.50 stddev:29.44 (34.8%) stddev-sans-first:30.43 00:13:20 INFO - PROCESS | 15699 | Values: 84.0 86.0 150.0 85.0 85.0 85.0 145.0 83.0 84.0 141.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 00:13:20 INFO - PROCESS | 15699 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 00:13:20 INFO - PROCESS | 15699 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 147.0 147.0 147.0 146.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 00:13:20 INFO - PROCESS | 15699 | Values: 95.0 96.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:65.00 stddev:2.00 (3.1%) stddev-sans-first:2.07 00:13:20 INFO - PROCESS | 15699 | Values: 64.0 63.0 65.0 64.0 65.0 67.0 70.0 66.0 64.0 65.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:67.00 stddev:1.07 (1.6%) stddev-sans-first:1.13 00:13:20 INFO - PROCESS | 15699 | Values: 67.0 66.0 66.0 66.0 67.0 66.0 67.0 65.0 69.0 67.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:82.50 stddev:3.03 (3.7%) stddev-sans-first:3.15 00:13:20 INFO - PROCESS | 15699 | Values: 85.0 87.0 90.0 81.0 82.0 81.0 83.0 82.0 81.0 82.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#11] stanford-crypto-ccm Cycles:10 Average:137.20 Median:132.00 stddev:13.51 (10.2%) stddev-sans-first:14.32 00:13:20 INFO - PROCESS | 15699 | Values: 136.0 132.0 132.0 132.0 131.0 129.0 129.0 132.0 173.0 146.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:177.00 stddev:3.02 (1.7%) stddev-sans-first:3.09 00:13:20 INFO - PROCESS | 15699 | Values: 173.0 174.0 179.0 171.0 177.0 172.0 173.0 178.0 179.0 177.0 00:13:20 INFO - PROCESS | 15699 | 00:13:20 INFO - PROCESS | 15699 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:1.77 (2.6%) stddev-sans-first:1.86 00:13:20 INFO - PROCESS | 15699 | Values: 70.0 69.0 69.0 69.0 73.0 69.0 69.0 71.0 67.0 67.0 00:13:20 INFO - PROCESS | 15699 | -------- Summary: end -------- 00:13:20 INFO - PROCESS | 15699 | 00:13:22 INFO - TEST-INFO | 15699: exit 0 00:13:22 INFO - TEST-OK | kraken | took 64690ms 00:13:22 INFO - SUITE-END | took 1186s 00:13:22 INFO - Completed test suite (00:19:46) 00:13:22 INFO - javascript benchmark 00:13:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2582.5867861142196, 2589.3656054266703, 2605.7394260573947, 2637.5120602688503, 2646.801489888259, 4522.118186953538, 4592.632589392849, 4596.770161491924, 4663.1839428100075, 4718.598919091877, 4620.630349572035, 4621.58405743483, 4631.8147274109, 4651.441826980758, 4690.460597031334, 4221.113566151112, 4254.787260636971, 4293.098449325646, 4356.695031347807, 4459.576340247671, 4366.781660916958, 4392.231359512072, 4405.933910991338, 4419.756913369763, 4436.600705936465, 4184.28867092595, 4223.1975924574235, 4241.554636763126, 4293.334533147367, 4309.202797482444, 4252.043290259698, 4271.530131685512, 4271.8077686503875, 4362.345648152803, 4373.037931655031, 4276.337167739005, 4282.015136518587, 4298.828046878121, 4328.696991210617, 4350.8912277193, 4536.818527258905, 4553.590176884063, 4620.630349572026, 4672.626189904833, 4716.127516409475, 4905.877353066202, 4926.0394223126505, 4932.309476673233, 4936.703797772145, 5014.222795466709, 4921.753912304371, 4933.876653083666, 4954.975225123887, 4959.528844759742, 4992.151334273182, 4568.063546972929, 4610.515895830957, 4615.2384856498975, 4632.97683511587, 4663.440387153563, 5117.539421452013, 5117.5394214520875, 5141.948580514222, 5162.793488260502, 5188.247704082887, 4907.705537667752, 4908.435529914105, 4918.827841025547, 4920.6309526785635, 4976.2286845539, 4908.410990681141, 4922.581580565692, 4970.502949705001, 4986.351774269322, 5008.1486147355035, 4959.776810043557, 4960.627952903546, 4961.05739909416, 4984.626153038536, 5007.324011258506, 4941.950580494221, 4951.059298733229, 4951.084049450863, 4953.6037117030555, 5007.223880298559, 4118.5057793065025, 4162.458880345596, 4165.500139983161, 4244.172386384687, 4291.184674911757, 3713.313037088147, 3723.8696645617274, 3736.5142531470733, 3736.869209577652, 3740.3080430120513, 4196.391523229115, 4200.28595138821, 4227.196832601796, 4240.660747140221, 4290.635295999875, 4925.9014819704125, 4926.926096108561, 4930.654854160174, 4982.7508624569355, 4991.775370108354, 4234.195502854509, 4237.978810105929, 4239.851605193803, 4284.592963668447, 4285.271503844354, 4362.520122786491, 4420.778961051935, 4422.402975598253, 4438.201123797748, 4492.124035813051, 4665.790039448165, 4668.066386722601, 4671.953280467152, 4715.858524244278, 4719.882002949885, 4916.8770780730765, 4958.801647934042, 4959.429665588504, 4964.3298154748645, 5024.145895197898, 4621.953780462152, 4631.189541830099, 4633.559811817872, 4662.114198302312, 4664.766761661971, 4909.042736666459, 4933.55597996182, 4936.605071594336, 4982.252662100714, 4997.3253610762085, 4987.975060124676, 4997.925031124463, 5000.099982003274, 5005.549500544968, 5069.391672999116, 4689.366935463669, 4724.385829842236, 4737.076270127292, 4862.465128835896, 4931.457539670705, 4795.736234507136, 4803.855884323476, 4804.25534042224, 4810.446798618063, 4816.975915120402, 4689.859304220879, 4697.530246975275, 4719.05618876233, 4791.9281210782565, 4806.2310030394965, 4823.975880120577, 4825.30033145199, 4835.105505481497, 4844.103840789465, 4896.6327525436445, 4737.9052418952115, 4750.5249475052215, 4751.382320298339, 4751.976240118777, 4753.168195565693], "value": 4562.291568056856, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.8625343664085, 556.7661582135507, 558.0346001417547, 569.0042425754929, 573.2347316332696, 27811.165665030057, 38073.0, 39300.41049384262, 39463.210735785266, 43300.0, 39999.80000099995, 40276.0, 40400.79799601005, 40409.39385909214, 40417.0, 7539.773806785792, 7727.227277272284, 7727.995360603142, 7735.187805280449, 7735.4585179037485, 7610.0, 7694.0, 7701.998740163771, 7703.114141873672, 7724.575148366837, 7673.23267673231, 7710.807229819271, 7716.151223365426, 7724.188960159158, 7726.227377262257, 7665.731699390522, 7699.846003079963, 7701.306882380557, 7709.653065612061, 7713.49862258957, 7620.466567340289, 7683.039620047494, 7690.5770182639935, 7690.653920573588, 7691.769246922601, 37936.62063379359, 38285.42571861424, 38289.042773930596, 38333.80833095845, 38338.23323533514, 2661.733826617342, 2703.0, 2753.600727894444, 2787.707290734484, 3082.8920987765546, 28356.574651380244, 28391.290217744514, 28427.431451371063, 28662.426751464853, 28881.71118288832, 3325.068980685412, 3331.7358573453885, 3381.9830900845586, 3409.4203985322556, 3429.416999110157, 249303.0, 250744.99999999817, 251619.0, 252621.0, 253688.0, 53559.0, 53952.0, 53980.73009634927, 54045.1893221602, 54045.45954540404, 47039.529604703515, 47377.0, 47823.282650760266, 48413.27380089302, 57360.71319643375, 13357.866421335857, 13362.465501380031, 13382.197068175941, 13422.530211442749, 13620.114692544952, 10114.039166279192, 10122.291439599157, 10133.0, 10133.0, 10139.391636501834, 15631.765523517157, 17395.0, 17908.46274611764, 17946.730799037763, 17949.102544872705, 6795.830104247335, 6828.970907164797, 6929.341712537301, 6968.95465680152, 6969.1985421676145, 758.6396461680657, 805.715795719131, 824.814416756237, 836.7364280251701, 944.0182210501139, 26289.73710262911, 26999.190024299303, 27463.0, 27486.862565687043, 27904.44191116207, 846.4498076250486, 872.075599864146, 877.591919757316, 906.6943601214231, 944.390867890207, 13320.733585328433, 14367.425302987764, 14431.96845947733, 14672.706545869236, 14707.55877323682, 402235.0, 405646.0, 406324.0, 406480.0, 406544.6727663605, 48947.51052489429, 49383.50616493861, 49400.75299623479, 49413.0, 49427.50572494301, 1143.5711608146944, 1155.6013175454455, 1157.3750174905456, 1172.038928078967, 1177.5054477119631, 25877.0, 26468.338291542867, 26474.470510589294, 26751.0, 27211.59182612302, 42405.0, 42949.0, 42977.3553396693, 42982.0, 43017.0, 350684.0, 353924.0, 355127.0, 355321.0, 355362.0, 21122.788772112697, 21492.0, 25344.366390840376, 25596.0, 25672.743272567037, 40851.59148408597, 41172.588274116875, 41235.81462780607, 41252.38121428121, 41743.373849391675, 34693.0, 35190.64809351976, 35337.82331088328, 35561.82219088888, 35993.10017249485, 34913.30173396569, 35207.47188792221, 35264.647353527165, 35454.0, 35624.46563301604, 34535.0, 34809.65190348165, 34980.47529287012, 35942.281154376244, 37927.81036094802, 28182.43635127327, 30498.390032199677, 30534.08397748071, 30845.845770771004, 30995.0, 24933.376665583506, 25169.748302516742, 25215.49569008573, 25503.24273478929, 25595.0], "value": 17081.695860169606, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.9051974927271, 345.16297977404804, 350.6703698523388, 354.28257777999545, 373.2479054704769, 12046.0, 15805.130717810516, 16161.75757363639, 17756.733648995276, 17917.7312340315, 13775.65560860979, 13942.930285348584, 15717.764233536505, 16105.436309729163, 16225.188740562984, 4299.978500107495, 4442.4224850769515, 4445.622122119607, 5022.522860328263, 5268.0, 3351.7151042161445, 5326.334208223972, 5355.437679043703, 5357.4374690657505, 5359.624826262163, 5018.623603229754, 5246.711430871301, 5291.047611429941, 5345.946540534584, 5349.866253343678, 5162.92255616166, 5328.653637513549, 5336.092864213074, 5338.919916201241, 5340.172273297645, 5196.896062078775, 5201.167813149916, 5201.24581935621, 5202.791888324463, 5207.791688332462, 11469.713257168554, 11581.76836463266, 11618.767624647544, 11654.825177622342, 11712.824307635392, 1734.4363081998274, 1783.8305360990814, 1810.2849374497, 1911.4170178095676, 2390.1992832401165, 8263.380246481538, 8634.26608738258, 8935.240504557121, 9107.0, 9112.134347237003, 1660.8671306295466, 1736.966504929565, 1740.582260257529, 1749.2215963896072, 1759.8944063356112, 67656.32343676599, 69535.65232173857, 70287.29712702859, 70843.0, 71354.64322678406, 9729.4648794317, 9751.3661611995, 9849.950750246346, 10018.799624007479, 10158.511164764532, 12424.254544727346, 12462.0, 12472.563460279029, 12519.624411267676, 12536.686582835502, 6780.288069752703, 6791.6077204173025, 6877.724891004404, 6987.615681137485, 7236.493445458873, 6743.2919543448215, 6818.4886133540185, 6981.511294209458, 6994.825129371705, 6998.545094568939, 3879.4956655634587, 3927.7446965947124, 4029.576894426043, 4062.1672557125717, 4087.46862907826, 1726.818684038183, 1763.3828160143846, 1769.6283780406259, 1792.542901560094, 1793.6189134366725, 739.7780665800238, 755.4938191411767, 823.8681810910225, 832.9375296852639, 873.863976830131, 16352.75470867938, 16457.506274811774, 16824.411145609844, 17143.57141071483, 17552.561185970197, 765.261522630653, 801.2788490358607, 807.8384323135397, 825.3438516379373, 833.2167762303416, 8077.515349079074, 9122.992941552848, 9343.71968840936, 9460.24318054554, 9498.479111979945, 290133.0, 295684.0, 295939.0, 400032.0, 401814.0, 69969.30030699627, 76953.61523192348, 78932.0, 80859.0, 81912.18087819045, 892.638481415028, 935.1770442011123, 939.5067589515559, 940.9764755881158, 944.0323668239971, 15317.77023344673, 15491.30289136969, 16189.75715364294, 16390.426335078217, 16426.917865410596, 35733.28533429265, 36055.45916811198, 36519.81740091283, 36969.81515092407, 37325.4401183977, 51210.48789512057, 52318.0, 52442.73778631083, 52563.0, 52937.0, 7194.244604316471, 7244.058272424551, 7290.854182916418, 7339.119305683352, 7478.140013898471, 10884.836727448936, 11799.52801887915, 11983.460744266704, 12032.09759268041, 12326.81509777336, 5136.049830781318, 6057.727402266997, 9890.505474726378, 10090.798184036425, 10285.177185824967, 10184.287099902936, 10372.896271037192, 10476.842847357448, 10572.41851698164, 10852.077573406348, 10264.64073757415, 10319.94840025825, 10522.473876306307, 10865.565377385132, 10914.181436392144, 7080.822979425556, 7320.084989376328, 7356.190819009797, 7636.389088872766, 7703.884441733267, 8537.099807937706, 9339.439633622005, 10049.799004019733, 10279.126274266771, 10560.313579617301], "value": 7784.620218596847, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1219.859716132645, 1300.902432317576, 1351.1015174908687, 1388.4446221511394, 1656.478209364051, 6971.372576468117, 7293.708251669934, 7332.340089391961, 7541.84916301675, 7542.8114297142465, 7368.778936631897, 7447.106347238326, 7454.180040195574, 7464.365528930034, 7473.701051957915, 1840.5030641726726, 2060.5157787919825, 2148.881811500371, 2177.4012146659716, 2178.2049551913515, 2181.6073106840763, 2200.185931205456, 2206.051397898903, 2206.6248737714545, 2211.3587059752654, 2112.725345705056, 2121.724175857144, 2142.1324363632675, 2143.517708515579, 2176.3688530326185, 2032.9898350508154, 2115.7778433264593, 2119.057019626267, 2128.9041993110345, 2132.0086159935627, 2074.9273775417787, 2085.6350138725816, 2085.989570052155, 2088.9477763055893, 2092.3618296419586, 7416.703331866719, 7458.664360103754, 7467.178610352857, 7469.439792015566, 7474.327310542078, 4297.247981603207, 4423.491298500681, 4431.379606855043, 4431.468223813163, 4441.267190913495, 2794.7345002224756, 2919.138854038064, 2925.7220564046384, 2929.663088744779, 2930.8388038657863, 910.4901255296988, 946.7017889364897, 960.7357976556433, 967.086103632068, 974.7758015656368, 9460.148586627236, 9533.618655253851, 9611.759706007406, 9615.807683846284, 9619.470929098898, 3587.9953612987883, 3741.569719482294, 3742.1954279829965, 3858.9421158682644, 3863.188730366598, 7776.377889768805, 7787.182345853604, 7788.922110778933, 7800.375969922393, 7804.219578042151, 4319.503257125471, 4320.049589090395, 4321.092570560155, 4335.393044973706, 4336.956630433655, 3419.076849250738, 3886.106195575034, 4042.0703238255364, 4044.0496483326397, 4059.7361171523758, 3225.483922572377, 3227.451333273302, 3317.502374643823, 3348.363810875926, 3385.6288203277713, 980.2403137568299, 983.7343917142474, 987.5555999800118, 992.8957459466797, 1004.3020101029861, 599.7690889007677, 675.5912672832964, 710.6837457331438, 726.6366816591704, 748.9325960663457, 4562.498125206224, 4579.702319349231, 4621.976890115528, 4707.835225767081, 4757.3577567028005, 387.43628021229046, 395.0577871775835, 397.3344647715066, 399.41884557968086, 400.21957183492304, 3138.3409484008157, 3190.4097741917353, 3195.6964088411564, 3248.951265731016, 3256.2673398485153, 8658.913410865936, 8998.685046023358, 9044.231240344512, 9057.81884362309, 9068.818623627623, 9060.95469522648, 9064.365494415328, 9069.59186836594, 9072.637094516145, 9076.636934522545, 6863.347981941708, 6874.381305682513, 6874.553154044971, 6880.965595172092, 6919.584824910521, 4647.604953578983, 4753.453352864327, 4780.713157210578, 4919.532644398776, 4931.7040977541465, 7678.155402905788, 8012.799680008046, 8184.468009579358, 8184.631691574013, 8193.09875913661, 8095.473794203358, 8224.0, 8389.11914248995, 8392.076871544248, 8400.0, 6290.811275661737, 6821.3519715626935, 6992.685329160098, 6996.440284777307, 7005.684744186626, 6499.057636642566, 6666.266710661726, 6720.025596288413, 6728.125343705287, 6871.484638652021, 5726.713664316684, 5802.274715660543, 5984.461398474158, 6101.725422355916, 6308.747650093945, 6053.0, 6053.697315134314, 6093.695315234309, 6118.051701986199, 6164.106204600397, 5587.385387607279, 5628.324601047901, 5642.379338272704, 5643.153526970823, 5860.062390017577, 4247.5752424757275, 4269.509006464296, 4279.015826359892, 4390.495093064211, 4524.0, 4316.330968699856, 4415.22733521626, 4447.399601053946, 4534.093181363674, 4621.537846215351], "value": 3779.6048127377835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.2287397924082, 266.8572313812111, 267.2730173926918, 267.85803524132206, 271.4218714138884, 4727.432708075027, 4760.381150450441, 4786.688865223767, 4791.880202994923, 4800.591949684277, 4697.788599513022, 4709.2936059591075, 4721.315409265654, 4731.810727570893, 4739.194336962716, 3500.2824420993634, 3574.535310409643, 3716.2753263113705, 3719.628037196279, 3726.2547490501875, 3672.192117734108, 3675.9448608270895, 3686.907827304312, 3725.1618385863235, 3740.532433445819, 3683.594804571495, 3690.431566584191, 3691.593924668284, 3698.2418604186078, 3701.666849983501, 3511.94732079019, 3524.506569080331, 3703.611120832314, 3707.4809526666154, 3713.6100709425664, 3170.524421336795, 3184.251700850298, 3191.186247506871, 3194.984025079883, 3201.5837941067743, 4537.72943575796, 4541.43232095988, 4551.340055691906, 4558.247889098295, 4581.518940511265, 4142.5857414258635, 4146.958530414688, 4154.376843473473, 4162.542120366757, 4170.895727606834, 4729.59798417135, 4884.389451318585, 4898.403845961646, 4941.369348115113, 4954.851354459372, 3525.665061819123, 3528.153243221634, 3530.6116327203986, 3534.169470174507, 3547.0, 5176.715280659562, 5190.429052804151, 5193.584513238932, 5216.400113986902, 5248.8425347239645, 3311.519829624691, 3496.3881320768764, 3513.7540372173703, 3584.4981702561663, 3598.2623562169692, 3549.8285565763235, 3559.2703495783303, 3564.7326450516316, 3574.517440145547, 3574.5174401455993, 4117.197146556453, 4119.8970025749595, 4127.607877251657, 4131.421600975866, 4132.524759652619, 4964.826231081895, 4995.550400463903, 5019.573336266385, 5024.723640199751, 5039.571636410946, 1161.2684009074399, 1212.8180772884139, 1222.2116734706065, 1233.685410220406, 1236.9876301237052, 756.3171942428163, 771.3482107619053, 782.7690831204808, 783.6591082878966, 788.2944764435798, 3947.3092208863336, 4247.193033323659, 4333.9133217335475, 4426.424564806619, 4439.64482841372, 5090.6691065081395, 5113.642045056885, 5141.742912854343, 5163.66436181647, 5168.870778230575, 4414.543200743747, 4432.711873728262, 4438.467383913951, 4458.487273963471, 4460.330950357472, 2596.4287856671504, 2681.6647919010124, 2693.5151672699103, 2697.689765676933, 2708.864556772193, 4823.263625094963, 4823.710577365369, 4835.0, 4869.104716227004, 4879.634027447955, 5247.0, 5269.235960785742, 5279.683219006795, 5279.947200528022, 5295.947040529622, 4709.317149013443, 4790.928136077961, 4802.079168332625, 4833.758312084311, 4860.878478038077, 5135.432283858071, 5142.357205349332, 5163.251328557413, 5168.896622067462, 5183.766730497061, 5059.87350316245, 5070.746462676925, 5080.517350851663, 5110.540051395392, 5118.97440512795, 4650.441946966385, 4858.3441235432765, 4866.318715379779, 4876.513292995525, 4879.926801098058, 3540.433530635086, 3549.627289134707, 3552.105034396958, 3553.200529880756, 3553.9466907995884, 5125.487451254845, 5140.665856719302, 5141.357330333708, 5160.587153027824, 5217.478252174752, 4759.262314341283, 5002.849914502571, 5004.3494345734825, 5046.6719663221775, 5067.797288108434, 5017.397912250553, 5045.924311135409, 5050.898982020412, 5062.873428164326, 5065.189569668835, 5023.673461225008, 5025.0954828129825, 5029.9245511318095, 5035.244713293036, 5041.596672266137, 4842.612590992782, 4927.605791536596, 5058.9494105058475, 5067.087924173684, 5101.540861322499, 4850.248211527219, 4922.4585295616735, 4978.601711863114, 4979.477154898684, 4980.825671101494], "value": 3716.318576532469, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.5866079522095, 610.6427739772233, 647.2394935950257, 655.4592461219495, 665.550753241562, 12817.679558011043, 12955.805662915063, 13820.308984550757, 13870.930645346773, 13875.098118622294, 13185.340732963337, 13488.46046158155, 13501.459941602348, 13516.0, 13519.188848669086, 595.0598055072975, 723.1322413104269, 739.4047791527818, 740.5482655580092, 741.218014994181, 718.5185925429948, 738.9408847292203, 739.693027393631, 744.9702011919517, 746.6789280609336, 784.352908850198, 788.1566723605744, 788.7791418402857, 790.4822341366386, 790.5059337913804, 618.7679620142446, 784.9803754906116, 785.8506883692108, 786.0057027859733, 786.0606575142707, 549.1844610753028, 562.1679913727666, 563.1749487001522, 564.4947771744266, 564.5963136357483, 12476.18904771187, 12508.624741257778, 12574.182678125984, 12578.0, 12622.621321360373, 9424.858627120599, 9691.27315451344, 9734.805303893883, 9965.0, 10188.184945204366, 9183.357164998453, 9345.672901448484, 10736.570537178504, 10847.620333288376, 10847.891521084846, 4017.2969730297373, 4074.7147699661036, 4129.0, 4221.97889010553, 4284.036091879302, 50947.23579146316, 53005.46994530082, 53117.2032419514, 58297.41702582962, 58583.0, 9457.38526995743, 9734.902650973541, 9971.40171589707, 9989.051040151175, 10032.1472674822, 18129.274829006958, 18146.637067258845, 18150.0, 18154.0, 18155.0, 6889.173299204128, 6921.93078069223, 6943.340382663639, 7067.540609860343, 7259.165196002422, 3494.305261947953, 3561.5191949086543, 3575.6424357564038, 3608.1881576645046, 3643.4352675335367, 2799.342154593669, 2918.1537354167476, 2939.76481881449, 2952.5423559348337, 2963.2443726849515, 500.87227756921754, 502.5502175552933, 505.1463027483541, 510.9540141387293, 512.4081685653076, 474.06498352154597, 481.1171500296924, 494.4659767451144, 499.1140725212704, 506.0940915760747, 7243.492955493065, 7569.848603028019, 7690.615469226516, 7790.805229869298, 7804.804879878048, 426.1753506964034, 456.6597884576012, 488.74585215688353, 508.5423119192756, 512.6872607709295, 3567.714582833367, 3628.9637103628625, 3672.394054980898, 3767.7174211933666, 3806.5241844769403, 104434.0, 105704.0, 106524.46737766262, 107198.46400768102, 111037.44481277703, 50011.0, 60702.0, 81811.0, 87144.0, 88689.0, 78775.0, 79008.99999999885, 79203.60398197972, 79394.0, 79873.0, 6996.650167491503, 7130.2869713028285, 7168.211496735251, 7194.712211511481, 7224.855502889807, 45024.324635129844, 46307.3053904185, 46854.76572617115, 47256.0, 48729.75635121943, 59579.404205957384, 66895.0, 68871.31128688648, 69388.63253460718, 70257.64871175612, 9230.400023998418, 9337.813243735223, 9342.439453632804, 9347.813043738952, 9544.379615324982, 10569.577216911237, 10620.256582039183, 10733.0, 10780.676579702622, 11715.0, 9312.301577381859, 9439.669611563562, 9485.857712134186, 9520.714378568655, 9798.75503112428, 9355.251579873458, 9431.952840235756, 9605.711828645151, 9612.66355677548, 10103.949480252551, 9192.494412807093, 9200.723978280663, 9279.62881484733, 10275.691729248134, 10573.04842564173, 7939.358066938712, 7998.280154786097, 8087.272145506932, 8149.7147599833725, 8771.210591046836, 6685.699143538455, 6902.654867256718, 7340.009098771598, 7419.925800741924, 7459.850802984019], "value": 6098.58697226063, "unit": "score"}], "name": "dromaeo_css", "value": 6108.934519525254, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 131.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 127.0, 155.0, 185.0, 123.0, 149.0, 153.0, 125.0, 191.0, 125.0], "value": 148.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 161.0, 161.0, 165.0, 161.0, 162.0, 161.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 75.0, 181.0, 78.0, 78.0, 75.0, 75.0, 165.0, 76.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 150.0, 85.0, 85.0, 85.0, 145.0, 83.0, 84.0, 141.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 64.0, 65.0, 67.0, 70.0, 66.0, 64.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 65.0, 69.0, 67.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 90.0, 81.0, 82.0, 81.0, 83.0, 82.0, 81.0, 82.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 132.0, 132.0, 132.0, 131.0, 129.0, 129.0, 132.0, 173.0, 146.0], "value": 137.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 179.0, 171.0, 177.0, 172.0, 173.0, 178.0, 179.0, 177.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 69.0, 73.0, 69.0, 69.0, 71.0, 67.0, 67.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1579.4999999999998, "alertThreshold": 2.0}]} 00:13:22 INFO - Return code: 0 00:13:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:13:22 INFO - # TBPL SUCCESS # 00:13:22 INFO - Running post-action listener: _resource_record_post_action 00:13:22 INFO - [mozharness: 2016-08-31 07:13:22.745609Z] Finished run-tests step (success) 00:13:22 INFO - Running post-run listener: _resource_record_post_run 00:13:22 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 405889024; Read time: 16; Write time: 264872 00:13:22 INFO - TinderboxPrint: CPU usage
13.0% 00:13:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 00:13:22 INFO - TinderboxPrint: I/O write bytes / time
405,889,024 / 264,872 00:13:22 INFO - TinderboxPrint: CPU idle
8,348.1 (87.0%) 00:13:22 INFO - TinderboxPrint: CPU user
1,200.1 (12.5%) 00:13:22 INFO - TinderboxPrint: Swap in / out
0 / 0 00:13:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114491392; Read time: 0; Write time: 96372 00:13:22 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 291397632; Read time: 0; Write time: 168500 00:13:23 INFO - Running post-run listener: _upload_blobber_files 00:13:23 INFO - Blob upload gear active. 00:13:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:13:23 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 00:13:23 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:13:23 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:13:23 INFO - (blobuploader) - INFO - Open directory for files ... 00:13:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:13:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:13:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:13:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:13:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:13:24 INFO - (blobuploader) - INFO - Done attempting. 00:13:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:13:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:13:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:13:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:13:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:13:28 INFO - (blobuploader) - INFO - Done attempting. 00:13:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:13:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:13:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:13:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:13:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:13:29 INFO - (blobuploader) - INFO - Done attempting. 00:13:29 INFO - (blobuploader) - INFO - Iteration through files over. 00:13:29 INFO - Return code: 0 00:13:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:13:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:13:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b5ef81554cfc02c74d5092b254823d170b15582c3415988c0a4c288ec3b26d9099c0b2d117442215f1d3e260f0ca96799d0477cdd329bc7022c635f1d5932012", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fce8061fb9be72b2ce1a4283cf38b72756f1069741267032f2dfec3da882ff1c56b1615371b8e3bdf57244bb525d16a0ade97793d78077f9e12eda180aad57c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2e17fff8933343edf7b8c54ded6f4f2567cb05e5c312a5dd863e96f3811242c687a2b704d6f89d4703d23f21db2fe6f4aa1571dde3b8bf4bf10397fab4cfc406"} 00:13:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:13:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:13:29 INFO - Contents: 00:13:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b5ef81554cfc02c74d5092b254823d170b15582c3415988c0a4c288ec3b26d9099c0b2d117442215f1d3e260f0ca96799d0477cdd329bc7022c635f1d5932012", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fce8061fb9be72b2ce1a4283cf38b72756f1069741267032f2dfec3da882ff1c56b1615371b8e3bdf57244bb525d16a0ade97793d78077f9e12eda180aad57c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2e17fff8933343edf7b8c54ded6f4f2567cb05e5c312a5dd863e96f3811242c687a2b704d6f89d4703d23f21db2fe6f4aa1571dde3b8bf4bf10397fab4cfc406"} 00:13:29 INFO - Running post-run listener: copy_logs_to_upload_dir 00:13:29 INFO - Copying logs to upload dir... 00:13:29 INFO - mkdir: /builds/slave/test/build/upload/logs 00:13:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.873948 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-08-31 00:13:29.201411) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 00:13:29.205097) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b5ef81554cfc02c74d5092b254823d170b15582c3415988c0a4c288ec3b26d9099c0b2d117442215f1d3e260f0ca96799d0477cdd329bc7022c635f1d5932012", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fce8061fb9be72b2ce1a4283cf38b72756f1069741267032f2dfec3da882ff1c56b1615371b8e3bdf57244bb525d16a0ade97793d78077f9e12eda180aad57c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2e17fff8933343edf7b8c54ded6f4f2567cb05e5c312a5dd863e96f3811242c687a2b704d6f89d4703d23f21db2fe6f4aa1571dde3b8bf4bf10397fab4cfc406"} build_url:https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/ee7wkeYXQUuOkY-WzdvyzA/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/Autoland-Non-PGO/sha512/b5ef81554cfc02c74d5092b254823d170b15582c3415988c0a4c288ec3b26d9099c0b2d117442215f1d3e260f0ca96799d0477cdd329bc7022c635f1d5932012", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fce8061fb9be72b2ce1a4283cf38b72756f1069741267032f2dfec3da882ff1c56b1615371b8e3bdf57244bb525d16a0ade97793d78077f9e12eda180aad57c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2e17fff8933343edf7b8c54ded6f4f2567cb05e5c312a5dd863e96f3811242c687a2b704d6f89d4703d23f21db2fe6f4aa1571dde3b8bf4bf10397fab4cfc406"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 00:13:29.230301) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 00:13:29.230648) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1472623372.716644-448508178 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 00:13:29.314429) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 00:13:29.314766) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 00:13:29.315062) ========= ========= Total master_lag: 0.19 =========