builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1490404005.1 results: success (0) revision: 65b0ac174753b22c01156d72fb42d2abd3176dd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.096484) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.097045) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.097335) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.112751) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.113146) ========= 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=923d835db2ba3686ebe126b700000192-1490404005.75760-823971796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-24 18:06:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2017-03-24 18:06:45 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121686 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.248844) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.249446) ========= 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=923d835db2ba3686ebe126b700000192-1490404005.75760-823971796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019743 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.298910) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:45.299244) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65b0ac174753b22c01156d72fb42d2abd3176dd1 --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 65b0ac174753b22c01156d72fb42d2abd3176dd1 --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=923d835db2ba3686ebe126b700000192-1490404005.75760-823971796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-24 18:06:45,917 truncating revision to first 12 chars 2017-03-24 18:06:45,917 Setting DEBUG logging. 2017-03-24 18:06:45,917 attempt 1/10 2017-03-24 18:06:45,917 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65b0ac174753?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-24 18:06:46,379 unpacking tar archive at: mozilla-central-65b0ac174753/testing/mozharness/ program finished with exit code 0 elapsedTime=0.753481 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:46.066928) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:46.067442) ========= script_repo_revision: 65b0ac174753b22c01156d72fb42d2abd3176dd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:46.067829) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:46.068122) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 18:06:46.078134) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-24 18:06:46.078503) ========= /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=923d835db2ba3686ebe126b700000192-1490404005.75760-823971796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:06:46 INFO - MultiFileLogger online at 20170324 18:06:46 in /builds/slave/test 18:06:46 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 18:06:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:06:46 INFO - {'append_to_log': False, 18:06:46 INFO - 'base_work_dir': '/builds/slave/test', 18:06:46 INFO - 'blob_upload_branch': 'Firefox', 18:06:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:06:46 INFO - 'branch': 'Firefox', 18:06:46 INFO - 'buildbot_json_path': 'buildprops.json', 18:06:46 INFO - 'config_files': ('talos/linux_config.py',), 18:06:46 INFO - 'default_actions': ('clobber', 18:06:46 INFO - 'read-buildbot-config', 18:06:46 INFO - 'download-and-extract', 18:06:46 INFO - 'populate-webroot', 18:06:46 INFO - 'create-virtualenv', 18:06:46 INFO - 'install', 18:06:46 INFO - 'run-tests'), 18:06:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:06:46 INFO - 'download_minidump_stackwalk': True, 18:06:46 INFO - 'download_symbols': 'ondemand', 18:06:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:06:46 INFO - 'tooltool.py': '/tools/tooltool.py', 18:06:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:06:46 INFO - '/tools/misc-python/virtualenv.py')}, 18:06:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:06:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:06:46 INFO - 'gecko_profile': False, 18:06:46 INFO - 'gecko_profile_interval': 0, 18:06:46 INFO - 'installer_path': 'installer.exe', 18:06:46 INFO - 'log_level': 'info', 18:06:46 INFO - 'log_name': 'talos', 18:06:46 INFO - 'log_to_console': True, 18:06:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:06:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:06:46 INFO - 'opt_config_files': (), 18:06:46 INFO - 'pip_index': False, 18:06:46 INFO - 'suite': 'dromaeojs', 18:06:46 INFO - 'system_bits': '32', 18:06:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:06:46 INFO - 'title': 'talos-linux64-ix-007', 18:06:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:06:46 INFO - 'use_talos_json': True, 18:06:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:06:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:06:46 INFO - 'work_dir': 'build'} 18:06:46 INFO - [mozharness: 2017-03-25 01:06:46.797163Z] Running clobber step. 18:06:46 INFO - Running pre-action listener: _resource_record_pre_action 18:06:46 INFO - Running main action method: clobber 18:06:46 INFO - rmtree: /builds/slave/test/build 18:06:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:06:47 INFO - Running post-action listener: _resource_record_post_action 18:06:47 INFO - [mozharness: 2017-03-25 01:06:47.738048Z] Finished clobber step (success) 18:06:47 INFO - [mozharness: 2017-03-25 01:06:47.738148Z] Running read-buildbot-config step. 18:06:47 INFO - Running pre-action listener: _resource_record_pre_action 18:06:47 INFO - Running main action method: read_buildbot_config 18:06:47 INFO - Using buildbot properties: 18:06:47 INFO - { 18:06:47 INFO - "project": "", 18:06:47 INFO - "product": "firefox", 18:06:47 INFO - "who": "kwierso@gmail.com", 18:06:47 INFO - "installer_path": "public/build/target.tar.bz2", 18:06:47 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:06:47 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:06:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:06:47 INFO - "stage_platform": "linux64-pgo", 18:06:47 INFO - "basedir": "/builds/slave/test", 18:06:47 INFO - "buildnumber": 107, 18:06:47 INFO - "platform": "ubuntu64_hw", 18:06:47 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:06:47 INFO - "slavebuilddir": "test", 18:06:47 INFO - "taskId": "Uz0o5URPRRK3mitjk4XqbQ", 18:06:47 INFO - "branch": "mozilla-central", 18:06:47 INFO - "script_repo_revision": "production", 18:06:47 INFO - "revision": "65b0ac174753b22c01156d72fb42d2abd3176dd1", 18:06:47 INFO - "slavename": "talos-linux64-ix-007", 18:06:47 INFO - "repo_path": "mozilla-central" 18:06:47 INFO - } 18:06:47 INFO - Finding installer, test and symbols from parent task. 18:06:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uz0o5URPRRK3mitjk4XqbQ'}, attempt #1 18:06:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uz0o5URPRRK3mitjk4XqbQ'}, attempt #1 18:06:48 INFO - Task dependencies: MQDWOkwYQeq3c2_6Zx-g8w 18:06:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w'}, attempt #1 18:06:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.tar.bz2 18:06:49 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/test_packages.json 18:06:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.crashreporter-symbols.zip 18:06:49 INFO - Running post-action listener: _resource_record_post_action 18:06:49 INFO - [mozharness: 2017-03-25 01:06:49.132542Z] Finished read-buildbot-config step (success) 18:06:49 INFO - [mozharness: 2017-03-25 01:06:49.132761Z] Running download-and-extract step. 18:06:49 INFO - Running pre-action listener: _resource_record_pre_action 18:06:49 INFO - Running main action method: download_and_extract 18:06:49 INFO - mkdir: /builds/slave/test/build/tests 18:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:49 INFO - trying https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.test_packages.json 18:06:49 INFO - Downloading https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:06:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:06:49 INFO - Downloaded 986 bytes. 18:06:49 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:06:49 INFO - Using the following test package requirements: 18:06:49 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:06:49 INFO - u'common': [u'target.common.tests.zip'], 18:06:49 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:06:49 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:06:49 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:06:49 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:06:49 INFO - u'mozbase': [u'target.common.tests.zip'], 18:06:49 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:06:49 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:06:49 INFO - u'web-platform': [u'target.common.tests.zip', 18:06:49 INFO - u'target.web-platform.tests.zip'], 18:06:49 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:06:49 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:06:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.common.tests.zip 18:06:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:06:49 INFO - Fetch https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.common.tests.zip into memory 18:06:50 INFO - Expected file size: 60251467 18:06:50 INFO - Obtained file size: 60251467 18:07:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:07:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.common.tests.zip 18:07:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:07:00 INFO - Fetch https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.common.tests.zip into memory 18:07:01 INFO - Expected file size: 60251467 18:07:01 INFO - Obtained file size: 60251467 18:07:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.talos.tests.zip 18:07:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:07:12 INFO - Fetch https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.talos.tests.zip into memory 18:07:13 INFO - Expected file size: 14404171 18:07:13 INFO - Obtained file size: 14404171 18:07:13 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')]} 18:07:13 INFO - trying https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.tar.bz2 18:07:13 INFO - Downloading https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:07:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:07:14 INFO - Downloaded 67964766 bytes. 18:07:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.tar.bz2 18:07:14 INFO - mkdir: /builds/slave/test/properties 18:07:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:07:14 INFO - Writing to file /builds/slave/test/properties/build_url 18:07:14 INFO - Contents: 18:07:14 INFO - build_url:https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.tar.bz2 18:07:14 INFO - Running post-action listener: _resource_record_post_action 18:07:14 INFO - Running post-action listener: set_extra_try_arguments 18:07:14 INFO - [mozharness: 2017-03-25 01:07:14.835848Z] Finished download-and-extract step (success) 18:07:14 INFO - [mozharness: 2017-03-25 01:07:14.835945Z] Running populate-webroot step. 18:07:14 INFO - Running pre-action listener: _resource_record_pre_action 18:07:14 INFO - Running main action method: populate_webroot 18:07:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:07:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:07:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:07:14 INFO - '--tppagecycles', 18:07:14 INFO - '7'], 18:07:14 INFO - 'tests': ['tsvgm']}}, 18:07:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:07:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:07:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:07:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:07:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:07:14 INFO - 'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:07:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:07:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:07:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:07:14 INFO - 'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['damp', 'tps']}, 18:07:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:07:14 INFO - 'tests': ['damp', 'tps']}, 18:07:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['dromaeo_dom']}, 18:07:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:07:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['basic_compositor_video']}, 18:07:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:07:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['a11yr', 18:07:14 INFO - 'ts_paint', 18:07:14 INFO - 'tpaint', 18:07:14 INFO - 'sessionrestore', 18:07:14 INFO - 'sessionrestore_no_auto_restore', 18:07:14 INFO - 'tabpaint']}, 18:07:14 INFO - 'other-e10s': {'tests': ['a11yr', 18:07:14 INFO - 'ts_paint', 18:07:14 INFO - 'tpaint', 18:07:14 INFO - 'sessionrestore', 18:07:14 INFO - 'sessionrestore_no_auto_restore', 18:07:14 INFO - 'tabpaint']}, 18:07:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:07:14 INFO - 'ts_paint', 18:07:14 INFO - 'tpaint', 18:07:14 INFO - 'sessionrestore', 18:07:14 INFO - 'sessionrestore_no_auto_restore', 18:07:14 INFO - 'tabpaint']}, 18:07:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:07:14 INFO - 'ts_paint', 18:07:14 INFO - 'tpaint', 18:07:14 INFO - 'sessionrestore', 18:07:14 INFO - 'sessionrestore_no_auto_restore', 18:07:14 INFO - 'tabpaint']}, 18:07:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['a11yr', 18:07:14 INFO - 'ts_paint', 18:07:14 INFO - 'tpaint', 18:07:14 INFO - 'sessionrestore', 18:07:14 INFO - 'sessionrestore_no_auto_restore', 18:07:14 INFO - 'tabpaint']}, 18:07:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['a11yr', 18:07:14 INFO - 'ts_paint', 18:07:14 INFO - 'tpaint', 18:07:14 INFO - 'sessionrestore', 18:07:14 INFO - 'sessionrestore_no_auto_restore', 18:07:14 INFO - 'tabpaint']}, 18:07:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['tsvgx', 18:07:14 INFO - 'tsvgr_opacity', 18:07:14 INFO - 'tart', 18:07:14 INFO - 'tscrollx', 18:07:14 INFO - 'cart', 18:07:14 INFO - 'tsvg_static']}, 18:07:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:07:14 INFO - 'tsvgr_opacity', 18:07:14 INFO - 'tart', 18:07:14 INFO - 'tscrollx', 18:07:14 INFO - 'cart', 18:07:14 INFO - 'tsvg_static']}, 18:07:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:07:14 INFO - 'talos_options': ['--disable-e10s'], 18:07:14 INFO - 'tests': ['tp5o']}, 18:07:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:07:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:07:14 INFO - 'talos_options': ['--disable-e10s', 18:07:14 INFO - '--xperf_path', 18:07:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:07:14 INFO - 'tests': ['tp5n']}, 18:07:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:07:14 INFO - 'talos_options': ['--xperf_path', 18:07:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:07:14 INFO - 'tests': ['tp5n']}}, 18:07:14 INFO - 'talos.zip': {'path': '', 18:07:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:07:14 INFO - Running post-action listener: _resource_record_post_action 18:07:14 INFO - [mozharness: 2017-03-25 01:07:14.849152Z] Finished populate-webroot step (success) 18:07:14 INFO - [mozharness: 2017-03-25 01:07:14.849243Z] Running create-virtualenv step. 18:07:14 INFO - Running pre-action listener: _resource_record_pre_action 18:07:14 INFO - Running main action method: create_virtualenv 18:07:14 INFO - Creating virtualenv /builds/slave/test/build/venv 18:07:14 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 18:07:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:07:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:07:14 INFO - Using real prefix '/usr' 18:07:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:07:15 INFO - Installing distribute.............................................................................................................................................................................................done. 18:07:17 INFO - Installing pip.................done. 18:07:17 INFO - Return code: 0 18:07:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:07:17 INFO - Reading from file tmpfile_stdout 18:07:17 INFO - Output received: 18:07:17 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) 18:07:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:07:17 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')]} 18:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:17 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')]} 18:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:17 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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07:17 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 18:07:17 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 18:07:17 INFO - Using env: {'DISPLAY': ':0', 18:07:17 INFO - 'HOME': '/home/cltbld', 18:07:17 INFO - 'LANG': 'en_US.UTF-8', 18:07:17 INFO - 'LANGUAGE': 'en_US:en', 18:07:17 INFO - 'LOGNAME': 'cltbld', 18:07:17 INFO - 'MAIL': '/var/mail/cltbld', 18:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:17 INFO - 'MOZ_NO_REMOTE': '1', 18:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:17 INFO - 'NO_EM_RESTART': '1', 18:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:17 INFO - 'PWD': '/builds/slave/test', 18:07:17 INFO - 'SHELL': '/bin/bash', 18:07:17 INFO - 'SHLVL': '1', 18:07:17 INFO - 'TERM': 'linux', 18:07:17 INFO - 'TMOUT': '86400', 18:07:17 INFO - 'USER': 'cltbld', 18:07:17 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:17 INFO - '_': '/tools/buildbot/bin/python'} 18:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:17 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 18:07:17 INFO - Cleaning up... 18:07:17 INFO - Return code: 0 18:07:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:07:17 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')]} 18:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:17 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')]} 18:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:17 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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07:17 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 18:07:17 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 18:07:17 INFO - Using env: {'DISPLAY': ':0', 18:07:17 INFO - 'HOME': '/home/cltbld', 18:07:17 INFO - 'LANG': 'en_US.UTF-8', 18:07:17 INFO - 'LANGUAGE': 'en_US:en', 18:07:17 INFO - 'LOGNAME': 'cltbld', 18:07:17 INFO - 'MAIL': '/var/mail/cltbld', 18:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:17 INFO - 'MOZ_NO_REMOTE': '1', 18:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:17 INFO - 'NO_EM_RESTART': '1', 18:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:17 INFO - 'PWD': '/builds/slave/test', 18:07:17 INFO - 'SHELL': '/bin/bash', 18:07:17 INFO - 'SHLVL': '1', 18:07:17 INFO - 'TERM': 'linux', 18:07:17 INFO - 'TMOUT': '86400', 18:07:17 INFO - 'USER': 'cltbld', 18:07:17 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:17 INFO - '_': '/tools/buildbot/bin/python'} 18:07:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:18 INFO - Downloading/unpacking psutil>=3.1.1 18:07:18 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 18:07:18 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 18:07:18 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 18:07:18 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 18:07:18 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 18:07:18 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 18:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:07:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:07:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:07:20 INFO - Installing collected packages: psutil 18:07:20 INFO - Running setup.py install for psutil 18:07:20 INFO - building 'psutil._psutil_linux' extension 18:07:20 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 18:07:20 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 18:07:20 INFO - building 'psutil._psutil_posix' extension 18:07:20 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 18:07:20 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 18:07:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:07:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:07:20 INFO - Successfully installed psutil 18:07:20 INFO - Cleaning up... 18:07:20 INFO - Return code: 0 18:07:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:07:20 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')]} 18:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:20 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')]} 18:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:20 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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07:20 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 18:07:20 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 18:07:20 INFO - Using env: {'DISPLAY': ':0', 18:07:20 INFO - 'HOME': '/home/cltbld', 18:07:20 INFO - 'LANG': 'en_US.UTF-8', 18:07:20 INFO - 'LANGUAGE': 'en_US:en', 18:07:20 INFO - 'LOGNAME': 'cltbld', 18:07:20 INFO - 'MAIL': '/var/mail/cltbld', 18:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:20 INFO - 'MOZ_NO_REMOTE': '1', 18:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:20 INFO - 'NO_EM_RESTART': '1', 18:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:20 INFO - 'PWD': '/builds/slave/test', 18:07:20 INFO - 'SHELL': '/bin/bash', 18:07:20 INFO - 'SHLVL': '1', 18:07:20 INFO - 'TERM': 'linux', 18:07:20 INFO - 'TMOUT': '86400', 18:07:20 INFO - 'USER': 'cltbld', 18:07:20 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:20 INFO - '_': '/tools/buildbot/bin/python'} 18:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 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 18:07:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:07:23 INFO - Installing collected packages: mozsystemmonitor 18:07:23 INFO - Running setup.py install for mozsystemmonitor 18:07:23 INFO - Successfully installed mozsystemmonitor 18:07:23 INFO - Cleaning up... 18:07:23 INFO - Return code: 0 18:07:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:07: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')]} 18:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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')]} 18:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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', '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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:07: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 jsonschema==2.5.1 18:07:23 INFO - Using env: {'DISPLAY': ':0', 18:07:23 INFO - 'HOME': '/home/cltbld', 18:07:23 INFO - 'LANG': 'en_US.UTF-8', 18:07:23 INFO - 'LANGUAGE': 'en_US:en', 18:07:23 INFO - 'LOGNAME': 'cltbld', 18:07:23 INFO - 'MAIL': '/var/mail/cltbld', 18:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:23 INFO - 'MOZ_NO_REMOTE': '1', 18:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:23 INFO - 'NO_EM_RESTART': '1', 18:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:23 INFO - 'PWD': '/builds/slave/test', 18:07:23 INFO - 'SHELL': '/bin/bash', 18:07:23 INFO - 'SHLVL': '1', 18:07:23 INFO - 'TERM': 'linux', 18:07:23 INFO - 'TMOUT': '86400', 18:07:23 INFO - 'USER': 'cltbld', 18:07:23 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:23 INFO - '_': '/tools/buildbot/bin/python'} 18:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:23 INFO - Downloading/unpacking jsonschema==2.5.1 18:07: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 18:07: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 18:07: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 18:07: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 18:07: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 18:07: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 18:07:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:07:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:07:25 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 18:07:25 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 18:07:25 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 18:07:25 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 18:07:25 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 18:07:25 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 18:07:25 INFO - Downloading functools32-3.2.3-2.tar.gz 18:07:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:07:25 INFO - warning: no files found matching '*.txt' 18:07:25 INFO - no previously-included directories found matching 'build' 18:07:25 INFO - no previously-included directories found matching 'dist' 18:07:25 INFO - no previously-included directories found matching '.git*' 18:07:25 INFO - Installing collected packages: jsonschema, functools32 18:07:25 INFO - Running setup.py install for functools32 18:07:25 INFO - warning: no files found matching '*.txt' 18:07:25 INFO - no previously-included directories found matching 'build' 18:07:25 INFO - no previously-included directories found matching 'dist' 18:07:25 INFO - no previously-included directories found matching '.git*' 18:07:25 INFO - Successfully installed jsonschema functools32 18:07:25 INFO - Cleaning up... 18:07:26 INFO - Return code: 0 18:07:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:07: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')]} 18:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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')]} 18:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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', '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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:07: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 functools32==3.2.3-2 18:07:26 INFO - Using env: {'DISPLAY': ':0', 18:07:26 INFO - 'HOME': '/home/cltbld', 18:07:26 INFO - 'LANG': 'en_US.UTF-8', 18:07:26 INFO - 'LANGUAGE': 'en_US:en', 18:07:26 INFO - 'LOGNAME': 'cltbld', 18:07:26 INFO - 'MAIL': '/var/mail/cltbld', 18:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:26 INFO - 'MOZ_NO_REMOTE': '1', 18:07:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:26 INFO - 'NO_EM_RESTART': '1', 18:07:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:26 INFO - 'PWD': '/builds/slave/test', 18:07:26 INFO - 'SHELL': '/bin/bash', 18:07:26 INFO - 'SHLVL': '1', 18:07:26 INFO - 'TERM': 'linux', 18:07:26 INFO - 'TMOUT': '86400', 18:07:26 INFO - 'USER': 'cltbld', 18:07:26 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:26 INFO - '_': '/tools/buildbot/bin/python'} 18:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:07:26 INFO - Cleaning up... 18:07:26 INFO - Return code: 0 18:07:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:07: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')]} 18:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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')]} 18:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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', '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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:07: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 blobuploader==1.2.4 18:07:26 INFO - Using env: {'DISPLAY': ':0', 18:07:26 INFO - 'HOME': '/home/cltbld', 18:07:26 INFO - 'LANG': 'en_US.UTF-8', 18:07:26 INFO - 'LANGUAGE': 'en_US:en', 18:07:26 INFO - 'LOGNAME': 'cltbld', 18:07:26 INFO - 'MAIL': '/var/mail/cltbld', 18:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:26 INFO - 'MOZ_NO_REMOTE': '1', 18:07:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:26 INFO - 'NO_EM_RESTART': '1', 18:07:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:26 INFO - 'PWD': '/builds/slave/test', 18:07:26 INFO - 'SHELL': '/bin/bash', 18:07:26 INFO - 'SHLVL': '1', 18:07:26 INFO - 'TERM': 'linux', 18:07:26 INFO - 'TMOUT': '86400', 18:07:26 INFO - 'USER': 'cltbld', 18:07:26 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:26 INFO - '_': '/tools/buildbot/bin/python'} 18:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:26 INFO - Downloading/unpacking blobuploader==1.2.4 18:07: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 18:07: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 18:07: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 18:07: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 18:07: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 18:07: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 18:07:28 INFO - Downloading blobuploader-1.2.4.tar.gz 18:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:07:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:07:28 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 18:07:28 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 18:07:28 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 18:07:28 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 18:07:28 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 18:07:28 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 18:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:07:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:07:28 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 18:07:28 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 18:07:28 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 18:07:28 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 18:07:28 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 18:07:28 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 18:07:29 INFO - Downloading docopt-0.6.1.tar.gz 18:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:07:29 INFO - Installing collected packages: blobuploader, requests, docopt 18:07:29 INFO - Running setup.py install for blobuploader 18:07:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:07:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:07:29 INFO - Running setup.py install for requests 18:07:29 INFO - Running setup.py install for docopt 18:07:29 INFO - Successfully installed blobuploader requests docopt 18:07:29 INFO - Cleaning up... 18:07:29 INFO - Return code: 0 18:07:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07: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')]} 18:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07: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')]} 18:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:29 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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07:29 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 18:07:29 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 18:07:29 INFO - Using env: {'DISPLAY': ':0', 18:07:29 INFO - 'HOME': '/home/cltbld', 18:07:29 INFO - 'LANG': 'en_US.UTF-8', 18:07:29 INFO - 'LANGUAGE': 'en_US:en', 18:07:29 INFO - 'LOGNAME': 'cltbld', 18:07:29 INFO - 'MAIL': '/var/mail/cltbld', 18:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:29 INFO - 'MOZ_NO_REMOTE': '1', 18:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:29 INFO - 'NO_EM_RESTART': '1', 18:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:29 INFO - 'PWD': '/builds/slave/test', 18:07:29 INFO - 'SHELL': '/bin/bash', 18:07:29 INFO - 'SHLVL': '1', 18:07:29 INFO - 'TERM': 'linux', 18:07:29 INFO - 'TMOUT': '86400', 18:07:29 INFO - 'USER': 'cltbld', 18:07:29 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:29 INFO - '_': '/tools/buildbot/bin/python'} 18:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:07:30 INFO - Running setup.py (path:/tmp/pip-vkkbaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:07:30 INFO - Running setup.py (path:/tmp/pip-klA3gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:07:30 INFO - Running setup.py (path:/tmp/pip-F86C1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:07:30 INFO - Running setup.py (path:/tmp/pip-2L6H1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:07:30 INFO - Running setup.py (path:/tmp/pip-RGCJvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:07:30 INFO - Running setup.py (path:/tmp/pip-oy6ybQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:07:30 INFO - Running setup.py (path:/tmp/pip-Maoiuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:07:30 INFO - Running setup.py (path:/tmp/pip-yJc6Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:07:30 INFO - Running setup.py (path:/tmp/pip-szzlJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:07:30 INFO - Running setup.py (path:/tmp/pip-yjIVGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:07:30 INFO - Running setup.py (path:/tmp/pip-cGVDBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:07:30 INFO - Running setup.py (path:/tmp/pip-U4JzSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:07:30 INFO - Running setup.py (path:/tmp/pip-BnpUIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:07:31 INFO - Running setup.py (path:/tmp/pip-Jxa37H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:31 INFO - Running setup.py (path:/tmp/pip-dC0Hsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:07:31 INFO - Running setup.py (path:/tmp/pip-5t6vq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:07:31 INFO - Running setup.py (path:/tmp/pip-MXvxvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:07:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:07:31 INFO - Running setup.py install for manifestparser 18:07:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:07:31 INFO - Running setup.py install for mozcrash 18:07:31 INFO - Running setup.py install for mozdebug 18:07:31 INFO - Running setup.py install for mozdevice 18:07:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:07:31 INFO - Running setup.py install for mozfile 18:07:31 INFO - Running setup.py install for mozhttpd 18:07:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:07:31 INFO - Running setup.py install for mozinfo 18:07:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Running setup.py install for mozInstall 18:07:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Running setup.py install for mozleak 18:07:32 INFO - Running setup.py install for mozlog 18:07:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Running setup.py install for moznetwork 18:07:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Running setup.py install for mozprocess 18:07:32 INFO - Running setup.py install for mozprofile 18:07:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Running setup.py install for mozrunner 18:07:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:07:32 INFO - Running setup.py install for mozscreenshot 18:07:32 INFO - Running setup.py install for moztest 18:07:33 INFO - Running setup.py install for mozversion 18:07:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:07:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:07:33 INFO - Cleaning up... 18:07:33 INFO - Return code: 0 18:07:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:33 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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07:33 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 18:07:33 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 18:07:33 INFO - Using env: {'DISPLAY': ':0', 18:07:33 INFO - 'HOME': '/home/cltbld', 18:07:33 INFO - 'LANG': 'en_US.UTF-8', 18:07:33 INFO - 'LANGUAGE': 'en_US:en', 18:07:33 INFO - 'LOGNAME': 'cltbld', 18:07:33 INFO - 'MAIL': '/var/mail/cltbld', 18:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:33 INFO - 'MOZ_NO_REMOTE': '1', 18:07:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:33 INFO - 'NO_EM_RESTART': '1', 18:07:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:33 INFO - 'PWD': '/builds/slave/test', 18:07:33 INFO - 'SHELL': '/bin/bash', 18:07:33 INFO - 'SHLVL': '1', 18:07:33 INFO - 'TERM': 'linux', 18:07:33 INFO - 'TMOUT': '86400', 18:07:33 INFO - 'USER': 'cltbld', 18:07:33 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:33 INFO - '_': '/tools/buildbot/bin/python'} 18:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:07:33 INFO - Running setup.py (path:/tmp/pip-rAFGtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:07:33 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)) 18:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:07:33 INFO - Running setup.py (path:/tmp/pip-WXpjbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:07:33 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)) 18:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:07:33 INFO - Running setup.py (path:/tmp/pip-rPjZ_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:07:33 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)) 18:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:07:33 INFO - Running setup.py (path:/tmp/pip-kNmwEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:07:33 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)) 18:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:07:33 INFO - Running setup.py (path:/tmp/pip-pZan0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:07:33 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)) 18:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:07:33 INFO - Running setup.py (path:/tmp/pip-eyV4U_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:07:33 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)) 18:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:07:33 INFO - Running setup.py (path:/tmp/pip-utX2hY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:07:33 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)) 18:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:07:33 INFO - Running setup.py (path:/tmp/pip-VPuuh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:07:34 INFO - Running setup.py (path:/tmp/pip-cA5_c8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:07:34 INFO - Running setup.py (path:/tmp/pip-z6FxCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:07:34 INFO - Running setup.py (path:/tmp/pip-xyuUSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:07:34 INFO - Running setup.py (path:/tmp/pip-E0in5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:07:34 INFO - Running setup.py (path:/tmp/pip-3Bz2ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:07:34 INFO - Running setup.py (path:/tmp/pip-0rHR9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:34 INFO - Running setup.py (path:/tmp/pip-uWuqtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:07:34 INFO - Running setup.py (path:/tmp/pip-gQScHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:07:34 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)) 18:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:07:34 INFO - Running setup.py (path:/tmp/pip-3yBy2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:07:34 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)) 18:07:34 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)) 18:07:34 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)) 18:07:34 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)) 18:07:34 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)) 18:07:34 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)) 18:07:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:07: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 18:07: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 18:07: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 18:07: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 18:07: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 18:07: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 18:07:36 INFO - Downloading blessings-1.6.tar.gz 18:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:07:36 INFO - Installing collected packages: blessings 18:07:36 INFO - Running setup.py install for blessings 18:07:36 INFO - Successfully installed blessings 18:07:36 INFO - Cleaning up... 18:07:36 INFO - Return code: 0 18:07:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:07:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:07:37 INFO - Reading from file tmpfile_stdout 18:07:37 INFO - Current package versions: 18:07:37 INFO - argparse == 1.2.1 18:07:37 INFO - blessings == 1.6 18:07:37 INFO - blobuploader == 1.2.4 18:07:37 INFO - docopt == 0.6.1 18:07:37 INFO - functools32 == 3.2.3-2 18:07:37 INFO - jsonschema == 2.5.1 18:07:37 INFO - manifestparser == 1.1 18:07:37 INFO - mozInstall == 1.12 18:07:37 INFO - mozcrash == 1.0 18:07:37 INFO - mozdebug == 0.1 18:07:37 INFO - mozdevice == 0.49 18:07:37 INFO - mozfile == 1.2 18:07:37 INFO - mozhttpd == 0.7 18:07:37 INFO - mozinfo == 0.9 18:07:37 INFO - mozleak == 0.1 18:07:37 INFO - mozlog == 3.4 18:07:37 INFO - moznetwork == 0.27 18:07:37 INFO - mozprocess == 0.25 18:07:37 INFO - mozprofile == 0.28 18:07:37 INFO - mozrunner == 6.13 18:07:37 INFO - mozscreenshot == 0.1 18:07:37 INFO - mozsystemmonitor == 0.3 18:07:37 INFO - moztest == 0.8 18:07:37 INFO - mozversion == 1.4 18:07:37 INFO - psutil == 3.1.1 18:07:37 INFO - requests == 1.2.3 18:07:37 INFO - wsgiref == 0.1.2 18:07:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:37 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')]} 18:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:37 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')]} 18:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:37 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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07:37 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 18:07:37 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 18:07:37 INFO - Using env: {'DISPLAY': ':0', 18:07:37 INFO - 'HOME': '/home/cltbld', 18:07:37 INFO - 'LANG': 'en_US.UTF-8', 18:07:37 INFO - 'LANGUAGE': 'en_US:en', 18:07:37 INFO - 'LOGNAME': 'cltbld', 18:07:37 INFO - 'MAIL': '/var/mail/cltbld', 18:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:37 INFO - 'MOZ_NO_REMOTE': '1', 18:07:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:37 INFO - 'NO_EM_RESTART': '1', 18:07:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:37 INFO - 'PWD': '/builds/slave/test', 18:07:37 INFO - 'SHELL': '/bin/bash', 18:07:37 INFO - 'SHLVL': '1', 18:07:37 INFO - 'TERM': 'linux', 18:07:37 INFO - 'TMOUT': '86400', 18:07:37 INFO - 'USER': 'cltbld', 18:07:37 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:37 INFO - '_': '/tools/buildbot/bin/python'} 18:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 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)) 18:07:37 INFO - Cleaning up... 18:07:37 INFO - Return code: 0 18:07:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:07:37 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')]} 18:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:37 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')]} 18:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:37 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 0x29271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2964a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, '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': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', '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 18:07:37 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 18:07:37 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 18:07:37 INFO - Using env: {'DISPLAY': ':0', 18:07:37 INFO - 'HOME': '/home/cltbld', 18:07:37 INFO - 'LANG': 'en_US.UTF-8', 18:07:37 INFO - 'LANGUAGE': 'en_US:en', 18:07:37 INFO - 'LOGNAME': 'cltbld', 18:07:37 INFO - 'MAIL': '/var/mail/cltbld', 18:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:37 INFO - 'MOZ_NO_REMOTE': '1', 18:07:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:37 INFO - 'NO_EM_RESTART': '1', 18:07:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:37 INFO - 'PWD': '/builds/slave/test', 18:07:37 INFO - 'SHELL': '/bin/bash', 18:07:37 INFO - 'SHLVL': '1', 18:07:37 INFO - 'TERM': 'linux', 18:07:37 INFO - 'TMOUT': '86400', 18:07:37 INFO - 'USER': 'cltbld', 18:07:37 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:37 INFO - '_': '/tools/buildbot/bin/python'} 18:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:07:37 INFO - Cleaning up... 18:07:37 INFO - Return code: 0 18:07:37 INFO - Running post-action listener: _resource_record_post_action 18:07:37 INFO - Running post-action listener: _start_resource_monitoring 18:07:37 INFO - Starting resource monitoring. 18:07:37 INFO - [mozharness: 2017-03-25 01:07:37.642231Z] Finished create-virtualenv step (success) 18:07:37 INFO - [mozharness: 2017-03-25 01:07:37.642746Z] Running install step. 18:07:37 INFO - Running pre-action listener: _resource_record_pre_action 18:07:37 INFO - Running main action method: install 18:07:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:07:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:07:37 INFO - Reading from file tmpfile_stdout 18:07:37 INFO - Detecting whether we're running mozinstall >=1.0... 18:07:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:07:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:07:37 INFO - Reading from file tmpfile_stdout 18:07:37 INFO - Output received: 18:07:37 INFO - Usage: mozinstall [options] installer 18:07:37 INFO - Options: 18:07:37 INFO - -h, --help show this help message and exit 18:07:37 INFO - -d DEST, --destination=DEST 18:07:37 INFO - Directory to install application into. [default: 18:07:37 INFO - "/builds/slave/test"] 18:07:37 INFO - --app=APP Application being installed. [default: firefox] 18:07:37 INFO - mkdir: /builds/slave/test/build/application 18:07:37 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'] 18:07:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:07:54 INFO - Reading from file tmpfile_stdout 18:07:54 INFO - Output received: 18:07:54 INFO - /builds/slave/test/build/application/firefox/firefox 18:07:54 INFO - Running post-action listener: _resource_record_post_action 18:07:54 INFO - [mozharness: 2017-03-25 01:07:54.884818Z] Finished install step (success) 18:07:54 INFO - [mozharness: 2017-03-25 01:07:54.885096Z] Running run-tests step. 18:07:54 INFO - Running pre-action listener: _resource_record_pre_action 18:07:54 INFO - Running main action method: run_tests 18:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:07:54 INFO - Python 2.7.3 18:07:54 INFO - Return code: 0 18:07:54 INFO - grabbing minidump binary from tooltool 18:07: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')]} 18:07:54 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 0x2bc2400>, '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 0x2bc33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:07:54 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 18:07:54 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 18:07:54 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 18:07:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:07:54 INFO - Return code: 0 18:07:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:07:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:07:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:07:54 INFO - ENV: RUST_BACKTRACE is now 1 18:07:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:07:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:07:54 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/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 18:07:54 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/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 18:07:54 INFO - Using env: {'DISPLAY': ':0', 18:07:54 INFO - 'HOME': '/home/cltbld', 18:07:54 INFO - 'LANG': 'en_US.UTF-8', 18:07:54 INFO - 'LANGUAGE': 'en_US:en', 18:07:54 INFO - 'LOGNAME': 'cltbld', 18:07:54 INFO - 'MAIL': '/var/mail/cltbld', 18:07:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:07:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:54 INFO - 'MOZ_NO_REMOTE': '1', 18:07:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:07:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:54 INFO - 'NO_EM_RESTART': '1', 18:07:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:54 INFO - 'PWD': '/builds/slave/test', 18:07:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:07:54 INFO - 'RUST_BACKTRACE': '1', 18:07:54 INFO - 'SHELL': '/bin/bash', 18:07:54 INFO - 'SHLVL': '1', 18:07:54 INFO - 'TERM': 'linux', 18:07:54 INFO - 'TMOUT': '86400', 18:07:54 INFO - 'USER': 'cltbld', 18:07:54 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1490404005.75760-823971796', 18:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:54 INFO - '_': '/tools/buildbot/bin/python'} 18:07:54 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/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 18:07:55 INFO - mozversion application_buildid: 20170325001738 18:07:55 INFO - mozversion application_changeset: 65b0ac174753b22c01156d72fb42d2abd3176dd1 18:07:55 INFO - mozversion application_display_name: Nightly 18:07:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:07:55 INFO - mozversion application_name: Firefox 18:07:55 INFO - mozversion application_remotingname: firefox 18:07:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:07:55 INFO - mozversion application_vendor: Mozilla 18:07:55 INFO - mozversion application_version: 55.0a1 18:07:55 INFO - mozversion platform_buildid: 20170325001738 18:07:55 INFO - mozversion platform_changeset: 65b0ac174753b22c01156d72fb42d2abd3176dd1 18:07:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:07:55 INFO - mozversion platform_version: 55.0a1 18:07:55 INFO - using testdate: 1490404075 18:07:55 INFO - actual date: 1490404075 18:07:55 INFO - starting webserver on 'localhost:46634' 18:07:55 INFO - SUITE-START | Running 2 tests 18:07:55 INFO - TEST-START | dromaeo_css 18:07:55 INFO - operating with platform_type : linux_ 18:07:55 INFO - Initialising browser for dromaeo_css test... 18:07:55 INFO - TEST-INFO | started process 11017 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UY3Kb/profile http://localhost:46634/getInfo.html) 18:07:59 INFO - PID 11017 | __metrics Screen width/height:1600/1200 18:07:59 INFO - PID 11017 | colorDepth:24 18:07:59 INFO - PID 11017 | Browser inner width/height: 1024/697 18:07:59 INFO - PID 11017 | __metrics 18:07:59 INFO - PID 11017 | 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" 18:07:59 INFO - PID 11017 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:08:00 INFO - TEST-INFO | 11017: exit 0 18:08:00 INFO - Browser initialized. 18:08:00 INFO - Running cycle 1/1 for dromaeo_css test... 18:08:00 INFO - TEST-INFO | started process 11110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UY3Kb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:08:12 INFO - PID 11110 | RSS: Main: 145457152 18:08:12 INFO - PID 11110 | 18:11:14 INFO - PID 11110 | Cycle 1(1): loaded http://localhost:46634/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46634/tests/dromaeo/cssquery-ext.html) 18:11:16 INFO - PID 11110 | RSS: Main: 293289984 18:11:16 INFO - PID 11110 | 18:13:02 INFO - PID 11110 | ************************* 18:13:02 INFO - PID 11110 | A coding exception was thrown and uncaught in a Task. 18:13:02 INFO - PID 11110 | 18:13:02 ERROR - PID 11110 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:13:02 INFO - PID 11110 | Full stack: 18:13:02 INFO - PID 11110 | ************************* 18:14:18 INFO - PID 11110 | Cycle 1(1): loaded http://localhost:46634/tests/dromaeo/cssquery-ext.html (next: http://localhost:46634/tests/dromaeo/cssquery-jquery.html) 18:14:19 INFO - PID 11110 | RSS: Main: 235012096 18:14:19 INFO - PID 11110 | 18:17:21 INFO - PID 11110 | Cycle 1(1): loaded http://localhost:46634/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46634/tests/dromaeo/cssquery-mootools.html) 18:17:21 INFO - PID 11110 | RSS: Main: 243380224 18:17:21 INFO - PID 11110 | 18:20:23 INFO - PID 11110 | Cycle 1(1): loaded http://localhost:46634/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46634/tests/dromaeo/cssquery-prototype.html) 18:20:24 INFO - PID 11110 | RSS: Main: 205291520 18:20:24 INFO - PID 11110 | 18:23:27 INFO - PID 11110 | Cycle 1(1): loaded http://localhost:46634/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46634/tests/dromaeo/cssquery-yui.html) 18:23:27 INFO - PID 11110 | RSS: Main: 185241600 18:23:27 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | Cycle 1(1): loaded http://localhost:46634/tests/dromaeo/cssquery-yui.html (next: http://localhost:46634/tests/dromaeo/cssquery-dojo.html) 18:26:30 INFO - PID 11110 | RSS: Main: 277774336 18:26:30 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | __start_tp_report 18:26:30 INFO - PID 11110 | _x_x_mozilla_page_load 18:26:30 INFO - PID 11110 | _x_x_mozilla_page_load_details 18:26:30 INFO - PID 11110 | |i|pagename|runs| 18:26:30 INFO - PID 11110 | |0;dojo.html;3318.1041118897897;3342.2814094969576;3360.7479439042077;3371.1066567359644;3394.032700680307;5663.773449062043;5730.971345143268;5770.076787713967;5790.363060063395;5794.507466865316;5790.797322093727;5803.332616749075;5813.418658134185;5856.6193197442135;5873.207117039209;5071.366079240095;5100.438951715309;5147.408048074463;5162.819301324445;5169.043726910515;5117.232415137741;5187.818426355078;5200.375954885419;5206.739663016853;5247.711375874325;5095.668781529189;5105.43840177582;5135.101357262502;5142.254373115915;5189.610779191576;5208.270842082112;5257.447967963385;5260.105782017066;5270.262163297141;5285.709285989269;5137.332146820927;5193.948060519384;5199.48005199477;5211.088059589594;5247.658902171384;5760.308762948431;5772.307323121252;5783.479486846204;5804.477597016289;5811.302643682785;6117.27937334216;6119.5104391648565;6120.111926550671;6174.289956654952;6251.78118765841;6328.462080723144;6337.6831158442355;6346.619202847798;6346.714397852108;6351.396617321347;5776.884462310814;5879.794207202727;5888.02847530157;5897.026990546512;5938.970305148447;6219.533534984895;6288.654124023131;6414.871702566016;6467.838304042437;6473.28793832678;6134.038624681888;6134.4978165938865;6139.154842350747;6140.609464233005;6140.662263575457;6239.750409983641;6240.937590624127;6243.968780156161;6244.188255526843;6250.656213908188;6120.204373431426;6240.188775459252;6250.468710159687;6251.218597675291;6251.562390632703;6092.749370501782;6159.137720719103;6191.4118158774845;6227.314995350508;6232.937670623326;4730.124926888537;4735.313379560013;4735.337052812609;4770.6660533763;4773.069251495995;4192.434021407132;4193.643540299047;4197.097624010852;4197.202531519001;4236.745795252295;5746.626469279484;5778.133280007948;5791.942080579141;5830.271216097988;5845.7369418376275;6258.843528911722;6279.3720627937755;6309.810705678837;6313.305536390993;6316.778912738124;5936.28764548257;5950.047992321294;5953.493953014043;5955.731992060368;6007.909881351783;5221;5271.604629652715;5285.312909321764;5335.306410166653;5349.839504814861;6546.0835483032415;6562.770303039466;6563.573367731273;6570.277269500351;6579.309172536815;6246.625202487865;6299.748010079728;6305.085762564494;6310.400511951358;6314.116023756769;6359.650219237987;6437.066625339393;6442.35576442352;6457.935420645734;6505.739770409131;6138.478229350555;6176.227971503562;6182.567220294536;6186.845328866814;6189.659568723763;6297.937020629735;6303.306636270098;6310.179676642191;6311.400416960381;6311.715972781144;5998.760049597967;5999.400059993965;6058;6112.083187521907;6130.111133885473;6044.153818465506;6094.380596348918;6124.877502449837;6130.049842274455;6132.38676132401;6014.729337179925;6052.818415447544;6065.090236464565;6066.393360663898;6066.4540191382985;5990.460858522754;6101.267847858286;6107.664078475549;6108.602940808833;6123.418275263843;5840.182374467493;5925.4370834770625;5934.643921364732;5941.554383421347;6013.067974463965 18:26:30 INFO - PID 11110 | |1;ext.html;751.1887161865186;818.4966245758858;834.9457285276454;1129.9435028248586;1133.818589025756;29410.852945735267;41402.79298603506;42540.57459425409;42590;42623.36064959028;44273.55726442735;44417;44440.55559444405;44506.33240501387;44696;5982.341942386334;8194.0576833664;8380.245777879989;8394.370422218328;8399.874001889977;6090.360512146205;7947.284744373005;8105.351571874236;8114.229148230908;8118.2693557579805;8229.259366657;8260.628271727757;8274.958625206866;8276.710315138942;8277.668893244294;8182.549959752211;8191.426600137993;8236.258736713695;8244.134365891556;8249;7929.841403171904;8199.836003279961;8262.339012878956;8277.213664701843;8277.58612069394;41143.794281028706;41424;41452;41454.1709165815;41490.1701965959;2967.1543610071244;2983.7314641682137;2988.8505574721175;2992.8653210605576;3023.909282721522;28424.715752842414;28437;28684.282892927637;29645.70354296451;29736.55395169074;3553.200529880782;3619.4751760994773;3620.149264922741;3639.4722765199162;3684.3552378333684;199502;199607;199834;199923;201758.9912050445;49662.50337496579;49731.50268497341;49898.25152622713;49944;50019.24971125363;51411;51433.742831286356;51499;51592;51600.741996290526;14582.125072495684;14625.341859616343;14629.561113166623;14636.926815366069;14679.266036698122;9886.901130988743;10018.298719089593;11773.411329433666;11821.468033938321;11832.112591555668;13976.720465590835;14030;14064.226467544384;14093.929530352283;14217.431302747775;5553.611247212656;5666.178404131378;5686.488216060575;5704.572157088235;5781.219535362757;1007.778288776468;1034.0538407357335;1039.308859608352;1042.1142029275056;1096.8299913513417;23874.88062559676;26005.349866253116;26110;26376.868115659297;26429;1125.7016890524017;1142.3659868772745;1146.7763786061637;1157.7221466847895;1174.8472698549133;13245.933770331087;13548.390322435514;13659.863401366058;13703.109297895606;13758.587242382744;214316;217471;218024;218684;333695;62566.374336256966;63617.68191159107;63674.68162659157;64013.67993160005;64103;1434.9641258968609;1460.5545308680742;1475.2918599072505;1476.734187846198;1479.1051413894438;27249.59125613157;28281.57577636378;28595.571066434437;28716;32332.191695207806;48545.75727121342;49007.754961224964;55923;55931.72034139803;55942.440575593726;320249;322995;326065;326434;327007;26009.349766255236;26194.214173574823;36297.45553816748;36428.4535731959;36516.81741591275;38572.42141367938;38684.80657596694;38957;39144.41283380695;39344.60655393409;29164.854175729834;30172.585993368502;31467.52798708067;32396.67603324031;32546.51180232251;26634.866825665747;26832.59751103774;28254.15237542877;28258.2935426616;29214.56178157287;39086.804565976985;39195;39420;39813.80093099516;39828.60171398365;32689.836550817094;32786.22958359724;32828.67171328256;32851.34297314088;33740.49389259212;25077.373065673502;26113.608295875958;26488;26618.86690566535;26621.393873301964 18:26:30 INFO - PID 11110 | |2;jquery.html;443.71602174608256;445.6702040490038;447.4183561370218;458.57581736893377;463.8353384548485;19524.21903123875;20167.09248083836;20306.59386812263;20338.288159914406;21775.891120544416;21816.672749908765;21965.780342196613;22094;22263.888680556614;22481.55036899261;5926.762929482827;5967.582269241155;6085.087236914454;6100.450959413652;6101.542384321171;5982.461578457938;6187.164732761087;6188.721507532172;6192.349803270659;6196.659183744892;5895.911561326562;6031.336552979169;6154.230721159855;6154.84612884677;6164.6917654117115;5964.433378829004;5991.400859914017;5998.580099393044;6003.669798161121;6098.2682078150465;5339.733013349317;5458.781648734046;5464.562834973192;5489.972550137264;5566.165075238706;24292;24557;24607.753922460724;24608.753912460827;24760.13339533108;2011.386527109231;2011.7686466056446;2018.697195420684;2030.5634288627869;2032.8576999610034;15353.232338383148;16603.169841507875;16730.66538669232;16849.747253791204;16950.49148525546;1817.4638481647946;1818.2908665620287;1818.4726429335476;1847.5842935339576;1847.8521718262507;106323.46838265836;108096.4595177027;108887.45556272248;111842;112243.43878280638;12381.504739810487;12389.876101239053;12564.874351256552;12671.493140274286;12823.871761282455;18075.27698892056;18090;18102.637947240983;18114.365997190034;18132.909335453238;8342.791430214173;8469.237768600855;8620;8705.303575713928;8719.04090550039;7668.309852113337;7675.270849269422;7705.190954949761;7921.564313962787;7971.322437592754;7821.921780782233;8017.478863873829;8023.719169829028;8139.267465927976;8207.95896020528;2290.599145149592;2315.9652605210936;2329.3594261577864;2329.7087864017;2412.21602979029;994.4977786217913;995.7759504111661;1001.8046480936148;1014.1126514299988;1015.6292953072076;19992.10035548404;20144.19423223084;20160.49598760043;20246.190152394032;20654.58690826175;1022.8619136416488;1030.7680771826278;1038.2161468091542;1048.8793788714395;1074.1138560687464;10083.344582602107;11164.832527512093;11188.608398706006;11191.552337906556;11218.495167717472;575478;582064;616701;621493.602915809;638896;106607;113306;114467.42766286281;114604;114782;1201.5073819733868;1213.3326670331282;1259.433255035256;1261.9369031548385;1271.173737070893;20099.095540700997;20241.79758202399;20584.897075514527;20976.47558811042;21055.473613159793;48784.85328747096;49467;49771;50107.49892501028;51024.48975510346;64483;65015.349846502824;65293.347066528724;66628;68237.65881170562;6383.553151279366;6900.551464154814;8763.649454021768;8863.379563430499;9050.502222377832;12871.742565148832;13290.40193191328;13628.86371136276;14577;14647.414103435744;12219.327936963196;12326;12482.937585312378;12722.745545089232;12787.296698681661;11334.319940803578;11500;11576.131790115607;11684.766304674029;11717.882821172021;12141.392930353624;12720.618381448572;12810.359482025675;12834;12900.225986440844;8709.869351959598;8851.114888511098;8950.910490895269;9000.50497222659;9329.440233586007;12367.876321236672;12412.255264684149;12554.121211515472;12719.173253738478;12957 18:26:30 INFO - PID 11110 | |3;mootools.html;1169.2692067457838;1295.3911661519085;1344.1128854955732;1357.4705864712764;1358.3140514040406;9150.405223660457;10539.683809485718;10918.617848375308;10931.508082136314;10932.453377331121;10679.679609611705;10752.086072683831;10781.8382724259;10786.460676966159;10789.83815242772;2011.8591698581106;2069.7516298044256;2072.253988564116;2390.0320370250033;2420.068273714624;2073.481629592602;2084.593504266669;2282.2126366403563;2368.0527788884497;2372.8694921779297;2328.790408863202;2348.612478940973;2352.3178278299274;2352.6823878776404;2355.352278123521;2237.630790919496;2242.7420846602686;2265;2343.941401464977;2380.9285721428387;2288.622377307742;2301.5972204864083;2304.6082166031815;2312.4681323295736;2312.664663623782;10942.4528773561;10944.124470042378;11098.944505277423;11108.277961932448;11108.722281942935;6010;6220.782272620482;6223.25320961483;6225.719842607094;6232.906506402408;3974.741641793274;3976.284268831609;4109.712320137592;4113.136241389282;4151.771652559139;1359.0554564577621;1374.9106308090043;1384.0380935250005;1407.9718405631932;1449.014670024383;16412.507624771275;16492.25784839685;16520.83479165217;16580.75128873068;16591.834081659148;5260.631755777135;5417.485338892878;5419.539339156216;5504.669719816824;5583.441655834384;13431.529896453578;13471.326433678278;13475.393607287695;13489.2580908051;13493.66265843342;6238.064290356392;6363.649999250086;6395.328490508523;6400.135981642512;6403.071554624554;5691.9146212806845;5803.448672376117;5846.4153584642045;5851.619644723164;5853.239078919798;3761.3417651910804;3800.638939300762;4133.132042271097;4139.79301034947;4172.541020487804;1202.0563857372097;1208.8247204155348;1234.7900856854176;1246.9438875250635;1248.993755031219;839.2824135364298;852.5098068610449;853.4793775796759;880.3529405886668;884.6019291318933;6775.796726098225;6950.582965022014;7004;7028.156621205488;7262.963685181646;942.1473566422399;955.06880791228;957.7701351675687;966.3428868369576;966.7148191283588;4131.9173616528105;4521.8191272349395;4556.567126123013;4666.580007799314;4823.252395878644;14082.647933801532;14633;14644.194569298577;14692.191929443983;14729.410823566937;13504.729905401837;13958.511452099128;13964.092333998258;13977.440902363995;13994.16035037901;10072.798544029078;10555.313904596176;10566.154707623371;10567.682969510774;10669.626563070255;6724.529282950147;6918.169819621678;6937.7571784987285;6965.686544105426;7044.8591028178125;12573;12761.234325940473;13099.541516046893;13102.606921792361;13112.934435327763;13017.153884997444;13029;13053.28206948627;13095.672608184872;13115.344232788513;9203;9276.654885041766;9320.161185493067;9358.672446464341;9412.858807118037;8949.59726812282;9163.08369163105;9986.201103911815;10430.843537346795;10446.529906154394;9043.547822608713;9411.43531388119;9458.337916346065;9541.284403669892;9582.472963987047;8864.822703546022;8955.507447090473;8980.101989800967;9016.18854303116;9110.544472776468;8174.427790054877;8318.334533237448;8541.231289184003;8578.742637720878;8580.356473264404;6353.809385718436;6422.068800023877;6492.415682588589;6501.674916254263;6534.215894092739;6434.324395938359;6463.289038205888;6586.176727909012;6607.768728094494;6670.466362691071 18:26:30 INFO - PID 11110 | |4;prototype.html;405.3959600195709;413.41708191450044;430.8535098066657;435.7429116821075;439.46604875076775;5773.913391299128;5846;5864.853378665535;5873.73568189432;5877.67672777997;5538.6399884007615;5687.7156142192935;5719.227904232927;5750.568707346944;5778.27771528559;4455.688101832873;4466;4466.82132714691;4466.955330446703;4475.574820392074;4493.910121797562;4503.1218912312115;4511.1654343946475;4519.412476378083;4536.591706746392;4293.947982744232;4411.492678341983;4457.064016556527;4472.977635111836;4479.3952816369865;4443.800028998703;4456.331550267453;4461.955380446203;4475.30632751924;4483.596476317114;3958.960410395888;3967.920641587181;3988.9030516607877;3989.740666856645;4016.8995775105564;5434.91847622286;5648.209250704905;5695.829125126253;5734.39788822176;5767.884642307173;5228.451012643655;5235.869103272448;5237.711925844077;5240.397354304266;5245.8459138989365;6107.236595425572;6128.693565321761;6132.846678833021;6151;6153.538484613672;4280.59334363235;4405.845795397146;4407.845725399596;4420.602145806861;4446.799894004777;6105.419985101453;6203.31763506014;6274.968625156891;6306.621602703853;6394.296627370985;4519.81920723174;4549.27211646135;4554.157480866051;4556.407667003268;4561.2473941799235;4335.349697545394;4438.423005009328;4463.866084017484;4511.255642818964;4615.123126605934;5142.51146141116;5148.675112905177;5193.948060519422;5196.792128314901;5224.947750522446;6124;6131.202943617302;6138.109974053827;6142.938570614237;6176.197094377702;1536.508317338441;1542.6143464133966;1551.9059063360303;1555.922203889801;1557.392616879418;1040.547361897577;1056.9682909512726;1060.761328701036;1065.9500392113762;1068;5909.172715819789;5939.46544810969;5974.522038236931;5974.641521508724;6002.849928751729;6194.9690251549355;6211.627302361872;6227;6262.966610509215;6309;5726.914096288559;5893.5579831512805;5931.258592675916;5982.611130276518;6001.669908155093;3298.967010329914;3329.234276116507;3334.3164651246434;3352.6312105668358;3356.1945133167865;6380.744770209232;6524.869502609921;6546.18172728409;6595.835104122435;6626.171728533934;6438.678066096676;6477.190351206099;6482.254540727877;6486.124373209556;6515.446187074057;6496.740130394825;6570.507211959027;6629.602223866487;6655.600663960178;6667.966660166765;6266.811995640138;6277.305127615609;6280.309165991828;6289.371062893674;6332.2717887441695;5981.491573216325;6069.696515174135;6087.421694938974;6250.281217660027;6251.281102673251;6525.9021114684265;6532.640704761284;6557.836054098686;6581.506387021088;6581.901271481028;4422.624076953495;4486.820527178877;4510.548945105464;4513.54864513546;4521.321801729767;6204.968975155095;6283;6284.402981716729;6289.213848268966;6319.399657032574;5890.322612899571;6109.602875813236;6118.418750218722;6129.540284478593;6146.231721034871;6061.939380606137;6089.969550152221;6100.511959043177;6140.3552626975315;6179.474744646755;6115.84710382244;6130.2030736004035;6131.386861313833;6134.386561344008;6135.294441139147;5941.584089113893;6103.816885493442;6109;6124.908126378197;6135.355787642234;5972.163897054503;5976.9103463448955;5992.340842507234;5995.310539287863;5997.460228579449 18:26:30 INFO - PID 11110 | |5;yui.html;783.8941742864712;787.4212453846424;787.9369650427965;802.001508122388;805.7985503624094;15920.681586368266;16144.192790360494;16231.675366492664;16238.106904120299;16257.75613365799;15987.120708361035;16063.116528590923;16198.83801161988;16202.837971620309;16241.431549895757;780.6292011294618;796.6972550430828;807.4832107451236;812.0580127052621;815.3395749442941;823.6952327638752;832.2177153475744;834.04501845387;834.1658341658342;834.6327615849037;874.9387542872001;878.6441491196076;879.5338470610586;879.8328317619664;880.4673172730502;861.444368382393;869.3393021303791;870.4733636150102;870.843248215321;871.3551971541077;640.5804198250154;640.6604499615212;643.488426700774;644.7131026693123;645.5577929118568;15410.3835846566;15635.609109772233;15696.13671248081;17562.38531651386;17938.641227175514;16441.67116657672;16451.42420015294;16776;16783.83216167835;16933;12116.757664846742;12272.140950133495;12533.686657833536;12866.421011054525;13344.733105337937;3227.5320078588707;3238.6599407062154;3283.4254005548933;3353.9161520961925;3359.1937934895695;92632;92839.53580232123;94045.52977235138;94425.52787236088;95861.52069239679;11751.236169648946;11953;12066.818997715041;12090.455929483194;12143.089268304913;25823.741762582133;25980.35049123787;26180;26354.73645263561;26444.603330950053;8085.919140808516;8136.918630813616;8200.630971606291;8205.79485512867;8317.875231871527;4394.648428125743;4396.406485124531;4445.177642136216;4505.639548836085;4599.310103484504;4207.97896010518;4309.978450107792;4318.028443600165;4319.632831209319;4320.4383430153875;759.6201899050475;772.1814876231213;778.2373274191211;780.0795061826985;781.4998401023349;636.5162476517879;647.6405594894784;648.7210499485172;665.2117241069349;678.9287124851819;9644.75888102789;9775.51122443875;9927.2058235341;10297.330673506198;10497.842532362021;623.5416968528128;657.139147716493;659.261626977788;659.9307072757388;664.9700763465559;4564.954350456519;4837.2260438329695;4850.563449289581;4852.56326930578;4860.8298709545;76501.61749191218;77741.61129194431;78046;79227.60386198148;79353.20646793574;53718;64838.02742958765;71173.7295762256;71643.2835671647;75586;63575.36424635787;65173.674131629035;65519;65787;68600;9037.50293733851;9347.106409458618;9418.387804792666;9449.102335278138;9536.46206512216;34418;34914.49338392258;35376.64623353836;36106.63893361033;36667.44998825073;43258;44250.336244957;44724.77637611791;45263.77368113138;45839.54160458353;12096.697582560506;12186.451609677762;12456.065795065588;12596.496140154657;12750.74498510006;13501.189928604315;13511.797323039966;13634;14236.572902812932;14703;11998.880011200126;12391.256524608552;12454.25274483534;12495.12534122639;12784.232946023269;12639.93680031594;12699.619011429671;13008.349582521025;13077.803832942323;13619;11674;11733.823992640288;11749.76500470003;12056.27856495624;12406;8241;8314.376421768513;8620.61207245663;8763.91236087631;8825.514596696987;9297.349185556946;9770.951145244228;9817.460039697886;9834.950825245827;9848.753781155528 18:26:30 INFO - PID 11110 | __end_tp_report 18:26:30 INFO - PID 11110 | __start_cc_report 18:26:30 INFO - PID 11110 | _x_x_mozilla_cycle_collect,1517 18:26:30 INFO - PID 11110 | __end_cc_report 18:26:30 INFO - PID 11110 | __startTimestamp1490405190955__endTimestamp 18:26:30 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | ------- Summary: start ------- 18:26:30 INFO - PID 11110 | Number of tests: 6 18:26:30 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | [#0] dojo.html Cycles:165 Average:5772.15 Median:6013.07 stddev:686.30 (11.4%) stddev-sans-first:660.86 18:26:30 INFO - PID 11110 | Values: 3318.1 3342.3 3360.7 3371.1 3394.0 5663.8 5731.0 5770.1 5790.4 5794.5 5790.8 5803.3 5813.4 5856.6 5873.2 5071.4 5100.4 5147.4 5162.8 5169.0 5117.2 5187.8 5200.4 5206.7 5247.7 5095.7 5105.4 5135.1 5142.3 5189.6 5208.3 5257.4 5260.1 5270.3 5285.7 5137.3 5193.9 5199.5 5211.1 5247.7 5760.3 5772.3 5783.5 5804.5 5811.3 6117.3 6119.5 6120.1 6174.3 6251.8 6328.5 6337.7 6346.6 6346.7 6351.4 5776.9 5879.8 5888.0 5897.0 5939.0 6219.5 6288.7 6414.9 6467.8 6473.3 6134.0 6134.5 6139.2 6140.6 6140.7 6239.8 6240.9 6244.0 6244.2 6250.7 6120.2 6240.2 6250.5 6251.2 6251.6 6092.7 6159.1 6191.4 6227.3 6232.9 4730.1 4735.3 4735.3 4770.7 4773.1 4192.4 4193.6 4197.1 4197.2 4236.7 5746.6 5778.1 5791.9 5830.3 5845.7 6258.8 6279.4 6309.8 6313.3 6316.8 5936.3 5950.0 5953.5 5955.7 6007.9 5221.0 5271.6 5285.3 5335.3 5349.8 6546.1 6562.8 6563.6 6570.3 6579.3 6246.6 6299.7 6305.1 6310.4 6314.1 6359.7 6437.1 6442.4 6457.9 6505.7 6138.5 6176.2 6182.6 6186.8 6189.7 6297.9 6303.3 6310.2 6311.4 6311.7 5998.8 5999.4 6058.0 6112.1 6130.1 6044.2 6094.4 6124.9 6130.0 6132.4 6014.7 6052.8 6065.1 6066.4 6066.5 5990.5 6101.3 6107.7 6108.6 6123.4 5840.2 5925.4 5934.6 5941.6 6013.1 18:26:30 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | [#1] ext.html Cycles:180 Average:42550.04 Median:32840.01 stddev:68630.88 (209.0%) stddev-sans-first:68751.65 18:26:30 INFO - PID 11110 | Values: 751.2 818.5 834.9 1129.9 1133.8 29410.9 41402.8 42540.6 42590.0 42623.4 44273.6 44417.0 44440.6 44506.3 44696.0 5982.3 8194.1 8380.2 8394.4 8399.9 6090.4 7947.3 8105.4 8114.2 8118.3 8229.3 8260.6 8275.0 8276.7 8277.7 8182.5 8191.4 8236.3 8244.1 8249.0 7929.8 8199.8 8262.3 8277.2 8277.6 41143.8 41424.0 41452.0 41454.2 41490.2 2967.2 2983.7 2988.9 2992.9 3023.9 28424.7 28437.0 28684.3 29645.7 29736.6 3553.2 3619.5 3620.1 3639.5 3684.4 199502.0 199607.0 199834.0 199923.0 201759.0 49662.5 49731.5 49898.3 49944.0 50019.2 51411.0 51433.7 51499.0 51592.0 51600.7 14582.1 14625.3 14629.6 14636.9 14679.3 9886.9 10018.3 11773.4 11821.5 11832.1 13976.7 14030.0 14064.2 14093.9 14217.4 5553.6 5666.2 5686.5 5704.6 5781.2 1007.8 1034.1 1039.3 1042.1 1096.8 23874.9 26005.3 26110.0 26376.9 26429.0 1125.7 1142.4 1146.8 1157.7 1174.8 13245.9 13548.4 13659.9 13703.1 13758.6 214316.0 217471.0 218024.0 218684.0 333695.0 62566.4 63617.7 63674.7 64013.7 64103.0 1435.0 1460.6 1475.3 1476.7 1479.1 27249.6 28281.6 28595.6 28716.0 32332.2 48545.8 49007.8 55923.0 55931.7 55942.4 320249.0 322995.0 326065.0 326434.0 327007.0 26009.3 26194.2 36297.5 36428.5 36516.8 38572.4 38684.8 38957.0 39144.4 39344.6 29164.9 30172.6 31467.5 32396.7 32546.5 26634.9 26832.6 28254.2 28258.3 29214.6 39086.8 39195.0 39420.0 39813.8 39828.6 32689.8 32786.2 32828.7 32851.3 33740.5 25077.4 26113.6 26488.0 26618.9 26621.4 18:26:30 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | [#2] jquery.html Cycles:180 Average:34866.32 Median:21015.97 stddev:100458.74 (478.0%) stddev-sans-first:100707.30 18:26:30 INFO - PID 11110 | Values: 443.7 445.7 447.4 458.6 463.8 19524.2 20167.1 20306.6 20338.3 21775.9 21816.7 21965.8 22094.0 22263.9 22481.6 5926.8 5967.6 6085.1 6100.5 6101.5 5982.5 6187.2 6188.7 6192.3 6196.7 5895.9 6031.3 6154.2 6154.8 6164.7 5964.4 5991.4 5998.6 6003.7 6098.3 5339.7 5458.8 5464.6 5490.0 5566.2 24292.0 24557.0 24607.8 24608.8 24760.1 2011.4 2011.8 2018.7 2030.6 2032.9 15353.2 16603.2 16730.7 16849.7 16950.5 1817.5 1818.3 1818.5 1847.6 1847.9 106323.5 108096.5 108887.5 111842.0 112243.4 12381.5 12389.9 12564.9 12671.5 12823.9 18075.3 18090.0 18102.6 18114.4 18132.9 8342.8 8469.2 8620.0 8705.3 8719.0 7668.3 7675.3 7705.2 7921.6 7971.3 7821.9 8017.5 8023.7 8139.3 8208.0 2290.6 2316.0 2329.4 2329.7 2412.2 994.5 995.8 1001.8 1014.1 1015.6 19992.1 20144.2 20160.5 20246.2 20654.6 1022.9 1030.8 1038.2 1048.9 1074.1 10083.3 11164.8 11188.6 11191.6 11218.5 575478.0 582064.0 616701.0 621493.6 638896.0 106607.0 113306.0 114467.4 114604.0 114782.0 1201.5 1213.3 1259.4 1261.9 1271.2 20099.1 20241.8 20584.9 20976.5 21055.5 48784.9 49467.0 49771.0 50107.5 51024.5 64483.0 65015.3 65293.3 66628.0 68237.7 6383.6 6900.6 8763.6 8863.4 9050.5 12871.7 13290.4 13628.9 14577.0 14647.4 12219.3 12326.0 12482.9 12722.7 12787.3 11334.3 11500.0 11576.1 11684.8 11717.9 12141.4 12720.6 12810.4 12834.0 12900.2 8709.9 8851.1 8950.9 9000.5 9329.4 12367.9 12412.3 12554.1 12719.2 12957.0 18:26:30 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | [#3] mootools.html Cycles:180 Average:7044.28 Median:3090.71 stddev:4428.29 (143.3%) stddev-sans-first:4418.71 18:26:30 INFO - PID 11110 | Values: 1169.3 1295.4 1344.1 1357.5 1358.3 9150.4 10539.7 10918.6 10931.5 10932.5 10679.7 10752.1 10781.8 10786.5 10789.8 2011.9 2069.8 2072.3 2390.0 2420.1 2073.5 2084.6 2282.2 2368.1 2372.9 2328.8 2348.6 2352.3 2352.7 2355.4 2237.6 2242.7 2265.0 2343.9 2380.9 2288.6 2301.6 2304.6 2312.5 2312.7 10942.5 10944.1 11098.9 11108.3 11108.7 6010.0 6220.8 6223.3 6225.7 6232.9 3974.7 3976.3 4109.7 4113.1 4151.8 1359.1 1374.9 1384.0 1408.0 1449.0 16412.5 16492.3 16520.8 16580.8 16591.8 5260.6 5417.5 5419.5 5504.7 5583.4 13431.5 13471.3 13475.4 13489.3 13493.7 6238.1 6363.6 6395.3 6400.1 6403.1 5691.9 5803.4 5846.4 5851.6 5853.2 3761.3 3800.6 4133.1 4139.8 4172.5 1202.1 1208.8 1234.8 1246.9 1249.0 839.3 852.5 853.5 880.4 884.6 6775.8 6950.6 7004.0 7028.2 7263.0 942.1 955.1 957.8 966.3 966.7 4131.9 4521.8 4556.6 4666.6 4823.3 14082.6 14633.0 14644.2 14692.2 14729.4 13504.7 13958.5 13964.1 13977.4 13994.2 10072.8 10555.3 10566.2 10567.7 10669.6 6724.5 6918.2 6937.8 6965.7 7044.9 12573.0 12761.2 13099.5 13102.6 13112.9 13017.2 13029.0 13053.3 13095.7 13115.3 9203.0 9276.7 9320.2 9358.7 9412.9 8949.6 9163.1 9986.2 10430.8 10446.5 9043.5 9411.4 9458.3 9541.3 9582.5 8864.8 8955.5 8980.1 9016.2 9110.5 8174.4 8318.3 8541.2 8578.7 8580.4 6353.8 6422.1 6492.4 6501.7 6534.2 6434.3 6463.3 6586.2 6607.8 6670.5 18:26:30 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | [#4] prototype.html Cycles:180 Average:5156.73 Median:5884.00 stddev:1520.51 (25.8%) stddev-sans-first:1482.37 18:26:30 INFO - PID 11110 | Values: 405.4 413.4 430.9 435.7 439.5 5773.9 5846.0 5864.9 5873.7 5877.7 5538.6 5687.7 5719.2 5750.6 5778.3 4455.7 4466.0 4466.8 4467.0 4475.6 4493.9 4503.1 4511.2 4519.4 4536.6 4293.9 4411.5 4457.1 4473.0 4479.4 4443.8 4456.3 4462.0 4475.3 4483.6 3959.0 3967.9 3988.9 3989.7 4016.9 5434.9 5648.2 5695.8 5734.4 5767.9 5228.5 5235.9 5237.7 5240.4 5245.8 6107.2 6128.7 6132.8 6151.0 6153.5 4280.6 4405.8 4407.8 4420.6 4446.8 6105.4 6203.3 6275.0 6306.6 6394.3 4519.8 4549.3 4554.2 4556.4 4561.2 4335.3 4438.4 4463.9 4511.3 4615.1 5142.5 5148.7 5193.9 5196.8 5224.9 6124.0 6131.2 6138.1 6142.9 6176.2 1536.5 1542.6 1551.9 1555.9 1557.4 1040.5 1057.0 1060.8 1066.0 1068.0 5909.2 5939.5 5974.5 5974.6 6002.8 6195.0 6211.6 6227.0 6263.0 6309.0 5726.9 5893.6 5931.3 5982.6 6001.7 3299.0 3329.2 3334.3 3352.6 3356.2 6380.7 6524.9 6546.2 6595.8 6626.2 6438.7 6477.2 6482.3 6486.1 6515.4 6496.7 6570.5 6629.6 6655.6 6668.0 6266.8 6277.3 6280.3 6289.4 6332.3 5981.5 6069.7 6087.4 6250.3 6251.3 6525.9 6532.6 6557.8 6581.5 6581.9 4422.6 4486.8 4510.5 4513.5 4521.3 6205.0 6283.0 6284.4 6289.2 6319.4 5890.3 6109.6 6118.4 6129.5 6146.2 6061.9 6090.0 6100.5 6140.4 6179.5 6115.8 6130.2 6131.4 6134.4 6135.3 5941.6 6103.8 6109.0 6124.9 6135.4 5972.2 5976.9 5992.3 5995.3 5997.5 18:26:30 INFO - PID 11110 | 18:26:30 INFO - PID 11110 | [#5] yui.html Cycles:180 Average:17636.49 Median:4856.70 stddev:23217.97 (478.1%) stddev-sans-first:23248.62 18:26:30 INFO - PID 11110 | Values: 783.9 787.4 787.9 802.0 805.8 15920.7 16144.2 16231.7 16238.1 16257.8 15987.1 16063.1 16198.8 16202.8 16241.4 780.6 796.7 807.5 812.1 815.3 823.7 832.2 834.0 834.2 834.6 874.9 878.6 879.5 879.8 880.5 861.4 869.3 870.5 870.8 871.4 640.6 640.7 643.5 644.7 645.6 15410.4 15635.6 15696.1 17562.4 17938.6 16441.7 16451.4 16776.0 16783.8 16933.0 12116.8 12272.1 12533.7 12866.4 13344.7 3227.5 3238.7 3283.4 3353.9 3359.2 92632.0 92839.5 94045.5 94425.5 95861.5 11751.2 11953.0 12066.8 12090.5 12143.1 25823.7 25980.4 26180.0 26354.7 26444.6 8085.9 8136.9 8200.6 8205.8 8317.9 4394.6 4396.4 4445.2 4505.6 4599.3 4208.0 4310.0 4318.0 4319.6 4320.4 759.6 772.2 778.2 780.1 781.5 636.5 647.6 648.7 665.2 678.9 9644.8 9775.5 9927.2 10297.3 10497.8 623.5 657.1 659.3 659.9 665.0 4565.0 4837.2 4850.6 4852.6 4860.8 76501.6 77741.6 78046.0 79227.6 79353.2 53718.0 64838.0 71173.7 71643.3 75586.0 63575.4 65173.7 65519.0 65787.0 68600.0 9037.5 9347.1 9418.4 9449.1 9536.5 34418.0 34914.5 35376.6 36106.6 36667.4 43258.0 44250.3 44724.8 45263.8 45839.5 12096.7 12186.5 12456.1 12596.5 12750.7 13501.2 13511.8 13634.0 14236.6 14703.0 11998.9 12391.3 12454.3 12495.1 12784.2 12639.9 12699.6 13008.3 13077.8 13619.0 11674.0 11733.8 11749.8 12056.3 12406.0 8241.0 8314.4 8620.6 8763.9 8825.5 9297.3 9771.0 9817.5 9835.0 9848.8 18:26:30 INFO - PID 11110 | -------- Summary: end -------- 18:26:30 INFO - PID 11110 | 18:26:31 INFO - TEST-INFO | 11110: exit 0 18:26:31 INFO - TEST-OK | dromaeo_css | took 1116631ms 18:26:31 INFO - TEST-START | kraken 18:26:31 INFO - operating with platform_type : linux_ 18:26:31 INFO - Initialising browser for kraken test... 18:26:31 INFO - TEST-INFO | started process 11439 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkiG36u/profile http://localhost:46634/getInfo.html) 18:26:35 INFO - PID 11439 | __metrics Screen width/height:1600/1200 18:26:35 INFO - PID 11439 | colorDepth:24 18:26:35 INFO - PID 11439 | Browser inner width/height: 1024/697 18:26:35 INFO - PID 11439 | __metrics 18:26:35 INFO - PID 11439 | 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" 18:26:35 INFO - PID 11439 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:26:36 INFO - TEST-INFO | 11439: exit 0 18:26:36 INFO - Browser initialized. 18:26:36 INFO - Running cycle 1/1 for kraken test... 18:26:36 INFO - TEST-INFO | started process 11534 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkiG36u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:48 INFO - PID 11534 | RSS: Main: 145776640 18:26:48 INFO - PID 11534 | 18:26:51 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46634/tests/kraken/driver.html?testName=audio-beat-detection) 18:26:52 INFO - PID 11534 | RSS: Main: 200974336 18:26:52 INFO - PID 11534 | 18:26:54 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46634/tests/kraken/driver.html?testName=audio-dft) 18:26:55 INFO - PID 11534 | RSS: Main: 179593216 18:26:55 INFO - PID 11534 | 18:26:58 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46634/tests/kraken/driver.html?testName=audio-fft) 18:26:58 INFO - PID 11534 | RSS: Main: 260763648 18:26:58 INFO - PID 11534 | 18:27:00 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46634/tests/kraken/driver.html?testName=audio-oscillator) 18:27:01 INFO - PID 11534 | RSS: Main: 173240320 18:27:01 INFO - PID 11534 | 18:27:03 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46634/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:27:04 INFO - PID 11534 | RSS: Main: 176463872 18:27:04 INFO - PID 11534 | 18:27:07 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46634/tests/kraken/driver.html?testName=imaging-darkroom) 18:27:07 INFO - PID 11534 | RSS: Main: 214892544 18:27:07 INFO - PID 11534 | 18:27:11 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46634/tests/kraken/driver.html?testName=imaging-desaturate) 18:27:11 INFO - PID 11534 | RSS: Main: 230793216 18:27:11 INFO - PID 11534 | 18:27:14 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46634/tests/kraken/driver.html?testName=json-parse-financial) 18:27:15 INFO - PID 11534 | RSS: Main: 244584448 18:27:15 INFO - PID 11534 | 18:27:16 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46634/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:27:17 INFO - PID 11534 | RSS: Main: 178335744 18:27:17 INFO - PID 11534 | 18:27:19 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46634/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:27:19 INFO - PID 11534 | RSS: Main: 184897536 18:27:19 INFO - PID 11534 | 18:27:21 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46634/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:27:22 INFO - PID 11534 | RSS: Main: 193794048 18:27:22 INFO - PID 11534 | 18:27:25 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46634/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:27:25 INFO - PID 11534 | RSS: Main: 204476416 18:27:25 INFO - PID 11534 | 18:27:28 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46634/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:27:29 INFO - PID 11534 | RSS: Main: 199761920 18:27:29 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | Cycle 1(1): loaded http://localhost:46634/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46634/tests/kraken/driver.html?testName=ai-astar) 18:27:31 INFO - PID 11534 | RSS: Main: 269246464 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | __start_tp_report 18:27:31 INFO - PID 11534 | _x_x_mozilla_page_load 18:27:31 INFO - PID 11534 | _x_x_mozilla_page_load_details 18:27:31 INFO - PID 11534 | |i|pagename|runs| 18:27:31 INFO - PID 11534 | |0;ai-astar;100;108;109;109;109;110;109;109;108;108 18:27:31 INFO - PID 11534 | |1;audio-beat-detection;144;116;122;160;118;119;117;116;140;121 18:27:31 INFO - PID 11534 | |2;audio-dft;162;162;163;163;162;161;161;164;162;163 18:27:31 INFO - PID 11534 | |3;audio-fft;75;76;76;108;75;73;73;73;91;74 18:27:31 INFO - PID 11534 | |4;audio-oscillator;82;82;118;82;83;82;97;82;82;111 18:27:31 INFO - PID 11534 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;113 18:27:31 INFO - PID 11534 | |6;imaging-darkroom;147;148;146;147;147;147;147;147;147;147 18:27:31 INFO - PID 11534 | |7;imaging-desaturate;94;94;94;93;94;94;94;94;94;93 18:27:31 INFO - PID 11534 | |8;json-parse-financial;57;57;55;57;56;57;56;58;56;56 18:27:31 INFO - PID 11534 | |9;json-stringify-tinderbox;55;55;55;55;56;55;55;56;56;54 18:27:31 INFO - PID 11534 | |10;stanford-crypto-aes;79;102;79;80;80;80;80;80;79;79 18:27:31 INFO - PID 11534 | |11;stanford-crypto-ccm;135;134;134;136;132;136;131;129;170;127 18:27:31 INFO - PID 11534 | |12;stanford-crypto-pbkdf2;184;185;194;179;185;191;185;179;180;180 18:27:31 INFO - PID 11534 | |13;stanford-crypto-sha256-iterative;67;64;67;69;67;65;65;65;65;67 18:27:31 INFO - PID 11534 | __end_tp_report 18:27:31 INFO - PID 11534 | __start_cc_report 18:27:31 INFO - PID 11534 | _x_x_mozilla_cycle_collect,1750 18:27:31 INFO - PID 11534 | __end_cc_report 18:27:31 INFO - PID 11534 | __startTimestamp1490405251369__endTimestamp 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | ------- Summary: start ------- 18:27:31 INFO - PID 11534 | Number of tests: 14 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#0] ai-astar Cycles:10 Average:107.90 Median:109.00 stddev:2.85 (2.6%) stddev-sans-first:0.67 18:27:31 INFO - PID 11534 | Values: 100.0 108.0 109.0 109.0 109.0 110.0 109.0 109.0 108.0 108.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:121.50 stddev:15.25 (12.6%) stddev-sans-first:14.93 18:27:31 INFO - PID 11534 | Values: 144.0 116.0 122.0 160.0 118.0 119.0 117.0 116.0 140.0 121.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 18:27:31 INFO - PID 11534 | Values: 162.0 162.0 163.0 163.0 162.0 161.0 161.0 164.0 162.0 163.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#3] audio-fft Cycles:10 Average:79.40 Median:75.00 stddev:11.38 (15.2%) stddev-sans-first:11.96 18:27:31 INFO - PID 11534 | Values: 75.0 76.0 76.0 108.0 75.0 73.0 73.0 73.0 91.0 74.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:82.00 stddev:13.77 (16.8%) stddev-sans-first:14.29 18:27:31 INFO - PID 11534 | Values: 82.0 82.0 118.0 82.0 83.0 82.0 97.0 82.0 82.0 111.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 18:27:31 INFO - PID 11534 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 18:27:31 INFO - PID 11534 | Values: 147.0 148.0 146.0 147.0 147.0 147.0 147.0 147.0 147.0 147.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:27:31 INFO - PID 11534 | Values: 94.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 93.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 18:27:31 INFO - PID 11534 | Values: 57.0 57.0 55.0 57.0 56.0 57.0 56.0 58.0 56.0 56.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:55.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 18:27:31 INFO - PID 11534 | Values: 55.0 55.0 55.0 55.0 56.0 55.0 55.0 56.0 56.0 54.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:80.00 stddev:7.11 (8.9%) stddev-sans-first:7.47 18:27:31 INFO - PID 11534 | Values: 79.0 102.0 79.0 80.0 80.0 80.0 80.0 80.0 79.0 79.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:134.50 stddev:12.18 (9.1%) stddev-sans-first:12.90 18:27:31 INFO - PID 11534 | Values: 135.0 134.0 134.0 136.0 132.0 136.0 131.0 129.0 170.0 127.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:185.00 stddev:5.09 (2.8%) stddev-sans-first:5.40 18:27:31 INFO - PID 11534 | Values: 184.0 185.0 194.0 179.0 185.0 191.0 185.0 179.0 180.0 180.0 18:27:31 INFO - PID 11534 | 18:27:31 INFO - PID 11534 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:67.00 stddev:1.52 (2.3%) stddev-sans-first:1.58 18:27:31 INFO - PID 11534 | Values: 67.0 64.0 67.0 69.0 67.0 65.0 65.0 65.0 65.0 67.0 18:27:31 INFO - PID 11534 | -------- Summary: end -------- 18:27:31 INFO - PID 11534 | 18:27:32 INFO - TEST-INFO | 11534: exit 0 18:27:32 INFO - TEST-OK | kraken | took 60589ms 18:27:32 INFO - SUITE-END | took 1177s 18:27:32 INFO - Completed test suite (00:19:37) 18:27:32 INFO - javascript benchmark 18:27:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7559.170002330887, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.1041118897897, 3342.2814094969576, 3360.7479439042077, 3371.1066567359644, 3394.032700680307, 5663.773449062043, 5730.971345143268, 5770.076787713967, 5790.363060063395, 5794.507466865316, 5790.797322093727, 5803.332616749075, 5813.418658134185, 5856.6193197442135, 5873.207117039209, 5071.366079240095, 5100.438951715309, 5147.408048074463, 5162.819301324445, 5169.043726910515, 5117.232415137741, 5187.818426355078, 5200.375954885419, 5206.739663016853, 5247.711375874325, 5095.668781529189, 5105.43840177582, 5135.101357262502, 5142.254373115915, 5189.610779191576, 5208.270842082112, 5257.447967963385, 5260.105782017066, 5270.262163297141, 5285.709285989269, 5137.332146820927, 5193.948060519384, 5199.48005199477, 5211.088059589594, 5247.658902171384, 5760.308762948431, 5772.307323121252, 5783.479486846204, 5804.477597016289, 5811.302643682785, 6117.27937334216, 6119.5104391648565, 6120.111926550671, 6174.289956654952, 6251.78118765841, 6328.462080723144, 6337.6831158442355, 6346.619202847798, 6346.714397852108, 6351.396617321347, 5776.884462310814, 5879.794207202727, 5888.02847530157, 5897.026990546512, 5938.970305148447, 6219.533534984895, 6288.654124023131, 6414.871702566016, 6467.838304042437, 6473.28793832678, 6134.038624681888, 6134.4978165938865, 6139.154842350747, 6140.609464233005, 6140.662263575457, 6239.750409983641, 6240.937590624127, 6243.968780156161, 6244.188255526843, 6250.656213908188, 6120.204373431426, 6240.188775459252, 6250.468710159687, 6251.218597675291, 6251.562390632703, 6092.749370501782, 6159.137720719103, 6191.4118158774845, 6227.314995350508, 6232.937670623326, 4730.124926888537, 4735.313379560013, 4735.337052812609, 4770.6660533763, 4773.069251495995, 4192.434021407132, 4193.643540299047, 4197.097624010852, 4197.202531519001, 4236.745795252295, 5746.626469279484, 5778.133280007948, 5791.942080579141, 5830.271216097988, 5845.7369418376275, 6258.843528911722, 6279.3720627937755, 6309.810705678837, 6313.305536390993, 6316.778912738124, 5936.28764548257, 5950.047992321294, 5953.493953014043, 5955.731992060368, 6007.909881351783, 5221.0, 5271.604629652715, 5285.312909321764, 5335.306410166653, 5349.839504814861, 6546.0835483032415, 6562.770303039466, 6563.573367731273, 6570.277269500351, 6579.309172536815, 6246.625202487865, 6299.748010079728, 6305.085762564494, 6310.400511951358, 6314.116023756769, 6359.650219237987, 6437.066625339393, 6442.35576442352, 6457.935420645734, 6505.739770409131, 6138.478229350555, 6176.227971503562, 6182.567220294536, 6186.845328866814, 6189.659568723763, 6297.937020629735, 6303.306636270098, 6310.179676642191, 6311.400416960381, 6311.715972781144, 5998.760049597967, 5999.400059993965, 6058.0, 6112.083187521907, 6130.111133885473, 6044.153818465506, 6094.380596348918, 6124.877502449837, 6130.049842274455, 6132.38676132401, 6014.729337179925, 6052.818415447544, 6065.090236464565, 6066.393360663898, 6066.4540191382985, 5990.460858522754, 6101.267847858286, 6107.664078475549, 6108.602940808833, 6123.418275263843, 5840.182374467493, 5925.4370834770625, 5934.643921364732, 5941.554383421347, 6013.067974463965], "value": 5724.022215452317, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.1887161865186, 818.4966245758858, 834.9457285276454, 1129.9435028248586, 1133.818589025756, 29410.852945735267, 41402.79298603506, 42540.57459425409, 42590.0, 42623.36064959028, 44273.55726442735, 44417.0, 44440.55559444405, 44506.33240501387, 44696.0, 5982.341942386334, 8194.0576833664, 8380.245777879989, 8394.370422218328, 8399.874001889977, 6090.360512146205, 7947.284744373005, 8105.351571874236, 8114.229148230908, 8118.2693557579805, 8229.259366657, 8260.628271727757, 8274.958625206866, 8276.710315138942, 8277.668893244294, 8182.549959752211, 8191.426600137993, 8236.258736713695, 8244.134365891556, 8249.0, 7929.841403171904, 8199.836003279961, 8262.339012878956, 8277.213664701843, 8277.58612069394, 41143.794281028706, 41424.0, 41452.0, 41454.1709165815, 41490.1701965959, 2967.1543610071244, 2983.7314641682137, 2988.8505574721175, 2992.8653210605576, 3023.909282721522, 28424.715752842414, 28437.0, 28684.282892927637, 29645.70354296451, 29736.55395169074, 3553.200529880782, 3619.4751760994773, 3620.149264922741, 3639.4722765199162, 3684.3552378333684, 199502.0, 199607.0, 199834.0, 199923.0, 201758.9912050445, 49662.50337496579, 49731.50268497341, 49898.25152622713, 49944.0, 50019.24971125363, 51411.0, 51433.742831286356, 51499.0, 51592.0, 51600.741996290526, 14582.125072495684, 14625.341859616343, 14629.561113166623, 14636.926815366069, 14679.266036698122, 9886.901130988743, 10018.298719089593, 11773.411329433666, 11821.468033938321, 11832.112591555668, 13976.720465590835, 14030.0, 14064.226467544384, 14093.929530352283, 14217.431302747775, 5553.611247212656, 5666.178404131378, 5686.488216060575, 5704.572157088235, 5781.219535362757, 1007.778288776468, 1034.0538407357335, 1039.308859608352, 1042.1142029275056, 1096.8299913513417, 23874.88062559676, 26005.349866253116, 26110.0, 26376.868115659297, 26429.0, 1125.7016890524017, 1142.3659868772745, 1146.7763786061637, 1157.7221466847895, 1174.8472698549133, 13245.933770331087, 13548.390322435514, 13659.863401366058, 13703.109297895606, 13758.587242382744, 214316.0, 217471.0, 218024.0, 218684.0, 333695.0, 62566.374336256966, 63617.68191159107, 63674.68162659157, 64013.67993160005, 64103.0, 1434.9641258968609, 1460.5545308680742, 1475.2918599072505, 1476.734187846198, 1479.1051413894438, 27249.59125613157, 28281.57577636378, 28595.571066434437, 28716.0, 32332.191695207806, 48545.75727121342, 49007.754961224964, 55923.0, 55931.72034139803, 55942.440575593726, 320249.0, 322995.0, 326065.0, 326434.0, 327007.0, 26009.349766255236, 26194.214173574823, 36297.45553816748, 36428.4535731959, 36516.81741591275, 38572.42141367938, 38684.80657596694, 38957.0, 39144.41283380695, 39344.60655393409, 29164.854175729834, 30172.585993368502, 31467.52798708067, 32396.67603324031, 32546.51180232251, 26634.866825665747, 26832.59751103774, 28254.15237542877, 28258.2935426616, 29214.56178157287, 39086.804565976985, 39195.0, 39420.0, 39813.80093099516, 39828.60171398365, 32689.836550817094, 32786.22958359724, 32828.67171328256, 32851.34297314088, 33740.49389259212, 25077.373065673502, 26113.608295875958, 26488.0, 26618.86690566535, 26621.393873301964], "value": 17697.435431177833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.71602174608256, 445.6702040490038, 447.4183561370218, 458.57581736893377, 463.8353384548485, 19524.21903123875, 20167.09248083836, 20306.59386812263, 20338.288159914406, 21775.891120544416, 21816.672749908765, 21965.780342196613, 22094.0, 22263.888680556614, 22481.55036899261, 5926.762929482827, 5967.582269241155, 6085.087236914454, 6100.450959413652, 6101.542384321171, 5982.461578457938, 6187.164732761087, 6188.721507532172, 6192.349803270659, 6196.659183744892, 5895.911561326562, 6031.336552979169, 6154.230721159855, 6154.84612884677, 6164.6917654117115, 5964.433378829004, 5991.400859914017, 5998.580099393044, 6003.669798161121, 6098.2682078150465, 5339.733013349317, 5458.781648734046, 5464.562834973192, 5489.972550137264, 5566.165075238706, 24292.0, 24557.0, 24607.753922460724, 24608.753912460827, 24760.13339533108, 2011.386527109231, 2011.7686466056446, 2018.697195420684, 2030.5634288627869, 2032.8576999610034, 15353.232338383148, 16603.169841507875, 16730.66538669232, 16849.747253791204, 16950.49148525546, 1817.4638481647946, 1818.2908665620287, 1818.4726429335476, 1847.5842935339576, 1847.8521718262507, 106323.46838265836, 108096.4595177027, 108887.45556272248, 111842.0, 112243.43878280638, 12381.504739810487, 12389.876101239053, 12564.874351256552, 12671.493140274286, 12823.871761282455, 18075.27698892056, 18090.0, 18102.637947240983, 18114.365997190034, 18132.909335453238, 8342.791430214173, 8469.237768600855, 8620.0, 8705.303575713928, 8719.04090550039, 7668.309852113337, 7675.270849269422, 7705.190954949761, 7921.564313962787, 7971.322437592754, 7821.921780782233, 8017.478863873829, 8023.719169829028, 8139.267465927976, 8207.95896020528, 2290.599145149592, 2315.9652605210936, 2329.3594261577864, 2329.7087864017, 2412.21602979029, 994.4977786217913, 995.7759504111661, 1001.8046480936148, 1014.1126514299988, 1015.6292953072076, 19992.10035548404, 20144.19423223084, 20160.49598760043, 20246.190152394032, 20654.58690826175, 1022.8619136416488, 1030.7680771826278, 1038.2161468091542, 1048.8793788714395, 1074.1138560687464, 10083.344582602107, 11164.832527512093, 11188.608398706006, 11191.552337906556, 11218.495167717472, 575478.0, 582064.0, 616701.0, 621493.602915809, 638896.0, 106607.0, 113306.0, 114467.42766286281, 114604.0, 114782.0, 1201.5073819733868, 1213.3326670331282, 1259.433255035256, 1261.9369031548385, 1271.173737070893, 20099.095540700997, 20241.79758202399, 20584.897075514527, 20976.47558811042, 21055.473613159793, 48784.85328747096, 49467.0, 49771.0, 50107.49892501028, 51024.48975510346, 64483.0, 65015.349846502824, 65293.347066528724, 66628.0, 68237.65881170562, 6383.553151279366, 6900.551464154814, 8763.649454021768, 8863.379563430499, 9050.502222377832, 12871.742565148832, 13290.40193191328, 13628.86371136276, 14577.0, 14647.414103435744, 12219.327936963196, 12326.0, 12482.937585312378, 12722.745545089232, 12787.296698681661, 11334.319940803578, 11500.0, 11576.131790115607, 11684.766304674029, 11717.882821172021, 12141.392930353624, 12720.618381448572, 12810.359482025675, 12834.0, 12900.225986440844, 8709.869351959598, 8851.114888511098, 8950.910490895269, 9000.50497222659, 9329.440233586007, 12367.876321236672, 12412.255264684149, 12554.121211515472, 12719.173253738478, 12957.0], "value": 10218.405275257772, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1169.2692067457838, 1295.3911661519085, 1344.1128854955732, 1357.4705864712764, 1358.3140514040406, 9150.405223660457, 10539.683809485718, 10918.617848375308, 10931.508082136314, 10932.453377331121, 10679.679609611705, 10752.086072683831, 10781.8382724259, 10786.460676966159, 10789.83815242772, 2011.8591698581106, 2069.7516298044256, 2072.253988564116, 2390.0320370250033, 2420.068273714624, 2073.481629592602, 2084.593504266669, 2282.2126366403563, 2368.0527788884497, 2372.8694921779297, 2328.790408863202, 2348.612478940973, 2352.3178278299274, 2352.6823878776404, 2355.352278123521, 2237.630790919496, 2242.7420846602686, 2265.0, 2343.941401464977, 2380.9285721428387, 2288.622377307742, 2301.5972204864083, 2304.6082166031815, 2312.4681323295736, 2312.664663623782, 10942.4528773561, 10944.124470042378, 11098.944505277423, 11108.277961932448, 11108.722281942935, 6010.0, 6220.782272620482, 6223.25320961483, 6225.719842607094, 6232.906506402408, 3974.741641793274, 3976.284268831609, 4109.712320137592, 4113.136241389282, 4151.771652559139, 1359.0554564577621, 1374.9106308090043, 1384.0380935250005, 1407.9718405631932, 1449.014670024383, 16412.507624771275, 16492.25784839685, 16520.83479165217, 16580.75128873068, 16591.834081659148, 5260.631755777135, 5417.485338892878, 5419.539339156216, 5504.669719816824, 5583.441655834384, 13431.529896453578, 13471.326433678278, 13475.393607287695, 13489.2580908051, 13493.66265843342, 6238.064290356392, 6363.649999250086, 6395.328490508523, 6400.135981642512, 6403.071554624554, 5691.9146212806845, 5803.448672376117, 5846.4153584642045, 5851.619644723164, 5853.239078919798, 3761.3417651910804, 3800.638939300762, 4133.132042271097, 4139.79301034947, 4172.541020487804, 1202.0563857372097, 1208.8247204155348, 1234.7900856854176, 1246.9438875250635, 1248.993755031219, 839.2824135364298, 852.5098068610449, 853.4793775796759, 880.3529405886668, 884.6019291318933, 6775.796726098225, 6950.582965022014, 7004.0, 7028.156621205488, 7262.963685181646, 942.1473566422399, 955.06880791228, 957.7701351675687, 966.3428868369576, 966.7148191283588, 4131.9173616528105, 4521.8191272349395, 4556.567126123013, 4666.580007799314, 4823.252395878644, 14082.647933801532, 14633.0, 14644.194569298577, 14692.191929443983, 14729.410823566937, 13504.729905401837, 13958.511452099128, 13964.092333998258, 13977.440902363995, 13994.16035037901, 10072.798544029078, 10555.313904596176, 10566.154707623371, 10567.682969510774, 10669.626563070255, 6724.529282950147, 6918.169819621678, 6937.7571784987285, 6965.686544105426, 7044.8591028178125, 12573.0, 12761.234325940473, 13099.541516046893, 13102.606921792361, 13112.934435327763, 13017.153884997444, 13029.0, 13053.28206948627, 13095.672608184872, 13115.344232788513, 9203.0, 9276.654885041766, 9320.161185493067, 9358.672446464341, 9412.858807118037, 8949.59726812282, 9163.08369163105, 9986.201103911815, 10430.843537346795, 10446.529906154394, 9043.547822608713, 9411.43531388119, 9458.337916346065, 9541.284403669892, 9582.472963987047, 8864.822703546022, 8955.507447090473, 8980.101989800967, 9016.18854303116, 9110.544472776468, 8174.427790054877, 8318.334533237448, 8541.231289184003, 8578.742637720878, 8580.356473264404, 6353.809385718436, 6422.068800023877, 6492.415682588589, 6501.674916254263, 6534.215894092739, 6434.324395938359, 6463.289038205888, 6586.176727909012, 6607.768728094494, 6670.466362691071], "value": 5321.278922336481, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.3959600195709, 413.41708191450044, 430.8535098066657, 435.7429116821075, 439.46604875076775, 5773.913391299128, 5846.0, 5864.853378665535, 5873.73568189432, 5877.67672777997, 5538.6399884007615, 5687.7156142192935, 5719.227904232927, 5750.568707346944, 5778.27771528559, 4455.688101832873, 4466.0, 4466.82132714691, 4466.955330446703, 4475.574820392074, 4493.910121797562, 4503.1218912312115, 4511.1654343946475, 4519.412476378083, 4536.591706746392, 4293.947982744232, 4411.492678341983, 4457.064016556527, 4472.977635111836, 4479.3952816369865, 4443.800028998703, 4456.331550267453, 4461.955380446203, 4475.30632751924, 4483.596476317114, 3958.960410395888, 3967.920641587181, 3988.9030516607877, 3989.740666856645, 4016.8995775105564, 5434.91847622286, 5648.209250704905, 5695.829125126253, 5734.39788822176, 5767.884642307173, 5228.451012643655, 5235.869103272448, 5237.711925844077, 5240.397354304266, 5245.8459138989365, 6107.236595425572, 6128.693565321761, 6132.846678833021, 6151.0, 6153.538484613672, 4280.59334363235, 4405.845795397146, 4407.845725399596, 4420.602145806861, 4446.799894004777, 6105.419985101453, 6203.31763506014, 6274.968625156891, 6306.621602703853, 6394.296627370985, 4519.81920723174, 4549.27211646135, 4554.157480866051, 4556.407667003268, 4561.2473941799235, 4335.349697545394, 4438.423005009328, 4463.866084017484, 4511.255642818964, 4615.123126605934, 5142.51146141116, 5148.675112905177, 5193.948060519422, 5196.792128314901, 5224.947750522446, 6124.0, 6131.202943617302, 6138.109974053827, 6142.938570614237, 6176.197094377702, 1536.508317338441, 1542.6143464133966, 1551.9059063360303, 1555.922203889801, 1557.392616879418, 1040.547361897577, 1056.9682909512726, 1060.761328701036, 1065.9500392113762, 1068.0, 5909.172715819789, 5939.46544810969, 5974.522038236931, 5974.641521508724, 6002.849928751729, 6194.9690251549355, 6211.627302361872, 6227.0, 6262.966610509215, 6309.0, 5726.914096288559, 5893.5579831512805, 5931.258592675916, 5982.611130276518, 6001.669908155093, 3298.967010329914, 3329.234276116507, 3334.3164651246434, 3352.6312105668358, 3356.1945133167865, 6380.744770209232, 6524.869502609921, 6546.18172728409, 6595.835104122435, 6626.171728533934, 6438.678066096676, 6477.190351206099, 6482.254540727877, 6486.124373209556, 6515.446187074057, 6496.740130394825, 6570.507211959027, 6629.602223866487, 6655.600663960178, 6667.966660166765, 6266.811995640138, 6277.305127615609, 6280.309165991828, 6289.371062893674, 6332.2717887441695, 5981.491573216325, 6069.696515174135, 6087.421694938974, 6250.281217660027, 6251.281102673251, 6525.9021114684265, 6532.640704761284, 6557.836054098686, 6581.506387021088, 6581.901271481028, 4422.624076953495, 4486.820527178877, 4510.548945105464, 4513.54864513546, 4521.321801729767, 6204.968975155095, 6283.0, 6284.402981716729, 6289.213848268966, 6319.399657032574, 5890.322612899571, 6109.602875813236, 6118.418750218722, 6129.540284478593, 6146.231721034871, 6061.939380606137, 6089.969550152221, 6100.511959043177, 6140.3552626975315, 6179.474744646755, 6115.84710382244, 6130.2030736004035, 6131.386861313833, 6134.386561344008, 6135.294441139147, 5941.584089113893, 6103.816885493442, 6109.0, 6124.908126378197, 6135.355787642234, 5972.163897054503, 5976.9103463448955, 5992.340842507234, 5995.310539287863, 5997.460228579449], "value": 4689.176898478512, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.8941742864712, 787.4212453846424, 787.9369650427965, 802.001508122388, 805.7985503624094, 15920.681586368266, 16144.192790360494, 16231.675366492664, 16238.106904120299, 16257.75613365799, 15987.120708361035, 16063.116528590923, 16198.83801161988, 16202.837971620309, 16241.431549895757, 780.6292011294618, 796.6972550430828, 807.4832107451236, 812.0580127052621, 815.3395749442941, 823.6952327638752, 832.2177153475744, 834.04501845387, 834.1658341658342, 834.6327615849037, 874.9387542872001, 878.6441491196076, 879.5338470610586, 879.8328317619664, 880.4673172730502, 861.444368382393, 869.3393021303791, 870.4733636150102, 870.843248215321, 871.3551971541077, 640.5804198250154, 640.6604499615212, 643.488426700774, 644.7131026693123, 645.5577929118568, 15410.3835846566, 15635.609109772233, 15696.13671248081, 17562.38531651386, 17938.641227175514, 16441.67116657672, 16451.42420015294, 16776.0, 16783.83216167835, 16933.0, 12116.757664846742, 12272.140950133495, 12533.686657833536, 12866.421011054525, 13344.733105337937, 3227.5320078588707, 3238.6599407062154, 3283.4254005548933, 3353.9161520961925, 3359.1937934895695, 92632.0, 92839.53580232123, 94045.52977235138, 94425.52787236088, 95861.52069239679, 11751.236169648946, 11953.0, 12066.818997715041, 12090.455929483194, 12143.089268304913, 25823.741762582133, 25980.35049123787, 26180.0, 26354.73645263561, 26444.603330950053, 8085.919140808516, 8136.918630813616, 8200.630971606291, 8205.79485512867, 8317.875231871527, 4394.648428125743, 4396.406485124531, 4445.177642136216, 4505.639548836085, 4599.310103484504, 4207.97896010518, 4309.978450107792, 4318.028443600165, 4319.632831209319, 4320.4383430153875, 759.6201899050475, 772.1814876231213, 778.2373274191211, 780.0795061826985, 781.4998401023349, 636.5162476517879, 647.6405594894784, 648.7210499485172, 665.2117241069349, 678.9287124851819, 9644.75888102789, 9775.51122443875, 9927.2058235341, 10297.330673506198, 10497.842532362021, 623.5416968528128, 657.139147716493, 659.261626977788, 659.9307072757388, 664.9700763465559, 4564.954350456519, 4837.2260438329695, 4850.563449289581, 4852.56326930578, 4860.8298709545, 76501.61749191218, 77741.61129194431, 78046.0, 79227.60386198148, 79353.20646793574, 53718.0, 64838.02742958765, 71173.7295762256, 71643.2835671647, 75586.0, 63575.36424635787, 65173.674131629035, 65519.0, 65787.0, 68600.0, 9037.50293733851, 9347.106409458618, 9418.387804792666, 9449.102335278138, 9536.46206512216, 34418.0, 34914.49338392258, 35376.64623353836, 36106.63893361033, 36667.44998825073, 43258.0, 44250.336244957, 44724.77637611791, 45263.77368113138, 45839.54160458353, 12096.697582560506, 12186.451609677762, 12456.065795065588, 12596.496140154657, 12750.74498510006, 13501.189928604315, 13511.797323039966, 13634.0, 14236.572902812932, 14703.0, 11998.880011200126, 12391.256524608552, 12454.25274483534, 12495.12534122639, 12784.232946023269, 12639.93680031594, 12699.619011429671, 13008.349582521025, 13077.803832942323, 13619.0, 11674.0, 11733.823992640288, 11749.76500470003, 12056.27856495624, 12406.0, 8241.0, 8314.376421768513, 8620.61207245663, 8763.91236087631, 8825.514596696987, 9297.349185556946, 9770.951145244228, 9817.460039697886, 9834.950825245827, 9848.753781155528], "value": 7222.785471237704, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 108.0, 108.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 122.0, 160.0, 118.0, 119.0, 117.0, 116.0, 140.0, 121.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 163.0, 162.0, 161.0, 161.0, 164.0, 162.0, 163.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 108.0, 75.0, 73.0, 73.0, 73.0, 91.0, 74.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 118.0, 82.0, 83.0, 82.0, 97.0, 82.0, 82.0, 111.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 57.0, 56.0, 57.0, 56.0, 58.0, 56.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 56.0, 55.0, 55.0, 56.0, 56.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 102.0, 79.0, 80.0, 80.0, 80.0, 80.0, 80.0, 79.0, 79.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 134.0, 136.0, 132.0, 136.0, 131.0, 129.0, 170.0, 127.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 194.0, 179.0, 185.0, 191.0, 185.0, 179.0, 180.0, 180.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 67.0, 69.0, 67.0, 65.0, 65.0, 65.0, 65.0, 67.0], "value": 66.1, "unit": "score"}]}]} 18:27:32 INFO - Return code: 0 18:27:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:27:32 ERROR - # TBPL SUCCESS # 18:27:32 INFO - Running post-action listener: _resource_record_post_action 18:27:32 INFO - [mozharness: 2017-03-25 01:27:32.685857Z] Finished run-tests step (success) 18:27:32 INFO - Running post-run listener: _resource_record_post_run 18:27:32 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 374394880; Read time: 0; Write time: 262208 18:27:32 INFO - TinderboxPrint: CPU usage
12.9% 18:27:32 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:27:32 INFO - TinderboxPrint: I/O write bytes / time
374,394,880 / 262,208 18:27:32 INFO - TinderboxPrint: CPU idle
8,297.7 (87.1%) 18:27:32 INFO - TinderboxPrint: CPU user
1,188.7 (12.5%) 18:27:32 INFO - TinderboxPrint: Swap in / out
0 / 0 18:27:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111808512; Read time: 0; Write time: 95100 18:27:32 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 260456448; Read time: 0; Write time: 166900 18:27:33 INFO - Running post-run listener: _upload_blobber_files 18:27:33 INFO - Blob upload gear active. 18:27:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:27:33 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 18:27:33 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'] 18:27:33 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 18:27:33 INFO - (blobuploader) - INFO - Open directory for files ... 18:27:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:27:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:27:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:27:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:27:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:27:34 INFO - (blobuploader) - INFO - Done attempting. 18:27:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:27:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:27:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:27:37 INFO - (blobuploader) - INFO - Done attempting. 18:27:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:27:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:27:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:27:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:27:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:27:38 INFO - (blobuploader) - INFO - Done attempting. 18:27:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:27:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:27:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:27:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:27:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:27:39 INFO - (blobuploader) - INFO - Done attempting. 18:27:39 INFO - (blobuploader) - INFO - Iteration through files over. 18:27:39 INFO - Return code: 0 18:27:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:27:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:27:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad2bc049509fcb90e8e9f2c26e6c1fbb00983a361fab4f0fc9c4537c159b2c3eaf871995f7f0f12d90ef161a3ed1375488cd208a90cb7ef18496cf95c586a25a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/374de5f7662d8d5647419786f6c6038d0d7d004263534441617770f88c78ffe8d573c1c1f227a4487a80704d2891494bc01181a0a2835a948f00bbd92e1f0bc2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7598f07a3c2c106397e86a4b598f6afc6e83b31e92f03f3c69d00d8bd3a849e049505c973724930550d908888e926ad1f97ebece83ef99289549dff383495c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e20ca494df22eb9de0d36a6c2ad9c4f6bd55008f0d6ba48930963690e85b3caf83a6d106498cbe37359e6e0dfdb514b38435b32e0e802adc69a147120dc91e17"} 18:27:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:27:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:27:39 INFO - Contents: 18:27:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad2bc049509fcb90e8e9f2c26e6c1fbb00983a361fab4f0fc9c4537c159b2c3eaf871995f7f0f12d90ef161a3ed1375488cd208a90cb7ef18496cf95c586a25a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/374de5f7662d8d5647419786f6c6038d0d7d004263534441617770f88c78ffe8d573c1c1f227a4487a80704d2891494bc01181a0a2835a948f00bbd92e1f0bc2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7598f07a3c2c106397e86a4b598f6afc6e83b31e92f03f3c69d00d8bd3a849e049505c973724930550d908888e926ad1f97ebece83ef99289549dff383495c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e20ca494df22eb9de0d36a6c2ad9c4f6bd55008f0d6ba48930963690e85b3caf83a6d106498cbe37359e6e0dfdb514b38435b32e0e802adc69a147120dc91e17"} 18:27:39 INFO - Running post-run listener: copy_logs_to_upload_dir 18:27:39 INFO - Copying logs to upload dir... 18:27:39 INFO - mkdir: /builds/slave/test/build/upload/logs 18:27:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.833735 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-24 18:27:38.920864) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 18:27:38.924488) ========= 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=923d835db2ba3686ebe126b700000192-1490404005.75760-823971796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad2bc049509fcb90e8e9f2c26e6c1fbb00983a361fab4f0fc9c4537c159b2c3eaf871995f7f0f12d90ef161a3ed1375488cd208a90cb7ef18496cf95c586a25a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/374de5f7662d8d5647419786f6c6038d0d7d004263534441617770f88c78ffe8d573c1c1f227a4487a80704d2891494bc01181a0a2835a948f00bbd92e1f0bc2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7598f07a3c2c106397e86a4b598f6afc6e83b31e92f03f3c69d00d8bd3a849e049505c973724930550d908888e926ad1f97ebece83ef99289549dff383495c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e20ca494df22eb9de0d36a6c2ad9c4f6bd55008f0d6ba48930963690e85b3caf83a6d106498cbe37359e6e0dfdb514b38435b32e0e802adc69a147120dc91e17"} build_url:https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/MQDWOkwYQeq3c2_6Zx-g8w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad2bc049509fcb90e8e9f2c26e6c1fbb00983a361fab4f0fc9c4537c159b2c3eaf871995f7f0f12d90ef161a3ed1375488cd208a90cb7ef18496cf95c586a25a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/374de5f7662d8d5647419786f6c6038d0d7d004263534441617770f88c78ffe8d573c1c1f227a4487a80704d2891494bc01181a0a2835a948f00bbd92e1f0bc2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7598f07a3c2c106397e86a4b598f6afc6e83b31e92f03f3c69d00d8bd3a849e049505c973724930550d908888e926ad1f97ebece83ef99289549dff383495c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e20ca494df22eb9de0d36a6c2ad9c4f6bd55008f0d6ba48930963690e85b3caf83a6d106498cbe37359e6e0dfdb514b38435b32e0e802adc69a147120dc91e17"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 18:27:38.949771) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:27:38.950113) ========= 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=923d835db2ba3686ebe126b700000192-1490404005.75760-823971796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004336 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:27:39.036583) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 18:27:39.036883) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 18:27:39.037197) ========= ========= Total master_lag: 0.16 =========