builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-038 starttime: 1490662866.56 results: success (0) revision: 5182b2c4b963ed87d038c7d9a4021463917076cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.556963) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.557403) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.557674) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.572621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.572938) ========= 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=8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-27 18:01:06-- 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% 114M=0s 2017-03-27 18:01:06 (114 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155192 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.745016) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.745347) ========= 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=8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016573 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.794497) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:06.797877) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5182b2c4b963ed87d038c7d9a4021463917076cd --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 5182b2c4b963ed87d038c7d9a4021463917076cd --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=8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-27 18:01:06,947 truncating revision to first 12 chars 2017-03-27 18:01:06,947 Setting DEBUG logging. 2017-03-27 18:01:06,947 attempt 1/10 2017-03-27 18:01:06,947 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5182b2c4b963?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 18:01:07,455 unpacking tar archive at: mozilla-central-5182b2c4b963/testing/mozharness/ program finished with exit code 0 elapsedTime=0.875715 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:07.687013) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:07.687333) ========= script_repo_revision: 5182b2c4b963ed87d038c7d9a4021463917076cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:07.687769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:07.688079) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:01:07.701546) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-27 18:01:07.701958) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:01:07 INFO - MultiFileLogger online at 20170327 18:01:07 in /builds/slave/test 18:01:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:01:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:07 INFO - {'append_to_log': False, 18:01:07 INFO - 'base_work_dir': '/builds/slave/test', 18:01:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:01:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:07 INFO - 'branch': 'Firefox-Non-PGO', 18:01:07 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:07 INFO - 'config_files': ('talos/linux_config.py',), 18:01:07 INFO - 'default_actions': ('clobber', 18:01:07 INFO - 'read-buildbot-config', 18:01:07 INFO - 'download-and-extract', 18:01:07 INFO - 'populate-webroot', 18:01:07 INFO - 'create-virtualenv', 18:01:07 INFO - 'install', 18:01:07 INFO - 'run-tests'), 18:01:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:07 INFO - 'download_minidump_stackwalk': True, 18:01:07 INFO - 'download_symbols': 'ondemand', 18:01:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:07 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:07 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:07 INFO - 'gecko_profile': False, 18:01:07 INFO - 'gecko_profile_interval': 0, 18:01:07 INFO - 'installer_path': 'installer.exe', 18:01:07 INFO - 'log_level': 'info', 18:01:07 INFO - 'log_name': 'talos', 18:01:07 INFO - 'log_to_console': True, 18:01:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:01:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:01:07 INFO - 'opt_config_files': (), 18:01:07 INFO - 'pip_index': False, 18:01:07 INFO - 'suite': 'dromaeojs-e10s', 18:01:07 INFO - 'system_bits': '32', 18:01:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:07 INFO - 'title': 'talos-linux64-ix-038', 18:01:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:07 INFO - 'use_talos_json': True, 18:01:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:07 INFO - 'work_dir': 'build'} 18:01:07 INFO - [mozharness: 2017-03-28 01:01:07.944239Z] Running clobber step. 18:01:07 INFO - Running pre-action listener: _resource_record_pre_action 18:01:07 INFO - Running main action method: clobber 18:01:07 INFO - rmtree: /builds/slave/test/build 18:01:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:08 INFO - Running post-action listener: _resource_record_post_action 18:01:08 INFO - [mozharness: 2017-03-28 01:01:08.881163Z] Finished clobber step (success) 18:01:08 INFO - [mozharness: 2017-03-28 01:01:08.881266Z] Running read-buildbot-config step. 18:01:08 INFO - Running pre-action listener: _resource_record_pre_action 18:01:08 INFO - Running main action method: read_buildbot_config 18:01:08 INFO - Using buildbot properties: 18:01:08 INFO - { 18:01:08 INFO - "project": "", 18:01:08 INFO - "product": "firefox", 18:01:08 INFO - "who": "kwierso@gmail.com", 18:01:08 INFO - "installer_path": "public/build/target.tar.bz2", 18:01:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:01:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:01:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:01:08 INFO - "stage_platform": "linux64", 18:01:08 INFO - "basedir": "/builds/slave/test", 18:01:08 INFO - "buildnumber": 738, 18:01:08 INFO - "platform": "ubuntu64_hw", 18:01:08 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:01:08 INFO - "slavebuilddir": "test", 18:01:08 INFO - "taskId": "PYE1BxdPRDGQXT-IedW8Wg", 18:01:08 INFO - "branch": "mozilla-central", 18:01:08 INFO - "script_repo_revision": "production", 18:01:08 INFO - "revision": "5182b2c4b963ed87d038c7d9a4021463917076cd", 18:01:08 INFO - "slavename": "talos-linux64-ix-038", 18:01:08 INFO - "repo_path": "mozilla-central" 18:01:08 INFO - } 18:01:08 INFO - Finding installer, test and symbols from parent task. 18:01:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PYE1BxdPRDGQXT-IedW8Wg'}, attempt #1 18:01:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PYE1BxdPRDGQXT-IedW8Wg'}, attempt #1 18:01:09 INFO - Task dependencies: eiP8qKFMSme46UT0yFw8yQ 18:01:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ'}, attempt #1 18:01:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.tar.bz2 18:01:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/test_packages.json 18:01:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.crashreporter-symbols.zip 18:01:10 INFO - Running post-action listener: _resource_record_post_action 18:01:10 INFO - [mozharness: 2017-03-28 01:01:10.267998Z] Finished read-buildbot-config step (success) 18:01:10 INFO - [mozharness: 2017-03-28 01:01:10.268219Z] Running download-and-extract step. 18:01:10 INFO - Running pre-action listener: _resource_record_pre_action 18:01:10 INFO - Running main action method: download_and_extract 18:01:10 INFO - mkdir: /builds/slave/test/build/tests 18:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:10 INFO - trying https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.test_packages.json 18:01:10 INFO - Downloading https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:01:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:01:10 INFO - Downloaded 986 bytes. 18:01:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:01:10 INFO - Using the following test package requirements: 18:01:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:01:10 INFO - u'common': [u'target.common.tests.zip'], 18:01:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:01:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:01:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:01:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:01:10 INFO - u'mozbase': [u'target.common.tests.zip'], 18:01:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:01:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:01:10 INFO - u'web-platform': [u'target.common.tests.zip', 18:01:10 INFO - u'target.web-platform.tests.zip'], 18:01:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:01:10 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:01:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.common.tests.zip 18:01:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:01:10 INFO - Fetch https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.common.tests.zip into memory 18:01:11 INFO - Expected file size: 58220190 18:01:11 INFO - Obtained file size: 58220190 18:01:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:01:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.common.tests.zip 18:01:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:01:21 INFO - Fetch https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.common.tests.zip into memory 18:01:23 INFO - Expected file size: 58220190 18:01:23 INFO - Obtained file size: 58220190 18:01:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.talos.tests.zip 18:01:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:01:33 INFO - Fetch https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.talos.tests.zip into memory 18:01:34 INFO - Expected file size: 14404171 18:01:34 INFO - Obtained file size: 14404171 18:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:34 INFO - trying https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.tar.bz2 18:01:34 INFO - Downloading https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:01:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:01:35 INFO - Downloaded 62547189 bytes. 18:01:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.tar.bz2 18:01:35 INFO - mkdir: /builds/slave/test/properties 18:01:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:35 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:35 INFO - Contents: 18:01:35 INFO - build_url:https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.tar.bz2 18:01:35 INFO - Running post-action listener: _resource_record_post_action 18:01:35 INFO - Running post-action listener: set_extra_try_arguments 18:01:35 INFO - [mozharness: 2017-03-28 01:01:35.775383Z] Finished download-and-extract step (success) 18:01:35 INFO - [mozharness: 2017-03-28 01:01:35.775481Z] Running populate-webroot step. 18:01:35 INFO - Running pre-action listener: _resource_record_pre_action 18:01:35 INFO - Running main action method: populate_webroot 18:01:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:35 INFO - '--tppagecycles', 18:01:35 INFO - '7'], 18:01:35 INFO - 'tests': ['tsvgm']}}, 18:01:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:01:35 INFO - 'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:01:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:01:35 INFO - 'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['damp', 'tps']}, 18:01:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:01:35 INFO - 'tests': ['damp', 'tps']}, 18:01:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['dromaeo_dom']}, 18:01:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['basic_compositor_video']}, 18:01:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:01:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['a11yr', 18:01:35 INFO - 'ts_paint', 18:01:35 INFO - 'tpaint', 18:01:35 INFO - 'sessionrestore', 18:01:35 INFO - 'sessionrestore_no_auto_restore', 18:01:35 INFO - 'tabpaint']}, 18:01:35 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:35 INFO - 'ts_paint', 18:01:35 INFO - 'tpaint', 18:01:35 INFO - 'sessionrestore', 18:01:35 INFO - 'sessionrestore_no_auto_restore', 18:01:35 INFO - 'tabpaint']}, 18:01:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:35 INFO - 'ts_paint', 18:01:35 INFO - 'tpaint', 18:01:35 INFO - 'sessionrestore', 18:01:35 INFO - 'sessionrestore_no_auto_restore', 18:01:35 INFO - 'tabpaint']}, 18:01:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:35 INFO - 'ts_paint', 18:01:35 INFO - 'tpaint', 18:01:35 INFO - 'sessionrestore', 18:01:35 INFO - 'sessionrestore_no_auto_restore', 18:01:35 INFO - 'tabpaint']}, 18:01:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['a11yr', 18:01:35 INFO - 'ts_paint', 18:01:35 INFO - 'tpaint', 18:01:35 INFO - 'sessionrestore', 18:01:35 INFO - 'sessionrestore_no_auto_restore', 18:01:35 INFO - 'tabpaint']}, 18:01:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['a11yr', 18:01:35 INFO - 'ts_paint', 18:01:35 INFO - 'tpaint', 18:01:35 INFO - 'sessionrestore', 18:01:35 INFO - 'sessionrestore_no_auto_restore', 18:01:35 INFO - 'tabpaint']}, 18:01:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['tsvgx', 18:01:35 INFO - 'tsvgr_opacity', 18:01:35 INFO - 'tart', 18:01:35 INFO - 'tscrollx', 18:01:35 INFO - 'cart', 18:01:35 INFO - 'tsvg_static']}, 18:01:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:35 INFO - 'tsvgr_opacity', 18:01:35 INFO - 'tart', 18:01:35 INFO - 'tscrollx', 18:01:35 INFO - 'cart', 18:01:35 INFO - 'tsvg_static']}, 18:01:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:01:35 INFO - 'talos_options': ['--disable-e10s'], 18:01:35 INFO - 'tests': ['tp5o']}, 18:01:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:01:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:01:35 INFO - 'talos_options': ['--disable-e10s', 18:01:35 INFO - '--xperf_path', 18:01:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:35 INFO - 'tests': ['tp5n']}, 18:01:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:01:35 INFO - 'talos_options': ['--xperf_path', 18:01:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:35 INFO - 'tests': ['tp5n']}}, 18:01:35 INFO - 'talos.zip': {'path': '', 18:01:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:35 INFO - Running post-action listener: _resource_record_post_action 18:01:35 INFO - [mozharness: 2017-03-28 01:01:35.788641Z] Finished populate-webroot step (success) 18:01:35 INFO - [mozharness: 2017-03-28 01:01:35.788730Z] Running create-virtualenv step. 18:01:35 INFO - Running pre-action listener: _resource_record_pre_action 18:01:35 INFO - Running main action method: create_virtualenv 18:01:35 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:35 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:01:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:35 INFO - Using real prefix '/usr' 18:01:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:38 INFO - Installing pip.................done. 18:01:38 INFO - Return code: 0 18:01:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:01:38 INFO - Reading from file tmpfile_stdout 18:01:38 INFO - Output received: 18:01:38 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:01:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:38 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:38 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:01:38 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:01:38 INFO - Using env: {'DISPLAY': ':0', 18:01:38 INFO - 'HOME': '/home/cltbld', 18:01:38 INFO - 'LANG': 'en_US.UTF-8', 18:01:38 INFO - 'LANGUAGE': 'en_US:en', 18:01:38 INFO - 'LOGNAME': 'cltbld', 18:01:38 INFO - 'MAIL': '/var/mail/cltbld', 18:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:38 INFO - 'MOZ_NO_REMOTE': '1', 18:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:38 INFO - 'NO_EM_RESTART': '1', 18:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:38 INFO - 'PWD': '/builds/slave/test', 18:01:38 INFO - 'SHELL': '/bin/bash', 18:01:38 INFO - 'SHLVL': '1', 18:01:38 INFO - 'TERM': 'linux', 18:01:38 INFO - 'TMOUT': '86400', 18:01:38 INFO - 'USER': 'cltbld', 18:01:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:38 INFO - '_': '/tools/buildbot/bin/python'} 18:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:38 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:01:38 INFO - Cleaning up... 18:01:38 INFO - Return code: 0 18:01:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:38 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:38 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:01:38 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:01:38 INFO - Using env: {'DISPLAY': ':0', 18:01:38 INFO - 'HOME': '/home/cltbld', 18:01:38 INFO - 'LANG': 'en_US.UTF-8', 18:01:38 INFO - 'LANGUAGE': 'en_US:en', 18:01:38 INFO - 'LOGNAME': 'cltbld', 18:01:38 INFO - 'MAIL': '/var/mail/cltbld', 18:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:38 INFO - 'MOZ_NO_REMOTE': '1', 18:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:38 INFO - 'NO_EM_RESTART': '1', 18:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:38 INFO - 'PWD': '/builds/slave/test', 18:01:38 INFO - 'SHELL': '/bin/bash', 18:01:38 INFO - 'SHLVL': '1', 18:01:38 INFO - 'TERM': 'linux', 18:01:38 INFO - 'TMOUT': '86400', 18:01:38 INFO - 'USER': 'cltbld', 18:01:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:38 INFO - '_': '/tools/buildbot/bin/python'} 18:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:39 INFO - Downloading/unpacking psutil>=3.1.1 18:01:39 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:01:39 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:01:39 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:01:39 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:01:39 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:01:39 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:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:01:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:41 INFO - Installing collected packages: psutil 18:01:41 INFO - Running setup.py install for psutil 18:01:41 INFO - building 'psutil._psutil_linux' extension 18:01:41 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:01:41 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:01:41 INFO - building 'psutil._psutil_posix' extension 18:01:41 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:01:41 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:01:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:41 INFO - Successfully installed psutil 18:01:41 INFO - Cleaning up... 18:01:41 INFO - Return code: 0 18:01:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:01:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:41 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:41 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:01:41 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:01:41 INFO - Using env: {'DISPLAY': ':0', 18:01:41 INFO - 'HOME': '/home/cltbld', 18:01:41 INFO - 'LANG': 'en_US.UTF-8', 18:01:41 INFO - 'LANGUAGE': 'en_US:en', 18:01:41 INFO - 'LOGNAME': 'cltbld', 18:01:41 INFO - 'MAIL': '/var/mail/cltbld', 18:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:41 INFO - 'MOZ_NO_REMOTE': '1', 18:01:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:41 INFO - 'NO_EM_RESTART': '1', 18:01:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:41 INFO - 'PWD': '/builds/slave/test', 18:01:41 INFO - 'SHELL': '/bin/bash', 18:01:41 INFO - 'SHLVL': '1', 18:01:41 INFO - 'TERM': 'linux', 18:01:41 INFO - 'TMOUT': '86400', 18:01:41 INFO - 'USER': 'cltbld', 18:01:41 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:41 INFO - '_': '/tools/buildbot/bin/python'} 18:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:01:41 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:01:41 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:01:41 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:01:41 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:01:41 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:01:41 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:01:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:01:43 INFO - Installing collected packages: mozsystemmonitor 18:01:43 INFO - Running setup.py install for mozsystemmonitor 18:01:44 INFO - Successfully installed mozsystemmonitor 18:01:44 INFO - Cleaning up... 18:01:44 INFO - Return code: 0 18:01:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:44 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:44 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:01:44 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:01:44 INFO - Using env: {'DISPLAY': ':0', 18:01:44 INFO - 'HOME': '/home/cltbld', 18:01:44 INFO - 'LANG': 'en_US.UTF-8', 18:01:44 INFO - 'LANGUAGE': 'en_US:en', 18:01:44 INFO - 'LOGNAME': 'cltbld', 18:01:44 INFO - 'MAIL': '/var/mail/cltbld', 18:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:44 INFO - 'MOZ_NO_REMOTE': '1', 18:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:44 INFO - 'NO_EM_RESTART': '1', 18:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:44 INFO - 'PWD': '/builds/slave/test', 18:01:44 INFO - 'SHELL': '/bin/bash', 18:01:44 INFO - 'SHLVL': '1', 18:01:44 INFO - 'TERM': 'linux', 18:01:44 INFO - 'TMOUT': '86400', 18:01:44 INFO - 'USER': 'cltbld', 18:01:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:44 INFO - '_': '/tools/buildbot/bin/python'} 18:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:44 INFO - Downloading/unpacking jsonschema==2.5.1 18:01:44 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:01:44 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:01:44 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:01:44 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:01:44 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:01:44 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:01:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:01:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:01:46 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:01:46 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:01:46 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:01:46 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:01:46 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:01:46 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:01:46 INFO - Downloading functools32-3.2.3-2.tar.gz 18:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:01:46 INFO - warning: no files found matching '*.txt' 18:01:46 INFO - no previously-included directories found matching 'build' 18:01:46 INFO - no previously-included directories found matching 'dist' 18:01:46 INFO - no previously-included directories found matching '.git*' 18:01:46 INFO - Installing collected packages: jsonschema, functools32 18:01:46 INFO - Running setup.py install for functools32 18:01:46 INFO - warning: no files found matching '*.txt' 18:01:46 INFO - no previously-included directories found matching 'build' 18:01:46 INFO - no previously-included directories found matching 'dist' 18:01:46 INFO - no previously-included directories found matching '.git*' 18:01:46 INFO - Successfully installed jsonschema functools32 18:01:46 INFO - Cleaning up... 18:01:46 INFO - Return code: 0 18:01:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:46 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:46 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:01:46 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:01:46 INFO - Using env: {'DISPLAY': ':0', 18:01:46 INFO - 'HOME': '/home/cltbld', 18:01:46 INFO - 'LANG': 'en_US.UTF-8', 18:01:46 INFO - 'LANGUAGE': 'en_US:en', 18:01:46 INFO - 'LOGNAME': 'cltbld', 18:01:46 INFO - 'MAIL': '/var/mail/cltbld', 18:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:46 INFO - 'MOZ_NO_REMOTE': '1', 18:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:46 INFO - 'NO_EM_RESTART': '1', 18:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:46 INFO - 'PWD': '/builds/slave/test', 18:01:46 INFO - 'SHELL': '/bin/bash', 18:01:46 INFO - 'SHLVL': '1', 18:01:46 INFO - 'TERM': 'linux', 18:01:46 INFO - 'TMOUT': '86400', 18:01:46 INFO - 'USER': 'cltbld', 18:01:46 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:46 INFO - '_': '/tools/buildbot/bin/python'} 18:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:01:47 INFO - Cleaning up... 18:01:47 INFO - Return code: 0 18:01:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:47 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:47 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:01:47 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:01:47 INFO - Using env: {'DISPLAY': ':0', 18:01:47 INFO - 'HOME': '/home/cltbld', 18:01:47 INFO - 'LANG': 'en_US.UTF-8', 18:01:47 INFO - 'LANGUAGE': 'en_US:en', 18:01:47 INFO - 'LOGNAME': 'cltbld', 18:01:47 INFO - 'MAIL': '/var/mail/cltbld', 18:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:47 INFO - 'MOZ_NO_REMOTE': '1', 18:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:47 INFO - 'NO_EM_RESTART': '1', 18:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:47 INFO - 'PWD': '/builds/slave/test', 18:01:47 INFO - 'SHELL': '/bin/bash', 18:01:47 INFO - 'SHLVL': '1', 18:01:47 INFO - 'TERM': 'linux', 18:01:47 INFO - 'TMOUT': '86400', 18:01:47 INFO - 'USER': 'cltbld', 18:01:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:47 INFO - '_': '/tools/buildbot/bin/python'} 18:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:47 INFO - Downloading/unpacking blobuploader==1.2.4 18:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:49 INFO - Downloading blobuploader-1.2.4.tar.gz 18:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:01:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:01:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:49 INFO - Downloading docopt-0.6.1.tar.gz 18:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:01:50 INFO - Installing collected packages: blobuploader, requests, docopt 18:01:50 INFO - Running setup.py install for blobuploader 18:01:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:01:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:01:50 INFO - Running setup.py install for requests 18:01:50 INFO - Running setup.py install for docopt 18:01:50 INFO - Successfully installed blobuploader requests docopt 18:01:50 INFO - Cleaning up... 18:01:50 INFO - Return code: 0 18:01:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:50 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:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:50 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:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:50 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:50 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:01:50 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:01:50 INFO - Using env: {'DISPLAY': ':0', 18:01:50 INFO - 'HOME': '/home/cltbld', 18:01:50 INFO - 'LANG': 'en_US.UTF-8', 18:01:50 INFO - 'LANGUAGE': 'en_US:en', 18:01:50 INFO - 'LOGNAME': 'cltbld', 18:01:50 INFO - 'MAIL': '/var/mail/cltbld', 18:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:50 INFO - 'MOZ_NO_REMOTE': '1', 18:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:50 INFO - 'NO_EM_RESTART': '1', 18:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:50 INFO - 'PWD': '/builds/slave/test', 18:01:50 INFO - 'SHELL': '/bin/bash', 18:01:50 INFO - 'SHLVL': '1', 18:01:50 INFO - 'TERM': 'linux', 18:01:50 INFO - 'TMOUT': '86400', 18:01:50 INFO - 'USER': 'cltbld', 18:01:50 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:50 INFO - '_': '/tools/buildbot/bin/python'} 18:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:01:50 INFO - Running setup.py (path:/tmp/pip-PUr0xS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:01:50 INFO - Running setup.py (path:/tmp/pip-yQjzug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:01:51 INFO - Running setup.py (path:/tmp/pip-EPbwpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:01:51 INFO - Running setup.py (path:/tmp/pip-1d4yUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:01:51 INFO - Running setup.py (path:/tmp/pip-WHfmN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:01:51 INFO - Running setup.py (path:/tmp/pip-KxjMg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:01:51 INFO - Running setup.py (path:/tmp/pip-tP3mty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:01:51 INFO - Running setup.py (path:/tmp/pip-qm9vrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:01:51 INFO - Running setup.py (path:/tmp/pip-7FzFSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:01:51 INFO - Running setup.py (path:/tmp/pip-H63D5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:01:51 INFO - Running setup.py (path:/tmp/pip-EAqD1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:01:51 INFO - Running setup.py (path:/tmp/pip-GJ_fqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:01:51 INFO - Running setup.py (path:/tmp/pip-LgeoV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:01:51 INFO - Running setup.py (path:/tmp/pip-AD12vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:51 INFO - Running setup.py (path:/tmp/pip-REDQnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:01:51 INFO - Running setup.py (path:/tmp/pip-ng8zo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:01:52 INFO - Running setup.py (path:/tmp/pip-gvTH2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:01:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:01:52 INFO - Running setup.py install for manifestparser 18:01:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for mozcrash 18:01:52 INFO - Running setup.py install for mozdebug 18:01:52 INFO - Running setup.py install for mozdevice 18:01:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for mozfile 18:01:52 INFO - Running setup.py install for mozhttpd 18:01:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for mozinfo 18:01:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for mozInstall 18:01:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for mozleak 18:01:52 INFO - Running setup.py install for mozlog 18:01:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:01:53 INFO - Running setup.py install for moznetwork 18:01:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:01:53 INFO - Running setup.py install for mozprocess 18:01:53 INFO - Running setup.py install for mozprofile 18:01:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:01:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:01:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:01:53 INFO - Running setup.py install for mozrunner 18:01:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:01:53 INFO - Running setup.py install for mozscreenshot 18:01:53 INFO - Running setup.py install for moztest 18:01:53 INFO - Running setup.py install for mozversion 18:01:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:01:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:01:53 INFO - Cleaning up... 18:01:53 INFO - Return code: 0 18:01:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:53 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:53 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:01:53 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:01:53 INFO - Using env: {'DISPLAY': ':0', 18:01:53 INFO - 'HOME': '/home/cltbld', 18:01:53 INFO - 'LANG': 'en_US.UTF-8', 18:01:53 INFO - 'LANGUAGE': 'en_US:en', 18:01:53 INFO - 'LOGNAME': 'cltbld', 18:01:53 INFO - 'MAIL': '/var/mail/cltbld', 18:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:53 INFO - 'MOZ_NO_REMOTE': '1', 18:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:53 INFO - 'NO_EM_RESTART': '1', 18:01:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:53 INFO - 'PWD': '/builds/slave/test', 18:01:53 INFO - 'SHELL': '/bin/bash', 18:01:53 INFO - 'SHLVL': '1', 18:01:53 INFO - 'TERM': 'linux', 18:01:53 INFO - 'TMOUT': '86400', 18:01:53 INFO - 'USER': 'cltbld', 18:01:53 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:53 INFO - '_': '/tools/buildbot/bin/python'} 18:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:01:54 INFO - Running setup.py (path:/tmp/pip-dKWiBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:01:54 INFO - Running setup.py (path:/tmp/pip-uYgOPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:01:54 INFO - Running setup.py (path:/tmp/pip-NPIgAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:01:54 INFO - Running setup.py (path:/tmp/pip-X7Qh4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:01:54 INFO - Running setup.py (path:/tmp/pip-5Ks9ED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:01:54 INFO - Running setup.py (path:/tmp/pip-LP2ADa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:01:54 INFO - Running setup.py (path:/tmp/pip-0ro7te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:01:54 INFO - Running setup.py (path:/tmp/pip-Lpg0Ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:01:54 INFO - Running setup.py (path:/tmp/pip-aEOX0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:01:54 INFO - Running setup.py (path:/tmp/pip-do8N8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:01:54 INFO - Running setup.py (path:/tmp/pip-DaYSOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:01:54 INFO - Running setup.py (path:/tmp/pip-z7McGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:01:54 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:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:01:54 INFO - Running setup.py (path:/tmp/pip-7hAX9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:01:55 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:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:01:55 INFO - Running setup.py (path:/tmp/pip-yc9MJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:01:55 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:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:55 INFO - Running setup.py (path:/tmp/pip-sIYHwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:55 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:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:01:55 INFO - Running setup.py (path:/tmp/pip-rE3cNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:01:55 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:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:01:55 INFO - Running setup.py (path:/tmp/pip-R5c5HZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:01:55 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:01:55 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:01:55 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:01:55 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:01:55 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:01:55 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:01:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:01:55 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:01:55 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:01:55 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:01:55 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:01:55 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:01:55 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:01:57 INFO - Downloading blessings-1.6.tar.gz 18:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:01:57 INFO - Installing collected packages: blessings 18:01:57 INFO - Running setup.py install for blessings 18:01:57 INFO - Successfully installed blessings 18:01:57 INFO - Cleaning up... 18:01:57 INFO - Return code: 0 18:01:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:01:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:01:57 INFO - Reading from file tmpfile_stdout 18:01:57 INFO - Current package versions: 18:01:57 INFO - argparse == 1.2.1 18:01:57 INFO - blessings == 1.6 18:01:57 INFO - blobuploader == 1.2.4 18:01:57 INFO - docopt == 0.6.1 18:01:57 INFO - functools32 == 3.2.3-2 18:01:57 INFO - jsonschema == 2.5.1 18:01:57 INFO - manifestparser == 1.1 18:01:57 INFO - mozInstall == 1.12 18:01:57 INFO - mozcrash == 1.0 18:01:57 INFO - mozdebug == 0.1 18:01:57 INFO - mozdevice == 0.49 18:01:57 INFO - mozfile == 1.2 18:01:57 INFO - mozhttpd == 0.7 18:01:57 INFO - mozinfo == 0.9 18:01:57 INFO - mozleak == 0.1 18:01:57 INFO - mozlog == 3.4 18:01:57 INFO - moznetwork == 0.27 18:01:57 INFO - mozprocess == 0.25 18:01:57 INFO - mozprofile == 0.28 18:01:57 INFO - mozrunner == 6.13 18:01:57 INFO - mozscreenshot == 0.1 18:01:57 INFO - mozsystemmonitor == 0.3 18:01:57 INFO - moztest == 0.8 18:01:57 INFO - mozversion == 1.4 18:01:57 INFO - psutil == 3.1.1 18:01:57 INFO - requests == 1.2.3 18:01:57 INFO - wsgiref == 0.1.2 18:01:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:57 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:57 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:01:57 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:01:57 INFO - Using env: {'DISPLAY': ':0', 18:01:57 INFO - 'HOME': '/home/cltbld', 18:01:57 INFO - 'LANG': 'en_US.UTF-8', 18:01:57 INFO - 'LANGUAGE': 'en_US:en', 18:01:57 INFO - 'LOGNAME': 'cltbld', 18:01:57 INFO - 'MAIL': '/var/mail/cltbld', 18:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:57 INFO - 'MOZ_NO_REMOTE': '1', 18:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:57 INFO - 'NO_EM_RESTART': '1', 18:01:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:57 INFO - 'PWD': '/builds/slave/test', 18:01:57 INFO - 'SHELL': '/bin/bash', 18:01:57 INFO - 'SHLVL': '1', 18:01:57 INFO - 'TERM': 'linux', 18:01:57 INFO - 'TMOUT': '86400', 18:01:57 INFO - 'USER': 'cltbld', 18:01:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:57 INFO - '_': '/tools/buildbot/bin/python'} 18:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 INFO - Cleaning up... 18:01:58 INFO - Return code: 0 18:01:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:01:58 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:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:58 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:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:58 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 0x168b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1783a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1928b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, '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': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', '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:01:58 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:01:58 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:01:58 INFO - Using env: {'DISPLAY': ':0', 18:01:58 INFO - 'HOME': '/home/cltbld', 18:01:58 INFO - 'LANG': 'en_US.UTF-8', 18:01:58 INFO - 'LANGUAGE': 'en_US:en', 18:01:58 INFO - 'LOGNAME': 'cltbld', 18:01:58 INFO - 'MAIL': '/var/mail/cltbld', 18:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:58 INFO - 'MOZ_NO_REMOTE': '1', 18:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:58 INFO - 'NO_EM_RESTART': '1', 18:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:58 INFO - 'PWD': '/builds/slave/test', 18:01:58 INFO - 'SHELL': '/bin/bash', 18:01:58 INFO - 'SHLVL': '1', 18:01:58 INFO - 'TERM': 'linux', 18:01:58 INFO - 'TMOUT': '86400', 18:01:58 INFO - 'USER': 'cltbld', 18:01:58 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:58 INFO - '_': '/tools/buildbot/bin/python'} 18:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:01:58 INFO - Cleaning up... 18:01:58 INFO - Return code: 0 18:01:58 INFO - Running post-action listener: _resource_record_post_action 18:01:58 INFO - Running post-action listener: _start_resource_monitoring 18:01:58 INFO - Starting resource monitoring. 18:01:58 INFO - [mozharness: 2017-03-28 01:01:58.282235Z] Finished create-virtualenv step (success) 18:01:58 INFO - [mozharness: 2017-03-28 01:01:58.282751Z] Running install step. 18:01:58 INFO - Running pre-action listener: _resource_record_pre_action 18:01:58 INFO - Running main action method: install 18:01:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:01:58 INFO - Reading from file tmpfile_stdout 18:01:58 INFO - Detecting whether we're running mozinstall >=1.0... 18:01:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:01:58 INFO - Reading from file tmpfile_stdout 18:01:58 INFO - Output received: 18:01:58 INFO - Usage: mozinstall [options] installer 18:01:58 INFO - Options: 18:01:58 INFO - -h, --help show this help message and exit 18:01:58 INFO - -d DEST, --destination=DEST 18:01:58 INFO - Directory to install application into. [default: 18:01:58 INFO - "/builds/slave/test"] 18:01:58 INFO - --app=APP Application being installed. [default: firefox] 18:01:58 INFO - mkdir: /builds/slave/test/build/application 18:01:58 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:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:02:14 INFO - Reading from file tmpfile_stdout 18:02:14 INFO - Output received: 18:02:14 INFO - /builds/slave/test/build/application/firefox/firefox 18:02:14 INFO - Running post-action listener: _resource_record_post_action 18:02:14 INFO - [mozharness: 2017-03-28 01:02:14.244344Z] Finished install step (success) 18:02:14 INFO - [mozharness: 2017-03-28 01:02:14.244621Z] Running run-tests step. 18:02:14 INFO - Running pre-action listener: _resource_record_pre_action 18:02:14 INFO - Running main action method: run_tests 18:02:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:14 INFO - Python 2.7.3 18:02:14 INFO - Return code: 0 18:02:14 INFO - grabbing minidump binary from tooltool 18:02:14 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:02:14 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 0x1926290>, '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 0x1926b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1927040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:14 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:02:14 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:02:14 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:02:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:14 INFO - Return code: 0 18:02:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:02:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:14 INFO - ENV: RUST_BACKTRACE is now 1 18:02:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:02:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:02:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:02:14 INFO - Using env: {'DISPLAY': ':0', 18:02:14 INFO - 'HOME': '/home/cltbld', 18:02:14 INFO - 'LANG': 'en_US.UTF-8', 18:02:14 INFO - 'LANGUAGE': 'en_US:en', 18:02:14 INFO - 'LOGNAME': 'cltbld', 18:02:14 INFO - 'MAIL': '/var/mail/cltbld', 18:02:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:14 INFO - 'MOZ_NO_REMOTE': '1', 18:02:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:14 INFO - 'NO_EM_RESTART': '1', 18:02:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:14 INFO - 'PWD': '/builds/slave/test', 18:02:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:14 INFO - 'RUST_BACKTRACE': '1', 18:02:14 INFO - 'SHELL': '/bin/bash', 18:02:14 INFO - 'SHLVL': '1', 18:02:14 INFO - 'TERM': 'linux', 18:02:14 INFO - 'TMOUT': '86400', 18:02:14 INFO - 'USER': 'cltbld', 18:02:14 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243', 18:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:14 INFO - '_': '/tools/buildbot/bin/python'} 18:02:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:02:14 INFO - mozversion application_buildid: 20170328001342 18:02:14 INFO - mozversion application_changeset: 5182b2c4b963ed87d038c7d9a4021463917076cd 18:02:14 INFO - mozversion application_display_name: Nightly 18:02:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:14 INFO - mozversion application_name: Firefox 18:02:14 INFO - mozversion application_remotingname: firefox 18:02:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:02:14 INFO - mozversion application_vendor: Mozilla 18:02:14 INFO - mozversion application_version: 55.0a1 18:02:14 INFO - mozversion platform_buildid: 20170328001342 18:02:14 INFO - mozversion platform_changeset: 5182b2c4b963ed87d038c7d9a4021463917076cd 18:02:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:02:14 INFO - mozversion platform_version: 55.0a1 18:02:14 INFO - using testdate: 1490662934 18:02:14 INFO - actual date: 1490662934 18:02:14 INFO - starting webserver on 'localhost:56319' 18:02:14 INFO - SUITE-START | Running 2 tests 18:02:14 INFO - TEST-START | dromaeo_css 18:02:14 INFO - operating with platform_type : linux_ 18:02:14 INFO - Initialising browser for dromaeo_css test... 18:02:14 INFO - TEST-INFO | started process 25201 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN4C4cF/profile http://localhost:56319/getInfo.html) 18:02:19 INFO - PID 25201 | __metrics Screen width/height:1600/1200 18:02:19 INFO - PID 25201 | colorDepth:24 18:02:19 INFO - PID 25201 | Browser inner width/height: 1024/697 18:02:19 INFO - PID 25201 | __metrics 18:02:20 ERROR - PID 25201 | 1490662940701 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6376:36 < canClose@browser.js:5227:12 < forceQuit@jar:file:///tmp/tmpN4C4cF/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmpN4C4cF/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmpN4C4cF/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmpN4C4cF/profile/extensions/talos-powers@TalosPowersService.js:55:9 18:02:20 ERROR - PID 25201 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 18:02:21 INFO - TEST-INFO | 25201: exit 0 18:02:21 INFO - Browser initialized. 18:02:21 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:21 INFO - TEST-INFO | started process 25330 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN4C4cF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:33 INFO - PID 25330 | RSS: Main: 156278784 18:02:33 INFO - PID 25330 | 18:05:38 INFO - PID 25330 | Cycle 1(1): loaded http://localhost:56319/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56319/tests/dromaeo/cssquery-ext.html) 18:05:40 INFO - PID 25330 | RSS: Main: 165343232 18:05:40 INFO - PID 25330 | 18:07:22 INFO - PID 25330 | ************************* 18:07:22 INFO - PID 25330 | A coding exception was thrown and uncaught in a Task. 18:07:22 INFO - PID 25330 | 18:07:22 ERROR - PID 25330 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:22 INFO - PID 25330 | Full stack: 18:07:22 INFO - PID 25330 | ************************* 18:07:22 INFO - PID 25330 | ************************* 18:07:22 INFO - PID 25330 | A coding exception was thrown and uncaught in a Task. 18:07:22 INFO - PID 25330 | 18:07:22 ERROR - PID 25330 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:22 INFO - PID 25330 | Full stack: 18:07:22 INFO - PID 25330 | ************************* 18:07:22 INFO - PID 25330 | ************************* 18:07:22 INFO - PID 25330 | A coding exception was thrown and uncaught in a Task. 18:07:22 INFO - PID 25330 | 18:07:22 ERROR - PID 25330 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:22 INFO - PID 25330 | Full stack: 18:07:22 INFO - PID 25330 | ************************* 18:07:22 INFO - PID 25330 | ************************* 18:07:22 INFO - PID 25330 | A coding exception was thrown and uncaught in a Task. 18:07:22 INFO - PID 25330 | 18:07:22 ERROR - PID 25330 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:22 INFO - PID 25330 | Full stack: 18:07:22 INFO - PID 25330 | ************************* 18:08:42 INFO - PID 25330 | Cycle 1(1): loaded http://localhost:56319/tests/dromaeo/cssquery-ext.html (next: http://localhost:56319/tests/dromaeo/cssquery-jquery.html) 18:08:43 INFO - PID 25330 | RSS: Main: 161984512 18:08:43 INFO - PID 25330 | 18:11:45 INFO - PID 25330 | Cycle 1(1): loaded http://localhost:56319/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56319/tests/dromaeo/cssquery-mootools.html) 18:11:46 INFO - PID 25330 | RSS: Main: 170303488 18:11:46 INFO - PID 25330 | 18:14:48 INFO - PID 25330 | Cycle 1(1): loaded http://localhost:56319/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56319/tests/dromaeo/cssquery-prototype.html) 18:14:48 INFO - PID 25330 | RSS: Main: 162275328 18:14:48 INFO - PID 25330 | 18:17:51 INFO - PID 25330 | Cycle 1(1): loaded http://localhost:56319/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56319/tests/dromaeo/cssquery-yui.html) 18:17:52 INFO - PID 25330 | RSS: Main: 169451520 18:17:52 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | Cycle 1(1): loaded http://localhost:56319/tests/dromaeo/cssquery-yui.html (next: http://localhost:56319/tests/dromaeo/cssquery-dojo.html) 18:20:55 INFO - PID 25330 | RSS: Main: 163176448 18:20:55 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | __start_tp_report 18:20:55 INFO - PID 25330 | _x_x_mozilla_page_load 18:20:55 INFO - PID 25330 | _x_x_mozilla_page_load_details 18:20:55 INFO - PID 25330 | |i|pagename|runs| 18:20:55 INFO - PID 25330 | |0;dojo.html;2695.4339588686375;2710.308871237834;2717.4836781011604;2744.1355972868546;2754.036087369421;5209;5247.8950420991605;5256.395514515827;5295.894082118365;5317.521423071923;5247.711375874325;5252.422233554315;5338.839834804952;5340.973295133528;5420.647657902234;4816.157172494808;4850.102730994778;4854.17479028566;4865.464798872121;4884.169691152495;4854.12625727369;4860.1494738420815;4898.804047838083;4913.82801601944;4963.3299504566785;4728.929066063977;4792.568668819823;4822.180229361017;4830.661853670244;4844.249141383091;4829.951700482985;4854.587360074398;4858.2469717193535;4890.315355850184;4910.337104490884;4770.952290477086;4775.164346239394;4803.063402636487;4838.177509823338;4847.95152048482;5103.515166059218;5153.896922061538;5280.947190528084;5308.601854860901;5371.919421208685;5722.97138514309;5731.283589551306;5780.208135478016;5791.913121303184;5793.7392817323325;5751.568632352591;5755.165501002331;5759.251297331362;5759.68321742304;5761.481466667978;5222.686638801684;5315.335583052119;5323.787048518094;5332.3867755207875;5342.171963345727;5758.539316854642;5818.970905145532;5830.5044071254415;5836;5842.269716285465;5577.665340079527;5599.468050535193;5615.775368985276;5678.346990096109;5697.3448053474385;5663.292088488939;5701.515371193412;5719.027765279912;5720.227769251181;5724.742386592613;5649.209110724501;5651.12407576818;5655.095184770417;5655.604107712503;5672.546196304285;5513;5579.832605021856;5646.858828529237;5692.6015178937905;5723.227364305849;4736.73947932867;4739.454962679336;4742.5257474252985;4772.832950846773;4777.498362671939;4037.899052523652;4052.63526282636;4069.9389509157386;4094.2220978014084;4109.691773117028;4817.181079216542;4882.829100981448;4922.926156107661;4925.605951523869;4953.034158339161;5746.310442746813;5748;5751.022326204556;5779.624324418899;5780.6415492359265;4894.216925291937;4894.926576101361;4899.877503062381;4907.803687852517;4936.777844996984;4951.554360107536;5045.9747701261995;5074.873128171826;5076.213186956028;5080.49195080489;5329.9467005331;5348.1977703343255;5369.570434365163;5385.111456609616;5409.621326507264;5628.690422026828;5743.655380677173;5763.625364351304;5765.625234359753;5795.652260864362;5253.106971814725;5257.894842103213;5315.255864179096;5346.625736198429;5371.435999220025;5625.043742563856;5663.830085097454;5664.065429204135;5672.12082127271;5678.914816277676;5761.39505351932;5767.7404516796005;5769.59612827098;5781.537477001746;5787.7974270902005;5499.065158923073;5567.47109024642;5577.358603760619;5633.774649013993;5643.097104463266;5479.452054794533;5509.531689806411;5525.668459892419;5527.585431092603;5581.386047534849;5439.483249091489;5444.891102177855;5473.67157970523;5485.533729632866;5530.032244357141;5465;5540.196671482693;5551.555875529867;5591.049521581423;5606.803761868315;5406.2701535292235;5411.675299482044;5420.403755586961;5432.375276843056;5438.728063596884 18:20:55 INFO - PID 25330 | |1;ext.html;657.2277573850727;728.5555810955318;751.3388218367834;1078.3368228539446;1086.7283179205199;30479.238019049506;31448.213794655145;31540;31592.842035789818;31686.207844803834;32761.344773104523;33189.17027074324;33192.170195745115;33244.50133247997;33245;7135.357817796397;7254.528455650379;7341.853162936738;7346.963265183666;7351.595662238575;7436.1448433430305;7454.850902981937;7475.551466911976;7536.095668519758;7567.924320756777;7505.437092218078;7565.508241964255;7575.318221360077;7580;7587.35507481862;7520.924790752078;7631.809204769869;7641.044869391326;7679.308862202429;7700.11448683403;7412.666430010662;7470.178280389153;7477.775666730006;7478.177400485996;7486.438517111184;34875.1281217969;34881.65118348835;34886.47670284922;34898.47652285218;35180.648193518246;2695.892164313425;2739.6986331503517;2762.5165596020615;2767.2528417327208;2798.8600569971622;24581.262562123164;24924.626130608056;24990.5001899961;25438.745612543822;25538.744612553823;2928.3557617324154;2976.28569143404;2979.8957036503616;2985.5223164293607;3005.218643152796;139575;141405;141916;142816;143739;40919;40920.795396022826;40945.181096377906;41132.1773564527;41313.1737365257;42568.361474577905;42897;43044.354334685006;43066.78466607647;43081.92295192582;11885.821712674317;11946.462409191607;11950.163488555892;12102.636920892388;12123.151379403356;9406.200472959737;9461.432314061178;9474.194693451134;9492.098250666175;9558.617655293849;12650.557230497072;12849.807252891214;12896.871031289755;13057.21656700601;13086.803697944539;5266.736663166903;5339.86650333737;5342.706151161723;5483.341998960071;5546.556275498031;860.7245681381897;878.2008372381102;888.8622263549161;929.656027269901;935.569637966543;20819.06314215864;21413;21533.353999380044;21619.56760864774;21699.240526581492;981.3375971219398;991.6231831903831;1004.542932965499;1009.5759780892043;1011.6459239266198;11074.446277686084;11170.497327620114;11316;11371.545138194546;11382.715432114263;234039;234345;234830;235834;236467;40139.59860401417;40556.538780734096;42401.36397954033;42425.78787106045;42797;1232.7904256276454;1237.8452693413324;1255.9372031398393;1256.6921104329497;1258.7734207842493;24584.262472125865;25128.24615261545;25637.59728597131;25934.740652593988;25935.740642593333;31001.8449907749;31398.529022065144;31463.21341966469;31498;31501.36997259996;227953;229525;231236;232156;233306;29529.85235073811;29690.257743556584;29757.404851903273;29825;29934.550981735727;35242.47136292906;35344;35437.82281088578;35939.46090808692;36271;26390.604140938285;26447.33881652974;26586.335341616614;26800;27481;26846.731532685204;27057.59413608837;27424.39468250513;27500;27652.58521122225;29423.26441838971;29716.10851674453;29771.255718607208;29850.402991940475;30474.390512189188;26382.736172638797;26472.470550589267;26485.470290593694;26801.731982679925;27622.171334859988;20298;21644.24245151412;21727.34817955464;21816.454588634777;22053.669194961767 18:20:55 INFO - PID 25330 | |2;jquery.html;411.3829256115826;418.11568532553656;424.9043965107851;438.75429759334776;444.6687218022574;17361.7395739064;17777.555561110978;18230.723849330552;18533.25866965323;18543.907280463594;18036.909815450937;18497.16762745676;19115.139818708158;19412.029398530052;19441.51396215095;5565;5586.10622300433;5718.142278658213;5726.68503232322;5736.082226843706;5777.62445441045;5800.738966746486;5851.13294485701;5869.266341707286;5869.266341707286;5595.21666966625;5782.3350314713925;5783.768649254025;5785.450382213704;5793.652380857141;5499.642523235977;5754.74103665336;5767.942320576824;5800.9129863052085;5801.593888427812;5119.872003199913;5158.561522270612;5168.198929165947;5184.481551844823;5189.091908915962;22628.660570091462;22661.773382266132;22984.885075574515;23005.654915176285;23035.1937682182;1609.2758258783595;1609.3723447855346;1626.0975158786869;1646.0946479436261;1672.9794825156644;13492.257925814076;14143;14172.291385430688;14191.432342706279;15398.769018464733;1702.6424450865334;1720.2688857235626;1725.0598423859026;1732.9480115596552;1761.8150094240048;89355.55322223413;91574;92121.0787892126;92864;93741;11142.554297828157;11209.215354925076;11353.829692554618;11498.25261358009;11614.883851161549;15691.686166276611;15708.60728481797;15720.842791572166;15749.212539373215;15802.683946321009;7171.175314838756;7270.563766174047;7429.962850185714;7456;7467.141278752904;6677.165354330708;6714.899276510856;6782.118324617768;6905.551139175937;6929.411000065051;7041.612711300928;7098.538594991309;7142.821429464201;7242.3010801840555;7257.709691612382;2056.7223424837753;2064.9793502064786;2122.5542636046603;2131.6269652810697;2205.2171479125;842.216738433263;866.3718803867272;876.4390789894472;876.9882455765635;898.2544488074882;15622.843771562138;15980.520584382488;16151.353945842035;16426.507204783877;16598.336066557204;868.8305780372766;875.8204568063659;888.29824438694;896.421807933879;899.1278459893892;8787.384883058125;8865.822683546294;8907.643694252158;9300.162985331353;9341.3928094675;399280;415778;420155;420374;421750;67798.32201678019;83630.5818470916;87985.5600722005;88395.55802220947;89033.10966890249;1029.6705054382676;1046.2937517175876;1082.767205050918;1084.5607528950788;1086.4947799273375;13686.589402317946;13719.862801371859;13845.238511881944;13971;14412;38627;39809.2038159241;39907.60092399155;41577.37633935433;43113;53941;54349;54666.453335467726;54674;55139.724301378235;8191.508509489449;8252.587370631565;8441.873371899304;8464.153584641486;8703.825923481369;12051.879481205076;12245.326507042199;12516;12518.12373133872;12905.483780649045;10272.845907311246;10615.31000484966;10734.785304294026;10847;11138.443077846237;10163.288569800043;10450.843237351597;10491.94754026225;10583.841242381524;10692.19808514349;11074.944625276821;11273.379964102052;11480.712982175512;11573.826392604286;11900.76198476043;7802.843943121219;7893.763187104396;8075.9501264837545;8253.463524870864;8645.567721613768;10114.393136411838;10618.575256989634;10626.574937002742;10774.515146818256;11160.162987775788 18:20:55 INFO - PID 25330 | |3;mootools.html;1214.4838443661445;1258.7671280813056;1269.5810382573745;1271.6502961685542;1276.744651069786;9329;9692.32153749237;9702.757431064218;9866.21070314375;10092.545835437404;9898.901010989906;10051.095401413872;10060.949695251531;10061.245406594498;10076.042775936292;2263.920762773299;2285.988570057147;2303.562323158603;2304.6197377432704;2309.3187509684685;2047.7849825768303;2110.2719561751173;2123.90618831302;2143.65701487762;2198.7471440784275;2169.533550286688;2244.1808739809935;2290.89690963907;2291.3126062181364;2304.0438218139534;2121.2999710095633;2162.2107930605293;2169.9891500542476;2175.3908905506482;2176.3579743975565;1793.748875157479;1825.3520000399933;1904.561950751335;1962.9214831406728;2163.5132095278595;9839;9927.553260103312;9932.80134397308;9949.75125621858;9951.90048099517;5619.578531610105;5817.7963771267805;5825.912611310834;5827.825165245049;5836.358000619928;3354.5974483061946;3374.2745309758275;3448.7067349743847;3503.9649603503894;3518.032541051206;1072.6942821295893;1087.9564817407295;1115.8549388579513;1128.3568366031366;1144.3534403062242;14435.422583096664;14455.060421072596;14517.346719397652;14556.70886582273;14624.195669238294;4730.5032971538185;4953.306537084811;5055.7219352935945;5080.288759573663;5084.949150508448;10677.626283080055;10823.67528974132;10843.945780271206;10858.2399232053;10876.836847447295;5629.774809007676;5672.688002159921;5691.800786972436;5706.3722990471015;5712.914306285409;5061.544460998528;5116.744162791845;5169.095408303532;5210.452902445264;5272.604554658339;3760.6803421708914;3849.653531182207;3886.591907849692;3914.2758589661003;3921.5490218624655;1124.701953982179;1135.8580177477816;1147.5811328864904;1159.5303901919735;1159.7158696119504;679.9456043516606;695.1206723494784;699.9920115034334;713.5896859305919;719.8200449887528;5319.175527793198;5331.893362132736;5352.545033672103;6258.561900666909;6272.372762723691;803.4697099914029;804.7505273365275;806.2381049907781;824.7319621123039;852.3309202276183;3508.1405055760993;3608.116011577128;3622.728295377857;3636.8545258189906;3920.313945059603;12465.813012804816;12634.684132896751;12652.114351995271;12735.745285094246;13100.475980960655;12499.312537810507;12635.1787133836;12714.682132946566;12746.553870614483;12763.042771792152;9210.447373157633;9434.764130896645;9483.241340692728;9487.952560237292;9528.904710953078;5466.371367292653;5479.698616576126;5527.751251193787;5665.03694371966;5727;11295.152863535432;11366.715832104264;11384.601538946097;11500.424978751196;11599.710007249549;11206.943965280121;11543.538258469567;11574.594889178838;11580.247283926517;11771;8222.83554328922;8347.83304333922;8355.248027677539;8362.749117526484;8420.410571259952;9160.083991600786;9255.074492550692;9335.57989890443;9371.625134994796;9404.670836520963;7770.067591889009;7881.290683838482;7911.28798408146;7949.562774047252;8057.717979870677;7471;7577.924220757943;7586.81032974158;7796.064472263365;7819.139894611474;6877.312268773083;6993.370596646327;6997.440204783503;6999.160100787938;6999.755008574675;5130.358705161855;5187.844364669066;5234.371875374979;5237.2667826505085;5447;5003.774830132726;5093.567046800915;5265.710385928811;5274.12976858814;5516.668999860021 18:20:55 INFO - PID 25330 | |4;prototype.html;385.82444987530675;398.91223930735237;408.80173116038725;410.2881500596466;410.42745370208576;5358;5542.889142217158;5578.693171875545;5592.468715472029;5636;5351.464853514646;5425.2675888755;5436.891262174754;5451.727413629323;5454.509094181523;4230.561609052921;4246.957530424687;4247.745135291877;4248.723832950864;4253;4051.493563304587;4192.170285057589;4256.041881050622;4280.828766849322;4283.507396649378;4151;4155.62599366057;4172.7496350218935;4179.540250572434;4201.789910504463;4160.7087503874745;4162.521310049353;4167.229062623424;4178.979105104486;4197.664186865043;3643.908902277438;3644.835982380799;3654.853805847763;3656.323580137683;3659.048647351681;5188.948110518922;5328.360596728378;5336.546393556552;5345.866353341159;5373.865653358697;4819.542143496362;4825.324454576362;4830.73430961297;4831.806727730886;4850.199717046683;5601.048639111637;5757.769689212427;5764.308283006024;5777.393373695785;5804.013317735995;3976.5426975897562;4000.3399439092514;4006.098627808749;4012.9197416051807;4025.194961007781;5820.050283155106;5986.49114825236;6010.338862725097;6089.695515224221;6191.690415479208;4130.938035929464;4134.669226461876;4137.420761093449;4139.110091330378;4160.334346504545;4202.117555313357;4210.663146948237;4212.199682060399;4222.408862759216;4239.576042395797;4645.953540464619;4833.347498087784;4840.467548569654;4840.693012886501;4849.515048495122;5804.448577385142;5809.8547536311935;5817.331006934172;5828.213191219216;5829.795957141565;1452.8982971192127;1471.6615178509003;1473.4990103364694;1473.6442472924934;1476.0700758522062;944.4427787605208;990.217727994876;992.0575453319232;998.8701468809008;1012.3622118065572;5064.290999260106;5093.821716239914;5117.820876269388;5119.948800511947;5120.92318615221;6061.151258908306;6061.302950160788;6069.969650151721;6074.51403887688;6075.483583895419;4897.632677549127;5059.848204553869;5072.9746351268;5074.391073071255;5103.591712662978;3149.637791653989;3201.199700074981;3214.3571285742946;3214.774965752422;3217.7747557670737;5577.8844231153935;5592.832215033555;5596.888062238814;5610.7194640268635;5623.465770751772;5903.583140046358;6142.846428839315;6213.316535181127;6252.906206406908;6257.092721555349;5586.720663966786;5605.878824235169;5634.352049514358;5634.577406694514;5669.149627555899;5862.794802181903;6050.788222412194;6063.757449701962;6091.847703807441;6095.878082438415;5971.701414929149;5972.193753843349;5974.1934838796205;5976.880462390641;5982.341942386421;5344.385395679391;5426.348838139448;5521.889562208814;5584.553235741049;5609.60618825621;4165.520965089054;4179.4357761702995;4195.1819395217635;4200.138971510772;4217.662586993095;5944.970275148597;6015.187949626745;6062.545309101747;6074.088886667036;6083.148359229623;5803.709814509173;5830.329512105993;5842.591018628826;5852.376622468202;5855.268091488564;5818.330891947311;5832.591718579828;5833.591648584558;5849.415058494116;5879.970600146972;5573.136163894603;5683.9715801420725;5695.943040569541;5718.771249149988;5767.596268261349;5872.911906321494;5892.970535147297;5895.616784908967;5901.409859014064;5905.91141132892;5444.972775136257;5707.8858422832145;5708.80019199326;5737.049403202765;5745.9425405745405 18:20:55 INFO - PID 25330 | |5;yui.html;687.2337343861595;699.3845416033889;703.5778532880272;721.6896734404207;724.4638967164296;11688;11731.413429328542;11840.407979601028;12024.579139730111;13382.799258011137;12975.480980760782;13173.802392964113;13177.736445271114;13200.207987520755;13858.23779692119;815.1888870573788;843.8396704626132;849.6431498770501;849.9447535910177;853.3812985585463;826.8511667899776;853.0914575976597;861.5821326656597;862.1378621378622;862.1593945902727;903.3721563513361;903.7605034665819;903.9785042901512;903.9819203615924;905.5200743605867;893.0399820193253;897.6050537763429;897.6454300551246;898.6225785170244;899.4738078224246;653.588239409174;659.8581305019444;660.7423104989058;664.1764212376618;668.3750693101965;13366.532171373956;13424.060315777899;13965.162090274616;14055.156690598495;14106.858931410658;13666.658333541742;14227.21750303733;14303.856961430356;14327.426902923871;14398.208098554574;10568.735781605521;10608.20438467118;10635.096016838577;10721.30311529748;10748.892511074868;2734.53512902805;2738.6439762830905;2775.2229375774814;2779.3885345224016;2793.8044336896423;65037.52068512087;65480;65569.67215163942;66370.66814665945;66458.33541664618;10827.404492752974;10993.945030274797;11057.668269951915;11197.216194866282;11569.19015668895;21753;21812.89093554522;21870.89064554667;22003.33989980303;22250.554988900454;6304.747810087545;6374.490040796725;6384.616922984543;6486.156799616114;6506.967465162644;3192.3615276944706;3235.4499735045097;3261.4129456698024;3291.4075466415798;3345;3508.947365789515;3575.1419659281587;3582.7850328980344;4000.899977500586;4160.875173744793;714.8391611887388;725.767754318613;735.5660160505328;743.5650144665432;745.1021519069508;526.8814516733704;560.8009156749376;563.2283771233436;563.433749082178;568.9004424225744;8407.701741965724;8579.613917373734;9916.355436896578;10355.741106472398;10459.163266938627;505.7395441347709;531.2323692264717;549.9505044546011;561.5311215135408;565.6125553995527;3665.8533658653355;3806.4290356446754;3821.675157611634;3936.149117588714;3955.920881582352;46713;47743;49130.26304605434;49652.25521617178;58372.12441813291;49480.25779613309;49824;50613.74693126584;51008.48991510112;51563.74218128961;44528.77735611301;45368.09263814705;46266.5373346269;46749;47446.76276618595;9097.818043639223;9596.23230141573;9613.759656008655;9618.326717129734;9633.373830700983;21843.344699659036;22493.550128997656;23352;23882.52234955326;24592.631110533;39172;41552.58447415608;41978.37032444577;42858;43256.78371608248;12093.879061209274;12179.451924663234;12184.75630487403;12394.442250098587;12866.67833304175;12271.325077120488;12324.691882703004;12741.17182383142;12887.48450061987;12909.612711618667;11576.421178941187;11740.530378784753;12044.648093112984;12068.879311206776;12223.449944752685;11954.282743035446;12247.326397048248;12551.435185416496;12555.79827144109;12836.743265134459;11626.593069242535;11825;12501.187422817487;12574.497020119094;12910.548130815376;8602.91397086021;8642.481451112953;8834.337424693045;8838.911610884066;9003.459792412476;9806.117449429586;9960.950195248977;10128.645497407555;10134.391936483835;10159.085682288629 18:20:55 INFO - PID 25330 | __end_tp_report 18:20:55 INFO - PID 25330 | __start_cc_report 18:20:55 INFO - PID 25330 | _x_x_mozilla_cycle_collect,391 18:20:55 INFO - PID 25330 | __end_cc_report 18:20:55 INFO - PID 25330 | __startTimestamp1490664055414__endTimestamp 18:20:55 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | ------- Summary: start ------- 18:20:55 INFO - PID 25330 | Number of tests: 6 18:20:55 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | [#0] dojo.html Cycles:165 Average:5239.59 Median:5385.11 stddev:604.90 (11.2%) stddev-sans-first:572.88 18:20:55 INFO - PID 25330 | Values: 2695.4 2710.3 2717.5 2744.1 2754.0 5209.0 5247.9 5256.4 5295.9 5317.5 5247.7 5252.4 5338.8 5341.0 5420.6 4816.2 4850.1 4854.2 4865.5 4884.2 4854.1 4860.1 4898.8 4913.8 4963.3 4728.9 4792.6 4822.2 4830.7 4844.2 4830.0 4854.6 4858.2 4890.3 4910.3 4771.0 4775.2 4803.1 4838.2 4848.0 5103.5 5153.9 5280.9 5308.6 5371.9 5723.0 5731.3 5780.2 5791.9 5793.7 5751.6 5755.2 5759.3 5759.7 5761.5 5222.7 5315.3 5323.8 5332.4 5342.2 5758.5 5819.0 5830.5 5836.0 5842.3 5577.7 5599.5 5615.8 5678.3 5697.3 5663.3 5701.5 5719.0 5720.2 5724.7 5649.2 5651.1 5655.1 5655.6 5672.5 5513.0 5579.8 5646.9 5692.6 5723.2 4736.7 4739.5 4742.5 4772.8 4777.5 4037.9 4052.6 4069.9 4094.2 4109.7 4817.2 4882.8 4922.9 4925.6 4953.0 5746.3 5748.0 5751.0 5779.6 5780.6 4894.2 4894.9 4899.9 4907.8 4936.8 4951.6 5046.0 5074.9 5076.2 5080.5 5329.9 5348.2 5369.6 5385.1 5409.6 5628.7 5743.7 5763.6 5765.6 5795.7 5253.1 5257.9 5315.3 5346.6 5371.4 5625.0 5663.8 5664.1 5672.1 5678.9 5761.4 5767.7 5769.6 5781.5 5787.8 5499.1 5567.5 5577.4 5633.8 5643.1 5479.5 5509.5 5525.7 5527.6 5581.4 5439.5 5444.9 5473.7 5485.5 5530.0 5465.0 5540.2 5551.6 5591.0 5606.8 5406.3 5411.7 5420.4 5432.4 5438.7 18:20:55 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | [#1] ext.html Cycles:180 Average:34221.76 Median:16417.96 stddev:53993.52 (328.9%) stddev-sans-first:54086.17 18:20:55 INFO - PID 25330 | Values: 657.2 728.6 751.3 1078.3 1086.7 30479.2 31448.2 31540.0 31592.8 31686.2 32761.3 33189.2 33192.2 33244.5 33245.0 7135.4 7254.5 7341.9 7347.0 7351.6 7436.1 7454.9 7475.6 7536.1 7567.9 7505.4 7565.5 7575.3 7580.0 7587.4 7520.9 7631.8 7641.0 7679.3 7700.1 7412.7 7470.2 7477.8 7478.2 7486.4 34875.1 34881.7 34886.5 34898.5 35180.6 2695.9 2739.7 2762.5 2767.3 2798.9 24581.3 24924.6 24990.5 25438.7 25538.7 2928.4 2976.3 2979.9 2985.5 3005.2 139575.0 141405.0 141916.0 142816.0 143739.0 40919.0 40920.8 40945.2 41132.2 41313.2 42568.4 42897.0 43044.4 43066.8 43081.9 11885.8 11946.5 11950.2 12102.6 12123.2 9406.2 9461.4 9474.2 9492.1 9558.6 12650.6 12849.8 12896.9 13057.2 13086.8 5266.7 5339.9 5342.7 5483.3 5546.6 860.7 878.2 888.9 929.7 935.6 20819.1 21413.0 21533.4 21619.6 21699.2 981.3 991.6 1004.5 1009.6 1011.6 11074.4 11170.5 11316.0 11371.5 11382.7 234039.0 234345.0 234830.0 235834.0 236467.0 40139.6 40556.5 42401.4 42425.8 42797.0 1232.8 1237.8 1255.9 1256.7 1258.8 24584.3 25128.2 25637.6 25934.7 25935.7 31001.8 31398.5 31463.2 31498.0 31501.4 227953.0 229525.0 231236.0 232156.0 233306.0 29529.9 29690.3 29757.4 29825.0 29934.6 35242.5 35344.0 35437.8 35939.5 36271.0 26390.6 26447.3 26586.3 26800.0 27481.0 26846.7 27057.6 27424.4 27500.0 27652.6 29423.3 29716.1 29771.3 29850.4 30474.4 26382.7 26472.5 26485.5 26801.7 27622.2 20298.0 21644.2 21727.3 21816.5 22053.7 18:20:55 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | [#2] jquery.html Cycles:180 Average:26577.11 Median:228677.69 stddev:69064.21 (30.2%) stddev-sans-first:69230.01 18:20:55 INFO - PID 25330 | Values: 411.4 418.1 424.9 438.8 444.7 17361.7 17777.6 18230.7 18533.3 18543.9 18036.9 18497.2 19115.1 19412.0 19441.5 5565.0 5586.1 5718.1 5726.7 5736.1 5777.6 5800.7 5851.1 5869.3 5869.3 5595.2 5782.3 5783.8 5785.5 5793.7 5499.6 5754.7 5767.9 5800.9 5801.6 5119.9 5158.6 5168.2 5184.5 5189.1 22628.7 22661.8 22984.9 23005.7 23035.2 1609.3 1609.4 1626.1 1646.1 1673.0 13492.3 14143.0 14172.3 14191.4 15398.8 1702.6 1720.3 1725.1 1732.9 1761.8 89355.6 91574.0 92121.1 92864.0 93741.0 11142.6 11209.2 11353.8 11498.3 11614.9 15691.7 15708.6 15720.8 15749.2 15802.7 7171.2 7270.6 7430.0 7456.0 7467.1 6677.2 6714.9 6782.1 6905.6 6929.4 7041.6 7098.5 7142.8 7242.3 7257.7 2056.7 2065.0 2122.6 2131.6 2205.2 842.2 866.4 876.4 877.0 898.3 15622.8 15980.5 16151.4 16426.5 16598.3 868.8 875.8 888.3 896.4 899.1 8787.4 8865.8 8907.6 9300.2 9341.4 399280.0 415778.0 420155.0 420374.0 421750.0 67798.3 83630.6 87985.6 88395.6 89033.1 1029.7 1046.3 1082.8 1084.6 1086.5 13686.6 13719.9 13845.2 13971.0 14412.0 38627.0 39809.2 39907.6 41577.4 43113.0 53941.0 54349.0 54666.5 54674.0 55139.7 8191.5 8252.6 8441.9 8464.2 8703.8 12051.9 12245.3 12516.0 12518.1 12905.5 10272.8 10615.3 10734.8 10847.0 11138.4 10163.3 10450.8 10491.9 10583.8 10692.2 11074.9 11273.4 11480.7 11573.8 11900.8 7802.8 7893.8 8076.0 8253.5 8645.6 10114.4 10618.6 10626.6 10774.5 11160.2 18:20:55 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | [#3] mootools.html Cycles:180 Average:6198.07 Median:4841.90 stddev:3900.15 (80.5%) stddev-sans-first:3893.11 18:20:55 INFO - PID 25330 | Values: 1214.5 1258.8 1269.6 1271.7 1276.7 9329.0 9692.3 9702.8 9866.2 10092.5 9898.9 10051.1 10060.9 10061.2 10076.0 2263.9 2286.0 2303.6 2304.6 2309.3 2047.8 2110.3 2123.9 2143.7 2198.7 2169.5 2244.2 2290.9 2291.3 2304.0 2121.3 2162.2 2170.0 2175.4 2176.4 1793.7 1825.4 1904.6 1962.9 2163.5 9839.0 9927.6 9932.8 9949.8 9951.9 5619.6 5817.8 5825.9 5827.8 5836.4 3354.6 3374.3 3448.7 3504.0 3518.0 1072.7 1088.0 1115.9 1128.4 1144.4 14435.4 14455.1 14517.3 14556.7 14624.2 4730.5 4953.3 5055.7 5080.3 5084.9 10677.6 10823.7 10843.9 10858.2 10876.8 5629.8 5672.7 5691.8 5706.4 5712.9 5061.5 5116.7 5169.1 5210.5 5272.6 3760.7 3849.7 3886.6 3914.3 3921.5 1124.7 1135.9 1147.6 1159.5 1159.7 679.9 695.1 700.0 713.6 719.8 5319.2 5331.9 5352.5 6258.6 6272.4 803.5 804.8 806.2 824.7 852.3 3508.1 3608.1 3622.7 3636.9 3920.3 12465.8 12634.7 12652.1 12735.7 13100.5 12499.3 12635.2 12714.7 12746.6 12763.0 9210.4 9434.8 9483.2 9488.0 9528.9 5466.4 5479.7 5527.8 5665.0 5727.0 11295.2 11366.7 11384.6 11500.4 11599.7 11206.9 11543.5 11574.6 11580.2 11771.0 8222.8 8347.8 8355.2 8362.7 8420.4 9160.1 9255.1 9335.6 9371.6 9404.7 7770.1 7881.3 7911.3 7949.6 8057.7 7471.0 7577.9 7586.8 7796.1 7819.1 6877.3 6993.4 6997.4 6999.2 6999.8 5130.4 5187.8 5234.4 5237.3 5447.0 5003.8 5093.6 5265.7 5274.1 5516.7 18:20:55 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | [#4] prototype.html Cycles:180 Average:4795.20 Median:5431.62 stddev:1413.42 (26.0%) stddev-sans-first:1378.09 18:20:55 INFO - PID 25330 | Values: 385.8 398.9 408.8 410.3 410.4 5358.0 5542.9 5578.7 5592.5 5636.0 5351.5 5425.3 5436.9 5451.7 5454.5 4230.6 4247.0 4247.7 4248.7 4253.0 4051.5 4192.2 4256.0 4280.8 4283.5 4151.0 4155.6 4172.7 4179.5 4201.8 4160.7 4162.5 4167.2 4179.0 4197.7 3643.9 3644.8 3654.9 3656.3 3659.0 5188.9 5328.4 5336.5 5345.9 5373.9 4819.5 4825.3 4830.7 4831.8 4850.2 5601.0 5757.8 5764.3 5777.4 5804.0 3976.5 4000.3 4006.1 4012.9 4025.2 5820.1 5986.5 6010.3 6089.7 6191.7 4130.9 4134.7 4137.4 4139.1 4160.3 4202.1 4210.7 4212.2 4222.4 4239.6 4646.0 4833.3 4840.5 4840.7 4849.5 5804.4 5809.9 5817.3 5828.2 5829.8 1452.9 1471.7 1473.5 1473.6 1476.1 944.4 990.2 992.1 998.9 1012.4 5064.3 5093.8 5117.8 5119.9 5120.9 6061.2 6061.3 6070.0 6074.5 6075.5 4897.6 5059.8 5073.0 5074.4 5103.6 3149.6 3201.2 3214.4 3214.8 3217.8 5577.9 5592.8 5596.9 5610.7 5623.5 5903.6 6142.8 6213.3 6252.9 6257.1 5586.7 5605.9 5634.4 5634.6 5669.1 5862.8 6050.8 6063.8 6091.8 6095.9 5971.7 5972.2 5974.2 5976.9 5982.3 5344.4 5426.3 5521.9 5584.6 5609.6 4165.5 4179.4 4195.2 4200.1 4217.7 5945.0 6015.2 6062.5 6074.1 6083.1 5803.7 5830.3 5842.6 5852.4 5855.3 5818.3 5832.6 5833.6 5849.4 5880.0 5573.1 5684.0 5695.9 5718.8 5767.6 5872.9 5893.0 5895.6 5901.4 5905.9 5445.0 5707.9 5708.8 5737.0 5745.9 18:20:55 INFO - PID 25330 | 18:20:55 INFO - PID 25330 | [#5] yui.html Cycles:180 Average:13917.00 Median:22856.73 stddev:16302.13 (71.3%) stddev-sans-first:16317.59 18:20:55 INFO - PID 25330 | Values: 687.2 699.4 703.6 721.7 724.5 11688.0 11731.4 11840.4 12024.6 13382.8 12975.5 13173.8 13177.7 13200.2 13858.2 815.2 843.8 849.6 849.9 853.4 826.9 853.1 861.6 862.1 862.2 903.4 903.8 904.0 904.0 905.5 893.0 897.6 897.6 898.6 899.5 653.6 659.9 660.7 664.2 668.4 13366.5 13424.1 13965.2 14055.2 14106.9 13666.7 14227.2 14303.9 14327.4 14398.2 10568.7 10608.2 10635.1 10721.3 10748.9 2734.5 2738.6 2775.2 2779.4 2793.8 65037.5 65480.0 65569.7 66370.7 66458.3 10827.4 10993.9 11057.7 11197.2 11569.2 21753.0 21812.9 21870.9 22003.3 22250.6 6304.7 6374.5 6384.6 6486.2 6507.0 3192.4 3235.4 3261.4 3291.4 3345.0 3508.9 3575.1 3582.8 4000.9 4160.9 714.8 725.8 735.6 743.6 745.1 526.9 560.8 563.2 563.4 568.9 8407.7 8579.6 9916.4 10355.7 10459.2 505.7 531.2 550.0 561.5 565.6 3665.9 3806.4 3821.7 3936.1 3955.9 46713.0 47743.0 49130.3 49652.3 58372.1 49480.3 49824.0 50613.7 51008.5 51563.7 44528.8 45368.1 46266.5 46749.0 47446.8 9097.8 9596.2 9613.8 9618.3 9633.4 21843.3 22493.6 23352.0 23882.5 24592.6 39172.0 41552.6 41978.4 42858.0 43256.8 12093.9 12179.5 12184.8 12394.4 12866.7 12271.3 12324.7 12741.2 12887.5 12909.6 11576.4 11740.5 12044.6 12068.9 12223.4 11954.3 12247.3 12551.4 12555.8 12836.7 11626.6 11825.0 12501.2 12574.5 12910.5 8602.9 8642.5 8834.3 8838.9 9003.5 9806.1 9961.0 10128.6 10134.4 10159.1 18:20:55 INFO - PID 25330 | -------- Summary: end -------- 18:20:55 INFO - PID 25330 | 18:20:56 INFO - TEST-INFO | 25330: exit 0 18:20:56 INFO - TEST-OK | dromaeo_css | took 1121820ms 18:20:56 INFO - TEST-START | kraken 18:20:56 INFO - operating with platform_type : linux_ 18:20:56 INFO - Initialising browser for kraken test... 18:20:56 INFO - TEST-INFO | started process 25706 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUAOilP/profile http://localhost:56319/getInfo.html) 18:21:01 INFO - PID 25706 | __metrics Screen width/height:1600/1200 18:21:01 INFO - PID 25706 | colorDepth:24 18:21:01 INFO - PID 25706 | Browser inner width/height: 1024/697 18:21:01 INFO - PID 25706 | __metrics 18:21:02 ERROR - PID 25706 | 1490664062078 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6376:36 < canClose@browser.js:5227:12 < forceQuit@jar:file:///tmp/tmpUAOilP/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmpUAOilP/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmpUAOilP/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmpUAOilP/profile/extensions/talos-powers@TalosPowersService.js:55:9 18:21:02 ERROR - PID 25706 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 18:21:03 INFO - TEST-INFO | 25706: exit 0 18:21:03 INFO - Browser initialized. 18:21:03 INFO - Running cycle 1/1 for kraken test... 18:21:03 INFO - TEST-INFO | started process 25838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUAOilP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:15 INFO - PID 25838 | RSS: Main: 156381184 18:21:15 INFO - PID 25838 | 18:21:17 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56319/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:18 INFO - PID 25838 | RSS: Main: 163643392 18:21:18 INFO - PID 25838 | 18:21:21 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56319/tests/kraken/driver.html?testName=audio-dft) 18:21:22 INFO - PID 25838 | RSS: Main: 161947648 18:21:22 INFO - PID 25838 | 18:21:25 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56319/tests/kraken/driver.html?testName=audio-fft) 18:21:25 INFO - PID 25838 | RSS: Main: 158744576 18:21:25 INFO - PID 25838 | 18:21:28 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56319/tests/kraken/driver.html?testName=audio-oscillator) 18:21:28 INFO - PID 25838 | RSS: Main: 159240192 18:21:28 INFO - PID 25838 | 18:21:30 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56319/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:21:31 INFO - PID 25838 | RSS: Main: 159690752 18:21:31 INFO - PID 25838 | 18:21:34 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56319/tests/kraken/driver.html?testName=imaging-darkroom) 18:21:35 INFO - PID 25838 | RSS: Main: 164081664 18:21:35 INFO - PID 25838 | 18:21:38 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56319/tests/kraken/driver.html?testName=imaging-desaturate) 18:21:39 INFO - PID 25838 | RSS: Main: 161804288 18:21:39 INFO - PID 25838 | 18:21:42 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56319/tests/kraken/driver.html?testName=json-parse-financial) 18:21:43 INFO - PID 25838 | RSS: Main: 162902016 18:21:43 INFO - PID 25838 | 18:21:44 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56319/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:21:45 INFO - PID 25838 | RSS: Main: 163225600 18:21:45 INFO - PID 25838 | 18:21:47 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56319/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:21:48 INFO - PID 25838 | RSS: Main: 163651584 18:21:48 INFO - PID 25838 | 18:21:50 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56319/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:21:50 INFO - PID 25838 | RSS: Main: 164167680 18:21:50 INFO - PID 25838 | 18:21:53 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:21:54 INFO - PID 25838 | RSS: Main: 164425728 18:21:54 INFO - PID 25838 | 18:21:57 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:21:58 INFO - PID 25838 | RSS: Main: 164032512 18:21:58 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | Cycle 1(1): loaded http://localhost:56319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56319/tests/kraken/driver.html?testName=ai-astar) 18:22:00 INFO - PID 25838 | RSS: Main: 165654528 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | __start_tp_report 18:22:00 INFO - PID 25838 | _x_x_mozilla_page_load 18:22:00 INFO - PID 25838 | _x_x_mozilla_page_load_details 18:22:00 INFO - PID 25838 | |i|pagename|runs| 18:22:00 INFO - PID 25838 | |0;ai-astar;104;112;112;112;113;112;113;113;113;113 18:22:00 INFO - PID 25838 | |1;audio-beat-detection;157;132;136;173;126;138;137;128;146;128 18:22:00 INFO - PID 25838 | |2;audio-dft;168;166;167;174;167;175;167;165;165;165 18:22:00 INFO - PID 25838 | |3;audio-fft;76;76;75;112;75;75;76;75;99;76 18:22:00 INFO - PID 25838 | |4;audio-oscillator;84;84;107;85;84;85;99;84;84;105 18:22:00 INFO - PID 25838 | |5;imaging-gaussian-blur;115;114;114;115;115;116;115;115;115;114 18:22:00 INFO - PID 25838 | |6;imaging-darkroom;151;150;151;151;152;150;151;151;151;151 18:22:00 INFO - PID 25838 | |7;imaging-desaturate;94;96;95;96;95;96;95;95;95;95 18:22:00 INFO - PID 25838 | |8;json-parse-financial;68;70;68;71;69;69;69;70;70;70 18:22:00 INFO - PID 25838 | |9;json-stringify-tinderbox;57;56;61;56;57;56;56;59;57;56 18:22:00 INFO - PID 25838 | |10;stanford-crypto-aes;84;84;84;83;89;82;83;83;85;83 18:22:00 INFO - PID 25838 | |11;stanford-crypto-ccm;139;143;141;141;136;134;143;148;182;141 18:22:00 INFO - PID 25838 | |12;stanford-crypto-pbkdf2;188;196;196;193;195;200;211;206;197;202 18:22:00 INFO - PID 25838 | |13;stanford-crypto-sha256-iterative;73;75;70;71;74;71;72;70;74;69 18:22:00 INFO - PID 25838 | __end_tp_report 18:22:00 INFO - PID 25838 | __start_cc_report 18:22:00 INFO - PID 25838 | _x_x_mozilla_cycle_collect,864 18:22:00 INFO - PID 25838 | __end_cc_report 18:22:00 INFO - PID 25838 | __startTimestamp1490664120425__endTimestamp 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | ------- Summary: start ------- 18:22:00 INFO - PID 25838 | Number of tests: 14 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:2.75 (2.4%) stddev-sans-first:0.53 18:22:00 INFO - PID 25838 | Values: 104.0 112.0 112.0 112.0 113.0 112.0 113.0 113.0 113.0 113.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:137.50 stddev:14.87 (10.8%) stddev-sans-first:14.46 18:22:00 INFO - PID 25838 | Values: 157.0 132.0 136.0 173.0 126.0 138.0 137.0 128.0 146.0 128.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#2] audio-dft Cycles:10 Average:167.90 Median:167.00 stddev:3.63 (2.2%) stddev-sans-first:3.86 18:22:00 INFO - PID 25838 | Values: 168.0 166.0 167.0 174.0 167.0 175.0 167.0 165.0 165.0 165.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.00 stddev:13.02 (17.1%) stddev-sans-first:13.66 18:22:00 INFO - PID 25838 | Values: 76.0 76.0 75.0 112.0 75.0 75.0 76.0 75.0 99.0 76.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:84.00 stddev:9.57 (11.4%) stddev-sans-first:9.90 18:22:00 INFO - PID 25838 | Values: 84.0 84.0 107.0 85.0 84.0 85.0 99.0 84.0 84.0 105.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#5] imaging-gaussian-blur Cycles:10 Average:114.80 Median:115.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 18:22:00 INFO - PID 25838 | Values: 115.0 114.0 114.0 115.0 115.0 116.0 115.0 115.0 115.0 114.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 18:22:00 INFO - PID 25838 | Values: 151.0 150.0 151.0 151.0 152.0 150.0 151.0 151.0 151.0 151.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 18:22:00 INFO - PID 25838 | Values: 94.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#8] json-parse-financial Cycles:10 Average:69.40 Median:70.00 stddev:0.97 (1.4%) stddev-sans-first:0.88 18:22:00 INFO - PID 25838 | Values: 68.0 70.0 68.0 71.0 69.0 69.0 69.0 70.0 70.0 70.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#9] json-stringify-tinderbox Cycles:10 Average:57.10 Median:57.00 stddev:1.66 (2.9%) stddev-sans-first:1.76 18:22:00 INFO - PID 25838 | Values: 57.0 56.0 61.0 56.0 57.0 56.0 56.0 59.0 57.0 56.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:1.94 (2.3%) stddev-sans-first:2.06 18:22:00 INFO - PID 25838 | Values: 84.0 84.0 84.0 83.0 89.0 82.0 83.0 83.0 85.0 83.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:142.00 stddev:13.63 (9.6%) stddev-sans-first:14.29 18:22:00 INFO - PID 25838 | Values: 139.0 143.0 141.0 141.0 136.0 134.0 143.0 148.0 182.0 141.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.40 Median:198.50 stddev:6.62 (3.3%) stddev-sans-first:5.85 18:22:00 INFO - PID 25838 | Values: 188.0 196.0 196.0 193.0 195.0 200.0 211.0 206.0 197.0 202.0 18:22:00 INFO - PID 25838 | 18:22:00 INFO - PID 25838 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:72.50 stddev:2.02 (2.8%) stddev-sans-first:2.11 18:22:00 INFO - PID 25838 | Values: 73.0 75.0 70.0 71.0 74.0 71.0 72.0 70.0 74.0 69.0 18:22:00 INFO - PID 25838 | -------- Summary: end -------- 18:22:00 INFO - PID 25838 | 18:22:01 INFO - TEST-INFO | 25838: exit 0 18:22:01 INFO - TEST-OK | kraken | took 65257ms 18:22:01 INFO - SUITE-END | took 1187s 18:22:02 INFO - Completed test suite (00:19:47) 18:22:02 INFO - javascript benchmark 18:22:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6689.08798627042, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2695.4339588686375, 2710.308871237834, 2717.4836781011604, 2744.1355972868546, 2754.036087369421, 5209.0, 5247.8950420991605, 5256.395514515827, 5295.894082118365, 5317.521423071923, 5247.711375874325, 5252.422233554315, 5338.839834804952, 5340.973295133528, 5420.647657902234, 4816.157172494808, 4850.102730994778, 4854.17479028566, 4865.464798872121, 4884.169691152495, 4854.12625727369, 4860.1494738420815, 4898.804047838083, 4913.82801601944, 4963.3299504566785, 4728.929066063977, 4792.568668819823, 4822.180229361017, 4830.661853670244, 4844.249141383091, 4829.951700482985, 4854.587360074398, 4858.2469717193535, 4890.315355850184, 4910.337104490884, 4770.952290477086, 4775.164346239394, 4803.063402636487, 4838.177509823338, 4847.95152048482, 5103.515166059218, 5153.896922061538, 5280.947190528084, 5308.601854860901, 5371.919421208685, 5722.97138514309, 5731.283589551306, 5780.208135478016, 5791.913121303184, 5793.7392817323325, 5751.568632352591, 5755.165501002331, 5759.251297331362, 5759.68321742304, 5761.481466667978, 5222.686638801684, 5315.335583052119, 5323.787048518094, 5332.3867755207875, 5342.171963345727, 5758.539316854642, 5818.970905145532, 5830.5044071254415, 5836.0, 5842.269716285465, 5577.665340079527, 5599.468050535193, 5615.775368985276, 5678.346990096109, 5697.3448053474385, 5663.292088488939, 5701.515371193412, 5719.027765279912, 5720.227769251181, 5724.742386592613, 5649.209110724501, 5651.12407576818, 5655.095184770417, 5655.604107712503, 5672.546196304285, 5513.0, 5579.832605021856, 5646.858828529237, 5692.6015178937905, 5723.227364305849, 4736.73947932867, 4739.454962679336, 4742.5257474252985, 4772.832950846773, 4777.498362671939, 4037.899052523652, 4052.63526282636, 4069.9389509157386, 4094.2220978014084, 4109.691773117028, 4817.181079216542, 4882.829100981448, 4922.926156107661, 4925.605951523869, 4953.034158339161, 5746.310442746813, 5748.0, 5751.022326204556, 5779.624324418899, 5780.6415492359265, 4894.216925291937, 4894.926576101361, 4899.877503062381, 4907.803687852517, 4936.777844996984, 4951.554360107536, 5045.9747701261995, 5074.873128171826, 5076.213186956028, 5080.49195080489, 5329.9467005331, 5348.1977703343255, 5369.570434365163, 5385.111456609616, 5409.621326507264, 5628.690422026828, 5743.655380677173, 5763.625364351304, 5765.625234359753, 5795.652260864362, 5253.106971814725, 5257.894842103213, 5315.255864179096, 5346.625736198429, 5371.435999220025, 5625.043742563856, 5663.830085097454, 5664.065429204135, 5672.12082127271, 5678.914816277676, 5761.39505351932, 5767.7404516796005, 5769.59612827098, 5781.537477001746, 5787.7974270902005, 5499.065158923073, 5567.47109024642, 5577.358603760619, 5633.774649013993, 5643.097104463266, 5479.452054794533, 5509.531689806411, 5525.668459892419, 5527.585431092603, 5581.386047534849, 5439.483249091489, 5444.891102177855, 5473.67157970523, 5485.533729632866, 5530.032244357141, 5465.0, 5540.196671482693, 5551.555875529867, 5591.049521581423, 5606.803761868315, 5406.2701535292235, 5411.675299482044, 5420.403755586961, 5432.375276843056, 5438.728063596884], "value": 5195.488634048713, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.2277573850727, 728.5555810955318, 751.3388218367834, 1078.3368228539446, 1086.7283179205199, 30479.238019049506, 31448.213794655145, 31540.0, 31592.842035789818, 31686.207844803834, 32761.344773104523, 33189.17027074324, 33192.170195745115, 33244.50133247997, 33245.0, 7135.357817796397, 7254.528455650379, 7341.853162936738, 7346.963265183666, 7351.595662238575, 7436.1448433430305, 7454.850902981937, 7475.551466911976, 7536.095668519758, 7567.924320756777, 7505.437092218078, 7565.508241964255, 7575.318221360077, 7580.0, 7587.35507481862, 7520.924790752078, 7631.809204769869, 7641.044869391326, 7679.308862202429, 7700.11448683403, 7412.666430010662, 7470.178280389153, 7477.775666730006, 7478.177400485996, 7486.438517111184, 34875.1281217969, 34881.65118348835, 34886.47670284922, 34898.47652285218, 35180.648193518246, 2695.892164313425, 2739.6986331503517, 2762.5165596020615, 2767.2528417327208, 2798.8600569971622, 24581.262562123164, 24924.626130608056, 24990.5001899961, 25438.745612543822, 25538.744612553823, 2928.3557617324154, 2976.28569143404, 2979.8957036503616, 2985.5223164293607, 3005.218643152796, 139575.0, 141405.0, 141916.0, 142816.0, 143739.0, 40919.0, 40920.795396022826, 40945.181096377906, 41132.1773564527, 41313.1737365257, 42568.361474577905, 42897.0, 43044.354334685006, 43066.78466607647, 43081.92295192582, 11885.821712674317, 11946.462409191607, 11950.163488555892, 12102.636920892388, 12123.151379403356, 9406.200472959737, 9461.432314061178, 9474.194693451134, 9492.098250666175, 9558.617655293849, 12650.557230497072, 12849.807252891214, 12896.871031289755, 13057.21656700601, 13086.803697944539, 5266.736663166903, 5339.86650333737, 5342.706151161723, 5483.341998960071, 5546.556275498031, 860.7245681381897, 878.2008372381102, 888.8622263549161, 929.656027269901, 935.569637966543, 20819.06314215864, 21413.0, 21533.353999380044, 21619.56760864774, 21699.240526581492, 981.3375971219398, 991.6231831903831, 1004.542932965499, 1009.5759780892043, 1011.6459239266198, 11074.446277686084, 11170.497327620114, 11316.0, 11371.545138194546, 11382.715432114263, 234039.0, 234345.0, 234830.0, 235834.0, 236467.0, 40139.59860401417, 40556.538780734096, 42401.36397954033, 42425.78787106045, 42797.0, 1232.7904256276454, 1237.8452693413324, 1255.9372031398393, 1256.6921104329497, 1258.7734207842493, 24584.262472125865, 25128.24615261545, 25637.59728597131, 25934.740652593988, 25935.740642593333, 31001.8449907749, 31398.529022065144, 31463.21341966469, 31498.0, 31501.36997259996, 227953.0, 229525.0, 231236.0, 232156.0, 233306.0, 29529.85235073811, 29690.257743556584, 29757.404851903273, 29825.0, 29934.550981735727, 35242.47136292906, 35344.0, 35437.82281088578, 35939.46090808692, 36271.0, 26390.604140938285, 26447.33881652974, 26586.335341616614, 26800.0, 27481.0, 26846.731532685204, 27057.59413608837, 27424.39468250513, 27500.0, 27652.58521122225, 29423.26441838971, 29716.10851674453, 29771.255718607208, 29850.402991940475, 30474.390512189188, 26382.736172638797, 26472.470550589267, 26485.470290593694, 26801.731982679925, 27622.171334859988, 20298.0, 21644.24245151412, 21727.34817955464, 21816.454588634777, 22053.669194961767], "value": 14952.884706859582, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.3829256115826, 418.11568532553656, 424.9043965107851, 438.75429759334776, 444.6687218022574, 17361.7395739064, 17777.555561110978, 18230.723849330552, 18533.25866965323, 18543.907280463594, 18036.909815450937, 18497.16762745676, 19115.139818708158, 19412.029398530052, 19441.51396215095, 5565.0, 5586.10622300433, 5718.142278658213, 5726.68503232322, 5736.082226843706, 5777.62445441045, 5800.738966746486, 5851.13294485701, 5869.266341707286, 5869.266341707286, 5595.21666966625, 5782.3350314713925, 5783.768649254025, 5785.450382213704, 5793.652380857141, 5499.642523235977, 5754.74103665336, 5767.942320576824, 5800.9129863052085, 5801.593888427812, 5119.872003199913, 5158.561522270612, 5168.198929165947, 5184.481551844823, 5189.091908915962, 22628.660570091462, 22661.773382266132, 22984.885075574515, 23005.654915176285, 23035.1937682182, 1609.2758258783595, 1609.3723447855346, 1626.0975158786869, 1646.0946479436261, 1672.9794825156644, 13492.257925814076, 14143.0, 14172.291385430688, 14191.432342706279, 15398.769018464733, 1702.6424450865334, 1720.2688857235626, 1725.0598423859026, 1732.9480115596552, 1761.8150094240048, 89355.55322223413, 91574.0, 92121.0787892126, 92864.0, 93741.0, 11142.554297828157, 11209.215354925076, 11353.829692554618, 11498.25261358009, 11614.883851161549, 15691.686166276611, 15708.60728481797, 15720.842791572166, 15749.212539373215, 15802.683946321009, 7171.175314838756, 7270.563766174047, 7429.962850185714, 7456.0, 7467.141278752904, 6677.165354330708, 6714.899276510856, 6782.118324617768, 6905.551139175937, 6929.411000065051, 7041.612711300928, 7098.538594991309, 7142.821429464201, 7242.3010801840555, 7257.709691612382, 2056.7223424837753, 2064.9793502064786, 2122.5542636046603, 2131.6269652810697, 2205.2171479125, 842.216738433263, 866.3718803867272, 876.4390789894472, 876.9882455765635, 898.2544488074882, 15622.843771562138, 15980.520584382488, 16151.353945842035, 16426.507204783877, 16598.336066557204, 868.8305780372766, 875.8204568063659, 888.29824438694, 896.421807933879, 899.1278459893892, 8787.384883058125, 8865.822683546294, 8907.643694252158, 9300.162985331353, 9341.3928094675, 399280.0, 415778.0, 420155.0, 420374.0, 421750.0, 67798.32201678019, 83630.5818470916, 87985.5600722005, 88395.55802220947, 89033.10966890249, 1029.6705054382676, 1046.2937517175876, 1082.767205050918, 1084.5607528950788, 1086.4947799273375, 13686.589402317946, 13719.862801371859, 13845.238511881944, 13971.0, 14412.0, 38627.0, 39809.2038159241, 39907.60092399155, 41577.37633935433, 43113.0, 53941.0, 54349.0, 54666.453335467726, 54674.0, 55139.724301378235, 8191.508509489449, 8252.587370631565, 8441.873371899304, 8464.153584641486, 8703.825923481369, 12051.879481205076, 12245.326507042199, 12516.0, 12518.12373133872, 12905.483780649045, 10272.845907311246, 10615.31000484966, 10734.785304294026, 10847.0, 11138.443077846237, 10163.288569800043, 10450.843237351597, 10491.94754026225, 10583.841242381524, 10692.19808514349, 11074.944625276821, 11273.379964102052, 11480.712982175512, 11573.826392604286, 11900.76198476043, 7802.843943121219, 7893.763187104396, 8075.9501264837545, 8253.463524870864, 8645.567721613768, 10114.393136411838, 10618.575256989634, 10626.574937002742, 10774.515146818256, 11160.162987775788], "value": 8886.057017146853, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1214.4838443661445, 1258.7671280813056, 1269.5810382573745, 1271.6502961685542, 1276.744651069786, 9329.0, 9692.32153749237, 9702.757431064218, 9866.21070314375, 10092.545835437404, 9898.901010989906, 10051.095401413872, 10060.949695251531, 10061.245406594498, 10076.042775936292, 2263.920762773299, 2285.988570057147, 2303.562323158603, 2304.6197377432704, 2309.3187509684685, 2047.7849825768303, 2110.2719561751173, 2123.90618831302, 2143.65701487762, 2198.7471440784275, 2169.533550286688, 2244.1808739809935, 2290.89690963907, 2291.3126062181364, 2304.0438218139534, 2121.2999710095633, 2162.2107930605293, 2169.9891500542476, 2175.3908905506482, 2176.3579743975565, 1793.748875157479, 1825.3520000399933, 1904.561950751335, 1962.9214831406728, 2163.5132095278595, 9839.0, 9927.553260103312, 9932.80134397308, 9949.75125621858, 9951.90048099517, 5619.578531610105, 5817.7963771267805, 5825.912611310834, 5827.825165245049, 5836.358000619928, 3354.5974483061946, 3374.2745309758275, 3448.7067349743847, 3503.9649603503894, 3518.032541051206, 1072.6942821295893, 1087.9564817407295, 1115.8549388579513, 1128.3568366031366, 1144.3534403062242, 14435.422583096664, 14455.060421072596, 14517.346719397652, 14556.70886582273, 14624.195669238294, 4730.5032971538185, 4953.306537084811, 5055.7219352935945, 5080.288759573663, 5084.949150508448, 10677.626283080055, 10823.67528974132, 10843.945780271206, 10858.2399232053, 10876.836847447295, 5629.774809007676, 5672.688002159921, 5691.800786972436, 5706.3722990471015, 5712.914306285409, 5061.544460998528, 5116.744162791845, 5169.095408303532, 5210.452902445264, 5272.604554658339, 3760.6803421708914, 3849.653531182207, 3886.591907849692, 3914.2758589661003, 3921.5490218624655, 1124.701953982179, 1135.8580177477816, 1147.5811328864904, 1159.5303901919735, 1159.7158696119504, 679.9456043516606, 695.1206723494784, 699.9920115034334, 713.5896859305919, 719.8200449887528, 5319.175527793198, 5331.893362132736, 5352.545033672103, 6258.561900666909, 6272.372762723691, 803.4697099914029, 804.7505273365275, 806.2381049907781, 824.7319621123039, 852.3309202276183, 3508.1405055760993, 3608.116011577128, 3622.728295377857, 3636.8545258189906, 3920.313945059603, 12465.813012804816, 12634.684132896751, 12652.114351995271, 12735.745285094246, 13100.475980960655, 12499.312537810507, 12635.1787133836, 12714.682132946566, 12746.553870614483, 12763.042771792152, 9210.447373157633, 9434.764130896645, 9483.241340692728, 9487.952560237292, 9528.904710953078, 5466.371367292653, 5479.698616576126, 5527.751251193787, 5665.03694371966, 5727.0, 11295.152863535432, 11366.715832104264, 11384.601538946097, 11500.424978751196, 11599.710007249549, 11206.943965280121, 11543.538258469567, 11574.594889178838, 11580.247283926517, 11771.0, 8222.83554328922, 8347.83304333922, 8355.248027677539, 8362.749117526484, 8420.410571259952, 9160.083991600786, 9255.074492550692, 9335.57989890443, 9371.625134994796, 9404.670836520963, 7770.067591889009, 7881.290683838482, 7911.28798408146, 7949.562774047252, 8057.717979870677, 7471.0, 7577.924220757943, 7586.81032974158, 7796.064472263365, 7819.139894611474, 6877.312268773083, 6993.370596646327, 6997.440204783503, 6999.160100787938, 6999.755008574675, 5130.358705161855, 5187.844364669066, 5234.371875374979, 5237.2667826505085, 5447.0, 5003.774830132726, 5093.567046800915, 5265.710385928811, 5274.12976858814, 5516.668999860021], "value": 4695.919677166287, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.82444987530675, 398.91223930735237, 408.80173116038725, 410.2881500596466, 410.42745370208576, 5358.0, 5542.889142217158, 5578.693171875545, 5592.468715472029, 5636.0, 5351.464853514646, 5425.2675888755, 5436.891262174754, 5451.727413629323, 5454.509094181523, 4230.561609052921, 4246.957530424687, 4247.745135291877, 4248.723832950864, 4253.0, 4051.493563304587, 4192.170285057589, 4256.041881050622, 4280.828766849322, 4283.507396649378, 4151.0, 4155.62599366057, 4172.7496350218935, 4179.540250572434, 4201.789910504463, 4160.7087503874745, 4162.521310049353, 4167.229062623424, 4178.979105104486, 4197.664186865043, 3643.908902277438, 3644.835982380799, 3654.853805847763, 3656.323580137683, 3659.048647351681, 5188.948110518922, 5328.360596728378, 5336.546393556552, 5345.866353341159, 5373.865653358697, 4819.542143496362, 4825.324454576362, 4830.73430961297, 4831.806727730886, 4850.199717046683, 5601.048639111637, 5757.769689212427, 5764.308283006024, 5777.393373695785, 5804.013317735995, 3976.5426975897562, 4000.3399439092514, 4006.098627808749, 4012.9197416051807, 4025.194961007781, 5820.050283155106, 5986.49114825236, 6010.338862725097, 6089.695515224221, 6191.690415479208, 4130.938035929464, 4134.669226461876, 4137.420761093449, 4139.110091330378, 4160.334346504545, 4202.117555313357, 4210.663146948237, 4212.199682060399, 4222.408862759216, 4239.576042395797, 4645.953540464619, 4833.347498087784, 4840.467548569654, 4840.693012886501, 4849.515048495122, 5804.448577385142, 5809.8547536311935, 5817.331006934172, 5828.213191219216, 5829.795957141565, 1452.8982971192127, 1471.6615178509003, 1473.4990103364694, 1473.6442472924934, 1476.0700758522062, 944.4427787605208, 990.217727994876, 992.0575453319232, 998.8701468809008, 1012.3622118065572, 5064.290999260106, 5093.821716239914, 5117.820876269388, 5119.948800511947, 5120.92318615221, 6061.151258908306, 6061.302950160788, 6069.969650151721, 6074.51403887688, 6075.483583895419, 4897.632677549127, 5059.848204553869, 5072.9746351268, 5074.391073071255, 5103.591712662978, 3149.637791653989, 3201.199700074981, 3214.3571285742946, 3214.774965752422, 3217.7747557670737, 5577.8844231153935, 5592.832215033555, 5596.888062238814, 5610.7194640268635, 5623.465770751772, 5903.583140046358, 6142.846428839315, 6213.316535181127, 6252.906206406908, 6257.092721555349, 5586.720663966786, 5605.878824235169, 5634.352049514358, 5634.577406694514, 5669.149627555899, 5862.794802181903, 6050.788222412194, 6063.757449701962, 6091.847703807441, 6095.878082438415, 5971.701414929149, 5972.193753843349, 5974.1934838796205, 5976.880462390641, 5982.341942386421, 5344.385395679391, 5426.348838139448, 5521.889562208814, 5584.553235741049, 5609.60618825621, 4165.520965089054, 4179.4357761702995, 4195.1819395217635, 4200.138971510772, 4217.662586993095, 5944.970275148597, 6015.187949626745, 6062.545309101747, 6074.088886667036, 6083.148359229623, 5803.709814509173, 5830.329512105993, 5842.591018628826, 5852.376622468202, 5855.268091488564, 5818.330891947311, 5832.591718579828, 5833.591648584558, 5849.415058494116, 5879.970600146972, 5573.136163894603, 5683.9715801420725, 5695.943040569541, 5718.771249149988, 5767.596268261349, 5872.911906321494, 5892.970535147297, 5895.616784908967, 5901.409859014064, 5905.91141132892, 5444.972775136257, 5707.8858422832145, 5708.80019199326, 5737.049403202765, 5745.9425405745405], "value": 4364.806185166012, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.2337343861595, 699.3845416033889, 703.5778532880272, 721.6896734404207, 724.4638967164296, 11688.0, 11731.413429328542, 11840.407979601028, 12024.579139730111, 13382.799258011137, 12975.480980760782, 13173.802392964113, 13177.736445271114, 13200.207987520755, 13858.23779692119, 815.1888870573788, 843.8396704626132, 849.6431498770501, 849.9447535910177, 853.3812985585463, 826.8511667899776, 853.0914575976597, 861.5821326656597, 862.1378621378622, 862.1593945902727, 903.3721563513361, 903.7605034665819, 903.9785042901512, 903.9819203615924, 905.5200743605867, 893.0399820193253, 897.6050537763429, 897.6454300551246, 898.6225785170244, 899.4738078224246, 653.588239409174, 659.8581305019444, 660.7423104989058, 664.1764212376618, 668.3750693101965, 13366.532171373956, 13424.060315777899, 13965.162090274616, 14055.156690598495, 14106.858931410658, 13666.658333541742, 14227.21750303733, 14303.856961430356, 14327.426902923871, 14398.208098554574, 10568.735781605521, 10608.20438467118, 10635.096016838577, 10721.30311529748, 10748.892511074868, 2734.53512902805, 2738.6439762830905, 2775.2229375774814, 2779.3885345224016, 2793.8044336896423, 65037.52068512087, 65480.0, 65569.67215163942, 66370.66814665945, 66458.33541664618, 10827.404492752974, 10993.945030274797, 11057.668269951915, 11197.216194866282, 11569.19015668895, 21753.0, 21812.89093554522, 21870.89064554667, 22003.33989980303, 22250.554988900454, 6304.747810087545, 6374.490040796725, 6384.616922984543, 6486.156799616114, 6506.967465162644, 3192.3615276944706, 3235.4499735045097, 3261.4129456698024, 3291.4075466415798, 3345.0, 3508.947365789515, 3575.1419659281587, 3582.7850328980344, 4000.899977500586, 4160.875173744793, 714.8391611887388, 725.767754318613, 735.5660160505328, 743.5650144665432, 745.1021519069508, 526.8814516733704, 560.8009156749376, 563.2283771233436, 563.433749082178, 568.9004424225744, 8407.701741965724, 8579.613917373734, 9916.355436896578, 10355.741106472398, 10459.163266938627, 505.7395441347709, 531.2323692264717, 549.9505044546011, 561.5311215135408, 565.6125553995527, 3665.8533658653355, 3806.4290356446754, 3821.675157611634, 3936.149117588714, 3955.920881582352, 46713.0, 47743.0, 49130.26304605434, 49652.25521617178, 58372.12441813291, 49480.25779613309, 49824.0, 50613.74693126584, 51008.48991510112, 51563.74218128961, 44528.77735611301, 45368.09263814705, 46266.5373346269, 46749.0, 47446.76276618595, 9097.818043639223, 9596.23230141573, 9613.759656008655, 9618.326717129734, 9633.373830700983, 21843.344699659036, 22493.550128997656, 23352.0, 23882.52234955326, 24592.631110533, 39172.0, 41552.58447415608, 41978.37032444577, 42858.0, 43256.78371608248, 12093.879061209274, 12179.451924663234, 12184.75630487403, 12394.442250098587, 12866.67833304175, 12271.325077120488, 12324.691882703004, 12741.17182383142, 12887.48450061987, 12909.612711618667, 11576.421178941187, 11740.530378784753, 12044.648093112984, 12068.879311206776, 12223.449944752685, 11954.282743035446, 12247.326397048248, 12551.435185416496, 12555.79827144109, 12836.743265134459, 11626.593069242535, 11825.0, 12501.187422817487, 12574.497020119094, 12910.548130815376, 8602.91397086021, 8642.481451112953, 8834.337424693045, 8838.911610884066, 9003.459792412476, 9806.117449429586, 9960.950195248977, 10128.645497407555, 10134.391936483835, 10159.085682288629], "value": 6330.278474342461, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1577.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 112.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 132.0, 136.0, 173.0, 126.0, 138.0, 137.0, 128.0, 146.0, 128.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 167.0, 174.0, 167.0, 175.0, 167.0, 165.0, 165.0, 165.0], "value": 167.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 112.0, 75.0, 75.0, 76.0, 75.0, 99.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 107.0, 85.0, 84.0, 85.0, 99.0, 84.0, 84.0, 105.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 115.0, 116.0, 115.0, 115.0, 115.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 151.0, 152.0, 150.0, 151.0, 151.0, 151.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 71.0, 69.0, 69.0, 69.0, 70.0, 70.0, 70.0], "value": 69.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 61.0, 56.0, 57.0, 56.0, 56.0, 59.0, 57.0, 56.0], "value": 57.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 83.0, 89.0, 82.0, 83.0, 83.0, 85.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 141.0, 141.0, 136.0, 134.0, 143.0, 148.0, 182.0, 141.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 196.0, 196.0, 193.0, 195.0, 200.0, 211.0, 206.0, 197.0, 202.0], "value": 198.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 70.0, 71.0, 74.0, 71.0, 72.0, 70.0, 74.0, 69.0], "value": 71.9, "unit": "score"}]}]} 18:22:02 INFO - Return code: 0 18:22:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:22:02 ERROR - # TBPL SUCCESS # 18:22:02 INFO - Running post-action listener: _resource_record_post_action 18:22:02 INFO - [mozharness: 2017-03-28 01:22:02.221968Z] Finished run-tests step (success) 18:22:02 INFO - Running post-run listener: _resource_record_post_run 18:22:02 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 0; Write bytes: 364445696; Read time: 0; Write time: 527960 18:22:02 INFO - TinderboxPrint: CPU usage
13.3% 18:22:02 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:22:02 INFO - TinderboxPrint: I/O write bytes / time
364,445,696 / 527,960 18:22:02 INFO - TinderboxPrint: CPU idle
8,318.9 (86.6%) 18:22:02 INFO - TinderboxPrint: CPU user
1,220.0 (12.7%) 18:22:02 INFO - TinderboxPrint: Swap in / out
0 / 0 18:22:02 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105795584; Read time: 0; Write time: 213980 18:22:02 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 258650112; Read time: 0; Write time: 313980 18:22:02 INFO - Running post-run listener: _upload_blobber_files 18:22:02 INFO - Blob upload gear active. 18:22:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:02 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:22:02 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:22:02 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:22:03 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:22:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:04 INFO - (blobuploader) - INFO - Done attempting. 18:22:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:22:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:22:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:07 INFO - (blobuploader) - INFO - Done attempting. 18:22:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:22:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:22:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:07 INFO - (blobuploader) - INFO - Done attempting. 18:22:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:22:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:22:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:08 INFO - (blobuploader) - INFO - Done attempting. 18:22:08 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:08 INFO - Return code: 0 18:22:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab4a5c5f0e8a558d524758ae5f693457241e4c66e69ae89ad464d3a65733c386bba8a620656ce825bf70711e6e32f34daf7a6e0d43cb60a46f4d84b70097e7e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/870d3ee253abbac25025e5205a306ba762fc510196f3fedd955cab0d77660bc2e95b7f4899e29991c925f4d082e75a4b8bf005ec9f21e7698ff85f29e62a1560", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04f2571f0a881558053c597b2bd0254f0e0cffe6ce28391f881dcb2267b975305f236e05720f05b94858108152a5856ac9044335723efca7340188f02779f349", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80af9215f13d537d6919e50881c751f31b0b1c16f08746e8a873057c54b08a2823bfe10b3fe0c93936084be8e02c9c24ad7f63185c842c5b53c359ec797727f9"} 18:22:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:08 INFO - Contents: 18:22:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab4a5c5f0e8a558d524758ae5f693457241e4c66e69ae89ad464d3a65733c386bba8a620656ce825bf70711e6e32f34daf7a6e0d43cb60a46f4d84b70097e7e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/870d3ee253abbac25025e5205a306ba762fc510196f3fedd955cab0d77660bc2e95b7f4899e29991c925f4d082e75a4b8bf005ec9f21e7698ff85f29e62a1560", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04f2571f0a881558053c597b2bd0254f0e0cffe6ce28391f881dcb2267b975305f236e05720f05b94858108152a5856ac9044335723efca7340188f02779f349", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80af9215f13d537d6919e50881c751f31b0b1c16f08746e8a873057c54b08a2823bfe10b3fe0c93936084be8e02c9c24ad7f63185c842c5b53c359ec797727f9"} 18:22:08 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:08 INFO - Copying logs to upload dir... 18:22:08 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.003276 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-27 18:22:08.721973) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:22:08.725157) ========= 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=8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab4a5c5f0e8a558d524758ae5f693457241e4c66e69ae89ad464d3a65733c386bba8a620656ce825bf70711e6e32f34daf7a6e0d43cb60a46f4d84b70097e7e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/870d3ee253abbac25025e5205a306ba762fc510196f3fedd955cab0d77660bc2e95b7f4899e29991c925f4d082e75a4b8bf005ec9f21e7698ff85f29e62a1560", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04f2571f0a881558053c597b2bd0254f0e0cffe6ce28391f881dcb2267b975305f236e05720f05b94858108152a5856ac9044335723efca7340188f02779f349", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80af9215f13d537d6919e50881c751f31b0b1c16f08746e8a873057c54b08a2823bfe10b3fe0c93936084be8e02c9c24ad7f63185c842c5b53c359ec797727f9"} build_url:https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011347 build_url: 'https://queue.taskcluster.net/v1/task/eiP8qKFMSme46UT0yFw8yQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab4a5c5f0e8a558d524758ae5f693457241e4c66e69ae89ad464d3a65733c386bba8a620656ce825bf70711e6e32f34daf7a6e0d43cb60a46f4d84b70097e7e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/870d3ee253abbac25025e5205a306ba762fc510196f3fedd955cab0d77660bc2e95b7f4899e29991c925f4d082e75a4b8bf005ec9f21e7698ff85f29e62a1560", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04f2571f0a881558053c597b2bd0254f0e0cffe6ce28391f881dcb2267b975305f236e05720f05b94858108152a5856ac9044335723efca7340188f02779f349", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80af9215f13d537d6919e50881c751f31b0b1c16f08746e8a873057c54b08a2823bfe10b3fe0c93936084be8e02c9c24ad7f63185c842c5b53c359ec797727f9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:22:08.750154) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:22:08.750462) ========= 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=8783db0840cc980e80bb0bd400000236-1490662865.842169-2363243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004398 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:22:08.835012) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:22:08.835319) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:22:08.835748) ========= ========= Total master_lag: 0.17 =========