builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1490557550.55 results: success (0) revision: cc53710589fb500610495da5258b7b9221edf681 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.547901) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.548383) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.548663) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.565522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.565946) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-26 12:45:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2017-03-26 12:45:50 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135569 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.715972) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.716346) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018842 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.770889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:50.771259) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cc53710589fb500610495da5258b7b9221edf681 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev cc53710589fb500610495da5258b7b9221edf681 --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=78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-26 12:45:51,082 truncating revision to first 12 chars 2017-03-26 12:45:51,082 Setting DEBUG logging. 2017-03-26 12:45:51,082 attempt 1/10 2017-03-26 12:45:51,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cc53710589fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-26 12:45:51,582 unpacking tar archive at: mozilla-central-cc53710589fb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.807910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:51.596230) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:51.596592) ========= script_repo_revision: cc53710589fb500610495da5258b7b9221edf681 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:51.597034) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:51.597301) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 12:45:51.608604) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-03-26 12:45:51.609020) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:45:52 INFO - MultiFileLogger online at 20170326 12:45:52 in /builds/slave/test 12:45:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:45:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:45:52 INFO - {'append_to_log': False, 12:45:52 INFO - 'base_work_dir': '/builds/slave/test', 12:45:52 INFO - 'blob_upload_branch': 'Firefox', 12:45:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:45:52 INFO - 'branch': 'Firefox', 12:45:52 INFO - 'buildbot_json_path': 'buildprops.json', 12:45:52 INFO - 'config_files': ('talos/linux_config.py',), 12:45:52 INFO - 'default_actions': ('clobber', 12:45:52 INFO - 'read-buildbot-config', 12:45:52 INFO - 'download-and-extract', 12:45:52 INFO - 'populate-webroot', 12:45:52 INFO - 'create-virtualenv', 12:45:52 INFO - 'install', 12:45:52 INFO - 'run-tests'), 12:45:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:45:52 INFO - 'download_minidump_stackwalk': True, 12:45:52 INFO - 'download_symbols': 'ondemand', 12:45:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:45:52 INFO - 'tooltool.py': '/tools/tooltool.py', 12:45:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:45:52 INFO - '/tools/misc-python/virtualenv.py')}, 12:45:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:45:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:45:52 INFO - 'gecko_profile': False, 12:45:52 INFO - 'gecko_profile_interval': 0, 12:45:52 INFO - 'installer_path': 'installer.exe', 12:45:52 INFO - 'log_level': 'info', 12:45:52 INFO - 'log_name': 'talos', 12:45:52 INFO - 'log_to_console': True, 12:45:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:45:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:45:52 INFO - 'opt_config_files': (), 12:45:52 INFO - 'pip_index': False, 12:45:52 INFO - 'suite': 'dromaeojs', 12:45:52 INFO - 'system_bits': '32', 12:45:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:45:52 INFO - 'title': 'talos-linux64-ix-019', 12:45:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:45:52 INFO - 'use_talos_json': True, 12:45:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:45:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:45:52 INFO - 'work_dir': 'build'} 12:45:52 INFO - [mozharness: 2017-03-26 19:45:52.012587Z] Running clobber step. 12:45:52 INFO - Running pre-action listener: _resource_record_pre_action 12:45:52 INFO - Running main action method: clobber 12:45:52 INFO - rmtree: /builds/slave/test/build 12:45:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:45:53 INFO - Running post-action listener: _resource_record_post_action 12:45:53 INFO - [mozharness: 2017-03-26 19:45:53.431995Z] Finished clobber step (success) 12:45:53 INFO - [mozharness: 2017-03-26 19:45:53.432094Z] Running read-buildbot-config step. 12:45:53 INFO - Running pre-action listener: _resource_record_pre_action 12:45:53 INFO - Running main action method: read_buildbot_config 12:45:53 INFO - Using buildbot properties: 12:45:53 INFO - { 12:45:53 INFO - "project": "", 12:45:53 INFO - "product": "firefox", 12:45:53 INFO - "who": "archaeopteryx@coole-files.de", 12:45:53 INFO - "installer_path": "public/build/target.tar.bz2", 12:45:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:45:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:45:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 12:45:53 INFO - "stage_platform": "linux64-pgo", 12:45:53 INFO - "basedir": "/builds/slave/test", 12:45:53 INFO - "buildnumber": 110, 12:45:53 INFO - "platform": "ubuntu64_hw", 12:45:53 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:45:53 INFO - "slavebuilddir": "test", 12:45:53 INFO - "taskId": "eF0gxt91RP-xXoT-iHVdsQ", 12:45:53 INFO - "branch": "mozilla-central", 12:45:53 INFO - "script_repo_revision": "production", 12:45:53 INFO - "revision": "cc53710589fb500610495da5258b7b9221edf681", 12:45:53 INFO - "slavename": "talos-linux64-ix-019", 12:45:53 INFO - "repo_path": "mozilla-central" 12:45:53 INFO - } 12:45:53 INFO - Finding installer, test and symbols from parent task. 12:45:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eF0gxt91RP-xXoT-iHVdsQ'}, attempt #1 12:45:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eF0gxt91RP-xXoT-iHVdsQ'}, attempt #1 12:45:54 INFO - Task dependencies: UY4NKbjoQ7WKATm7b-qO6Q 12:45:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q'}, attempt #1 12:45:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.tar.bz2 12:45:54 INFO - Set test_url: https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/test_packages.json 12:45:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.crashreporter-symbols.zip 12:45:54 INFO - Running post-action listener: _resource_record_post_action 12:45:54 INFO - [mozharness: 2017-03-26 19:45:54.948348Z] Finished read-buildbot-config step (success) 12:45:54 INFO - [mozharness: 2017-03-26 19:45:54.948566Z] Running download-and-extract step. 12:45:54 INFO - Running pre-action listener: _resource_record_pre_action 12:45:54 INFO - Running main action method: download_and_extract 12:45:54 INFO - mkdir: /builds/slave/test/build/tests 12:45: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')]} 12:45:54 INFO - trying https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.test_packages.json 12:45:54 INFO - Downloading https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:45:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:45:55 INFO - Downloaded 986 bytes. 12:45:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:45:55 INFO - Using the following test package requirements: 12:45:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:45:55 INFO - u'common': [u'target.common.tests.zip'], 12:45:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:45:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:45:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:45:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:45:55 INFO - u'mozbase': [u'target.common.tests.zip'], 12:45:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:45:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:45:55 INFO - u'web-platform': [u'target.common.tests.zip', 12:45:55 INFO - u'target.web-platform.tests.zip'], 12:45:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:45:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:45:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.common.tests.zip 12:45:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:45:55 INFO - Fetch https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.common.tests.zip into memory 12:45:56 INFO - Expected file size: 60250059 12:45:56 INFO - Obtained file size: 60250059 12:46:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:46:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.common.tests.zip 12:46:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:46:06 INFO - Fetch https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.common.tests.zip into memory 12:46:07 INFO - Expected file size: 60250059 12:46:07 INFO - Obtained file size: 60250059 12:46:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.talos.tests.zip 12:46:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:46:18 INFO - Fetch https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.talos.tests.zip into memory 12:46:19 INFO - Expected file size: 14404171 12:46:19 INFO - Obtained file size: 14404171 12:46: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')]} 12:46:19 INFO - trying https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.tar.bz2 12:46:19 INFO - Downloading https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:46:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:46:20 INFO - Downloaded 68121047 bytes. 12:46:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.tar.bz2 12:46:20 INFO - mkdir: /builds/slave/test/properties 12:46:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:46:20 INFO - Writing to file /builds/slave/test/properties/build_url 12:46:20 INFO - Contents: 12:46:20 INFO - build_url:https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.tar.bz2 12:46:20 INFO - Running post-action listener: _resource_record_post_action 12:46:20 INFO - Running post-action listener: set_extra_try_arguments 12:46:20 INFO - [mozharness: 2017-03-26 19:46:20.735889Z] Finished download-and-extract step (success) 12:46:20 INFO - [mozharness: 2017-03-26 19:46:20.735985Z] Running populate-webroot step. 12:46:20 INFO - Running pre-action listener: _resource_record_pre_action 12:46:20 INFO - Running main action method: populate_webroot 12:46:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:46:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:46:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:46:20 INFO - '--tppagecycles', 12:46:20 INFO - '7'], 12:46:20 INFO - 'tests': ['tsvgm']}}, 12:46:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:46:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:46:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:46:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:46:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:46:20 INFO - 'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:46:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:46:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:46:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:46:20 INFO - 'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['damp', 'tps']}, 12:46:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:46:20 INFO - 'tests': ['damp', 'tps']}, 12:46:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['dromaeo_dom']}, 12:46:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:46:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['basic_compositor_video']}, 12:46:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:46:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['a11yr', 12:46:20 INFO - 'ts_paint', 12:46:20 INFO - 'tpaint', 12:46:20 INFO - 'sessionrestore', 12:46:20 INFO - 'sessionrestore_no_auto_restore', 12:46:20 INFO - 'tabpaint']}, 12:46:20 INFO - 'other-e10s': {'tests': ['a11yr', 12:46:20 INFO - 'ts_paint', 12:46:20 INFO - 'tpaint', 12:46:20 INFO - 'sessionrestore', 12:46:20 INFO - 'sessionrestore_no_auto_restore', 12:46:20 INFO - 'tabpaint']}, 12:46:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:46:20 INFO - 'ts_paint', 12:46:20 INFO - 'tpaint', 12:46:20 INFO - 'sessionrestore', 12:46:20 INFO - 'sessionrestore_no_auto_restore', 12:46:20 INFO - 'tabpaint']}, 12:46:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:46:20 INFO - 'ts_paint', 12:46:20 INFO - 'tpaint', 12:46:20 INFO - 'sessionrestore', 12:46:20 INFO - 'sessionrestore_no_auto_restore', 12:46:20 INFO - 'tabpaint']}, 12:46:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['a11yr', 12:46:20 INFO - 'ts_paint', 12:46:20 INFO - 'tpaint', 12:46:20 INFO - 'sessionrestore', 12:46:20 INFO - 'sessionrestore_no_auto_restore', 12:46:20 INFO - 'tabpaint']}, 12:46:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['a11yr', 12:46:20 INFO - 'ts_paint', 12:46:20 INFO - 'tpaint', 12:46:20 INFO - 'sessionrestore', 12:46:20 INFO - 'sessionrestore_no_auto_restore', 12:46:20 INFO - 'tabpaint']}, 12:46:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['tsvgx', 12:46:20 INFO - 'tsvgr_opacity', 12:46:20 INFO - 'tart', 12:46:20 INFO - 'tscrollx', 12:46:20 INFO - 'cart', 12:46:20 INFO - 'tsvg_static']}, 12:46:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:46:20 INFO - 'tsvgr_opacity', 12:46:20 INFO - 'tart', 12:46:20 INFO - 'tscrollx', 12:46:20 INFO - 'cart', 12:46:20 INFO - 'tsvg_static']}, 12:46:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:46:20 INFO - 'talos_options': ['--disable-e10s'], 12:46:20 INFO - 'tests': ['tp5o']}, 12:46:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:46:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:46:20 INFO - 'talos_options': ['--disable-e10s', 12:46:20 INFO - '--xperf_path', 12:46:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:46:20 INFO - 'tests': ['tp5n']}, 12:46:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:46:20 INFO - 'talos_options': ['--xperf_path', 12:46:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:46:20 INFO - 'tests': ['tp5n']}}, 12:46:20 INFO - 'talos.zip': {'path': '', 12:46:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:46:20 INFO - Running post-action listener: _resource_record_post_action 12:46:20 INFO - [mozharness: 2017-03-26 19:46:20.749103Z] Finished populate-webroot step (success) 12:46:20 INFO - [mozharness: 2017-03-26 19:46:20.749194Z] Running create-virtualenv step. 12:46:20 INFO - Running pre-action listener: _resource_record_pre_action 12:46:20 INFO - Running main action method: create_virtualenv 12:46:20 INFO - Creating virtualenv /builds/slave/test/build/venv 12:46:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:46:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:46:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:46:20 INFO - Using real prefix '/usr' 12:46:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:46:21 INFO - Installing distribute.............................................................................................................................................................................................done. 12:46:23 INFO - Installing pip.................done. 12:46:23 INFO - Return code: 0 12:46:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:46:23 INFO - Reading from file tmpfile_stdout 12:46:23 INFO - Output received: 12:46:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:46:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:46:23 INFO - Using env: {'DISPLAY': ':0', 12:46:23 INFO - 'HOME': '/home/cltbld', 12:46:23 INFO - 'LANG': 'en_US.UTF-8', 12:46:23 INFO - 'LANGUAGE': 'en_US:en', 12:46:23 INFO - 'LOGNAME': 'cltbld', 12:46:23 INFO - 'MAIL': '/var/mail/cltbld', 12:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:23 INFO - 'MOZ_NO_REMOTE': '1', 12:46:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:23 INFO - 'NO_EM_RESTART': '1', 12:46:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:23 INFO - 'PWD': '/builds/slave/test', 12:46:23 INFO - 'SHELL': '/bin/bash', 12:46:23 INFO - 'SHLVL': '1', 12:46:23 INFO - 'TERM': 'linux', 12:46:23 INFO - 'TMOUT': '86400', 12:46:23 INFO - 'USER': 'cltbld', 12:46:23 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:23 INFO - '_': '/tools/buildbot/bin/python'} 12:46:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:46:23 INFO - Cleaning up... 12:46:23 INFO - Return code: 0 12:46:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:46:23 INFO - Using env: {'DISPLAY': ':0', 12:46:23 INFO - 'HOME': '/home/cltbld', 12:46:23 INFO - 'LANG': 'en_US.UTF-8', 12:46:23 INFO - 'LANGUAGE': 'en_US:en', 12:46:23 INFO - 'LOGNAME': 'cltbld', 12:46:23 INFO - 'MAIL': '/var/mail/cltbld', 12:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:23 INFO - 'MOZ_NO_REMOTE': '1', 12:46:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:23 INFO - 'NO_EM_RESTART': '1', 12:46:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:23 INFO - 'PWD': '/builds/slave/test', 12:46:23 INFO - 'SHELL': '/bin/bash', 12:46:23 INFO - 'SHLVL': '1', 12:46:23 INFO - 'TERM': 'linux', 12:46:23 INFO - 'TMOUT': '86400', 12:46:23 INFO - 'USER': 'cltbld', 12:46:23 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:23 INFO - '_': '/tools/buildbot/bin/python'} 12:46:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:23 INFO - Downloading/unpacking psutil>=3.1.1 12:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:24 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 12:46:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:46:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:46:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:46:26 INFO - Installing collected packages: psutil 12:46:26 INFO - Running setup.py install for psutil 12:46:26 INFO - building 'psutil._psutil_linux' extension 12:46:26 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 12:46:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:46:26 INFO - building 'psutil._psutil_posix' extension 12:46:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:46:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:46:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:46:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:46:26 INFO - Successfully installed psutil 12:46:26 INFO - Cleaning up... 12:46:26 INFO - Return code: 0 12:46:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:46:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:46:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:46:26 INFO - Using env: {'DISPLAY': ':0', 12:46:26 INFO - 'HOME': '/home/cltbld', 12:46:26 INFO - 'LANG': 'en_US.UTF-8', 12:46:26 INFO - 'LANGUAGE': 'en_US:en', 12:46:26 INFO - 'LOGNAME': 'cltbld', 12:46:26 INFO - 'MAIL': '/var/mail/cltbld', 12:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:26 INFO - 'MOZ_NO_REMOTE': '1', 12:46:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:26 INFO - 'NO_EM_RESTART': '1', 12:46:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:26 INFO - 'PWD': '/builds/slave/test', 12:46:26 INFO - 'SHELL': '/bin/bash', 12:46:26 INFO - 'SHLVL': '1', 12:46:26 INFO - 'TERM': 'linux', 12:46:26 INFO - 'TMOUT': '86400', 12:46:26 INFO - 'USER': 'cltbld', 12:46:26 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:26 INFO - '_': '/tools/buildbot/bin/python'} 12:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:46:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:46:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:46:28 INFO - Installing collected packages: mozsystemmonitor 12:46:28 INFO - Running setup.py install for mozsystemmonitor 12:46:29 INFO - Successfully installed mozsystemmonitor 12:46:29 INFO - Cleaning up... 12:46:29 INFO - Return code: 0 12:46:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:46:29 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')]} 12:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:29 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')]} 12:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:29 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 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:29 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 12:46:29 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 12:46:29 INFO - Using env: {'DISPLAY': ':0', 12:46:29 INFO - 'HOME': '/home/cltbld', 12:46:29 INFO - 'LANG': 'en_US.UTF-8', 12:46:29 INFO - 'LANGUAGE': 'en_US:en', 12:46:29 INFO - 'LOGNAME': 'cltbld', 12:46:29 INFO - 'MAIL': '/var/mail/cltbld', 12:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:29 INFO - 'MOZ_NO_REMOTE': '1', 12:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:29 INFO - 'NO_EM_RESTART': '1', 12:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:29 INFO - 'PWD': '/builds/slave/test', 12:46:29 INFO - 'SHELL': '/bin/bash', 12:46:29 INFO - 'SHLVL': '1', 12:46:29 INFO - 'TERM': 'linux', 12:46:29 INFO - 'TMOUT': '86400', 12:46:29 INFO - 'USER': 'cltbld', 12:46:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:29 INFO - '_': '/tools/buildbot/bin/python'} 12:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:29 INFO - Downloading/unpacking jsonschema==2.5.1 12:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:46:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:31 INFO - Downloading functools32-3.2.3-2.tar.gz 12:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:46:31 INFO - warning: no files found matching '*.txt' 12:46:31 INFO - no previously-included directories found matching 'build' 12:46:31 INFO - no previously-included directories found matching 'dist' 12:46:31 INFO - no previously-included directories found matching '.git*' 12:46:31 INFO - Installing collected packages: jsonschema, functools32 12:46:31 INFO - Running setup.py install for functools32 12:46:31 INFO - warning: no files found matching '*.txt' 12:46:31 INFO - no previously-included directories found matching 'build' 12:46:31 INFO - no previously-included directories found matching 'dist' 12:46:31 INFO - no previously-included directories found matching '.git*' 12:46:31 INFO - Successfully installed jsonschema functools32 12:46:31 INFO - Cleaning up... 12:46:31 INFO - Return code: 0 12:46:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:46:31 INFO - Using env: {'DISPLAY': ':0', 12:46:31 INFO - 'HOME': '/home/cltbld', 12:46:31 INFO - 'LANG': 'en_US.UTF-8', 12:46:31 INFO - 'LANGUAGE': 'en_US:en', 12:46:31 INFO - 'LOGNAME': 'cltbld', 12:46:31 INFO - 'MAIL': '/var/mail/cltbld', 12:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:31 INFO - 'MOZ_NO_REMOTE': '1', 12:46:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:31 INFO - 'NO_EM_RESTART': '1', 12:46:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:31 INFO - 'PWD': '/builds/slave/test', 12:46:31 INFO - 'SHELL': '/bin/bash', 12:46:31 INFO - 'SHLVL': '1', 12:46:31 INFO - 'TERM': 'linux', 12:46:31 INFO - 'TMOUT': '86400', 12:46:31 INFO - 'USER': 'cltbld', 12:46:31 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:31 INFO - '_': '/tools/buildbot/bin/python'} 12:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:46:32 INFO - Cleaning up... 12:46:32 INFO - Return code: 0 12:46:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:32 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 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:32 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 12:46:32 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 12:46:32 INFO - Using env: {'DISPLAY': ':0', 12:46:32 INFO - 'HOME': '/home/cltbld', 12:46:32 INFO - 'LANG': 'en_US.UTF-8', 12:46:32 INFO - 'LANGUAGE': 'en_US:en', 12:46:32 INFO - 'LOGNAME': 'cltbld', 12:46:32 INFO - 'MAIL': '/var/mail/cltbld', 12:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:32 INFO - 'MOZ_NO_REMOTE': '1', 12:46:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:32 INFO - 'NO_EM_RESTART': '1', 12:46:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:32 INFO - 'PWD': '/builds/slave/test', 12:46:32 INFO - 'SHELL': '/bin/bash', 12:46:32 INFO - 'SHLVL': '1', 12:46:32 INFO - 'TERM': 'linux', 12:46:32 INFO - 'TMOUT': '86400', 12:46:32 INFO - 'USER': 'cltbld', 12:46:32 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:32 INFO - '_': '/tools/buildbot/bin/python'} 12:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:32 INFO - Downloading/unpacking blobuploader==1.2.4 12:46:32 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 12:46:32 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 12:46:32 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 12:46:32 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 12:46:32 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 12:46:32 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 12:46:34 INFO - Downloading blobuploader-1.2.4.tar.gz 12:46:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:46:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:46:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:34 INFO - Downloading docopt-0.6.1.tar.gz 12:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:46:35 INFO - Installing collected packages: blobuploader, requests, docopt 12:46:35 INFO - Running setup.py install for blobuploader 12:46:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:46:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:46:35 INFO - Running setup.py install for requests 12:46:35 INFO - Running setup.py install for docopt 12:46:35 INFO - Successfully installed blobuploader requests docopt 12:46:35 INFO - Cleaning up... 12:46:35 INFO - Return code: 0 12:46:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46: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')]} 12:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46: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')]} 12:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:46:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:46:35 INFO - Using env: {'DISPLAY': ':0', 12:46:35 INFO - 'HOME': '/home/cltbld', 12:46:35 INFO - 'LANG': 'en_US.UTF-8', 12:46:35 INFO - 'LANGUAGE': 'en_US:en', 12:46:35 INFO - 'LOGNAME': 'cltbld', 12:46:35 INFO - 'MAIL': '/var/mail/cltbld', 12:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:35 INFO - 'MOZ_NO_REMOTE': '1', 12:46:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:35 INFO - 'NO_EM_RESTART': '1', 12:46:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:35 INFO - 'PWD': '/builds/slave/test', 12:46:35 INFO - 'SHELL': '/bin/bash', 12:46:35 INFO - 'SHLVL': '1', 12:46:35 INFO - 'TERM': 'linux', 12:46:35 INFO - 'TMOUT': '86400', 12:46:35 INFO - 'USER': 'cltbld', 12:46:35 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:35 INFO - '_': '/tools/buildbot/bin/python'} 12:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:46:35 INFO - Running setup.py (path:/tmp/pip-t9DcUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:46:35 INFO - Running setup.py (path:/tmp/pip-le3kHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:46:36 INFO - Running setup.py (path:/tmp/pip-byzFoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:46:36 INFO - Running setup.py (path:/tmp/pip-bxSQFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:46:36 INFO - Running setup.py (path:/tmp/pip-NmRjV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:46:36 INFO - Running setup.py (path:/tmp/pip-LzJ5kN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:46:36 INFO - Running setup.py (path:/tmp/pip-1UaqN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:46:36 INFO - Running setup.py (path:/tmp/pip-NofU6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:46:36 INFO - Running setup.py (path:/tmp/pip-CQYpI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:46:36 INFO - Running setup.py (path:/tmp/pip-XRRdTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:46:36 INFO - Running setup.py (path:/tmp/pip-5dmzzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:46:36 INFO - Running setup.py (path:/tmp/pip-vJIgHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:46:36 INFO - Running setup.py (path:/tmp/pip-sQgksG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:46:36 INFO - Running setup.py (path:/tmp/pip-2NjQOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:36 INFO - Running setup.py (path:/tmp/pip-dGtC_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:46:36 INFO - Running setup.py (path:/tmp/pip-NkE7TR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:46:37 INFO - Running setup.py (path:/tmp/pip-PKkqsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:46:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:46:37 INFO - Running setup.py install for manifestparser 12:46:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:46:37 INFO - Running setup.py install for mozcrash 12:46:37 INFO - Running setup.py install for mozdebug 12:46:37 INFO - Running setup.py install for mozdevice 12:46:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:46:37 INFO - Running setup.py install for mozfile 12:46:37 INFO - Running setup.py install for mozhttpd 12:46:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:46:37 INFO - Running setup.py install for mozinfo 12:46:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:46:37 INFO - Running setup.py install for mozInstall 12:46:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:46:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:46:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:46:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:46:37 INFO - Running setup.py install for mozleak 12:46:38 INFO - Running setup.py install for mozlog 12:46:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:46:38 INFO - Running setup.py install for moznetwork 12:46:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:46:38 INFO - Running setup.py install for mozprocess 12:46:38 INFO - Running setup.py install for mozprofile 12:46:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:46:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:46:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:46:38 INFO - Running setup.py install for mozrunner 12:46:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:46:38 INFO - Running setup.py install for mozscreenshot 12:46:38 INFO - Running setup.py install for moztest 12:46:38 INFO - Running setup.py install for mozversion 12:46:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:46:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:46:38 INFO - Cleaning up... 12:46:38 INFO - Return code: 0 12:46:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:46:38 INFO - Using env: {'DISPLAY': ':0', 12:46:38 INFO - 'HOME': '/home/cltbld', 12:46:38 INFO - 'LANG': 'en_US.UTF-8', 12:46:38 INFO - 'LANGUAGE': 'en_US:en', 12:46:38 INFO - 'LOGNAME': 'cltbld', 12:46:38 INFO - 'MAIL': '/var/mail/cltbld', 12:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:38 INFO - 'MOZ_NO_REMOTE': '1', 12:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:38 INFO - 'NO_EM_RESTART': '1', 12:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:38 INFO - 'PWD': '/builds/slave/test', 12:46:38 INFO - 'SHELL': '/bin/bash', 12:46:38 INFO - 'SHLVL': '1', 12:46:38 INFO - 'TERM': 'linux', 12:46:38 INFO - 'TMOUT': '86400', 12:46:38 INFO - 'USER': 'cltbld', 12:46:38 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:38 INFO - '_': '/tools/buildbot/bin/python'} 12:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:46:39 INFO - Running setup.py (path:/tmp/pip-SYxxr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:46:39 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)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:46:39 INFO - Running setup.py (path:/tmp/pip-hFaeI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:46:39 INFO - Running setup.py (path:/tmp/pip-na3tkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:46:39 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)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:46:39 INFO - Running setup.py (path:/tmp/pip-pshFj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:46:39 INFO - Running setup.py (path:/tmp/pip-tNJntm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:46:39 INFO - Running setup.py (path:/tmp/pip-KeLvzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:46:39 INFO - Running setup.py (path:/tmp/pip-FWu8VJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:46:39 INFO - Running setup.py (path:/tmp/pip-ude_VH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:46:39 INFO - Running setup.py (path:/tmp/pip-oyxJIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:46:39 INFO - Running setup.py (path:/tmp/pip-yEOneN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:46:39 INFO - Running setup.py (path:/tmp/pip-O1FcOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:46:39 INFO - Running setup.py (path:/tmp/pip-yGwWkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 12:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:46:40 INFO - Running setup.py (path:/tmp/pip-bgjwmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:46:40 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)) 12:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:46:40 INFO - Running setup.py (path:/tmp/pip-CeQZfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:40 INFO - Running setup.py (path:/tmp/pip-NJxGKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:40 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)) 12:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:46:40 INFO - Running setup.py (path:/tmp/pip-ktDtKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 12:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:46:40 INFO - Running setup.py (path:/tmp/pip-af2jhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:46:40 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)) 12:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:46:40 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)) 12:46:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:46:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:46:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:42 INFO - Downloading blessings-1.6.tar.gz 12:46:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:46:42 INFO - Installing collected packages: blessings 12:46:42 INFO - Running setup.py install for blessings 12:46:42 INFO - Successfully installed blessings 12:46:42 INFO - Cleaning up... 12:46:42 INFO - Return code: 0 12:46:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:46:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:46:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:46:42 INFO - Reading from file tmpfile_stdout 12:46:42 INFO - Current package versions: 12:46:42 INFO - argparse == 1.2.1 12:46:42 INFO - blessings == 1.6 12:46:42 INFO - blobuploader == 1.2.4 12:46:42 INFO - docopt == 0.6.1 12:46:42 INFO - functools32 == 3.2.3-2 12:46:42 INFO - jsonschema == 2.5.1 12:46:42 INFO - manifestparser == 1.1 12:46:42 INFO - mozInstall == 1.12 12:46:42 INFO - mozcrash == 1.0 12:46:42 INFO - mozdebug == 0.1 12:46:42 INFO - mozdevice == 0.49 12:46:42 INFO - mozfile == 1.2 12:46:42 INFO - mozhttpd == 0.7 12:46:42 INFO - mozinfo == 0.9 12:46:42 INFO - mozleak == 0.1 12:46:42 INFO - mozlog == 3.4 12:46:42 INFO - moznetwork == 0.27 12:46:42 INFO - mozprocess == 0.25 12:46:42 INFO - mozprofile == 0.28 12:46:42 INFO - mozrunner == 6.13 12:46:42 INFO - mozscreenshot == 0.1 12:46:42 INFO - mozsystemmonitor == 0.3 12:46:42 INFO - moztest == 0.8 12:46:42 INFO - mozversion == 1.4 12:46:42 INFO - psutil == 3.1.1 12:46:42 INFO - requests == 1.2.3 12:46:42 INFO - wsgiref == 0.1.2 12:46:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46: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')]} 12:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46: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')]} 12:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:46:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:46:42 INFO - Using env: {'DISPLAY': ':0', 12:46:42 INFO - 'HOME': '/home/cltbld', 12:46:42 INFO - 'LANG': 'en_US.UTF-8', 12:46:42 INFO - 'LANGUAGE': 'en_US:en', 12:46:42 INFO - 'LOGNAME': 'cltbld', 12:46:42 INFO - 'MAIL': '/var/mail/cltbld', 12:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:42 INFO - 'MOZ_NO_REMOTE': '1', 12:46:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:42 INFO - 'NO_EM_RESTART': '1', 12:46:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:42 INFO - 'PWD': '/builds/slave/test', 12:46:42 INFO - 'SHELL': '/bin/bash', 12:46:42 INFO - 'SHLVL': '1', 12:46:42 INFO - 'TERM': 'linux', 12:46:42 INFO - 'TMOUT': '86400', 12:46:42 INFO - 'USER': 'cltbld', 12:46:42 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:42 INFO - '_': '/tools/buildbot/bin/python'} 12:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 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)) 12:46:43 INFO - Cleaning up... 12:46:43 INFO - Return code: 0 12:46:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:43 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 0x18b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18efa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b50310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', '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 12:46:43 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 12:46:43 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 12:46:43 INFO - Using env: {'DISPLAY': ':0', 12:46:43 INFO - 'HOME': '/home/cltbld', 12:46:43 INFO - 'LANG': 'en_US.UTF-8', 12:46:43 INFO - 'LANGUAGE': 'en_US:en', 12:46:43 INFO - 'LOGNAME': 'cltbld', 12:46:43 INFO - 'MAIL': '/var/mail/cltbld', 12:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:43 INFO - 'MOZ_NO_REMOTE': '1', 12:46:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:46:43 INFO - 'NO_EM_RESTART': '1', 12:46:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:43 INFO - 'PWD': '/builds/slave/test', 12:46:43 INFO - 'SHELL': '/bin/bash', 12:46:43 INFO - 'SHLVL': '1', 12:46:43 INFO - 'TERM': 'linux', 12:46:43 INFO - 'TMOUT': '86400', 12:46:43 INFO - 'USER': 'cltbld', 12:46:43 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:43 INFO - '_': '/tools/buildbot/bin/python'} 12:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:46:43 INFO - Cleaning up... 12:46:43 INFO - Return code: 0 12:46:43 INFO - Running post-action listener: _resource_record_post_action 12:46:43 INFO - Running post-action listener: _start_resource_monitoring 12:46:43 INFO - Starting resource monitoring. 12:46:43 INFO - [mozharness: 2017-03-26 19:46:43.322208Z] Finished create-virtualenv step (success) 12:46:43 INFO - [mozharness: 2017-03-26 19:46:43.322726Z] Running install step. 12:46:43 INFO - Running pre-action listener: _resource_record_pre_action 12:46:43 INFO - Running main action method: install 12:46:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:46:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:46:43 INFO - Reading from file tmpfile_stdout 12:46:43 INFO - Detecting whether we're running mozinstall >=1.0... 12:46:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:46:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:46:43 INFO - Reading from file tmpfile_stdout 12:46:43 INFO - Output received: 12:46:43 INFO - Usage: mozinstall [options] installer 12:46:43 INFO - Options: 12:46:43 INFO - -h, --help show this help message and exit 12:46:43 INFO - -d DEST, --destination=DEST 12:46:43 INFO - Directory to install application into. [default: 12:46:43 INFO - "/builds/slave/test"] 12:46:43 INFO - --app=APP Application being installed. [default: firefox] 12:46:43 INFO - mkdir: /builds/slave/test/build/application 12:46:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:46:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:47:00 INFO - Reading from file tmpfile_stdout 12:47:00 INFO - Output received: 12:47:00 INFO - /builds/slave/test/build/application/firefox/firefox 12:47:00 INFO - Running post-action listener: _resource_record_post_action 12:47:00 INFO - [mozharness: 2017-03-26 19:47:00.542391Z] Finished install step (success) 12:47:00 INFO - [mozharness: 2017-03-26 19:47:00.542667Z] Running run-tests step. 12:47:00 INFO - Running pre-action listener: _resource_record_pre_action 12:47:00 INFO - Running main action method: run_tests 12:47:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:47:00 INFO - Python 2.7.3 12:47:00 INFO - Return code: 0 12:47:00 INFO - grabbing minidump binary from tooltool 12:47: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')]} 12:47:00 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d400>, '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 0x1b4e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:47:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:47:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:47:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:47:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:47:00 INFO - Return code: 0 12:47:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:47:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:47:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:47:00 INFO - ENV: RUST_BACKTRACE is now 1 12:47:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:47:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:47:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 12:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 12:47:00 INFO - Using env: {'DISPLAY': ':0', 12:47:00 INFO - 'HOME': '/home/cltbld', 12:47:00 INFO - 'LANG': 'en_US.UTF-8', 12:47:00 INFO - 'LANGUAGE': 'en_US:en', 12:47:00 INFO - 'LOGNAME': 'cltbld', 12:47:00 INFO - 'MAIL': '/var/mail/cltbld', 12:47:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:47:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:00 INFO - 'MOZ_NO_REMOTE': '1', 12:47:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:47:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:00 INFO - 'NO_EM_RESTART': '1', 12:47:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:00 INFO - 'PWD': '/builds/slave/test', 12:47:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:47:00 INFO - 'RUST_BACKTRACE': '1', 12:47:00 INFO - 'SHELL': '/bin/bash', 12:47:00 INFO - 'SHLVL': '1', 12:47:00 INFO - 'TERM': 'linux', 12:47:00 INFO - 'TMOUT': '86400', 12:47:00 INFO - 'USER': 'cltbld', 12:47:00 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257', 12:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:00 INFO - '_': '/tools/buildbot/bin/python'} 12:47:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 12:47:00 INFO - mozversion application_buildid: 20170326185025 12:47:00 INFO - mozversion application_changeset: cc53710589fb500610495da5258b7b9221edf681 12:47:00 INFO - mozversion application_display_name: Nightly 12:47:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:47:00 INFO - mozversion application_name: Firefox 12:47:00 INFO - mozversion application_remotingname: firefox 12:47:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:47:00 INFO - mozversion application_vendor: Mozilla 12:47:00 INFO - mozversion application_version: 55.0a1 12:47:00 INFO - mozversion platform_buildid: 20170326185025 12:47:00 INFO - mozversion platform_changeset: cc53710589fb500610495da5258b7b9221edf681 12:47:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:47:00 INFO - mozversion platform_version: 55.0a1 12:47:00 INFO - using testdate: 1490557620 12:47:00 INFO - actual date: 1490557620 12:47:00 INFO - starting webserver on 'localhost:59749' 12:47:00 INFO - SUITE-START | Running 2 tests 12:47:00 INFO - TEST-START | dromaeo_css 12:47:00 INFO - operating with platform_type : linux_ 12:47:00 INFO - Initialising browser for dromaeo_css test... 12:47:00 INFO - TEST-INFO | started process 17405 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpej6fnf/profile http://localhost:59749/getInfo.html) 12:47:04 INFO - PID 17405 | __metrics Screen width/height:1600/1200 12:47:04 INFO - PID 17405 | colorDepth:24 12:47:04 INFO - PID 17405 | Browser inner width/height: 1024/697 12:47:04 INFO - PID 17405 | __metrics 12:47:05 ERROR - PID 17405 | 1490557625881 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 12:47:05 INFO - PID 17405 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:47:05 INFO - PID 17405 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:47:07 INFO - TEST-INFO | 17405: exit 0 12:47:07 INFO - Browser initialized. 12:47:07 INFO - Running cycle 1/1 for dromaeo_css test... 12:47:07 INFO - TEST-INFO | started process 17500 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpej6fnf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:47:19 INFO - PID 17500 | RSS: Main: 145268736 12:47:19 INFO - PID 17500 | 12:50:22 INFO - PID 17500 | Cycle 1(1): loaded http://localhost:59749/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59749/tests/dromaeo/cssquery-ext.html) 12:50:23 INFO - PID 17500 | RSS: Main: 274673664 12:50:23 INFO - PID 17500 | 12:52:09 INFO - PID 17500 | ************************* 12:52:09 INFO - PID 17500 | A coding exception was thrown and uncaught in a Task. 12:52:09 INFO - PID 17500 | 12:52:09 ERROR - PID 17500 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:52:09 INFO - PID 17500 | Full stack: 12:52:09 INFO - PID 17500 | ************************* 12:52:09 INFO - PID 17500 | ************************* 12:52:09 INFO - PID 17500 | A coding exception was thrown and uncaught in a Task. 12:52:09 INFO - PID 17500 | 12:52:09 ERROR - PID 17500 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:52:09 INFO - PID 17500 | Full stack: 12:52:09 INFO - PID 17500 | ************************* 12:52:09 INFO - PID 17500 | ************************* 12:52:09 INFO - PID 17500 | A coding exception was thrown and uncaught in a Task. 12:52:09 INFO - PID 17500 | 12:52:09 ERROR - PID 17500 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:52:09 INFO - PID 17500 | Full stack: 12:52:09 INFO - PID 17500 | ************************* 12:52:09 INFO - PID 17500 | ************************* 12:52:09 INFO - PID 17500 | A coding exception was thrown and uncaught in a Task. 12:52:09 INFO - PID 17500 | 12:52:09 ERROR - PID 17500 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:52:09 INFO - PID 17500 | Full stack: 12:52:09 INFO - PID 17500 | ************************* 12:53:25 INFO - PID 17500 | Cycle 1(1): loaded http://localhost:59749/tests/dromaeo/cssquery-ext.html (next: http://localhost:59749/tests/dromaeo/cssquery-jquery.html) 12:53:26 INFO - PID 17500 | RSS: Main: 229515264 12:53:26 INFO - PID 17500 | 12:56:28 INFO - PID 17500 | Cycle 1(1): loaded http://localhost:59749/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59749/tests/dromaeo/cssquery-mootools.html) 12:56:28 INFO - PID 17500 | RSS: Main: 176664576 12:56:28 INFO - PID 17500 | 12:59:30 INFO - PID 17500 | Cycle 1(1): loaded http://localhost:59749/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59749/tests/dromaeo/cssquery-prototype.html) 12:59:31 INFO - PID 17500 | RSS: Main: 176312320 12:59:31 INFO - PID 17500 | 13:02:33 INFO - PID 17500 | Cycle 1(1): loaded http://localhost:59749/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59749/tests/dromaeo/cssquery-yui.html) 13:02:34 INFO - PID 17500 | RSS: Main: 185430016 13:02:34 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | Cycle 1(1): loaded http://localhost:59749/tests/dromaeo/cssquery-yui.html (next: http://localhost:59749/tests/dromaeo/cssquery-dojo.html) 13:05:37 INFO - PID 17500 | RSS: Main: 271495168 13:05:37 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | __start_tp_report 13:05:37 INFO - PID 17500 | _x_x_mozilla_page_load 13:05:37 INFO - PID 17500 | _x_x_mozilla_page_load_details 13:05:37 INFO - PID 17500 | |i|pagename|runs| 13:05:37 INFO - PID 17500 | |0;dojo.html;3260.3642289753493;3293;3298.323843612059;3327.8169700666467;3335.316260166666;5620.718964051802;5638.887222255557;5644.153376993453;5670.432956704328;5679.772809087637;5631.7184140793;5635.492805647491;5693.715314234282;5695.971520142393;5761.625494342865;5013.874653133665;5079.746012699368;5090.389153301599;5161.612879034069;5185.429602743695;5224.268602395668;5226.686398816064;5229.712365819878;5232.816851410202;5268.025415298163;5053.797848086072;5080.59355251579;5091.516305950929;5155.097857874857;5156.716380599066;5047.192449208109;5050.191969284897;5054.898902021976;5055.292259083732;5062.164742817431;4974;5007.273945277914;5026.195808670632;5034;5040.42035165957;5543.584231182677;5549.334079910396;5551.500364967173;5554.472325129106;5563.777448902039;6106.786262480836;6112.991356426184;6114.266288045463;6116.174316467309;6129.1419201311855;6119.326874043852;6134.222293197073;6134.559047376611;6136.427173609184;6140.9776596329475;5724.4561766632105;5726.599138060337;5727.971360143214;5741.741621627037;5764.077747560412;6337.081123237105;6367.235931688227;6367.872642547216;6368;6370.554061215763;5869.295684517885;5929.792457263975;5938.851528711817;6081.391860813883;6089.8477538061015;5977.790777322773;5991.880162396727;6027.939720602825;6041.486473649789;6043.8186854394435;6096.146539484475;6102.481289090389;6106.297775755845;6117;6117.449429551361;5882.764689412461;6079.392060793885;6090.177825993523;6090.33006369299;6098.298695649968;4638.327442520816;4665.440147182359;4671.27595222741;4689.976550117296;4698.248280275208;4136.131412403369;4139.586041395836;4146.979265103655;4180.602842729936;4189.2668782962855;5672.404397538282;5673.489385955284;5675.31896172462;5677;5683.516901063374;5961.165436838846;5971.581989260797;6036.5170786336985;6118.969405152946;6129.823912511083;5645.576581756384;5667.858303542362;5695.1741997410145;5701.344345400249;5706.400827913092;5139.100657384943;5144.7942082316285;5160.767765450564;5161.303224064778;5177.352830896138;6169.907451388143;6295.5907865988565;6316.778912738032;6317.241930968313;6320.71556779946;5982.670953097621;6105.126180223362;6169.84575385619;6184.505239580732;6189.81430557084;6359.523035772243;6379.585326953733;6390.936090639034;6403.711832967434;6403.807885763248;6009.2188015557695;6056.394360563909;6063.545234107548;6065.2418447694035;6074.605150665193;6022.759089636541;6064.605800622945;6067.029275315928;6125.356837531995;6134.509239260938;5971.223740913653;5975.581709280309;5979.701014949323;5985.6408615483215;6024.487918526975;5815.854603634773;5866.2960444745195;5894.852628684317;5895.4399332063385;6001.159837622824;5944.108383742473;6009.188759517584;6021.42796434338;6021.638701677914;6028.487578555696;5822.155787410887;5851.268591426072;5874.794382196603;5886.411358864079;5898.64608123514;5746.396628353963;5767.567432442667;5774.249347584787;5775.306963164279;5775.48020678141 13:05:37 INFO - PID 17500 | |1;ext.html;607.3956413368699;662.2020465339265;676.0670275020473;1118.62526053772;1139.3164101539073;29579.408411831777;36863.26273474529;37757.24485510288;37785.81107094468;37823.243535129346;38976;39503;39514.40728389077;39529.80235098828;39535;5996.5202783777195;6153.815385538441;7929.603519824014;8106.432549721522;8107.351411887035;7855.450118491708;7901.802454938644;7902.288794008538;8101.149379315176;8103.10865804761;8009.199080092002;8098.190180981884;8103.837923241532;8105.513669179868;8107.878381824277;7989.5605741684185;8028.638711258008;8046.597670116529;8050.798730031737;8069.798255043642;8013.278804907586;8014.639341229658;8070.2333278338465;8078.798030049237;8082.313003394718;35636.46545301822;35861;35899;35930.640693593254;35966.82016589926;2945.101743968089;2970.3465237647683;2971.390864872696;2981.970180298191;3031.317953460476;27748.722512774817;27920.84118639028;27941.169151133443;28684;29082.563761543595;3516.8188095463747;3553;3603.6756691897854;3610.205754733954;3641.526601541809;208801;209068;209284;209627;209743;42717.57282427198;42773.57226427758;42911.57088429138;42942;42996.19551360589;43624.78187609105;43631.12737745227;43759.34360984588;43839.34240986388;44009.11981760347;15114.168720720467;15244;15249.923750381176;15263.08421494714;15271.694566108616;10214.540345684314;10638.73403164927;10715.142788576895;12418.689532761753;12486.063545234143;12398.876011239952;12407.317597532041;12474.875251247371;12654.557090501788;12667.746645067231;5113.207452844815;5190.844274671766;5234.18870075139;5237.973810130925;5264.289320941701;1001.3090967232587;1003.8494225866033;1005.1757558801859;1023.1712313026472;1040.6357774778767;25265.494690106094;25273;25384.238472845474;25407.745922540907;25460.363490912874;1116.5031560955297;1122.405125283601;1135.216700476685;1159.5303901919567;1168.824676298562;12066;12824.551140710217;12892.677683057998;12921.289329086803;13028.348582571023;304474;304995;305117;305171;305780;53101.7344913273;53136.73431632817;53176.46823531792;53374.46625533695;53466.7326663372;1463.0928824129107;1501.129344979909;1512.2438780609696;1516.3782849031843;1519.0961377980084;21696.674549882082;22223.77776222217;22711.432214194778;22718;22892.19877304353;39886;39926.60073399229;39978.60021399749;40036;40042.39936400898;292919;296533;296997;298501;298552;30477.695223047485;30902;33358.33283334271;33364;33427.98580212988;39030.024249393995;42295.78852105843;42321.365179522945;42657.78671106625;42943.35584966165;31685;31906;32639.836800815843;32687.509687355185;33108.834455827564;31848.36303273968;31852.522212166372;31918;32088.35823283568;32116;36353.454698180074;37305.62694373022;38088.04779880525;38218.808905955295;38244.23511529698;30521.694783052775;30617.846910766195;30693.846530767205;31141;31437.214069648442;24561.385965350295;24939.62590561107;25203.495930081663;25217;25571.374297654485 13:05:37 INFO - PID 17500 | |2;jquery.html;389.46945935098347;435.5579087226466;437.63894786800876;449.7773602066977;454.7430701653565;17730.556736081588;19822.207111715532;20116.295929642467;20192.59614807703;20279.797202027974;19296.4211073668;20490.48773780656;21000.579988400223;21040.368788936357;21210.15159393626;5985.431384018522;6108.14485971962;6177.320494745574;6192.894415898593;6200.16297799798;5851;6115.4190351916495;6122.785702500413;6126.448619624233;6126.938730612681;5858.238429004224;5975.850603734898;6107.297660769002;6109.5723299369065;6111.572189946705;5982.072778719284;6008.429199226067;6051.606645568068;6063.787767428141;6065.848353791191;5428.809991650312;5437.238786569884;5458.6724796512335;5470.617056806025;5473.233747275384;24563.263102106965;24821.379465513506;24829.00683972639;24860;25365.365865853317;1992.1334219614514;2009.1862795567818;2033.1460786469718;2036.2465887621527;2078.6570215914358;15244.23778811066;15946.122963237018;16133.193340332937;16279.430219942364;16340.509784706477;1890.2816929566677;1899.6390685769798;1910.1308904448442;1927.8264956154014;1955.257002339102;100091;101899.490502547;102125;102779.486102569;102991;12377.319247441297;12393.690157746129;12423;12521.373931303398;12619.116661833767;17172.656546869242;17222.741658875126;17249.741253881202;17311.04789236604;17331.220095095752;8594.87107693385;8694.17405346491;8850.778730531789;8854.911450885409;8900.243479304203;8084;8093.231143041402;8166.387520935954;8343.707970221014;8366.247037766509;7579.57453614849;7596.924030759622;7754;7827.412944029221;7830.804229894185;2131.3925531223526;2140;2152.8170105541203;2154.256781410411;2243.618584840581;1008.702432782331;1020.637673625867;1024.5952848624752;1041.7082817306637;1048.1352883870836;19371.90314048449;19789.703154452407;19837.801621983883;19853;20162.092705828272;1068.471106802138;1069.8769641491328;1073.7745073534645;1086.1745073744012;1100.4827730966433;9937.205023598095;10881.673549793519;10881.727956801144;10894;10995.560177592966;301089;302596;303173;573313;578380;104807.4759626197;107152;108418;109839;110726;1077.876044254905;1227.1471327427353;1229.2562999385423;1259.3153469094952;1274.6048724895313;14304;18182.27270909149;18940.621187575896;19724;20143.899280503505;48040.27939580979;48413.27380089231;49692.75153624354;50328.49671503238;51115.23327150021;65239.673801630684;65431.67284163549;65461.34538654552;65633.67183164053;66578;8474.491530508189;8556.358273129415;8678.088800675838;8783.033866274576;8929.196372326522;13349.733005340033;13452.5291614793;13622.523211687543;13673;14492.34784434682;11303.434828258718;11305.604303849326;11548.775815832363;11753.118516111497;11801.114916381477;10911.781764364827;10943.28868623537;11057.889421106007;11130.16523760705;11250.268732532359;11618.186726929034;11723.237989530653;11845.230060046415;12484.687882803002;12514.624561263177;8373.330133589176;8415.95792021036;8564.785880353042;8716.825663486821;8808.603612837309;11230.94384528072;11994.220375675553;12019.519219231483;12090.758184836079;12173.147879648339 13:05:37 INFO - PID 17500 | |3;mootools.html;1295.92224466532;1352.1751731443817;1355.058234527004;1365.310518188315;1367.0840536840317;9865;10242.180625549947;10252.692419227416;10262;10273.075423211909;9407.435553866773;10632.681019569405;10927.781444371127;10935.398553079598;10943.83584246237;2256.142665787003;2298.1726578431753;2298.885055747214;2300.608896487601;2305.3890718959487;1818.245428147324;2172.9891350543303;2260.525289689167;2263.4567703751063;2286.9085236590518;1966.783653798081;2169.1540299283292;2236.4408897775556;2241.1371621925596;2242.125571027301;2153.257126291427;2203.8347123965686;2213.756486786452;2218.3344996501064;2221.31139346803;2080.9479763005893;2100.5693832764246;2112.0284669052253;2116.5872654832315;2119.8940052997286;10661.893381066246;10760.946195269053;10763.3541987481;10764.439156322289;10770.299930504492;5473.698946557938;5722.570807189478;5734.311882574118;5736.110902810071;5736.512396446307;3256.8208748518823;3436.759426840122;3516.5604299462566;3540.150363912642;3545.680888720002;1178.4755783676267;1180.4333919718583;1193.3198077096058;1207.3661327802886;1273.617914625618;16378.590535236595;16481.25834337458;16516.917415412965;16541.83458165427;16680.249388777655;4693.881501267856;4746.5490778376;4770.594499467515;4858.611311095104;4907.779149938261;11937.224080434744;11948.283103013848;11950.940245298718;11959.282443053444;11965.222260553037;5721.342045664718;5733.455321744427;5735.11105778605;5735.627184233012;5743.4256574342235;5246.921296180561;5261.842144735664;5293.814716484904;5322.2282768998275;5381.650192737459;4027.9194416111513;4063.349864021742;4081.3265811141423;4084.252914370749;4097.467329247179;1128.2553514680271;1130.5364800431782;1131.2533727739653;1150.511032811052;1157.3287493253893;584.430180573937;763.8701420758484;773.1997382708869;774.1948373691298;786.4180506425319;5738.110592858114;5833.329167145747;5895.882082358415;5993.370696076936;6271.278802937721;552.6242155334411;570.5692202387171;592.520058752412;597.1073245497921;705.6965504832868;3684.8526058957877;3755.8732380285805;3799;3863.9613603864163;3959.7822119783687;13857.861421385656;14683.92658036703;14733.631659208606;14738.55784326472;14772.18752968597;13275.933620331836;13815.171089734647;13831.654208644864;13998.090124141898;14423.927880360741;9885.308028437941;10052.447115408722;10060.798784024279;10061.245406594535;10119.595216191417;5968.403159683997;6132.969335153296;6228.937710622836;6265.718042687999;6373.075903993802;12526.49894004266;12899;12994.610161695164;13072.281024543741;13089.41097650589;12909.289989050692;12923.741525169631;12930.741385172432;13091.934540327238;13131.34343282813;8785.25325347327;9069.775580296575;9084.954575227082;9086.136817002374;9093.090690930854;9738.516678247002;10310;10369.325993810378;10386.376817390981;10395.532201051123;8380.497170169809;8510.446820956618;8739.912600874164;8743.431676940978;8884.244839188741;8319.168083191875;8357.038940521918;8410.87383689233;8429.072801991653;8470.449420787629;7543.886841697269;7643.350315223268;7722.498037627536;7724.0731112266885;7732.226777322223;5558.44415558441;5603.38362780102;5726.74229659658;5822.592418530662;5835.533157347317;5652.152177173457;5656.632318899258;5756.16535602327;5780.450857168562;5902.852428689317 13:05:37 INFO - PID 17500 | |4;prototype.html;388.4154347706701;390.3052566431751;392.19795518165347;392.73882867892854;395.7111308744616;5745.511631511321;5815.418458154182;5816.243888294521;5842.094475356316;5852.180694702734;5254.185601231806;5742.339630942442;5779.711014449282;5793;5822.243108395903;4552.157850797613;4553.203189441834;4558.475775285851;4564.360989461478;4571.862844114666;4566.725996440225;4573.382593349905;4577.496475387721;4593.862184134481;4595.195840727859;4212.3260278355465;4360.97819510902;4399.172719733836;4559.498455169929;4576.032306108372;4345.739255644656;4371.584699453547;4373.693841431101;4390.473143222818;4412.735235885857;3851.9422208666892;3855.556610989744;3861.841447565719;3868.729188956774;3869.090763670535;5527;5690.374058853523;5693.516051135658;5699.2590963174935;5699.344575373843;5172.405173405032;5173.948260517384;5183.2743415921805;5184.144616138333;5189.506996835295;6169.2288463942;6171.876562468726;6207.906881396782;6208.565400421972;6221.657808820513;4169.478815148106;4272.572742725733;4317.15815415994;4323.481182258118;4323.978380108111;6163.599366041238;6311.810645680683;6423.807285781434;6506;6507.121538592277;4315.331123675835;4384.6273066789045;4388.924713445226;4396.692231543825;4401.735895846259;4200.957990420118;4300.3549467580115;4432.84485043022;4437.511873693891;4446.799894004777;4788.379565223816;4982.427020892644;4988.176950803077;4994.175960966472;5140.357455318085;6147.877042459215;6158.261008678987;6162.7226774795245;6173.629582225081;6190.380961903774;1483.814523184602;1522.4899658614488;1523.1470376589145;1527.2440142129494;1532.5172570640214;1004.382304882492;1024.9180065594735;1029.9957541397227;1031.4842578710645;1033.9224558158169;5826.854328641733;5887.5575484006695;5891.911621325684;5914.526837852962;5934.584579079509;6285.96857015712;6301.275353334425;6336.271328797156;6353.68231588428;6375.947968585224;5648.435156484319;5787.479126878601;5843.415658434207;5854.862975014089;5862.824115276549;3333.633300336961;3338.4825352070466;3344.966550334514;3353.2958078803726;3366.259422926952;6348.841278968062;6497.758273395671;6596;6601;6605.504587155982;6526.184226971629;6549.443297319781;6567.211934567787;6576.177977752781;6603.438707709803;6497.076315657879;6628;6669.733210671616;6713.865722685617;6714.832129196711;6379.904301435575;6380.649064301508;6392;6398.360163983564;6424.421802037839;6333.145025421509;6338.936610633835;6359.459445947146;6383.2978372378075;6395.136656551492;6453.612163499535;6598.505112116514;6627.5360724750735;6688.565243259172;6699.263081061177;4380.9780951095045;4381.583749543788;4398.582134697199;4413.22768515515;4421.11577684458;6208.379162083756;6375.234971803413;6375.234971803413;6411.3909178627955;6427.517936154901;6164.537659675452;6218.626882387071;6221.6623425963635;6225.3774622537385;6239.344868788712;6196.194494715658;6196.481861943853;6206.255249370104;6207.6721229538925;6221.906671400023;5942.31663358702;6137.164272329794;6183.938160618336;6227;6229.501639868709;6209.96895015522;6241.469475094668;6249.437550620466;6251.874662560783;6254.906176407267;5934.13074881411;6009.338972712911;6024.307204671343;6037.426444487766;6075.939240607538 13:05:37 INFO - PID 17500 | |5;yui.html;679.5277282288805;694.198201077755;732.2092140488273;751.8195633048067;757.1746795992369;14951.102933823964;15719.528414147593;16304.918475407621;16354.509364719062;16507;16690.749638755427;16817.15914204288;16863.494095177135;16919.238634261455;17223.483295501122;805.2511164616903;823;826.5412695953743;827.1439060572309;827.2596026556229;775.8021704465353;802.6147449224376;812.252727490707;812.5490352854164;822.3955392786297;862.6635612111281;863.8445079885618;864.779481232288;864.9437786543886;865.2645251536206;846.5047946951041;861.2765277167179;862.8403745307075;863.3697400897317;863.9870401943945;629.4901130084601;630.7130255733676;631.2961048431013;633.885900537903;634.8825467288566;15476.303566339542;16005.439809606723;16072.75890861638;16433.91783041089;16440.83559164405;14454.494092706705;15019.774703379459;16103.677926441405;16403.42588009414;16658.6668266634;10875.782484350348;10883.292585981886;11319.547218111265;11970.880291197063;12037.157398982075;3092.6907309268895;3115.6572776994512;3124.3126512167287;3176.348848486055;3231.8545665445113;81623;81821.59089204576;82112.58943705303;82114.58942705249;82224.58887705523;12490.687732806571;12701.174423662433;12711.682207944876;12765.170263932814;12969.351532423341;21592.35222943314;22053.889730551244;22423.43941401478;24600.507989840462;24835;8066.717664881701;8175.754727358189;8287.088420273765;8389.32885369169;8413.368997325226;3998.4402183694306;4085.55058943516;4104.138130992525;4113.506379234511;4217.367394890791;4169.353750168729;4257.808398622069;4268.807903644343;4283.828646854091;4291.849785257501;759.4607828441758;768.3353898877431;768.5081547809407;779.3609240422912;780.441983981456;564.5963136357524;591.5060924128401;600.342624825815;604.4227762486814;605.231356177652;8755.299576033902;9390.765230869283;9754.561044753003;10697.358158510515;10977.615783447542;538.3916174722539;555.9916601250984;558.7262241501635;561.2871653000748;562.9240052592847;4306.763128027988;4822;4869.586085182799;4879.072976134523;4886.438059623118;74269;75044.24955750373;75052.62473687597;76264;79027.60486197533;55733.44266557364;62626.686866565375;64916.67541662262;65392.673036634515;67103.66448167825;57178.428215718144;57750.7112464435;58528.12207816801;58626.413735862945;58659;9769.778777652793;10913.890861091606;11167.553297867995;11224.831627525757;11464.828027579426;32587.69649214005;32633.673663264013;32727.672723272462;32749.345013100083;32900.835495822364;39847.80076099601;39908;40270.79864600658;40301.596984029784;40478.595214047484;12963.351832408152;13447.596572102853;13587;13667.931660341636;13764.862351376758;14060.156390616596;14494.565163044703;14547;14696;14924;13539.458421663418;13807.792883106962;13835;14894.627634308796;14896.40414383456;13109.868901310865;13251.602451926457;13665.18008919468;13814.06134396606;14352;12127.939360303142;13591.660208494866;14243.430262789787;14420.783688244475;14696.706065878408;9899.257555683496;10045.547950342398;10072.496375181065;10228.693139205836;10752.301100428447;11718.70703232426;12262.75474490523;12358.196717213352;12526.812097818722;12936.611901642966 13:05:37 INFO - PID 17500 | __end_tp_report 13:05:37 INFO - PID 17500 | __start_cc_report 13:05:37 INFO - PID 17500 | _x_x_mozilla_cycle_collect,1396 13:05:37 INFO - PID 17500 | __end_cc_report 13:05:37 INFO - PID 17500 | __startTimestamp1490558737460__endTimestamp 13:05:37 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | ------- Summary: start ------- 13:05:37 INFO - PID 17500 | Number of tests: 6 13:05:37 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | [#0] dojo.html Cycles:165 Average:5638.18 Median:5851.27 stddev:656.21 (11.2%) stddev-sans-first:631.16 13:05:37 INFO - PID 17500 | Values: 3260.4 3293.0 3298.3 3327.8 3335.3 5620.7 5638.9 5644.2 5670.4 5679.8 5631.7 5635.5 5693.7 5696.0 5761.6 5013.9 5079.7 5090.4 5161.6 5185.4 5224.3 5226.7 5229.7 5232.8 5268.0 5053.8 5080.6 5091.5 5155.1 5156.7 5047.2 5050.2 5054.9 5055.3 5062.2 4974.0 5007.3 5026.2 5034.0 5040.4 5543.6 5549.3 5551.5 5554.5 5563.8 6106.8 6113.0 6114.3 6116.2 6129.1 6119.3 6134.2 6134.6 6136.4 6141.0 5724.5 5726.6 5728.0 5741.7 5764.1 6337.1 6367.2 6367.9 6368.0 6370.6 5869.3 5929.8 5938.9 6081.4 6089.8 5977.8 5991.9 6027.9 6041.5 6043.8 6096.1 6102.5 6106.3 6117.0 6117.4 5882.8 6079.4 6090.2 6090.3 6098.3 4638.3 4665.4 4671.3 4690.0 4698.2 4136.1 4139.6 4147.0 4180.6 4189.3 5672.4 5673.5 5675.3 5677.0 5683.5 5961.2 5971.6 6036.5 6119.0 6129.8 5645.6 5667.9 5695.2 5701.3 5706.4 5139.1 5144.8 5160.8 5161.3 5177.4 6169.9 6295.6 6316.8 6317.2 6320.7 5982.7 6105.1 6169.8 6184.5 6189.8 6359.5 6379.6 6390.9 6403.7 6403.8 6009.2 6056.4 6063.5 6065.2 6074.6 6022.8 6064.6 6067.0 6125.4 6134.5 5971.2 5975.6 5979.7 5985.6 6024.5 5815.9 5866.3 5894.9 5895.4 6001.2 5944.1 6009.2 6021.4 6021.6 6028.5 5822.2 5851.3 5874.8 5886.4 5898.6 5746.4 5767.6 5774.2 5775.3 5775.5 13:05:37 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | [#1] ext.html Cycles:180 Average:42164.13 Median:32102.18 stddev:71716.53 (223.4%) stddev-sans-first:71849.83 13:05:37 INFO - PID 17500 | Values: 607.4 662.2 676.1 1118.6 1139.3 29579.4 36863.3 37757.2 37785.8 37823.2 38976.0 39503.0 39514.4 39529.8 39535.0 5996.5 6153.8 7929.6 8106.4 8107.4 7855.5 7901.8 7902.3 8101.1 8103.1 8009.2 8098.2 8103.8 8105.5 8107.9 7989.6 8028.6 8046.6 8050.8 8069.8 8013.3 8014.6 8070.2 8078.8 8082.3 35636.5 35861.0 35899.0 35930.6 35966.8 2945.1 2970.3 2971.4 2982.0 3031.3 27748.7 27920.8 27941.2 28684.0 29082.6 3516.8 3553.0 3603.7 3610.2 3641.5 208801.0 209068.0 209284.0 209627.0 209743.0 42717.6 42773.6 42911.6 42942.0 42996.2 43624.8 43631.1 43759.3 43839.3 44009.1 15114.2 15244.0 15249.9 15263.1 15271.7 10214.5 10638.7 10715.1 12418.7 12486.1 12398.9 12407.3 12474.9 12654.6 12667.7 5113.2 5190.8 5234.2 5238.0 5264.3 1001.3 1003.8 1005.2 1023.2 1040.6 25265.5 25273.0 25384.2 25407.7 25460.4 1116.5 1122.4 1135.2 1159.5 1168.8 12066.0 12824.6 12892.7 12921.3 13028.3 304474.0 304995.0 305117.0 305171.0 305780.0 53101.7 53136.7 53176.5 53374.5 53466.7 1463.1 1501.1 1512.2 1516.4 1519.1 21696.7 22223.8 22711.4 22718.0 22892.2 39886.0 39926.6 39978.6 40036.0 40042.4 292919.0 296533.0 296997.0 298501.0 298552.0 30477.7 30902.0 33358.3 33364.0 33428.0 39030.0 42295.8 42321.4 42657.8 42943.4 31685.0 31906.0 32639.8 32687.5 33108.8 31848.4 31852.5 31918.0 32088.4 32116.0 36353.5 37305.6 38088.0 38218.8 38244.2 30521.7 30617.8 30693.8 31141.0 31437.2 24561.4 24939.6 25203.5 25217.0 25571.4 13:05:37 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | [#2] jquery.html Cycles:180 Average:28847.04 Median:20236.20 stddev:72975.26 (360.6%) stddev-sans-first:73148.70 13:05:37 INFO - PID 17500 | Values: 389.5 435.6 437.6 449.8 454.7 17730.6 19822.2 20116.3 20192.6 20279.8 19296.4 20490.5 21000.6 21040.4 21210.2 5985.4 6108.1 6177.3 6192.9 6200.2 5851.0 6115.4 6122.8 6126.4 6126.9 5858.2 5975.9 6107.3 6109.6 6111.6 5982.1 6008.4 6051.6 6063.8 6065.8 5428.8 5437.2 5458.7 5470.6 5473.2 24563.3 24821.4 24829.0 24860.0 25365.4 1992.1 2009.2 2033.1 2036.2 2078.7 15244.2 15946.1 16133.2 16279.4 16340.5 1890.3 1899.6 1910.1 1927.8 1955.3 100091.0 101899.5 102125.0 102779.5 102991.0 12377.3 12393.7 12423.0 12521.4 12619.1 17172.7 17222.7 17249.7 17311.0 17331.2 8594.9 8694.2 8850.8 8854.9 8900.2 8084.0 8093.2 8166.4 8343.7 8366.2 7579.6 7596.9 7754.0 7827.4 7830.8 2131.4 2140.0 2152.8 2154.3 2243.6 1008.7 1020.6 1024.6 1041.7 1048.1 19371.9 19789.7 19837.8 19853.0 20162.1 1068.5 1069.9 1073.8 1086.2 1100.5 9937.2 10881.7 10881.7 10894.0 10995.6 301089.0 302596.0 303173.0 573313.0 578380.0 104807.5 107152.0 108418.0 109839.0 110726.0 1077.9 1227.1 1229.3 1259.3 1274.6 14304.0 18182.3 18940.6 19724.0 20143.9 48040.3 48413.3 49692.8 50328.5 51115.2 65239.7 65431.7 65461.3 65633.7 66578.0 8474.5 8556.4 8678.1 8783.0 8929.2 13349.7 13452.5 13622.5 13673.0 14492.3 11303.4 11305.6 11548.8 11753.1 11801.1 10911.8 10943.3 11057.9 11130.2 11250.3 11618.2 11723.2 11845.2 12484.7 12514.6 8373.3 8416.0 8564.8 8716.8 8808.6 11230.9 11994.2 12019.5 12090.8 12173.1 13:05:37 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | [#3] mootools.html Cycles:180 Average:6687.35 Median:2781.10 stddev:4412.20 (158.6%) stddev-sans-first:4405.98 13:05:37 INFO - PID 17500 | Values: 1295.9 1352.2 1355.1 1365.3 1367.1 9865.0 10242.2 10252.7 10262.0 10273.1 9407.4 10632.7 10927.8 10935.4 10943.8 2256.1 2298.2 2298.9 2300.6 2305.4 1818.2 2173.0 2260.5 2263.5 2286.9 1966.8 2169.2 2236.4 2241.1 2242.1 2153.3 2203.8 2213.8 2218.3 2221.3 2080.9 2100.6 2112.0 2116.6 2119.9 10661.9 10760.9 10763.4 10764.4 10770.3 5473.7 5722.6 5734.3 5736.1 5736.5 3256.8 3436.8 3516.6 3540.2 3545.7 1178.5 1180.4 1193.3 1207.4 1273.6 16378.6 16481.3 16516.9 16541.8 16680.2 4693.9 4746.5 4770.6 4858.6 4907.8 11937.2 11948.3 11950.9 11959.3 11965.2 5721.3 5733.5 5735.1 5735.6 5743.4 5246.9 5261.8 5293.8 5322.2 5381.7 4027.9 4063.3 4081.3 4084.3 4097.5 1128.3 1130.5 1131.3 1150.5 1157.3 584.4 763.9 773.2 774.2 786.4 5738.1 5833.3 5895.9 5993.4 6271.3 552.6 570.6 592.5 597.1 705.7 3684.9 3755.9 3799.0 3864.0 3959.8 13857.9 14683.9 14733.6 14738.6 14772.2 13275.9 13815.2 13831.7 13998.1 14423.9 9885.3 10052.4 10060.8 10061.2 10119.6 5968.4 6133.0 6228.9 6265.7 6373.1 12526.5 12899.0 12994.6 13072.3 13089.4 12909.3 12923.7 12930.7 13091.9 13131.3 8785.3 9069.8 9085.0 9086.1 9093.1 9738.5 10310.0 10369.3 10386.4 10395.5 8380.5 8510.4 8739.9 8743.4 8884.2 8319.2 8357.0 8410.9 8429.1 8470.4 7543.9 7643.4 7722.5 7724.1 7732.2 5558.4 5603.4 5726.7 5822.6 5835.5 5652.2 5656.6 5756.2 5780.5 5902.9 13:05:37 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | [#4] prototype.html Cycles:180 Average:5163.25 Median:5834.47 stddev:1560.83 (26.8%) stddev-sans-first:1523.51 13:05:37 INFO - PID 17500 | Values: 388.4 390.3 392.2 392.7 395.7 5745.5 5815.4 5816.2 5842.1 5852.2 5254.2 5742.3 5779.7 5793.0 5822.2 4552.2 4553.2 4558.5 4564.4 4571.9 4566.7 4573.4 4577.5 4593.9 4595.2 4212.3 4361.0 4399.2 4559.5 4576.0 4345.7 4371.6 4373.7 4390.5 4412.7 3851.9 3855.6 3861.8 3868.7 3869.1 5527.0 5690.4 5693.5 5699.3 5699.3 5172.4 5173.9 5183.3 5184.1 5189.5 6169.2 6171.9 6207.9 6208.6 6221.7 4169.5 4272.6 4317.2 4323.5 4324.0 6163.6 6311.8 6423.8 6506.0 6507.1 4315.3 4384.6 4388.9 4396.7 4401.7 4201.0 4300.4 4432.8 4437.5 4446.8 4788.4 4982.4 4988.2 4994.2 5140.4 6147.9 6158.3 6162.7 6173.6 6190.4 1483.8 1522.5 1523.1 1527.2 1532.5 1004.4 1024.9 1030.0 1031.5 1033.9 5826.9 5887.6 5891.9 5914.5 5934.6 6286.0 6301.3 6336.3 6353.7 6375.9 5648.4 5787.5 5843.4 5854.9 5862.8 3333.6 3338.5 3345.0 3353.3 3366.3 6348.8 6497.8 6596.0 6601.0 6605.5 6526.2 6549.4 6567.2 6576.2 6603.4 6497.1 6628.0 6669.7 6713.9 6714.8 6379.9 6380.6 6392.0 6398.4 6424.4 6333.1 6338.9 6359.5 6383.3 6395.1 6453.6 6598.5 6627.5 6688.6 6699.3 4381.0 4381.6 4398.6 4413.2 4421.1 6208.4 6375.2 6375.2 6411.4 6427.5 6164.5 6218.6 6221.7 6225.4 6239.3 6196.2 6196.5 6206.3 6207.7 6221.9 5942.3 6137.2 6183.9 6227.0 6229.5 6210.0 6241.5 6249.4 6251.9 6254.9 5934.1 6009.3 6024.3 6037.4 6075.9 13:05:37 INFO - PID 17500 | 13:05:37 INFO - PID 17500 | [#5] yui.html Cycles:180 Average:16895.55 Median:22282.07 stddev:20973.29 (94.1%) stddev-sans-first:20996.78 13:05:37 INFO - PID 17500 | Values: 679.5 694.2 732.2 751.8 757.2 14951.1 15719.5 16304.9 16354.5 16507.0 16690.7 16817.2 16863.5 16919.2 17223.5 805.3 823.0 826.5 827.1 827.3 775.8 802.6 812.3 812.5 822.4 862.7 863.8 864.8 864.9 865.3 846.5 861.3 862.8 863.4 864.0 629.5 630.7 631.3 633.9 634.9 15476.3 16005.4 16072.8 16433.9 16440.8 14454.5 15019.8 16103.7 16403.4 16658.7 10875.8 10883.3 11319.5 11970.9 12037.2 3092.7 3115.7 3124.3 3176.3 3231.9 81623.0 81821.6 82112.6 82114.6 82224.6 12490.7 12701.2 12711.7 12765.2 12969.4 21592.4 22053.9 22423.4 24600.5 24835.0 8066.7 8175.8 8287.1 8389.3 8413.4 3998.4 4085.6 4104.1 4113.5 4217.4 4169.4 4257.8 4268.8 4283.8 4291.8 759.5 768.3 768.5 779.4 780.4 564.6 591.5 600.3 604.4 605.2 8755.3 9390.8 9754.6 10697.4 10977.6 538.4 556.0 558.7 561.3 562.9 4306.8 4822.0 4869.6 4879.1 4886.4 74269.0 75044.2 75052.6 76264.0 79027.6 55733.4 62626.7 64916.7 65392.7 67103.7 57178.4 57750.7 58528.1 58626.4 58659.0 9769.8 10913.9 11167.6 11224.8 11464.8 32587.7 32633.7 32727.7 32749.3 32900.8 39847.8 39908.0 40270.8 40301.6 40478.6 12963.4 13447.6 13587.0 13667.9 13764.9 14060.2 14494.6 14547.0 14696.0 14924.0 13539.5 13807.8 13835.0 14894.6 14896.4 13109.9 13251.6 13665.2 13814.1 14352.0 12127.9 13591.7 14243.4 14420.8 14696.7 9899.3 10045.5 10072.5 10228.7 10752.3 11718.7 12262.8 12358.2 12526.8 12936.6 13:05:37 INFO - PID 17500 | -------- Summary: end -------- 13:05:37 INFO - PID 17500 | 13:05:38 INFO - TEST-INFO | 17500: exit 0 13:05:38 INFO - TEST-OK | dromaeo_css | took 1117388ms 13:05:38 INFO - TEST-START | kraken 13:05:38 INFO - operating with platform_type : linux_ 13:05:38 INFO - Initialising browser for kraken test... 13:05:38 INFO - TEST-INFO | started process 17831 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgfAGx1/profile http://localhost:59749/getInfo.html) 13:05:42 INFO - PID 17831 | __metrics Screen width/height:1600/1200 13:05:42 INFO - PID 17831 | colorDepth:24 13:05:42 INFO - PID 17831 | Browser inner width/height: 1024/697 13:05:42 INFO - PID 17831 | __metrics 13:05:43 ERROR - PID 17831 | 1490558743255 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 13:05:43 INFO - PID 17831 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:05:43 INFO - PID 17831 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:05:44 INFO - TEST-INFO | 17831: exit 0 13:05:44 INFO - Browser initialized. 13:05:44 INFO - Running cycle 1/1 for kraken test... 13:05:44 INFO - TEST-INFO | started process 17929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgfAGx1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:05:56 INFO - PID 17929 | RSS: Main: 145715200 13:05:56 INFO - PID 17929 | 13:05:58 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59749/tests/kraken/driver.html?testName=audio-beat-detection) 13:05:59 INFO - PID 17929 | RSS: Main: 201379840 13:05:59 INFO - PID 17929 | 13:06:02 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59749/tests/kraken/driver.html?testName=audio-dft) 13:06:02 INFO - PID 17929 | RSS: Main: 180834304 13:06:02 INFO - PID 17929 | 13:06:05 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59749/tests/kraken/driver.html?testName=audio-fft) 13:06:06 INFO - PID 17929 | RSS: Main: 261660672 13:06:06 INFO - PID 17929 | 13:06:08 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59749/tests/kraken/driver.html?testName=audio-oscillator) 13:06:09 INFO - PID 17929 | RSS: Main: 173367296 13:06:09 INFO - PID 17929 | 13:06:11 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59749/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:06:11 INFO - PID 17929 | RSS: Main: 176414720 13:06:11 INFO - PID 17929 | 13:06:14 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59749/tests/kraken/driver.html?testName=imaging-darkroom) 13:06:15 INFO - PID 17929 | RSS: Main: 215379968 13:06:15 INFO - PID 17929 | 13:06:18 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59749/tests/kraken/driver.html?testName=imaging-desaturate) 13:06:19 INFO - PID 17929 | RSS: Main: 229253120 13:06:19 INFO - PID 17929 | 13:06:22 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59749/tests/kraken/driver.html?testName=json-parse-financial) 13:06:22 INFO - PID 17929 | RSS: Main: 241020928 13:06:22 INFO - PID 17929 | 13:06:24 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59749/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:06:25 INFO - PID 17929 | RSS: Main: 178397184 13:06:25 INFO - PID 17929 | 13:06:26 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59749/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:06:27 INFO - PID 17929 | RSS: Main: 184090624 13:06:27 INFO - PID 17929 | 13:06:29 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59749/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:06:30 INFO - PID 17929 | RSS: Main: 194633728 13:06:30 INFO - PID 17929 | 13:06:32 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59749/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:06:33 INFO - PID 17929 | RSS: Main: 203304960 13:06:33 INFO - PID 17929 | 13:06:36 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59749/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:06:37 INFO - PID 17929 | RSS: Main: 199958528 13:06:37 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | Cycle 1(1): loaded http://localhost:59749/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59749/tests/kraken/driver.html?testName=ai-astar) 13:06:38 INFO - PID 17929 | RSS: Main: 266858496 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | __start_tp_report 13:06:38 INFO - PID 17929 | _x_x_mozilla_page_load 13:06:38 INFO - PID 17929 | _x_x_mozilla_page_load_details 13:06:38 INFO - PID 17929 | |i|pagename|runs| 13:06:38 INFO - PID 17929 | |0;ai-astar;102;109;108;109;108;110;109;110;110;109 13:06:38 INFO - PID 17929 | |1;audio-beat-detection;141;120;118;175;121;118;116;121;140;121 13:06:38 INFO - PID 17929 | |2;audio-dft;163;160;160;161;161;161;160;161;161;162 13:06:38 INFO - PID 17929 | |3;audio-fft;73;73;77;106;74;75;75;74;91;74 13:06:38 INFO - PID 17929 | |4;audio-oscillator;82;82;112;83;82;83;100;83;82;111 13:06:38 INFO - PID 17929 | |5;imaging-gaussian-blur;113;114;113;113;113;113;112;113;113;113 13:06:38 INFO - PID 17929 | |6;imaging-darkroom;146;148;147;147;147;147;147;146;147;148 13:06:38 INFO - PID 17929 | |7;imaging-desaturate;94;93;94;94;93;94;93;94;93;93 13:06:38 INFO - PID 17929 | |8;json-parse-financial;59;57;55;56;57;58;57;58;57;56 13:06:38 INFO - PID 17929 | |9;json-stringify-tinderbox;54;54;55;55;54;55;55;54;55;54 13:06:38 INFO - PID 17929 | |10;stanford-crypto-aes;79;87;80;80;81;81;79;80;78;79 13:06:38 INFO - PID 17929 | |11;stanford-crypto-ccm;141;131;144;136;126;132;126;131;173;127 13:06:38 INFO - PID 17929 | |12;stanford-crypto-pbkdf2;187;183;179;183;186;183;182;185;181;179 13:06:38 INFO - PID 17929 | |13;stanford-crypto-sha256-iterative;66;66;65;67;64;64;69;70;64;66 13:06:38 INFO - PID 17929 | __end_tp_report 13:06:38 INFO - PID 17929 | __start_cc_report 13:06:38 INFO - PID 17929 | _x_x_mozilla_cycle_collect,1750 13:06:38 INFO - PID 17929 | __end_cc_report 13:06:38 INFO - PID 17929 | __startTimestamp1490558798982__endTimestamp 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | ------- Summary: start ------- 13:06:38 INFO - PID 17929 | Number of tests: 14 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#0] ai-astar Cycles:10 Average:108.40 Median:109.00 stddev:2.37 (2.2%) stddev-sans-first:0.78 13:06:38 INFO - PID 17929 | Values: 102.0 109.0 108.0 109.0 108.0 110.0 109.0 110.0 110.0 109.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:121.00 stddev:18.45 (15.3%) stddev-sans-first:19.06 13:06:38 INFO - PID 17929 | Values: 141.0 120.0 118.0 175.0 121.0 118.0 116.0 121.0 140.0 121.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:0.67 13:06:38 INFO - PID 17929 | Values: 163.0 160.0 160.0 161.0 161.0 161.0 160.0 161.0 161.0 162.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#3] audio-fft Cycles:10 Average:79.20 Median:74.50 stddev:10.83 (14.5%) stddev-sans-first:11.25 13:06:38 INFO - PID 17929 | Values: 73.0 73.0 77.0 106.0 74.0 75.0 75.0 74.0 91.0 74.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:82.00 stddev:12.60 (15.4%) stddev-sans-first:13.02 13:06:38 INFO - PID 17929 | Values: 82.0 82.0 112.0 83.0 82.0 83.0 100.0 83.0 82.0 111.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 13:06:38 INFO - PID 17929 | Values: 113.0 114.0 113.0 113.0 113.0 113.0 112.0 113.0 113.0 113.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 13:06:38 INFO - PID 17929 | Values: 146.0 148.0 147.0 147.0 147.0 147.0 147.0 146.0 147.0 148.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 13:06:38 INFO - PID 17929 | Values: 94.0 93.0 94.0 94.0 93.0 94.0 93.0 94.0 93.0 93.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:1.15 (2.0%) stddev-sans-first:0.97 13:06:38 INFO - PID 17929 | Values: 59.0 57.0 55.0 56.0 57.0 58.0 57.0 58.0 57.0 56.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:55.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 13:06:38 INFO - PID 17929 | Values: 54.0 54.0 55.0 55.0 54.0 55.0 55.0 54.0 55.0 54.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:80.00 stddev:2.50 (3.1%) stddev-sans-first:2.60 13:06:38 INFO - PID 17929 | Values: 79.0 87.0 80.0 80.0 81.0 81.0 79.0 80.0 78.0 79.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#11] stanford-crypto-ccm Cycles:10 Average:136.70 Median:134.00 stddev:14.14 (10.6%) stddev-sans-first:14.91 13:06:38 INFO - PID 17929 | Values: 141.0 131.0 144.0 136.0 126.0 132.0 126.0 131.0 173.0 127.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.80 Median:183.00 stddev:2.70 (1.5%) stddev-sans-first:2.40 13:06:38 INFO - PID 17929 | Values: 187.0 183.0 179.0 183.0 186.0 183.0 182.0 185.0 181.0 179.0 13:06:38 INFO - PID 17929 | 13:06:38 INFO - PID 17929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:2.08 (3.1%) stddev-sans-first:2.20 13:06:38 INFO - PID 17929 | Values: 66.0 66.0 65.0 67.0 64.0 64.0 69.0 70.0 64.0 66.0 13:06:38 INFO - PID 17929 | -------- Summary: end -------- 13:06:38 INFO - PID 17929 | 13:06:40 INFO - TEST-INFO | 17929: exit 0 13:06:40 INFO - TEST-OK | kraken | took 61832ms 13:06:40 INFO - SUITE-END | took 1179s 13:06:40 INFO - Completed test suite (00:19:39) 13:06:40 INFO - javascript benchmark 13:06:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7330.348531418906, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3260.3642289753493, 3293.0, 3298.323843612059, 3327.8169700666467, 3335.316260166666, 5620.718964051802, 5638.887222255557, 5644.153376993453, 5670.432956704328, 5679.772809087637, 5631.7184140793, 5635.492805647491, 5693.715314234282, 5695.971520142393, 5761.625494342865, 5013.874653133665, 5079.746012699368, 5090.389153301599, 5161.612879034069, 5185.429602743695, 5224.268602395668, 5226.686398816064, 5229.712365819878, 5232.816851410202, 5268.025415298163, 5053.797848086072, 5080.59355251579, 5091.516305950929, 5155.097857874857, 5156.716380599066, 5047.192449208109, 5050.191969284897, 5054.898902021976, 5055.292259083732, 5062.164742817431, 4974.0, 5007.273945277914, 5026.195808670632, 5034.0, 5040.42035165957, 5543.584231182677, 5549.334079910396, 5551.500364967173, 5554.472325129106, 5563.777448902039, 6106.786262480836, 6112.991356426184, 6114.266288045463, 6116.174316467309, 6129.1419201311855, 6119.326874043852, 6134.222293197073, 6134.559047376611, 6136.427173609184, 6140.9776596329475, 5724.4561766632105, 5726.599138060337, 5727.971360143214, 5741.741621627037, 5764.077747560412, 6337.081123237105, 6367.235931688227, 6367.872642547216, 6368.0, 6370.554061215763, 5869.295684517885, 5929.792457263975, 5938.851528711817, 6081.391860813883, 6089.8477538061015, 5977.790777322773, 5991.880162396727, 6027.939720602825, 6041.486473649789, 6043.8186854394435, 6096.146539484475, 6102.481289090389, 6106.297775755845, 6117.0, 6117.449429551361, 5882.764689412461, 6079.392060793885, 6090.177825993523, 6090.33006369299, 6098.298695649968, 4638.327442520816, 4665.440147182359, 4671.27595222741, 4689.976550117296, 4698.248280275208, 4136.131412403369, 4139.586041395836, 4146.979265103655, 4180.602842729936, 4189.2668782962855, 5672.404397538282, 5673.489385955284, 5675.31896172462, 5677.0, 5683.516901063374, 5961.165436838846, 5971.581989260797, 6036.5170786336985, 6118.969405152946, 6129.823912511083, 5645.576581756384, 5667.858303542362, 5695.1741997410145, 5701.344345400249, 5706.400827913092, 5139.100657384943, 5144.7942082316285, 5160.767765450564, 5161.303224064778, 5177.352830896138, 6169.907451388143, 6295.5907865988565, 6316.778912738032, 6317.241930968313, 6320.71556779946, 5982.670953097621, 6105.126180223362, 6169.84575385619, 6184.505239580732, 6189.81430557084, 6359.523035772243, 6379.585326953733, 6390.936090639034, 6403.711832967434, 6403.807885763248, 6009.2188015557695, 6056.394360563909, 6063.545234107548, 6065.2418447694035, 6074.605150665193, 6022.759089636541, 6064.605800622945, 6067.029275315928, 6125.356837531995, 6134.509239260938, 5971.223740913653, 5975.581709280309, 5979.701014949323, 5985.6408615483215, 6024.487918526975, 5815.854603634773, 5866.2960444745195, 5894.852628684317, 5895.4399332063385, 6001.159837622824, 5944.108383742473, 6009.188759517584, 6021.42796434338, 6021.638701677914, 6028.487578555696, 5822.155787410887, 5851.268591426072, 5874.794382196603, 5886.411358864079, 5898.64608123514, 5746.396628353963, 5767.567432442667, 5774.249347584787, 5775.306963164279, 5775.48020678141], "value": 5593.279892494138, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.3956413368699, 662.2020465339265, 676.0670275020473, 1118.62526053772, 1139.3164101539073, 29579.408411831777, 36863.26273474529, 37757.24485510288, 37785.81107094468, 37823.243535129346, 38976.0, 39503.0, 39514.40728389077, 39529.80235098828, 39535.0, 5996.5202783777195, 6153.815385538441, 7929.603519824014, 8106.432549721522, 8107.351411887035, 7855.450118491708, 7901.802454938644, 7902.288794008538, 8101.149379315176, 8103.10865804761, 8009.199080092002, 8098.190180981884, 8103.837923241532, 8105.513669179868, 8107.878381824277, 7989.5605741684185, 8028.638711258008, 8046.597670116529, 8050.798730031737, 8069.798255043642, 8013.278804907586, 8014.639341229658, 8070.2333278338465, 8078.798030049237, 8082.313003394718, 35636.46545301822, 35861.0, 35899.0, 35930.640693593254, 35966.82016589926, 2945.101743968089, 2970.3465237647683, 2971.390864872696, 2981.970180298191, 3031.317953460476, 27748.722512774817, 27920.84118639028, 27941.169151133443, 28684.0, 29082.563761543595, 3516.8188095463747, 3553.0, 3603.6756691897854, 3610.205754733954, 3641.526601541809, 208801.0, 209068.0, 209284.0, 209627.0, 209743.0, 42717.57282427198, 42773.57226427758, 42911.57088429138, 42942.0, 42996.19551360589, 43624.78187609105, 43631.12737745227, 43759.34360984588, 43839.34240986388, 44009.11981760347, 15114.168720720467, 15244.0, 15249.923750381176, 15263.08421494714, 15271.694566108616, 10214.540345684314, 10638.73403164927, 10715.142788576895, 12418.689532761753, 12486.063545234143, 12398.876011239952, 12407.317597532041, 12474.875251247371, 12654.557090501788, 12667.746645067231, 5113.207452844815, 5190.844274671766, 5234.18870075139, 5237.973810130925, 5264.289320941701, 1001.3090967232587, 1003.8494225866033, 1005.1757558801859, 1023.1712313026472, 1040.6357774778767, 25265.494690106094, 25273.0, 25384.238472845474, 25407.745922540907, 25460.363490912874, 1116.5031560955297, 1122.405125283601, 1135.216700476685, 1159.5303901919567, 1168.824676298562, 12066.0, 12824.551140710217, 12892.677683057998, 12921.289329086803, 13028.348582571023, 304474.0, 304995.0, 305117.0, 305171.0, 305780.0, 53101.7344913273, 53136.73431632817, 53176.46823531792, 53374.46625533695, 53466.7326663372, 1463.0928824129107, 1501.129344979909, 1512.2438780609696, 1516.3782849031843, 1519.0961377980084, 21696.674549882082, 22223.77776222217, 22711.432214194778, 22718.0, 22892.19877304353, 39886.0, 39926.60073399229, 39978.60021399749, 40036.0, 40042.39936400898, 292919.0, 296533.0, 296997.0, 298501.0, 298552.0, 30477.695223047485, 30902.0, 33358.33283334271, 33364.0, 33427.98580212988, 39030.024249393995, 42295.78852105843, 42321.365179522945, 42657.78671106625, 42943.35584966165, 31685.0, 31906.0, 32639.836800815843, 32687.509687355185, 33108.834455827564, 31848.36303273968, 31852.522212166372, 31918.0, 32088.35823283568, 32116.0, 36353.454698180074, 37305.62694373022, 38088.04779880525, 38218.808905955295, 38244.23511529698, 30521.694783052775, 30617.846910766195, 30693.846530767205, 31141.0, 31437.214069648442, 24561.385965350295, 24939.62590561107, 25203.495930081663, 25217.0, 25571.374297654485], "value": 16933.355517652104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.46945935098347, 435.5579087226466, 437.63894786800876, 449.7773602066977, 454.7430701653565, 17730.556736081588, 19822.207111715532, 20116.295929642467, 20192.59614807703, 20279.797202027974, 19296.4211073668, 20490.48773780656, 21000.579988400223, 21040.368788936357, 21210.15159393626, 5985.431384018522, 6108.14485971962, 6177.320494745574, 6192.894415898593, 6200.16297799798, 5851.0, 6115.4190351916495, 6122.785702500413, 6126.448619624233, 6126.938730612681, 5858.238429004224, 5975.850603734898, 6107.297660769002, 6109.5723299369065, 6111.572189946705, 5982.072778719284, 6008.429199226067, 6051.606645568068, 6063.787767428141, 6065.848353791191, 5428.809991650312, 5437.238786569884, 5458.6724796512335, 5470.617056806025, 5473.233747275384, 24563.263102106965, 24821.379465513506, 24829.00683972639, 24860.0, 25365.365865853317, 1992.1334219614514, 2009.1862795567818, 2033.1460786469718, 2036.2465887621527, 2078.6570215914358, 15244.23778811066, 15946.122963237018, 16133.193340332937, 16279.430219942364, 16340.509784706477, 1890.2816929566677, 1899.6390685769798, 1910.1308904448442, 1927.8264956154014, 1955.257002339102, 100091.0, 101899.490502547, 102125.0, 102779.486102569, 102991.0, 12377.319247441297, 12393.690157746129, 12423.0, 12521.373931303398, 12619.116661833767, 17172.656546869242, 17222.741658875126, 17249.741253881202, 17311.04789236604, 17331.220095095752, 8594.87107693385, 8694.17405346491, 8850.778730531789, 8854.911450885409, 8900.243479304203, 8084.0, 8093.231143041402, 8166.387520935954, 8343.707970221014, 8366.247037766509, 7579.57453614849, 7596.924030759622, 7754.0, 7827.412944029221, 7830.804229894185, 2131.3925531223526, 2140.0, 2152.8170105541203, 2154.256781410411, 2243.618584840581, 1008.702432782331, 1020.637673625867, 1024.5952848624752, 1041.7082817306637, 1048.1352883870836, 19371.90314048449, 19789.703154452407, 19837.801621983883, 19853.0, 20162.092705828272, 1068.471106802138, 1069.8769641491328, 1073.7745073534645, 1086.1745073744012, 1100.4827730966433, 9937.205023598095, 10881.673549793519, 10881.727956801144, 10894.0, 10995.560177592966, 301089.0, 302596.0, 303173.0, 573313.0, 578380.0, 104807.4759626197, 107152.0, 108418.0, 109839.0, 110726.0, 1077.876044254905, 1227.1471327427353, 1229.2562999385423, 1259.3153469094952, 1274.6048724895313, 14304.0, 18182.27270909149, 18940.621187575896, 19724.0, 20143.899280503505, 48040.27939580979, 48413.27380089231, 49692.75153624354, 50328.49671503238, 51115.23327150021, 65239.673801630684, 65431.67284163549, 65461.34538654552, 65633.67183164053, 66578.0, 8474.491530508189, 8556.358273129415, 8678.088800675838, 8783.033866274576, 8929.196372326522, 13349.733005340033, 13452.5291614793, 13622.523211687543, 13673.0, 14492.34784434682, 11303.434828258718, 11305.604303849326, 11548.775815832363, 11753.118516111497, 11801.114916381477, 10911.781764364827, 10943.28868623537, 11057.889421106007, 11130.16523760705, 11250.268732532359, 11618.186726929034, 11723.237989530653, 11845.230060046415, 12484.687882803002, 12514.624561263177, 8373.330133589176, 8415.95792021036, 8564.785880353042, 8716.825663486821, 8808.603612837309, 11230.94384528072, 11994.220375675553, 12019.519219231483, 12090.758184836079, 12173.147879648339], "value": 9921.50596944843, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.92224466532, 1352.1751731443817, 1355.058234527004, 1365.310518188315, 1367.0840536840317, 9865.0, 10242.180625549947, 10252.692419227416, 10262.0, 10273.075423211909, 9407.435553866773, 10632.681019569405, 10927.781444371127, 10935.398553079598, 10943.83584246237, 2256.142665787003, 2298.1726578431753, 2298.885055747214, 2300.608896487601, 2305.3890718959487, 1818.245428147324, 2172.9891350543303, 2260.525289689167, 2263.4567703751063, 2286.9085236590518, 1966.783653798081, 2169.1540299283292, 2236.4408897775556, 2241.1371621925596, 2242.125571027301, 2153.257126291427, 2203.8347123965686, 2213.756486786452, 2218.3344996501064, 2221.31139346803, 2080.9479763005893, 2100.5693832764246, 2112.0284669052253, 2116.5872654832315, 2119.8940052997286, 10661.893381066246, 10760.946195269053, 10763.3541987481, 10764.439156322289, 10770.299930504492, 5473.698946557938, 5722.570807189478, 5734.311882574118, 5736.110902810071, 5736.512396446307, 3256.8208748518823, 3436.759426840122, 3516.5604299462566, 3540.150363912642, 3545.680888720002, 1178.4755783676267, 1180.4333919718583, 1193.3198077096058, 1207.3661327802886, 1273.617914625618, 16378.590535236595, 16481.25834337458, 16516.917415412965, 16541.83458165427, 16680.249388777655, 4693.881501267856, 4746.5490778376, 4770.594499467515, 4858.611311095104, 4907.779149938261, 11937.224080434744, 11948.283103013848, 11950.940245298718, 11959.282443053444, 11965.222260553037, 5721.342045664718, 5733.455321744427, 5735.11105778605, 5735.627184233012, 5743.4256574342235, 5246.921296180561, 5261.842144735664, 5293.814716484904, 5322.2282768998275, 5381.650192737459, 4027.9194416111513, 4063.349864021742, 4081.3265811141423, 4084.252914370749, 4097.467329247179, 1128.2553514680271, 1130.5364800431782, 1131.2533727739653, 1150.511032811052, 1157.3287493253893, 584.430180573937, 763.8701420758484, 773.1997382708869, 774.1948373691298, 786.4180506425319, 5738.110592858114, 5833.329167145747, 5895.882082358415, 5993.370696076936, 6271.278802937721, 552.6242155334411, 570.5692202387171, 592.520058752412, 597.1073245497921, 705.6965504832868, 3684.8526058957877, 3755.8732380285805, 3799.0, 3863.9613603864163, 3959.7822119783687, 13857.861421385656, 14683.92658036703, 14733.631659208606, 14738.55784326472, 14772.18752968597, 13275.933620331836, 13815.171089734647, 13831.654208644864, 13998.090124141898, 14423.927880360741, 9885.308028437941, 10052.447115408722, 10060.798784024279, 10061.245406594535, 10119.595216191417, 5968.403159683997, 6132.969335153296, 6228.937710622836, 6265.718042687999, 6373.075903993802, 12526.49894004266, 12899.0, 12994.610161695164, 13072.281024543741, 13089.41097650589, 12909.289989050692, 12923.741525169631, 12930.741385172432, 13091.934540327238, 13131.34343282813, 8785.25325347327, 9069.775580296575, 9084.954575227082, 9086.136817002374, 9093.090690930854, 9738.516678247002, 10310.0, 10369.325993810378, 10386.376817390981, 10395.532201051123, 8380.497170169809, 8510.446820956618, 8739.912600874164, 8743.431676940978, 8884.244839188741, 8319.168083191875, 8357.038940521918, 8410.87383689233, 8429.072801991653, 8470.449420787629, 7543.886841697269, 7643.350315223268, 7722.498037627536, 7724.0731112266885, 7732.226777322223, 5558.44415558441, 5603.38362780102, 5726.74229659658, 5822.592418530662, 5835.533157347317, 5652.152177173457, 5656.632318899258, 5756.16535602327, 5780.450857168562, 5902.852428689317], "value": 4931.580411822977, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.4154347706701, 390.3052566431751, 392.19795518165347, 392.73882867892854, 395.7111308744616, 5745.511631511321, 5815.418458154182, 5816.243888294521, 5842.094475356316, 5852.180694702734, 5254.185601231806, 5742.339630942442, 5779.711014449282, 5793.0, 5822.243108395903, 4552.157850797613, 4553.203189441834, 4558.475775285851, 4564.360989461478, 4571.862844114666, 4566.725996440225, 4573.382593349905, 4577.496475387721, 4593.862184134481, 4595.195840727859, 4212.3260278355465, 4360.97819510902, 4399.172719733836, 4559.498455169929, 4576.032306108372, 4345.739255644656, 4371.584699453547, 4373.693841431101, 4390.473143222818, 4412.735235885857, 3851.9422208666892, 3855.556610989744, 3861.841447565719, 3868.729188956774, 3869.090763670535, 5527.0, 5690.374058853523, 5693.516051135658, 5699.2590963174935, 5699.344575373843, 5172.405173405032, 5173.948260517384, 5183.2743415921805, 5184.144616138333, 5189.506996835295, 6169.2288463942, 6171.876562468726, 6207.906881396782, 6208.565400421972, 6221.657808820513, 4169.478815148106, 4272.572742725733, 4317.15815415994, 4323.481182258118, 4323.978380108111, 6163.599366041238, 6311.810645680683, 6423.807285781434, 6506.0, 6507.121538592277, 4315.331123675835, 4384.6273066789045, 4388.924713445226, 4396.692231543825, 4401.735895846259, 4200.957990420118, 4300.3549467580115, 4432.84485043022, 4437.511873693891, 4446.799894004777, 4788.379565223816, 4982.427020892644, 4988.176950803077, 4994.175960966472, 5140.357455318085, 6147.877042459215, 6158.261008678987, 6162.7226774795245, 6173.629582225081, 6190.380961903774, 1483.814523184602, 1522.4899658614488, 1523.1470376589145, 1527.2440142129494, 1532.5172570640214, 1004.382304882492, 1024.9180065594735, 1029.9957541397227, 1031.4842578710645, 1033.9224558158169, 5826.854328641733, 5887.5575484006695, 5891.911621325684, 5914.526837852962, 5934.584579079509, 6285.96857015712, 6301.275353334425, 6336.271328797156, 6353.68231588428, 6375.947968585224, 5648.435156484319, 5787.479126878601, 5843.415658434207, 5854.862975014089, 5862.824115276549, 3333.633300336961, 3338.4825352070466, 3344.966550334514, 3353.2958078803726, 3366.259422926952, 6348.841278968062, 6497.758273395671, 6596.0, 6601.0, 6605.504587155982, 6526.184226971629, 6549.443297319781, 6567.211934567787, 6576.177977752781, 6603.438707709803, 6497.076315657879, 6628.0, 6669.733210671616, 6713.865722685617, 6714.832129196711, 6379.904301435575, 6380.649064301508, 6392.0, 6398.360163983564, 6424.421802037839, 6333.145025421509, 6338.936610633835, 6359.459445947146, 6383.2978372378075, 6395.136656551492, 6453.612163499535, 6598.505112116514, 6627.5360724750735, 6688.565243259172, 6699.263081061177, 4380.9780951095045, 4381.583749543788, 4398.582134697199, 4413.22768515515, 4421.11577684458, 6208.379162083756, 6375.234971803413, 6375.234971803413, 6411.3909178627955, 6427.517936154901, 6164.537659675452, 6218.626882387071, 6221.6623425963635, 6225.3774622537385, 6239.344868788712, 6196.194494715658, 6196.481861943853, 6206.255249370104, 6207.6721229538925, 6221.906671400023, 5942.31663358702, 6137.164272329794, 6183.938160618336, 6227.0, 6229.501639868709, 6209.96895015522, 6241.469475094668, 6249.437550620466, 6251.874662560783, 6254.906176407267, 5934.13074881411, 6009.338972712911, 6024.307204671343, 6037.426444487766, 6075.939240607538], "value": 4672.271492409371, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.5277282288805, 694.198201077755, 732.2092140488273, 751.8195633048067, 757.1746795992369, 14951.102933823964, 15719.528414147593, 16304.918475407621, 16354.509364719062, 16507.0, 16690.749638755427, 16817.15914204288, 16863.494095177135, 16919.238634261455, 17223.483295501122, 805.2511164616903, 823.0, 826.5412695953743, 827.1439060572309, 827.2596026556229, 775.8021704465353, 802.6147449224376, 812.252727490707, 812.5490352854164, 822.3955392786297, 862.6635612111281, 863.8445079885618, 864.779481232288, 864.9437786543886, 865.2645251536206, 846.5047946951041, 861.2765277167179, 862.8403745307075, 863.3697400897317, 863.9870401943945, 629.4901130084601, 630.7130255733676, 631.2961048431013, 633.885900537903, 634.8825467288566, 15476.303566339542, 16005.439809606723, 16072.75890861638, 16433.91783041089, 16440.83559164405, 14454.494092706705, 15019.774703379459, 16103.677926441405, 16403.42588009414, 16658.6668266634, 10875.782484350348, 10883.292585981886, 11319.547218111265, 11970.880291197063, 12037.157398982075, 3092.6907309268895, 3115.6572776994512, 3124.3126512167287, 3176.348848486055, 3231.8545665445113, 81623.0, 81821.59089204576, 82112.58943705303, 82114.58942705249, 82224.58887705523, 12490.687732806571, 12701.174423662433, 12711.682207944876, 12765.170263932814, 12969.351532423341, 21592.35222943314, 22053.889730551244, 22423.43941401478, 24600.507989840462, 24835.0, 8066.717664881701, 8175.754727358189, 8287.088420273765, 8389.32885369169, 8413.368997325226, 3998.4402183694306, 4085.55058943516, 4104.138130992525, 4113.506379234511, 4217.367394890791, 4169.353750168729, 4257.808398622069, 4268.807903644343, 4283.828646854091, 4291.849785257501, 759.4607828441758, 768.3353898877431, 768.5081547809407, 779.3609240422912, 780.441983981456, 564.5963136357524, 591.5060924128401, 600.342624825815, 604.4227762486814, 605.231356177652, 8755.299576033902, 9390.765230869283, 9754.561044753003, 10697.358158510515, 10977.615783447542, 538.3916174722539, 555.9916601250984, 558.7262241501635, 561.2871653000748, 562.9240052592847, 4306.763128027988, 4822.0, 4869.586085182799, 4879.072976134523, 4886.438059623118, 74269.0, 75044.24955750373, 75052.62473687597, 76264.0, 79027.60486197533, 55733.44266557364, 62626.686866565375, 64916.67541662262, 65392.673036634515, 67103.66448167825, 57178.428215718144, 57750.7112464435, 58528.12207816801, 58626.413735862945, 58659.0, 9769.778777652793, 10913.890861091606, 11167.553297867995, 11224.831627525757, 11464.828027579426, 32587.69649214005, 32633.673663264013, 32727.672723272462, 32749.345013100083, 32900.835495822364, 39847.80076099601, 39908.0, 40270.79864600658, 40301.596984029784, 40478.595214047484, 12963.351832408152, 13447.596572102853, 13587.0, 13667.931660341636, 13764.862351376758, 14060.156390616596, 14494.565163044703, 14547.0, 14696.0, 14924.0, 13539.458421663418, 13807.792883106962, 13835.0, 14894.627634308796, 14896.40414383456, 13109.868901310865, 13251.602451926457, 13665.18008919468, 13814.06134396606, 14352.0, 12127.939360303142, 13591.660208494866, 14243.430262789787, 14420.783688244475, 14696.706065878408, 9899.257555683496, 10045.547950342398, 10072.496375181065, 10228.693139205836, 10752.301100428447, 11718.70703232426, 12262.75474490523, 12358.196717213352, 12526.812097818722, 12936.611901642966], "value": 7164.950087041503, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 109.0, 108.0, 109.0, 108.0, 110.0, 109.0, 110.0, 110.0, 109.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 120.0, 118.0, 175.0, 121.0, 118.0, 116.0, 121.0, 140.0, 121.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 161.0, 161.0, 161.0, 160.0, 161.0, 161.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 77.0, 106.0, 74.0, 75.0, 75.0, 74.0, 91.0, 74.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 112.0, 83.0, 82.0, 83.0, 100.0, 83.0, 82.0, 111.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 148.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 55.0, 56.0, 57.0, 58.0, 57.0, 58.0, 57.0, 56.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0, 54.0, 55.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 87.0, 80.0, 80.0, 81.0, 81.0, 79.0, 80.0, 78.0, 79.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 131.0, 144.0, 136.0, 126.0, 132.0, 126.0, 131.0, 173.0, 127.0], "value": 136.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 179.0, 183.0, 186.0, 183.0, 182.0, 185.0, 181.0, 179.0], "value": 182.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 67.0, 64.0, 64.0, 69.0, 70.0, 64.0, 66.0], "value": 66.1, "unit": "score"}]}]} 13:06:40 INFO - Return code: 0 13:06:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:06:40 ERROR - # TBPL SUCCESS # 13:06:40 INFO - Running post-action listener: _resource_record_post_action 13:06:40 INFO - [mozharness: 2017-03-26 20:06:40.300012Z] Finished run-tests step (success) 13:06:40 INFO - Running post-run listener: _resource_record_post_run 13:06:40 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 151552; Write bytes: 376938496; Read time: 36; Write time: 278332 13:06:40 INFO - TinderboxPrint: CPU usage
12.9% 13:06:40 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 36 13:06:40 INFO - TinderboxPrint: I/O write bytes / time
376,938,496 / 278,332 13:06:40 INFO - TinderboxPrint: CPU idle
8,311.8 (87.1%) 13:06:40 INFO - TinderboxPrint: CPU user
1,185.7 (12.4%) 13:06:40 INFO - TinderboxPrint: Swap in / out
0 / 0 13:06:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111767552; Read time: 0; Write time: 93264 13:06:40 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 147456; Write bytes: 263954432; Read time: 24; Write time: 185028 13:06:40 INFO - Running post-run listener: _upload_blobber_files 13:06:40 INFO - Blob upload gear active. 13:06:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:06:40 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 13:06:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:06:41 INFO - (blobuploader) - INFO - Open directory for files ... 13:06:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:06:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:06:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:06:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:06:42 INFO - (blobuploader) - INFO - Done attempting. 13:06:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:06:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:06:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:06:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:06:43 INFO - (blobuploader) - INFO - Done attempting. 13:06:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:06:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:06:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:06:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:06:44 INFO - (blobuploader) - INFO - Done attempting. 13:06:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:06:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:06:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:06:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:06:47 INFO - (blobuploader) - INFO - Done attempting. 13:06:47 INFO - (blobuploader) - INFO - Iteration through files over. 13:06:47 INFO - Return code: 0 13:06:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:06:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:06:47 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5096e05058fd6c49dc263d4ae0afcbce6d2398b76fe52b7d7b7e5e34cdb14de53076026a3119f61713130d7d7c4f76eec1b25755464491d06c9f538fda450986", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f13d9ddebf8896d925695f11f24e1e242b403a8b9ca310ad170a5f6e1aa4f8ec06462657459803411ea7ac7aaad63510428250f747d6ce5b86426d2cfcb2f14", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e32936de2186935f1ab0a13d511f0ae412c69f8f78b36542c960741624cf39bcb656b7908f44d0285a5d3667d6b641ebd1badc018f651be36dcd38888d514e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a600a54e8f5b2df9b8ba5b132e8009a36fb46c54829d63eee8fe98e7aa216c1c99a4a2bf86c90fcaa04b566ecbf65c1a7c1499d776dbc31539deffc3c5f3a611"} 13:06:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:06:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:06:47 INFO - Contents: 13:06:47 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5096e05058fd6c49dc263d4ae0afcbce6d2398b76fe52b7d7b7e5e34cdb14de53076026a3119f61713130d7d7c4f76eec1b25755464491d06c9f538fda450986", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f13d9ddebf8896d925695f11f24e1e242b403a8b9ca310ad170a5f6e1aa4f8ec06462657459803411ea7ac7aaad63510428250f747d6ce5b86426d2cfcb2f14", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e32936de2186935f1ab0a13d511f0ae412c69f8f78b36542c960741624cf39bcb656b7908f44d0285a5d3667d6b641ebd1badc018f651be36dcd38888d514e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a600a54e8f5b2df9b8ba5b132e8009a36fb46c54829d63eee8fe98e7aa216c1c99a4a2bf86c90fcaa04b566ecbf65c1a7c1499d776dbc31539deffc3c5f3a611"} 13:06:47 INFO - Running post-run listener: copy_logs_to_upload_dir 13:06:47 INFO - Copying logs to upload dir... 13:06:47 INFO - mkdir: /builds/slave/test/build/upload/logs 13:06:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.803581 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-03-26 13:06:47.427659) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 13:06:47.431250) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5096e05058fd6c49dc263d4ae0afcbce6d2398b76fe52b7d7b7e5e34cdb14de53076026a3119f61713130d7d7c4f76eec1b25755464491d06c9f538fda450986", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f13d9ddebf8896d925695f11f24e1e242b403a8b9ca310ad170a5f6e1aa4f8ec06462657459803411ea7ac7aaad63510428250f747d6ce5b86426d2cfcb2f14", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e32936de2186935f1ab0a13d511f0ae412c69f8f78b36542c960741624cf39bcb656b7908f44d0285a5d3667d6b641ebd1badc018f651be36dcd38888d514e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a600a54e8f5b2df9b8ba5b132e8009a36fb46c54829d63eee8fe98e7aa216c1c99a4a2bf86c90fcaa04b566ecbf65c1a7c1499d776dbc31539deffc3c5f3a611"} build_url:https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011497 build_url: 'https://queue.taskcluster.net/v1/task/UY4NKbjoQ7WKATm7b-qO6Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5096e05058fd6c49dc263d4ae0afcbce6d2398b76fe52b7d7b7e5e34cdb14de53076026a3119f61713130d7d7c4f76eec1b25755464491d06c9f538fda450986", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f13d9ddebf8896d925695f11f24e1e242b403a8b9ca310ad170a5f6e1aa4f8ec06462657459803411ea7ac7aaad63510428250f747d6ce5b86426d2cfcb2f14", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e32936de2186935f1ab0a13d511f0ae412c69f8f78b36542c960741624cf39bcb656b7908f44d0285a5d3667d6b641ebd1badc018f651be36dcd38888d514e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a600a54e8f5b2df9b8ba5b132e8009a36fb46c54829d63eee8fe98e7aa216c1c99a4a2bf86c90fcaa04b566ecbf65c1a7c1499d776dbc31539deffc3c5f3a611"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 13:06:47.458418) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 13:06:47.458825) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1490545179.344323-190138257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 13:06:47.541984) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 13:06:47.542300) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 13:06:47.542685) ========= ========= Total master_lag: 0.18 =========