builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-063 starttime: 1490494318.91 results: success (0) revision: f5e214144799889e2408c4841351f4053f00544e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:58.909717) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:58.910156) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:58.910432) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:58.927956) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:58.928213) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-25 19:12:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2017-03-25 19:12:01 (219 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124064 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.065734) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.066005) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016579 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.114638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.114916) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f5e214144799889e2408c4841351f4053f00544e --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 f5e214144799889e2408c4841351f4053f00544e --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=769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-25 19:12:01,730 truncating revision to first 12 chars 2017-03-25 19:12:01,730 Setting DEBUG logging. 2017-03-25 19:12:01,730 attempt 1/10 2017-03-25 19:12:01,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f5e214144799?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-25 19:12:02,022 unpacking tar archive at: mozilla-central-f5e214144799/testing/mozharness/ program finished with exit code 0 elapsedTime=0.605786 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.738870) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.739163) ========= script_repo_revision: f5e214144799889e2408c4841351f4053f00544e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.739525) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.739774) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 19:11:59.750269) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-03-25 19:11:59.750544) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:12:02 INFO - MultiFileLogger online at 20170325 19:12:02 in /builds/slave/test 19:12:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 19:12:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:12:02 INFO - {'append_to_log': False, 19:12:02 INFO - 'base_work_dir': '/builds/slave/test', 19:12:02 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:12:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:12:02 INFO - 'branch': 'Firefox-Non-PGO', 19:12:02 INFO - 'buildbot_json_path': 'buildprops.json', 19:12:02 INFO - 'config_files': ('talos/linux_config.py',), 19:12:02 INFO - 'default_actions': ('clobber', 19:12:02 INFO - 'read-buildbot-config', 19:12:02 INFO - 'download-and-extract', 19:12:02 INFO - 'populate-webroot', 19:12:02 INFO - 'create-virtualenv', 19:12:02 INFO - 'install', 19:12:02 INFO - 'run-tests'), 19:12:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:12:02 INFO - 'download_minidump_stackwalk': True, 19:12:02 INFO - 'download_symbols': 'ondemand', 19:12:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:12:02 INFO - 'tooltool.py': '/tools/tooltool.py', 19:12:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:12:02 INFO - '/tools/misc-python/virtualenv.py')}, 19:12:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:12:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:12:02 INFO - 'gecko_profile': False, 19:12:02 INFO - 'gecko_profile_interval': 0, 19:12:02 INFO - 'installer_path': 'installer.exe', 19:12:02 INFO - 'log_level': 'info', 19:12:02 INFO - 'log_name': 'talos', 19:12:02 INFO - 'log_to_console': True, 19:12:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:12:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:12:02 INFO - 'opt_config_files': (), 19:12:02 INFO - 'pip_index': False, 19:12:02 INFO - 'suite': 'dromaeojs', 19:12:02 INFO - 'system_bits': '32', 19:12:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:12:02 INFO - 'title': 'talos-linux64-ix-063', 19:12:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:12:02 INFO - 'use_talos_json': True, 19:12:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:12:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:12:02 INFO - 'work_dir': 'build'} 19:12:02 INFO - [mozharness: 2017-03-26 02:12:02.465410Z] Running clobber step. 19:12:02 INFO - Running pre-action listener: _resource_record_pre_action 19:12:02 INFO - Running main action method: clobber 19:12:02 INFO - rmtree: /builds/slave/test/build 19:12:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:12:03 INFO - Running post-action listener: _resource_record_post_action 19:12:03 INFO - [mozharness: 2017-03-26 02:12:03.929068Z] Finished clobber step (success) 19:12:03 INFO - [mozharness: 2017-03-26 02:12:03.929169Z] Running read-buildbot-config step. 19:12:03 INFO - Running pre-action listener: _resource_record_pre_action 19:12:03 INFO - Running main action method: read_buildbot_config 19:12:03 INFO - Using buildbot properties: 19:12:03 INFO - { 19:12:03 INFO - "project": "", 19:12:03 INFO - "product": "firefox", 19:12:03 INFO - "who": "philringnalda@gmail.com", 19:12:03 INFO - "installer_path": "public/build/target.tar.bz2", 19:12:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:12:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:12:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 19:12:03 INFO - "stage_platform": "linux64", 19:12:03 INFO - "basedir": "/builds/slave/test", 19:12:03 INFO - "buildnumber": 514, 19:12:03 INFO - "platform": "ubuntu64_hw", 19:12:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:12:03 INFO - "slavebuilddir": "test", 19:12:03 INFO - "taskId": "d-44qGHISBm4G-BxIaUXNQ", 19:12:03 INFO - "branch": "mozilla-central", 19:12:03 INFO - "script_repo_revision": "production", 19:12:03 INFO - "revision": "f5e214144799889e2408c4841351f4053f00544e", 19:12:03 INFO - "slavename": "talos-linux64-ix-063", 19:12:03 INFO - "repo_path": "mozilla-central" 19:12:03 INFO - } 19:12:03 INFO - Finding installer, test and symbols from parent task. 19:12:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d-44qGHISBm4G-BxIaUXNQ'}, attempt #1 19:12:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d-44qGHISBm4G-BxIaUXNQ'}, attempt #1 19:12:04 INFO - Task dependencies: b_iikFB9QCyzN_mea2vFJw 19:12:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw'}, attempt #1 19:12:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.tar.bz2 19:12:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/test_packages.json 19:12:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.crashreporter-symbols.zip 19:12:05 INFO - Running post-action listener: _resource_record_post_action 19:12:05 INFO - [mozharness: 2017-03-26 02:12:05.406090Z] Finished read-buildbot-config step (success) 19:12:05 INFO - [mozharness: 2017-03-26 02:12:05.406307Z] Running download-and-extract step. 19:12:05 INFO - Running pre-action listener: _resource_record_pre_action 19:12:05 INFO - Running main action method: download_and_extract 19:12:05 INFO - mkdir: /builds/slave/test/build/tests 19:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:05 INFO - trying https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.test_packages.json 19:12:05 INFO - Downloading https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:12:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:12:06 INFO - Downloaded 986 bytes. 19:12:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:12:06 INFO - Using the following test package requirements: 19:12:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:12:06 INFO - u'common': [u'target.common.tests.zip'], 19:12:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:12:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:12:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:12:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:12:06 INFO - u'mozbase': [u'target.common.tests.zip'], 19:12:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:12:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:12:06 INFO - u'web-platform': [u'target.common.tests.zip', 19:12:06 INFO - u'target.web-platform.tests.zip'], 19:12:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:12:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:12:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.common.tests.zip 19:12:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:12:06 INFO - Fetch https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.common.tests.zip into memory 19:12:07 INFO - Expected file size: 58128619 19:12:07 INFO - Obtained file size: 58128619 19:12:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:12:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.common.tests.zip 19:12:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:12:17 INFO - Fetch https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.common.tests.zip into memory 19:12:18 INFO - Expected file size: 58128619 19:12:18 INFO - Obtained file size: 58128619 19:12:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.talos.tests.zip 19:12:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:12:29 INFO - Fetch https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.talos.tests.zip into memory 19:12:30 INFO - Expected file size: 14404171 19:12:30 INFO - Obtained file size: 14404171 19:12:30 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')]} 19:12:30 INFO - trying https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.tar.bz2 19:12:30 INFO - Downloading https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:12:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:12:32 INFO - Downloaded 62566363 bytes. 19:12:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.tar.bz2 19:12:32 INFO - mkdir: /builds/slave/test/properties 19:12:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:12:32 INFO - Writing to file /builds/slave/test/properties/build_url 19:12:32 INFO - Contents: 19:12:32 INFO - build_url:https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.tar.bz2 19:12:32 INFO - Running post-action listener: _resource_record_post_action 19:12:32 INFO - Running post-action listener: set_extra_try_arguments 19:12:32 INFO - [mozharness: 2017-03-26 02:12:32.503954Z] Finished download-and-extract step (success) 19:12:32 INFO - [mozharness: 2017-03-26 02:12:32.504127Z] Running populate-webroot step. 19:12:32 INFO - Running pre-action listener: _resource_record_pre_action 19:12:32 INFO - Running main action method: populate_webroot 19:12:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:12:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:12:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:12:32 INFO - '--tppagecycles', 19:12:32 INFO - '7'], 19:12:32 INFO - 'tests': ['tsvgm']}}, 19:12:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:12:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:12:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:12:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:12:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:12:32 INFO - 'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:12:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:12:32 INFO - 'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['damp', 'tps']}, 19:12:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:12:32 INFO - 'tests': ['damp', 'tps']}, 19:12:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['dromaeo_dom']}, 19:12:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:12:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['basic_compositor_video']}, 19:12:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:12:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['a11yr', 19:12:32 INFO - 'ts_paint', 19:12:32 INFO - 'tpaint', 19:12:32 INFO - 'sessionrestore', 19:12:32 INFO - 'sessionrestore_no_auto_restore', 19:12:32 INFO - 'tabpaint']}, 19:12:32 INFO - 'other-e10s': {'tests': ['a11yr', 19:12:32 INFO - 'ts_paint', 19:12:32 INFO - 'tpaint', 19:12:32 INFO - 'sessionrestore', 19:12:32 INFO - 'sessionrestore_no_auto_restore', 19:12:32 INFO - 'tabpaint']}, 19:12:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:12:32 INFO - 'ts_paint', 19:12:32 INFO - 'tpaint', 19:12:32 INFO - 'sessionrestore', 19:12:32 INFO - 'sessionrestore_no_auto_restore', 19:12:32 INFO - 'tabpaint']}, 19:12:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:12:32 INFO - 'ts_paint', 19:12:32 INFO - 'tpaint', 19:12:32 INFO - 'sessionrestore', 19:12:32 INFO - 'sessionrestore_no_auto_restore', 19:12:32 INFO - 'tabpaint']}, 19:12:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['a11yr', 19:12:32 INFO - 'ts_paint', 19:12:32 INFO - 'tpaint', 19:12:32 INFO - 'sessionrestore', 19:12:32 INFO - 'sessionrestore_no_auto_restore', 19:12:32 INFO - 'tabpaint']}, 19:12:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['a11yr', 19:12:32 INFO - 'ts_paint', 19:12:32 INFO - 'tpaint', 19:12:32 INFO - 'sessionrestore', 19:12:32 INFO - 'sessionrestore_no_auto_restore', 19:12:32 INFO - 'tabpaint']}, 19:12:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['tsvgx', 19:12:32 INFO - 'tsvgr_opacity', 19:12:32 INFO - 'tart', 19:12:32 INFO - 'tscrollx', 19:12:32 INFO - 'cart', 19:12:32 INFO - 'tsvg_static']}, 19:12:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:12:32 INFO - 'tsvgr_opacity', 19:12:32 INFO - 'tart', 19:12:32 INFO - 'tscrollx', 19:12:32 INFO - 'cart', 19:12:32 INFO - 'tsvg_static']}, 19:12:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:12:32 INFO - 'talos_options': ['--disable-e10s'], 19:12:32 INFO - 'tests': ['tp5o']}, 19:12:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:12:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:12:32 INFO - 'talos_options': ['--disable-e10s', 19:12:32 INFO - '--xperf_path', 19:12:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:32 INFO - 'tests': ['tp5n']}, 19:12:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:12:32 INFO - 'talos_options': ['--xperf_path', 19:12:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:32 INFO - 'tests': ['tp5n']}}, 19:12:32 INFO - 'talos.zip': {'path': '', 19:12:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:12:32 INFO - Running post-action listener: _resource_record_post_action 19:12:32 INFO - [mozharness: 2017-03-26 02:12:32.519761Z] Finished populate-webroot step (success) 19:12:32 INFO - [mozharness: 2017-03-26 02:12:32.519850Z] Running create-virtualenv step. 19:12:32 INFO - Running pre-action listener: _resource_record_pre_action 19:12:32 INFO - Running main action method: create_virtualenv 19:12:32 INFO - Creating virtualenv /builds/slave/test/build/venv 19:12:32 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 19:12:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:12:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:12:32 INFO - Using real prefix '/usr' 19:12:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:12:33 INFO - Installing distribute.............................................................................................................................................................................................done. 19:12:35 INFO - Installing pip.................done. 19:12:35 INFO - Return code: 0 19:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:12:35 INFO - Reading from file tmpfile_stdout 19:12:35 INFO - Output received: 19:12:35 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) 19:12:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:35 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 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:35 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 19:12:35 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 19:12:35 INFO - Using env: {'DISPLAY': ':0', 19:12:35 INFO - 'HOME': '/home/cltbld', 19:12:35 INFO - 'LANG': 'en_US.UTF-8', 19:12:35 INFO - 'LANGUAGE': 'en_US:en', 19:12:35 INFO - 'LOGNAME': 'cltbld', 19:12:35 INFO - 'MAIL': '/var/mail/cltbld', 19:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:35 INFO - 'MOZ_NO_REMOTE': '1', 19:12:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:35 INFO - 'NO_EM_RESTART': '1', 19:12:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:35 INFO - 'PWD': '/builds/slave/test', 19:12:35 INFO - 'SHELL': '/bin/bash', 19:12:35 INFO - 'SHLVL': '1', 19:12:35 INFO - 'TERM': 'linux', 19:12:35 INFO - 'TMOUT': '86400', 19:12:35 INFO - 'USER': 'cltbld', 19:12:35 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:35 INFO - '_': '/tools/buildbot/bin/python'} 19:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:35 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 19:12:35 INFO - Cleaning up... 19:12:35 INFO - Return code: 0 19:12:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:35 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 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:35 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 19:12:35 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 19:12:35 INFO - Using env: {'DISPLAY': ':0', 19:12:35 INFO - 'HOME': '/home/cltbld', 19:12:35 INFO - 'LANG': 'en_US.UTF-8', 19:12:35 INFO - 'LANGUAGE': 'en_US:en', 19:12:35 INFO - 'LOGNAME': 'cltbld', 19:12:35 INFO - 'MAIL': '/var/mail/cltbld', 19:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:35 INFO - 'MOZ_NO_REMOTE': '1', 19:12:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:35 INFO - 'NO_EM_RESTART': '1', 19:12:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:35 INFO - 'PWD': '/builds/slave/test', 19:12:35 INFO - 'SHELL': '/bin/bash', 19:12:35 INFO - 'SHLVL': '1', 19:12:35 INFO - 'TERM': 'linux', 19:12:35 INFO - 'TMOUT': '86400', 19:12:35 INFO - 'USER': 'cltbld', 19:12:35 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:35 INFO - '_': '/tools/buildbot/bin/python'} 19:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:35 INFO - Downloading/unpacking psutil>=3.1.1 19:12:35 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 19:12:35 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 19:12:35 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 19:12:35 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 19:12:35 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 19:12:35 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 19:12:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:12:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:37 INFO - Installing collected packages: psutil 19:12:37 INFO - Running setup.py install for psutil 19:12:37 INFO - building 'psutil._psutil_linux' extension 19:12:37 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 19:12:38 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 19:12:38 INFO - building 'psutil._psutil_posix' extension 19:12:38 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 19:12:38 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 19:12:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:38 INFO - Successfully installed psutil 19:12:38 INFO - Cleaning up... 19:12:38 INFO - Return code: 0 19:12:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:12: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 mozsystemmonitor==0.3 19:12:38 INFO - Using env: {'DISPLAY': ':0', 19:12:38 INFO - 'HOME': '/home/cltbld', 19:12:38 INFO - 'LANG': 'en_US.UTF-8', 19:12:38 INFO - 'LANGUAGE': 'en_US:en', 19:12:38 INFO - 'LOGNAME': 'cltbld', 19:12:38 INFO - 'MAIL': '/var/mail/cltbld', 19:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:38 INFO - 'MOZ_NO_REMOTE': '1', 19:12:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:38 INFO - 'NO_EM_RESTART': '1', 19:12:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:38 INFO - 'PWD': '/builds/slave/test', 19:12:38 INFO - 'SHELL': '/bin/bash', 19:12:38 INFO - 'SHLVL': '1', 19:12:38 INFO - 'TERM': 'linux', 19:12:38 INFO - 'TMOUT': '86400', 19:12:38 INFO - 'USER': 'cltbld', 19:12:38 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:38 INFO - '_': '/tools/buildbot/bin/python'} 19:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:12:38 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 19:12:38 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 19:12:38 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 19:12:38 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 19:12:38 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 19:12:38 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 19:12:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:12:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:12:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:12:40 INFO - Installing collected packages: mozsystemmonitor 19:12:40 INFO - Running setup.py install for mozsystemmonitor 19:12:40 INFO - Successfully installed mozsystemmonitor 19:12:40 INFO - Cleaning up... 19:12:40 INFO - Return code: 0 19:12:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:12:40 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')]} 19:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:40 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')]} 19:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:40 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 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:40 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 19:12:40 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 19:12:40 INFO - Using env: {'DISPLAY': ':0', 19:12:40 INFO - 'HOME': '/home/cltbld', 19:12:40 INFO - 'LANG': 'en_US.UTF-8', 19:12:40 INFO - 'LANGUAGE': 'en_US:en', 19:12:40 INFO - 'LOGNAME': 'cltbld', 19:12:40 INFO - 'MAIL': '/var/mail/cltbld', 19:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:40 INFO - 'MOZ_NO_REMOTE': '1', 19:12:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:40 INFO - 'NO_EM_RESTART': '1', 19:12:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:40 INFO - 'PWD': '/builds/slave/test', 19:12:40 INFO - 'SHELL': '/bin/bash', 19:12:40 INFO - 'SHLVL': '1', 19:12:40 INFO - 'TERM': 'linux', 19:12:40 INFO - 'TMOUT': '86400', 19:12:40 INFO - 'USER': 'cltbld', 19:12:40 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:40 INFO - '_': '/tools/buildbot/bin/python'} 19:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:41 INFO - Downloading/unpacking jsonschema==2.5.1 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:12:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:12:43 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 19:12:43 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 19:12:43 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 19:12:43 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 19:12:43 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 19:12:43 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 19:12:43 INFO - Downloading functools32-3.2.3-2.tar.gz 19:12:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:12:43 INFO - warning: no files found matching '*.txt' 19:12:43 INFO - no previously-included directories found matching 'build' 19:12:43 INFO - no previously-included directories found matching 'dist' 19:12:43 INFO - no previously-included directories found matching '.git*' 19:12:43 INFO - Installing collected packages: jsonschema, functools32 19:12:43 INFO - Running setup.py install for functools32 19:12:43 INFO - warning: no files found matching '*.txt' 19:12:43 INFO - no previously-included directories found matching 'build' 19:12:43 INFO - no previously-included directories found matching 'dist' 19:12:43 INFO - no previously-included directories found matching '.git*' 19:12:43 INFO - Successfully installed jsonschema functools32 19:12:43 INFO - Cleaning up... 19:12:43 INFO - Return code: 0 19:12:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:12:43 INFO - Using env: {'DISPLAY': ':0', 19:12:43 INFO - 'HOME': '/home/cltbld', 19:12:43 INFO - 'LANG': 'en_US.UTF-8', 19:12:43 INFO - 'LANGUAGE': 'en_US:en', 19:12:43 INFO - 'LOGNAME': 'cltbld', 19:12:43 INFO - 'MAIL': '/var/mail/cltbld', 19:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:43 INFO - 'MOZ_NO_REMOTE': '1', 19:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:43 INFO - 'NO_EM_RESTART': '1', 19:12:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:43 INFO - 'PWD': '/builds/slave/test', 19:12:43 INFO - 'SHELL': '/bin/bash', 19:12:43 INFO - 'SHLVL': '1', 19:12:43 INFO - 'TERM': 'linux', 19:12:43 INFO - 'TMOUT': '86400', 19:12:43 INFO - 'USER': 'cltbld', 19:12:43 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:43 INFO - '_': '/tools/buildbot/bin/python'} 19:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:12:43 INFO - Cleaning up... 19:12:43 INFO - Return code: 0 19:12:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:12:43 INFO - Using env: {'DISPLAY': ':0', 19:12:43 INFO - 'HOME': '/home/cltbld', 19:12:43 INFO - 'LANG': 'en_US.UTF-8', 19:12:43 INFO - 'LANGUAGE': 'en_US:en', 19:12:43 INFO - 'LOGNAME': 'cltbld', 19:12:43 INFO - 'MAIL': '/var/mail/cltbld', 19:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:43 INFO - 'MOZ_NO_REMOTE': '1', 19:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:43 INFO - 'NO_EM_RESTART': '1', 19:12:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:43 INFO - 'PWD': '/builds/slave/test', 19:12:43 INFO - 'SHELL': '/bin/bash', 19:12:43 INFO - 'SHLVL': '1', 19:12:43 INFO - 'TERM': 'linux', 19:12:43 INFO - 'TMOUT': '86400', 19:12:43 INFO - 'USER': 'cltbld', 19:12:43 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:43 INFO - '_': '/tools/buildbot/bin/python'} 19:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:44 INFO - Downloading/unpacking blobuploader==1.2.4 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:12:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:12:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12:46 INFO - Downloading docopt-0.6.1.tar.gz 19:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:12:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:12:46 INFO - Running setup.py install for blobuploader 19:12:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:12:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:12:46 INFO - Running setup.py install for requests 19:12:47 INFO - Running setup.py install for docopt 19:12:47 INFO - Successfully installed blobuploader requests docopt 19:12:47 INFO - Cleaning up... 19:12:47 INFO - Return code: 0 19:12:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:47 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 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:47 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 19:12:47 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 19:12:47 INFO - Using env: {'DISPLAY': ':0', 19:12:47 INFO - 'HOME': '/home/cltbld', 19:12:47 INFO - 'LANG': 'en_US.UTF-8', 19:12:47 INFO - 'LANGUAGE': 'en_US:en', 19:12:47 INFO - 'LOGNAME': 'cltbld', 19:12:47 INFO - 'MAIL': '/var/mail/cltbld', 19:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:47 INFO - 'MOZ_NO_REMOTE': '1', 19:12:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:47 INFO - 'NO_EM_RESTART': '1', 19:12:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:47 INFO - 'PWD': '/builds/slave/test', 19:12:47 INFO - 'SHELL': '/bin/bash', 19:12:47 INFO - 'SHLVL': '1', 19:12:47 INFO - 'TERM': 'linux', 19:12:47 INFO - 'TMOUT': '86400', 19:12:47 INFO - 'USER': 'cltbld', 19:12:47 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:47 INFO - '_': '/tools/buildbot/bin/python'} 19:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:47 INFO - Running setup.py (path:/tmp/pip-77c6lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:47 INFO - Running setup.py (path:/tmp/pip-72pXgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:47 INFO - Running setup.py (path:/tmp/pip-ApS3jG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:47 INFO - Running setup.py (path:/tmp/pip-JFzWLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:47 INFO - Running setup.py (path:/tmp/pip-kuwX4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:48 INFO - Running setup.py (path:/tmp/pip-_Vd_qL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:48 INFO - Running setup.py (path:/tmp/pip-GeJsag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:48 INFO - Running setup.py (path:/tmp/pip-DvM9fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:48 INFO - Running setup.py (path:/tmp/pip-9ABarK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:48 INFO - Running setup.py (path:/tmp/pip-Zfc46C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:48 INFO - Running setup.py (path:/tmp/pip-9tKGBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:48 INFO - Running setup.py (path:/tmp/pip-dL69wY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:48 INFO - Running setup.py (path:/tmp/pip-OSIh9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:48 INFO - Running setup.py (path:/tmp/pip-YI39AM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:48 INFO - Running setup.py (path:/tmp/pip-AwTm2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:48 INFO - Running setup.py (path:/tmp/pip-3zUOXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:48 INFO - Running setup.py (path:/tmp/pip-1sKdwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:12:48 INFO - Running setup.py install for manifestparser 19:12:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:12:48 INFO - Running setup.py install for mozcrash 19:12:49 INFO - Running setup.py install for mozdebug 19:12:49 INFO - Running setup.py install for mozdevice 19:12:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Running setup.py install for mozfile 19:12:49 INFO - Running setup.py install for mozhttpd 19:12:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Running setup.py install for mozinfo 19:12:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Running setup.py install for mozInstall 19:12:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Running setup.py install for mozleak 19:12:49 INFO - Running setup.py install for mozlog 19:12:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Running setup.py install for moznetwork 19:12:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:12:49 INFO - Running setup.py install for mozprocess 19:12:50 INFO - Running setup.py install for mozprofile 19:12:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:12:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:12:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:12:50 INFO - Running setup.py install for mozrunner 19:12:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:12:50 INFO - Running setup.py install for mozscreenshot 19:12:50 INFO - Running setup.py install for moztest 19:12:50 INFO - Running setup.py install for mozversion 19:12:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:12:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:12:50 INFO - Cleaning up... 19:12:50 INFO - Return code: 0 19:12:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:50 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 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:50 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 19:12:50 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 19:12:50 INFO - Using env: {'DISPLAY': ':0', 19:12:50 INFO - 'HOME': '/home/cltbld', 19:12:50 INFO - 'LANG': 'en_US.UTF-8', 19:12:50 INFO - 'LANGUAGE': 'en_US:en', 19:12:50 INFO - 'LOGNAME': 'cltbld', 19:12:50 INFO - 'MAIL': '/var/mail/cltbld', 19:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:50 INFO - 'MOZ_NO_REMOTE': '1', 19:12:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:50 INFO - 'NO_EM_RESTART': '1', 19:12:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:50 INFO - 'PWD': '/builds/slave/test', 19:12:50 INFO - 'SHELL': '/bin/bash', 19:12:50 INFO - 'SHLVL': '1', 19:12:50 INFO - 'TERM': 'linux', 19:12:50 INFO - 'TMOUT': '86400', 19:12:50 INFO - 'USER': 'cltbld', 19:12:50 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:50 INFO - '_': '/tools/buildbot/bin/python'} 19:12:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:50 INFO - Running setup.py (path:/tmp/pip-iI2JBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:50 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)) 19:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:50 INFO - Running setup.py (path:/tmp/pip-6ON16U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:51 INFO - Running setup.py (path:/tmp/pip-QNfQv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:51 INFO - Running setup.py (path:/tmp/pip-KecQVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:51 INFO - Running setup.py (path:/tmp/pip-21NuOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:51 INFO - Running setup.py (path:/tmp/pip-XGfHrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:51 INFO - Running setup.py (path:/tmp/pip-0sd0M2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:51 INFO - Running setup.py (path:/tmp/pip-XUj8dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:51 INFO - Running setup.py (path:/tmp/pip-DeY0UC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:51 INFO - Running setup.py (path:/tmp/pip-CetfeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:51 INFO - Running setup.py (path:/tmp/pip-RiP2BM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:51 INFO - Running setup.py (path:/tmp/pip-UWYCFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:51 INFO - Running setup.py (path:/tmp/pip-ZTtnr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:51 INFO - Running setup.py (path:/tmp/pip-svokPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:51 INFO - Running setup.py (path:/tmp/pip-7eUDBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:51 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)) 19:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:51 INFO - Running setup.py (path:/tmp/pip-MXGlFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:52 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)) 19:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:52 INFO - Running setup.py (path:/tmp/pip-o6R3Rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:52 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)) 19:12:52 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)) 19:12:52 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)) 19:12:52 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)) 19:12:52 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)) 19:12:52 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)) 19:12:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:54 INFO - Downloading blessings-1.6.tar.gz 19:12:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:12:54 INFO - Installing collected packages: blessings 19:12:54 INFO - Running setup.py install for blessings 19:12:54 INFO - Successfully installed blessings 19:12:54 INFO - Cleaning up... 19:12:54 INFO - Return code: 0 19:12:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:12:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:54 INFO - Reading from file tmpfile_stdout 19:12:54 INFO - Current package versions: 19:12:54 INFO - argparse == 1.2.1 19:12:54 INFO - blessings == 1.6 19:12:54 INFO - blobuploader == 1.2.4 19:12:54 INFO - docopt == 0.6.1 19:12:54 INFO - functools32 == 3.2.3-2 19:12:54 INFO - jsonschema == 2.5.1 19:12:54 INFO - manifestparser == 1.1 19:12:54 INFO - mozInstall == 1.12 19:12:54 INFO - mozcrash == 1.0 19:12:54 INFO - mozdebug == 0.1 19:12:54 INFO - mozdevice == 0.49 19:12:54 INFO - mozfile == 1.2 19:12:54 INFO - mozhttpd == 0.7 19:12:54 INFO - mozinfo == 0.9 19:12:54 INFO - mozleak == 0.1 19:12:54 INFO - mozlog == 3.4 19:12:54 INFO - moznetwork == 0.27 19:12:54 INFO - mozprocess == 0.25 19:12:54 INFO - mozprofile == 0.28 19:12:54 INFO - mozrunner == 6.13 19:12:54 INFO - mozscreenshot == 0.1 19:12:54 INFO - mozsystemmonitor == 0.3 19:12:54 INFO - moztest == 0.8 19:12:54 INFO - mozversion == 1.4 19:12:54 INFO - psutil == 3.1.1 19:12:54 INFO - requests == 1.2.3 19:12:54 INFO - wsgiref == 0.1.2 19:12:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:54 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 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:54 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 19:12:54 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 19:12:54 INFO - Using env: {'DISPLAY': ':0', 19:12:54 INFO - 'HOME': '/home/cltbld', 19:12:54 INFO - 'LANG': 'en_US.UTF-8', 19:12:54 INFO - 'LANGUAGE': 'en_US:en', 19:12:54 INFO - 'LOGNAME': 'cltbld', 19:12:54 INFO - 'MAIL': '/var/mail/cltbld', 19:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:54 INFO - 'MOZ_NO_REMOTE': '1', 19:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:54 INFO - 'NO_EM_RESTART': '1', 19:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:54 INFO - 'PWD': '/builds/slave/test', 19:12:54 INFO - 'SHELL': '/bin/bash', 19:12:54 INFO - 'SHLVL': '1', 19:12:54 INFO - 'TERM': 'linux', 19:12:54 INFO - 'TMOUT': '86400', 19:12:54 INFO - 'USER': 'cltbld', 19:12:54 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:54 INFO - '_': '/tools/buildbot/bin/python'} 19:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 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)) 19:12:54 INFO - Cleaning up... 19:12:54 INFO - Return code: 0 19:12:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23061f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', '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 19:12:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:12:54 INFO - Using env: {'DISPLAY': ':0', 19:12:54 INFO - 'HOME': '/home/cltbld', 19:12:54 INFO - 'LANG': 'en_US.UTF-8', 19:12:54 INFO - 'LANGUAGE': 'en_US:en', 19:12:54 INFO - 'LOGNAME': 'cltbld', 19:12:54 INFO - 'MAIL': '/var/mail/cltbld', 19:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:54 INFO - 'MOZ_NO_REMOTE': '1', 19:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:54 INFO - 'NO_EM_RESTART': '1', 19:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:54 INFO - 'PWD': '/builds/slave/test', 19:12:54 INFO - 'SHELL': '/bin/bash', 19:12:54 INFO - 'SHLVL': '1', 19:12:54 INFO - 'TERM': 'linux', 19:12:54 INFO - 'TMOUT': '86400', 19:12:54 INFO - 'USER': 'cltbld', 19:12:54 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:54 INFO - '_': '/tools/buildbot/bin/python'} 19:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:12:55 INFO - Cleaning up... 19:12:55 INFO - Return code: 0 19:12:55 INFO - Running post-action listener: _resource_record_post_action 19:12:55 INFO - Running post-action listener: _start_resource_monitoring 19:12:55 INFO - Starting resource monitoring. 19:12:55 INFO - [mozharness: 2017-03-26 02:12:55.108796Z] Finished create-virtualenv step (success) 19:12:55 INFO - [mozharness: 2017-03-26 02:12:55.109470Z] Running install step. 19:12:55 INFO - Running pre-action listener: _resource_record_pre_action 19:12:55 INFO - Running main action method: install 19:12:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:55 INFO - Reading from file tmpfile_stdout 19:12:55 INFO - Detecting whether we're running mozinstall >=1.0... 19:12:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:12:55 INFO - Reading from file tmpfile_stdout 19:12:55 INFO - Output received: 19:12:55 INFO - Usage: mozinstall [options] installer 19:12:55 INFO - Options: 19:12:55 INFO - -h, --help show this help message and exit 19:12:55 INFO - -d DEST, --destination=DEST 19:12:55 INFO - Directory to install application into. [default: 19:12:55 INFO - "/builds/slave/test"] 19:12:55 INFO - --app=APP Application being installed. [default: firefox] 19:12:55 INFO - mkdir: /builds/slave/test/build/application 19:12:55 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'] 19:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:13:11 INFO - Reading from file tmpfile_stdout 19:13:11 INFO - Output received: 19:13:11 INFO - /builds/slave/test/build/application/firefox/firefox 19:13:11 INFO - Running post-action listener: _resource_record_post_action 19:13:11 INFO - [mozharness: 2017-03-26 02:13:11.078814Z] Finished install step (success) 19:13:11 INFO - [mozharness: 2017-03-26 02:13:11.079090Z] Running run-tests step. 19:13:11 INFO - Running pre-action listener: _resource_record_pre_action 19:13:11 INFO - Running main action method: run_tests 19:13:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:13:11 INFO - Python 2.7.3 19:13:11 INFO - Return code: 0 19:13:11 INFO - grabbing minidump binary from tooltool 19:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:11 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 0x25a1290>, '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 0x25a1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25a2040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:13:11 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 19:13:11 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 19:13:11 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 19:13:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:13:11 INFO - Return code: 0 19:13:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:13:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:13:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:13:11 INFO - ENV: RUST_BACKTRACE is now 1 19:13:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:13:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:13:11 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:13:11 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-063 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:13:11 INFO - Using env: {'DISPLAY': ':0', 19:13:11 INFO - 'HOME': '/home/cltbld', 19:13:11 INFO - 'LANG': 'en_US.UTF-8', 19:13:11 INFO - 'LANGUAGE': 'en_US:en', 19:13:11 INFO - 'LOGNAME': 'cltbld', 19:13:11 INFO - 'MAIL': '/var/mail/cltbld', 19:13:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:13:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:11 INFO - 'MOZ_NO_REMOTE': '1', 19:13:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:13:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:11 INFO - 'NO_EM_RESTART': '1', 19:13:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:11 INFO - 'PWD': '/builds/slave/test', 19:13:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:13:11 INFO - 'RUST_BACKTRACE': '1', 19:13:11 INFO - 'SHELL': '/bin/bash', 19:13:11 INFO - 'SHLVL': '1', 19:13:11 INFO - 'TERM': 'linux', 19:13:11 INFO - 'TMOUT': '86400', 19:13:11 INFO - 'USER': 'cltbld', 19:13:11 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521', 19:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:11 INFO - '_': '/tools/buildbot/bin/python'} 19:13:11 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:13:11 INFO - mozversion application_buildid: 20170326014008 19:13:11 INFO - mozversion application_changeset: f5e214144799889e2408c4841351f4053f00544e 19:13:11 INFO - mozversion application_display_name: Nightly 19:13:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:13:11 INFO - mozversion application_name: Firefox 19:13:11 INFO - mozversion application_remotingname: firefox 19:13:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:13:11 INFO - mozversion application_vendor: Mozilla 19:13:11 INFO - mozversion application_version: 55.0a1 19:13:11 INFO - mozversion platform_buildid: 20170326014008 19:13:11 INFO - mozversion platform_changeset: f5e214144799889e2408c4841351f4053f00544e 19:13:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:13:11 INFO - mozversion platform_version: 55.0a1 19:13:11 INFO - using testdate: 1490494391 19:13:11 INFO - actual date: 1490494391 19:13:11 INFO - starting webserver on 'localhost:38219' 19:13:11 INFO - SUITE-START | Running 2 tests 19:13:11 INFO - TEST-START | dromaeo_css 19:13:11 INFO - operating with platform_type : linux_ 19:13:11 INFO - Initialising browser for dromaeo_css test... 19:13:11 INFO - TEST-INFO | started process 15854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyApMgD/profile http://localhost:38219/getInfo.html) 19:13:15 INFO - PID 15854 | __metrics Screen width/height:1600/1200 19:13:15 INFO - PID 15854 | colorDepth:24 19:13:15 INFO - PID 15854 | Browser inner width/height: 1024/697 19:13:15 INFO - PID 15854 | __metrics 19:13:16 ERROR - PID 15854 | 1490494396531 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 19:13:16 INFO - PID 15854 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:13:16 INFO - PID 15854 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:13:17 INFO - TEST-INFO | 15854: exit 0 19:13:17 INFO - Browser initialized. 19:13:17 INFO - Running cycle 1/1 for dromaeo_css test... 19:13:17 INFO - TEST-INFO | started process 15948 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyApMgD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:29 INFO - PID 15948 | RSS: Main: 149323776 19:13:29 INFO - PID 15948 | 19:16:35 INFO - PID 15948 | Cycle 1(1): loaded http://localhost:38219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38219/tests/dromaeo/cssquery-ext.html) 19:16:38 INFO - PID 15948 | RSS: Main: 371159040 19:16:38 INFO - PID 15948 | 19:18:19 INFO - PID 15948 | ************************* 19:18:19 INFO - PID 15948 | A coding exception was thrown and uncaught in a Task. 19:18:19 INFO - PID 15948 | 19:18:19 ERROR - PID 15948 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:18:19 INFO - PID 15948 | Full stack: 19:18:19 INFO - PID 15948 | ************************* 19:18:19 INFO - PID 15948 | ************************* 19:18:19 INFO - PID 15948 | A coding exception was thrown and uncaught in a Task. 19:18:19 INFO - PID 15948 | 19:18:19 ERROR - PID 15948 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:18:19 INFO - PID 15948 | Full stack: 19:18:19 INFO - PID 15948 | ************************* 19:18:19 INFO - PID 15948 | ************************* 19:18:19 INFO - PID 15948 | A coding exception was thrown and uncaught in a Task. 19:18:19 INFO - PID 15948 | 19:18:19 ERROR - PID 15948 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:18:19 INFO - PID 15948 | Full stack: 19:18:19 INFO - PID 15948 | ************************* 19:18:19 INFO - PID 15948 | ************************* 19:18:19 INFO - PID 15948 | A coding exception was thrown and uncaught in a Task. 19:18:19 INFO - PID 15948 | 19:18:19 ERROR - PID 15948 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:18:19 INFO - PID 15948 | Full stack: 19:18:19 INFO - PID 15948 | ************************* 19:19:41 INFO - PID 15948 | Cycle 1(1): loaded http://localhost:38219/tests/dromaeo/cssquery-ext.html (next: http://localhost:38219/tests/dromaeo/cssquery-jquery.html) 19:19:42 INFO - PID 15948 | RSS: Main: 244928512 19:19:42 INFO - PID 15948 | 19:22:44 INFO - PID 15948 | Cycle 1(1): loaded http://localhost:38219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38219/tests/dromaeo/cssquery-mootools.html) 19:22:44 INFO - PID 15948 | RSS: Main: 183115776 19:22:44 INFO - PID 15948 | 19:25:47 INFO - PID 15948 | Cycle 1(1): loaded http://localhost:38219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38219/tests/dromaeo/cssquery-prototype.html) 19:25:47 INFO - PID 15948 | RSS: Main: 198742016 19:25:47 INFO - PID 15948 | 19:28:51 INFO - PID 15948 | Cycle 1(1): loaded http://localhost:38219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38219/tests/dromaeo/cssquery-yui.html) 19:28:51 INFO - PID 15948 | RSS: Main: 186097664 19:28:51 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | Cycle 1(1): loaded http://localhost:38219/tests/dromaeo/cssquery-yui.html (next: http://localhost:38219/tests/dromaeo/cssquery-dojo.html) 19:31:54 INFO - PID 15948 | RSS: Main: 278683648 19:31:54 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | __start_tp_report 19:31:54 INFO - PID 15948 | _x_x_mozilla_page_load 19:31:54 INFO - PID 15948 | _x_x_mozilla_page_load_details 19:31:54 INFO - PID 15948 | |i|pagename|runs| 19:31:54 INFO - PID 15948 | |0;dojo.html;2698.0421950207674;2713.9185824425276;2728.195182421186;2743.3827388837503;2751.339678477166;5170.844874653757;5254.658447200929;5270.051390749663;5297.046531624311;5350.518453339204;5210.609204309682;5221.164613661815;5247.790088396469;5249.081410753112;5264.710440925757;4736.573708366246;4764.404449443819;4782.928256076161;4815.5906747926465;4833.347498087766;4763.356946812188;4797.664163508556;4825.734584597846;4840.443349014856;4846.273059041155;4891.6331275154325;4892.926606100911;4895.11887860185;4914.385701787277;4941.45643979162;651.7923955316787;4725.291206319045;4867.123917694814;4886.804527818883;4891.16850135476;4208.810603522848;4689.249720044776;4801.663883528155;4802.087603355386;4813.470518242991;5274.261603375492;5280.894382112337;5285.2600635911;5290.100682883881;5293.285406470154;5664.433556644344;5775.768969241268;5783.0747080467345;5783.392743761887;5832.912506312408;5549.1676248562635;5636.15457681347;5637.915431268493;5710.543156547507;5743.052396354597;5320;5321.068812957717;5322.494363035506;5350.785968561292;5369.24830523727;5863.70681465925;5865.73604187804;5869.207656966255;5869.354371019184;5869.677167755729;5369.892602148013;5436.728163591804;5437.700926449004;5576.749046292926;5578.497935185772;5673.092305231144;5698.458646428583;5699.800506982236;5777;5785.942140578624;5493.176023596492;5568.860778480489;5573.721313934287;5630.23991761107;5761.107028410603;5686.658800471985;5694.971525142348;5695.1741997410145;5729.455701708331;5749.9425005750245;4683.1336202802595;4842.370491836039;4851.684640498427;4856.854294371175;4883.682560633619;4031.2945234584417;4051.068254301468;4052.169305292408;4065.308897487434;4070.6539944105084;4776.450708168605;4778.808847646124;4781.192915574622;4784.425868895685;4828.782704778293;5571.0807716727095;5622;5622.97188514063;5625.099984002622;5630.324361076697;4743.264793956942;4811.879703007383;4865.416150061945;4910.631702622318;4922.458529561745;5144.43411224765;5145.4597267287145;5145.716985565755;5146.948530514647;5189.974050129726;5307.920381194208;5353.437889021752;5353.892922141613;5360;5407.756650950638;5548.778048878161;5556.305461817273;5614.466625670555;5697.401772813961;5702.315722113373;5201.6098792591465;5203.843884683465;5205.141151709925;5210.583153347648;5215.739213039257;5414.0796064669885;5416.566674665938;5419.2954915862265;5438.864028399322;5467.72661366938;5563.721813909207;5567.137093750475;5568.387477377404;5570.554355651457;5571.804986825442;5440.918386224289;5501.669899806025;5502.202180683858;5504.339479262514;5504.504594586347;5490.258815060076;5512.6968016759465;5513.58648101386;5519.089350257163;5520.723963801875;5440.020796256554;5444.8094316698725;5458.099413596712;5469.917951230815;5499.395066542757;5620.381758006534;5622.550195984393;5637.718114094197;5640.210370548209;5648.4633959773755;5252.527272545489;5325.707086110301;5415.59383046281;5424.7558859850615;5479.369872464717 19:31:54 INFO - PID 15948 | |1;ext.html;722.050503587782;737.7823542055091;1069.1553672598648;1083.4799296337753;1085.5277954089972;30318.545221821663;31633.683663163363;31747.04758857238;31782.205444863863;31793.52309715356;32546;32905.17737056575;32971.505427418546;33004;33093.17267068318;5491.231227628115;5580.218769372291;7217.06178196835;7356.1172659280965;7358.411327093846;7052;7217.530860494077;7226.313500217471;7227.602481863495;7251.383632391253;7314.232005639385;7378.040854688883;7378.74174403896;7380;7381.372583330423;7437.776866694008;7503.812404689872;7526.510776799545;7529.284717951786;7533.397328213785;7266.091738532684;7337.523061001018;7345.375643070345;7353.044104266464;7359.153697324823;33201.501977470354;33481.16297092592;33532.83233583817;33563.16092097693;33613.6638633613;2583.6124581312765;2604.6744156980376;2604.9088281910044;2612.7256638052922;2661.0420248710448;22980.65529017066;23027.42431439211;23362.182323618763;23456.413589660395;23486.882565587235;2935.7357837794702;2997.1008697390694;3004.2939909121337;3008.247938015496;3014.954775678367;154471.99999999886;154693.22653386774;154773;155066.99999999886;155232.2238388812;34044.14889627729;34586.30827383438;36667.26665466676;41729;41926.79036604797;38613.42079868804;38712.61287387146;38747.41878871819;38860;38912;10151;10286.125679317192;11760.76478470443;11900.464479098462;11961.10291728124;9660.227181825438;9664.855027174597;9667.226621870233;9669.468179250209;9669.485933657159;11926.1651684383;12172.513099476098;12202.511899523919;12322.876771232352;12386.3187524687;4710.057988402334;4791.31686255698;4819.783109760069;4844.491328410537;4865.9756701216265;865.1824026295217;876.8509353409936;879.4942907828024;880.9515476648718;884.9380543362032;20390.79609203889;22598.774012259997;24281;24293.392665183514;24400.389990250387;930.7440453875239;931.1666058877267;939.4269495607674;958.9136977672043;968.5447839515416;10845.51195196218;11371.545138194546;11378.886211137948;11565.537378504934;11613.187076904536;197990;201092;201707;202385;218401;42845.57154428478;43163.78418107952;43244;43269.56730432718;43381.34927976083;1183.5483823363056;1217.220978573696;1217.5255934440895;1217.945192466341;1240.0761432732672;22547.5490490186;22602.434939126655;23661.763382365956;23708.288751337488;23771.762282377647;29387;29447.96932107366;29450;29478.852605736833;29482.705172948856;153277.23361383122;156104;158900;162091;162381;24956.251312460656;26364.868175658998;26549.734502654726;26750.197494075208;26809.463810723286;31259.687403126587;31405.371892562478;31582.36835263236;31974.52038219475;35419.645803541636;25756.742432575433;26083.217503474927;26164.34589135287;26744.59883101794;27925.16224513268;24966.37584060413;25340.49319013646;25603.615945760455;25912.870435647703;26554;27551.862240688668;27704.58443123395;27777.44445111127;27799.861000694866;28339.43321133607;25073;25701;25854.482910342063;26864.32839179036;27583.586246206723;20638.690419643415;21687.783122168577;21691.674624880325;21737.565248695253;21749.782502174774 19:31:54 INFO - PID 15948 | |2;jquery.html;363.8580953428165;408.78538767147245;411.6048593350383;413.8944569134871;430.76092768513485;11914.285142891422;13321.600351989444;17361.392351267692;18077.09614519272;18271.54321141969;16926.40757573485;17077.57306067349;17750.556236094104;17782.199801008952;18241.179146938386;5659.943400566003;5844.766209351621;5847.093700476433;5856.853578660525;5857.26784151981;5391.892162156754;5418.078926582471;5441.455854414547;5454.781808727646;5464.9180262295895;5430.456954304577;5439.265699130626;5445.618806683534;5446.15584584388;5452.318460192476;5289.312389389374;5648.152777083429;5663;5669.9433005669825;5680.687562184078;4965.4000849384265;5068.82259120929;5113.437521872628;5117.385913690381;5119.283300337956;20397.38807835767;20759;20968.79031209699;21085.156593736232;21101;1540.614846288428;1558.2208895552224;1566.4752307976842;1587.1588058329105;1613.0563620282148;13346.466141354334;13460.259685717281;13602.523911663044;13703.588892333246;14130.293485325692;1710.7604935308943;1764.1091248919213;1783.8540374827612;1784.9464516064538;1787.2046939112101;67477.66261168662;75036.62481687557;75305.6234718823;75551.622241889;76915.6154219231;10054.74863128413;10081.848772268422;10266.743331416774;10451.477426128815;10524.947895060457;14207.502737404142;14216.147031178163;14216.218108003952;14248.928755356157;14267.64330891736;7000.334968178015;7307.89038164428;7318.121825380882;7397.630118494054;7500.36246919007;6897.379235868695;7002.859942801218;7115.964420177866;7131.322524360177;7138.464615153885;6418.679066046679;6613.834654133685;6617.735290588419;6621.86756264872;6629.966850165718;1822.3166312632763;1889.6220755848885;1918.1464248409325;1931.9806801932082;1955.9315423960093;844.9408541402166;865.5542395666237;868.1665601023088;880.7225723896952;887.2148148888151;15845.366185352457;16347.836521634868;16435.09606971628;16463.588410289838;17073.146342682816;883.9823203535893;886.9733907982771;891.0777345447431;898.8831451911341;907.3421769217265;8767;8874.112588741074;9153.130452606993;9189.264858811279;9299.209567186865;410123;412111;413272;415760;422535;86068;86730;87912.5604371974;89684;89958;1024.114141267798;1034.6689059501039;1063.473580577619;1064.302881612545;1065.8667666541683;14137.363818628392;14192.219427931563;14235.64410889736;14374.209418482083;15122.621934451727;40062.79968600255;41149.382759258035;41471.37792933169;41542;43145;51472.74263628658;51776;52034;52106;54396.456035439136;7888.684452621831;7909.446338756462;7921.366290696846;7984.321332686786;8320.500769953822;11908.97547416924;12105.152639315163;12169.39153042362;12211.26732396059;12328;10637.202209834139;10753.892461075602;10798.946005270238;10852.620158294421;10972.780544389227;8834.734957951272;8960.462372257685;9025.909740902769;9126.908730912606;9618.182454491447;10271.7432064199;10305.639302624671;10492.52783624754;10724.678259652223;10861.619843305447;7660;7664.501807382502;7721.150673426031;7779.494332868572;7971.681132754625;9547;9576.329656923948;9587.089226523469;9610.807783844144;9966.950165249127 19:31:54 INFO - PID 15948 | |3;mootools.html;1115.6485707002298;1255.240579449433;1300.9804852927202;1319.7492476429475;1320.0693511074694;8216.219459151385;9440.905590944089;9730.367526110807;9768.169705575036;9788.85316720248;9509.857352139723;9607.183389411908;9611;9611.134997850191;9612.423254604728;2088.2586681727958;2127.425595089325;2150.43013601395;2151.4943988162768;2154.547545015541;1916.2047750183658;1993.6710442776925;2207.995362110236;2266.3880752196897;2275.101334972685;2203.636399993999;2222.388843068153;2225.276785044859;2226.8329875259337;2231.241377931503;2193;2233.7654546272734;2234.4525591230254;2238.2166241815235;2238.4851484158735;2028.5334373094124;2083.260442542891;2163.113123619324;2196.176433837311;2196.6924630551734;9770.609175633037;9819.410835349901;9854.655087071918;9859.852102218472;9870.851937220947;5310.256564081032;5505.862353441156;5506.339239291271;5511.173324001432;5531.668099913978;2986.8954586589584;3205.5351973963643;3273.2307907641684;3277.852496637657;3298.208429976812;1040.8646875906159;1044.9101796407185;1065.4140222877386;1084.1852045691357;1086.9782604347947;14021.369038393297;14082.366293516714;14100.365483553265;14113.364898579588;14183.645408864757;4683.742394168354;4941.802327906844;5037.420696619935;5096.210087436453;5141.897162056738;12041.939790300992;12056.336901470504;12063.336516491501;12071.336076515876;12087.093467989936;5665.5467562594895;5666.206731057655;5672.7730890763905;5677.545796336283;5678.205051292823;4263.147370525845;4705.788239529229;4724.645651576146;5016.523430274118;5126.6923984561045;3686.262747450467;4081.6122468365456;4140.875773726732;4162.854300099542;4177.143685544456;1252.1860790486257;1286.273255610577;1291.6060601516629;1299.5827760365712;1316.374722007039;700.6041586503711;704.4364508393265;717;726.5023458930649;745.7986343687174;5386.245925570423;5500.917486237709;5574.439157036037;5632.127020311858;5871.295444546597;802.4984384759525;804.2520455975881;808.8180159464074;827.2017503109408;833.1751565949667;3595.227026189382;3801.8669346572738;3884.436756670267;3922.4312474691583;3943.0142464383903;12069.637910862688;12796.61610151697;12814.871851281554;12835.101542891907;12838.486460541473;12418.75162496763;12738.745225095632;12870.871291287154;12946.417411216518;13028.348582570834;8831.55842207887;9101.635934562675;9252.676156334495;9284.62861485533;9357.859632105525;5675.602707810413;5723.771049157987;5774.82675519735;5853.268341457318;5969.104634304889;11483.425828708698;11678.591249306233;11739.47172377228;11744.236624619372;11827.763444731228;11784.410779461165;11873.287602743863;11890.34603096838;11932.880671193177;11952.58165964187;8146.2261085196815;8368.623411946355;8382.58087095655;8384.748457546284;8396.076431592397;7550.320471157622;8698.60856261457;9077.228435583049;9084.364094513321;9205.217556507772;7738.362073259291;7942.205779422012;8009.719659811879;8035.196480352152;8102.392320575863;7710.575918324322;7796.10344810354;7801.375889928906;7806.92193078062;7861.174576669596;6948.756793512202;7082.18554866197;7115.2528984455885;7116.1460624723295;7119.786406407816;5216.426193118829;5229.294045303835;5262.789488420421;5273.762680679455;5512.062949298709;5138.3833939927445;5146.228065790161;5212.296339994053;5241.60687948413;5409.675419474844 19:31:54 INFO - PID 15948 | |4;prototype.html;375.6506449002428;401.91559772447783;407.62905755762245;407.9877603671891;407.9979600101998;5381.56947444204;5403.83788486345;5411.323584551931;5412.512873841353;5459.344878614562;5071.847844564669;5230.320058392414;5265.736713164336;5273.209018647195;5329.840104796853;4137.151883863816;4140.31684772013;4141.792910354486;4146.60607242313;4154.127633197032;4140.275451795938;4141.813618387165;4142.17156568686;4151.398047283142;4155;3907.257621052005;4041.6564592009713;4100;4110.958890411102;4117.238310912476;4066.207089617526;4090.284200264957;4092.0997380576373;4094.3858421236755;4101.097758493127;3587.515685382466;3590.6355584877842;3591.8742844000594;3594.209273959751;3603.171270607775;4937.506249375069;5177.119889618736;5179.766910488999;5180.740962951837;5194.091034069061;4697.436307643105;4732.763361831895;4742.739149346785;4744.383230180055;4745.122152401782;5593.300837395325;5611.102223644238;5619.611665042341;5629.268195134607;5641.774329026875;3829.636184562462;3958.782266975315;3973.403989401584;3976.065624578222;3983.601639836022;5553.722313884289;5653.378128405872;5775.595708300462;5863.736131874076;5876.588638795286;4029.3351596986167;4043.3126368516832;4073.9388909166387;4082.408050832613;4099.733517321364;4096.402403062808;4110.09577892869;4122.855700050543;4123.97938010308;4127.876163715094;4383.802728877208;4537.455505339381;4556.202664533694;4628.907421851544;4693.20215563355;5542.334919809648;5551.417101204315;5564.638298510584;5575.804846830341;5588.413216612278;1411.2308791708338;1417.518043865091;1421.9573412797633;1426.486464872645;1426.5149849051372;939.8026414453042;966.3428868369435;980.8087422952456;983.5623147699348;989.6338354808767;4855.5387238212315;4894;4940.061388336205;4947.505249475023;4951.282064100757;5753.539716822645;5767.913481297784;5777.855553611109;5802.94197058024;5810.854728631733;4735.887066539361;4914.36113305268;4940.555350018516;4949.356583644103;4953.380827396575;3045.746147850619;3064.249258931576;3074.723989544314;3094.520349345855;3097.798643088192;5330.520253177233;5364.14173732201;5371.328583927009;5415.783368665209;5437.429069947678;5652.378238393773;5810.331811841608;5871.706414679249;5874.9118763218585;5882.205902203148;5378.273933019071;5378.892422151535;5384.165454354581;5389.326334208224;5393.8112166074;5518.06192947208;5728.427157284238;5739.397363276796;5749.154168686527;5758.617931696446;5572.60991730575;5624.550035997192;5639.436056394492;5654.830355089354;5672.432756724294;5162.199859021857;5226.555742761908;5284.471552844685;5420.457954204548;5434.184872269191;4078.5105787305715;4081.122558649786;4094.5700701427113;4097.692673049473;4098.6516146127915;5575.470330318613;5756.884862302814;5766.826995190151;5798.420157984167;5802.4197580241635;5542.473465020817;5546.72266386671;5557.971340460784;5560.582051576822;5568.77724891016;5556.91664625039;5564.052581596409;5588.161775733673;5588.20002398373;5610.410906854721;5352.41123476425;5500.4224556420995;5514.476124768141;5515.200295957144;5565.719884426686;5605.663660180402;5606.318723865618;5610.691411972381;5632.85917852057;5635.126555383922;5444.8366549003595;5455.754491047833;5470.507654311131;5484.025750729655;5496.835094947158 19:31:54 INFO - PID 15948 | |5;yui.html;685.6537448588465;702.5538782872874;703.8768215562276;705.9894101588477;706.5125063706042;12714.427850746715;13107.279099649515;13115.6721081973;13120.47518099276;13132.606021819336;12039;12210.145289829716;12419.937900310508;12495.250284982907;12561.309127997956;838.2832678060247;860.2472836268265;862.7023676831483;863.7365603490932;864.170396419435;819.0908092017855;839.7019058234331;851.2126283188057;851.6635928808117;852.3096292003497;886.1315910407804;897.6678628907314;898.9595468203946;899.2042042792143;899.5142622983581;874.715717391847;888.2671795768453;892.8526793079134;893.7988952485704;894.3203165594196;656.238763034882;656.6421300391298;658.5094104891838;658.9637569933652;659.4131223211346;13771;13788.379522921392;13964.162150271017;13977.161370317814;14312.21282829444;13478.797818032737;14194.858051419456;14293.999420040602;14324.355404006845;14398.568042958728;10487.37075775456;10598.152147828156;10606.68179954595;10858;10867.891321086767;2511.1336588876807;2515.9245222643167;2534.6071358939394;2573.1637217904067;2612.529744645963;60271.69864150695;60852.69573652103;60957.39042609561;62388;63161;10183;10222.027693776721;10419.976171564764;10435.37387756737;10441;20955.26656567013;21122.366329010154;21223.257185998726;21298;21327.78672213258;6362.427381535592;6421.165248517663;6440.033994900802;6446.677666116675;6464.450521705614;3339.3154403347257;3417.6924076833207;3469.774464659789;3519.2433626770367;3526.5239192708655;3429.794212347267;3467.029231815096;3482.1990942082957;3493.825308734604;3504.3867323218333;708.7554793596202;711.697528550364;713.9643017849087;724.2431658916445;729.7117638532856;468.9740321809749;487.8658368948497;496.3720893070269;503.86899406154663;505.8710028942596;8920.86618700707;8947.284217262604;8978.326625503114;9194.448333100036;9216.400933939272;528.6801485101458;550.5540512185142;551.8344496650989;556.8051182086318;559.5495626021092;3854.730168888207;4020.8994775130273;4066.7356621819486;4166.312558427825;4218.2196293685765;56275.437245627836;58551;59267.70366148228;61711.691441542505;61807.690961544904;52144.21783673248;52942.20586691203;53302.46697533053;53764.19353709697;54273.72863135659;47949.520504794506;48198.5180148201;48236;49631.25553116706;49690.50309496931;7928.365730741642;8235.423520353517;8254.917450825415;8257.42198046155;8384.413091083565;26495.205143844945;26713.866430667724;26923.73076269212;27167.592486112328;27424.314392140357;32523.51214731828;32826.17934551655;32867.34265314728;33033;33664.663353367134;10090.646827361006;10106.292559521056;10154.54304556282;10250.231232657432;10494.108000820015;11460.94269528647;11478.196526243417;11509.36698481558;11509.654710358367;11755.588554400554;10374.42940638242;10398.116160126474;10581.788364232827;10780.622678206226;11052.778944420907;10806.621768238074;10825.404602746925;10869.130469562258;10888.606395972236;11405.771884562428;10089.19476260274;10114.949425252827;10222.335548189343;10328.535215915452;10673.146148307962;7134.785956421316;7140.483406163197;7207.891881621885;7315.817104572428;7383.15093764224;8587.742367728979;8693.130686931509;8870.822583548423;8952.462852228888;9034.23832352685 19:31:54 INFO - PID 15948 | __end_tp_report 19:31:54 INFO - PID 15948 | __start_cc_report 19:31:54 INFO - PID 15948 | _x_x_mozilla_cycle_collect,2962 19:31:54 INFO - PID 15948 | __end_cc_report 19:31:54 INFO - PID 15948 | __startTimestamp1490495514870__endTimestamp 19:31:54 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | ------- Summary: start ------- 19:31:54 INFO - PID 15948 | Number of tests: 6 19:31:54 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | [#0] dojo.html Cycles:165 Average:5175.71 Median:5353.89 stddev:692.20 (12.9%) stddev-sans-first:666.47 19:31:54 INFO - PID 15948 | Values: 2698.0 2713.9 2728.2 2743.4 2751.3 5170.8 5254.7 5270.1 5297.0 5350.5 5210.6 5221.2 5247.8 5249.1 5264.7 4736.6 4764.4 4782.9 4815.6 4833.3 4763.4 4797.7 4825.7 4840.4 4846.3 4891.6 4892.9 4895.1 4914.4 4941.5 651.8 4725.3 4867.1 4886.8 4891.2 4208.8 4689.2 4801.7 4802.1 4813.5 5274.3 5280.9 5285.3 5290.1 5293.3 5664.4 5775.8 5783.1 5783.4 5832.9 5549.2 5636.2 5637.9 5710.5 5743.1 5320.0 5321.1 5322.5 5350.8 5369.2 5863.7 5865.7 5869.2 5869.4 5869.7 5369.9 5436.7 5437.7 5576.7 5578.5 5673.1 5698.5 5699.8 5777.0 5785.9 5493.2 5568.9 5573.7 5630.2 5761.1 5686.7 5695.0 5695.2 5729.5 5749.9 4683.1 4842.4 4851.7 4856.9 4883.7 4031.3 4051.1 4052.2 4065.3 4070.7 4776.5 4778.8 4781.2 4784.4 4828.8 5571.1 5622.0 5623.0 5625.1 5630.3 4743.3 4811.9 4865.4 4910.6 4922.5 5144.4 5145.5 5145.7 5146.9 5190.0 5307.9 5353.4 5353.9 5360.0 5407.8 5548.8 5556.3 5614.5 5697.4 5702.3 5201.6 5203.8 5205.1 5210.6 5215.7 5414.1 5416.6 5419.3 5438.9 5467.7 5563.7 5567.1 5568.4 5570.6 5571.8 5440.9 5501.7 5502.2 5504.3 5504.5 5490.3 5512.7 5513.6 5519.1 5520.7 5440.0 5444.8 5458.1 5469.9 5499.4 5620.4 5622.6 5637.7 5640.2 5648.5 5252.5 5325.7 5415.6 5424.8 5479.4 19:31:54 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | [#1] ext.html Cycles:180 Average:30990.44 Median:29448.98 stddev:45054.48 (153.0%) stddev-sans-first:45123.55 19:31:54 INFO - PID 15948 | Values: 722.1 737.8 1069.2 1083.5 1085.5 30318.5 31633.7 31747.0 31782.2 31793.5 32546.0 32905.2 32971.5 33004.0 33093.2 5491.2 5580.2 7217.1 7356.1 7358.4 7052.0 7217.5 7226.3 7227.6 7251.4 7314.2 7378.0 7378.7 7380.0 7381.4 7437.8 7503.8 7526.5 7529.3 7533.4 7266.1 7337.5 7345.4 7353.0 7359.2 33201.5 33481.2 33532.8 33563.2 33613.7 2583.6 2604.7 2604.9 2612.7 2661.0 22980.7 23027.4 23362.2 23456.4 23486.9 2935.7 2997.1 3004.3 3008.2 3015.0 154472.0 154693.2 154773.0 155067.0 155232.2 34044.1 34586.3 36667.3 41729.0 41926.8 38613.4 38712.6 38747.4 38860.0 38912.0 10151.0 10286.1 11760.8 11900.5 11961.1 9660.2 9664.9 9667.2 9669.5 9669.5 11926.2 12172.5 12202.5 12322.9 12386.3 4710.1 4791.3 4819.8 4844.5 4866.0 865.2 876.9 879.5 881.0 884.9 20390.8 22598.8 24281.0 24293.4 24400.4 930.7 931.2 939.4 958.9 968.5 10845.5 11371.5 11378.9 11565.5 11613.2 197990.0 201092.0 201707.0 202385.0 218401.0 42845.6 43163.8 43244.0 43269.6 43381.3 1183.5 1217.2 1217.5 1217.9 1240.1 22547.5 22602.4 23661.8 23708.3 23771.8 29387.0 29448.0 29450.0 29478.9 29482.7 153277.2 156104.0 158900.0 162091.0 162381.0 24956.3 26364.9 26549.7 26750.2 26809.5 31259.7 31405.4 31582.4 31974.5 35419.6 25756.7 26083.2 26164.3 26744.6 27925.2 24966.4 25340.5 25603.6 25912.9 26554.0 27551.9 27704.6 27777.4 27799.9 28339.4 25073.0 25701.0 25854.5 26864.3 27583.6 20638.7 21687.8 21691.7 21737.6 21749.8 19:31:54 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | [#2] jquery.html Cycles:180 Average:25771.11 Median:5017.11 stddev:68685.22 (1369.0%) stddev-sans-first:68851.40 19:31:54 INFO - PID 15948 | Values: 363.9 408.8 411.6 413.9 430.8 11914.3 13321.6 17361.4 18077.1 18271.5 16926.4 17077.6 17750.6 17782.2 18241.2 5659.9 5844.8 5847.1 5856.9 5857.3 5391.9 5418.1 5441.5 5454.8 5464.9 5430.5 5439.3 5445.6 5446.2 5452.3 5289.3 5648.2 5663.0 5669.9 5680.7 4965.4 5068.8 5113.4 5117.4 5119.3 20397.4 20759.0 20968.8 21085.2 21101.0 1540.6 1558.2 1566.5 1587.2 1613.1 13346.5 13460.3 13602.5 13703.6 14130.3 1710.8 1764.1 1783.9 1784.9 1787.2 67477.7 75036.6 75305.6 75551.6 76915.6 10054.7 10081.8 10266.7 10451.5 10524.9 14207.5 14216.1 14216.2 14248.9 14267.6 7000.3 7307.9 7318.1 7397.6 7500.4 6897.4 7002.9 7116.0 7131.3 7138.5 6418.7 6613.8 6617.7 6621.9 6630.0 1822.3 1889.6 1918.1 1932.0 1955.9 844.9 865.6 868.2 880.7 887.2 15845.4 16347.8 16435.1 16463.6 17073.1 884.0 887.0 891.1 898.9 907.3 8767.0 8874.1 9153.1 9189.3 9299.2 410123.0 412111.0 413272.0 415760.0 422535.0 86068.0 86730.0 87912.6 89684.0 89958.0 1024.1 1034.7 1063.5 1064.3 1065.9 14137.4 14192.2 14235.6 14374.2 15122.6 40062.8 41149.4 41471.4 41542.0 43145.0 51472.7 51776.0 52034.0 52106.0 54396.5 7888.7 7909.4 7921.4 7984.3 8320.5 11909.0 12105.2 12169.4 12211.3 12328.0 10637.2 10753.9 10798.9 10852.6 10972.8 8834.7 8960.5 9025.9 9126.9 9618.2 10271.7 10305.6 10492.5 10724.7 10861.6 7660.0 7664.5 7721.2 7779.5 7971.7 9547.0 9576.3 9587.1 9610.8 9967.0 19:31:54 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | [#3] mootools.html Cycles:180 Average:6194.27 Median:5026.97 stddev:3912.41 (77.8%) stddev-sans-first:3904.78 19:31:54 INFO - PID 15948 | Values: 1115.6 1255.2 1301.0 1319.7 1320.1 8216.2 9440.9 9730.4 9768.2 9788.9 9509.9 9607.2 9611.0 9611.1 9612.4 2088.3 2127.4 2150.4 2151.5 2154.5 1916.2 1993.7 2208.0 2266.4 2275.1 2203.6 2222.4 2225.3 2226.8 2231.2 2193.0 2233.8 2234.5 2238.2 2238.5 2028.5 2083.3 2163.1 2196.2 2196.7 9770.6 9819.4 9854.7 9859.9 9870.9 5310.3 5505.9 5506.3 5511.2 5531.7 2986.9 3205.5 3273.2 3277.9 3298.2 1040.9 1044.9 1065.4 1084.2 1087.0 14021.4 14082.4 14100.4 14113.4 14183.6 4683.7 4941.8 5037.4 5096.2 5141.9 12041.9 12056.3 12063.3 12071.3 12087.1 5665.5 5666.2 5672.8 5677.5 5678.2 4263.1 4705.8 4724.6 5016.5 5126.7 3686.3 4081.6 4140.9 4162.9 4177.1 1252.2 1286.3 1291.6 1299.6 1316.4 700.6 704.4 717.0 726.5 745.8 5386.2 5500.9 5574.4 5632.1 5871.3 802.5 804.3 808.8 827.2 833.2 3595.2 3801.9 3884.4 3922.4 3943.0 12069.6 12796.6 12814.9 12835.1 12838.5 12418.8 12738.7 12870.9 12946.4 13028.3 8831.6 9101.6 9252.7 9284.6 9357.9 5675.6 5723.8 5774.8 5853.3 5969.1 11483.4 11678.6 11739.5 11744.2 11827.8 11784.4 11873.3 11890.3 11932.9 11952.6 8146.2 8368.6 8382.6 8384.7 8396.1 7550.3 8698.6 9077.2 9084.4 9205.2 7738.4 7942.2 8009.7 8035.2 8102.4 7710.6 7796.1 7801.4 7806.9 7861.2 6948.8 7082.2 7115.3 7116.1 7119.8 5216.4 5229.3 5262.8 5273.8 5512.1 5138.4 5146.2 5212.3 5241.6 5409.7 19:31:54 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | [#4] prototype.html Cycles:180 Average:4622.03 Median:5278.84 stddev:1337.06 (25.3%) stddev-sans-first:1302.27 19:31:54 INFO - PID 15948 | Values: 375.7 401.9 407.6 408.0 408.0 5381.6 5403.8 5411.3 5412.5 5459.3 5071.8 5230.3 5265.7 5273.2 5329.8 4137.2 4140.3 4141.8 4146.6 4154.1 4140.3 4141.8 4142.2 4151.4 4155.0 3907.3 4041.7 4100.0 4111.0 4117.2 4066.2 4090.3 4092.1 4094.4 4101.1 3587.5 3590.6 3591.9 3594.2 3603.2 4937.5 5177.1 5179.8 5180.7 5194.1 4697.4 4732.8 4742.7 4744.4 4745.1 5593.3 5611.1 5619.6 5629.3 5641.8 3829.6 3958.8 3973.4 3976.1 3983.6 5553.7 5653.4 5775.6 5863.7 5876.6 4029.3 4043.3 4073.9 4082.4 4099.7 4096.4 4110.1 4122.9 4124.0 4127.9 4383.8 4537.5 4556.2 4628.9 4693.2 5542.3 5551.4 5564.6 5575.8 5588.4 1411.2 1417.5 1422.0 1426.5 1426.5 939.8 966.3 980.8 983.6 989.6 4855.5 4894.0 4940.1 4947.5 4951.3 5753.5 5767.9 5777.9 5802.9 5810.9 4735.9 4914.4 4940.6 4949.4 4953.4 3045.7 3064.2 3074.7 3094.5 3097.8 5330.5 5364.1 5371.3 5415.8 5437.4 5652.4 5810.3 5871.7 5874.9 5882.2 5378.3 5378.9 5384.2 5389.3 5393.8 5518.1 5728.4 5739.4 5749.2 5758.6 5572.6 5624.6 5639.4 5654.8 5672.4 5162.2 5226.6 5284.5 5420.5 5434.2 4078.5 4081.1 4094.6 4097.7 4098.7 5575.5 5756.9 5766.8 5798.4 5802.4 5542.5 5546.7 5558.0 5560.6 5568.8 5556.9 5564.1 5588.2 5588.2 5610.4 5352.4 5500.4 5514.5 5515.2 5565.7 5605.7 5606.3 5610.7 5632.9 5635.1 5444.8 5455.8 5470.5 5484.0 5496.8 19:31:54 INFO - PID 15948 | 19:31:54 INFO - PID 15948 | [#5] yui.html Cycles:180 Average:13673.46 Median:24209.25 stddev:16688.84 (68.9%) stddev-sans-first:16707.15 19:31:54 INFO - PID 15948 | Values: 685.7 702.6 703.9 706.0 706.5 12714.4 13107.3 13115.7 13120.5 13132.6 12039.0 12210.1 12419.9 12495.3 12561.3 838.3 860.2 862.7 863.7 864.2 819.1 839.7 851.2 851.7 852.3 886.1 897.7 899.0 899.2 899.5 874.7 888.3 892.9 893.8 894.3 656.2 656.6 658.5 659.0 659.4 13771.0 13788.4 13964.2 13977.2 14312.2 13478.8 14194.9 14294.0 14324.4 14398.6 10487.4 10598.2 10606.7 10858.0 10867.9 2511.1 2515.9 2534.6 2573.2 2612.5 60271.7 60852.7 60957.4 62388.0 63161.0 10183.0 10222.0 10420.0 10435.4 10441.0 20955.3 21122.4 21223.3 21298.0 21327.8 6362.4 6421.2 6440.0 6446.7 6464.5 3339.3 3417.7 3469.8 3519.2 3526.5 3429.8 3467.0 3482.2 3493.8 3504.4 708.8 711.7 714.0 724.2 729.7 469.0 487.9 496.4 503.9 505.9 8920.9 8947.3 8978.3 9194.4 9216.4 528.7 550.6 551.8 556.8 559.5 3854.7 4020.9 4066.7 4166.3 4218.2 56275.4 58551.0 59267.7 61711.7 61807.7 52144.2 52942.2 53302.5 53764.2 54273.7 47949.5 48198.5 48236.0 49631.3 49690.5 7928.4 8235.4 8254.9 8257.4 8384.4 26495.2 26713.9 26923.7 27167.6 27424.3 32523.5 32826.2 32867.3 33033.0 33664.7 10090.6 10106.3 10154.5 10250.2 10494.1 11460.9 11478.2 11509.4 11509.7 11755.6 10374.4 10398.1 10581.8 10780.6 11052.8 10806.6 10825.4 10869.1 10888.6 11405.8 10089.2 10114.9 10222.3 10328.5 10673.1 7134.8 7140.5 7207.9 7315.8 7383.2 8587.7 8693.1 8870.8 8952.5 9034.2 19:31:54 INFO - PID 15948 | -------- Summary: end -------- 19:31:54 INFO - PID 15948 | 19:31:55 INFO - TEST-INFO | 15948: exit 0 19:31:55 INFO - TEST-OK | dromaeo_css | took 1124443ms 19:31:55 INFO - TEST-START | kraken 19:31:55 INFO - operating with platform_type : linux_ 19:31:55 INFO - Initialising browser for kraken test... 19:31:55 INFO - TEST-INFO | started process 16275 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFeN1gF/profile http://localhost:38219/getInfo.html) 19:31:59 INFO - PID 16275 | __metrics Screen width/height:1600/1200 19:31:59 INFO - PID 16275 | colorDepth:24 19:31:59 INFO - PID 16275 | Browser inner width/height: 1024/697 19:31:59 INFO - PID 16275 | __metrics 19:32:00 ERROR - PID 16275 | 1490495520857 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 19:32:00 INFO - PID 16275 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:32:00 INFO - PID 16275 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:32:01 INFO - TEST-INFO | 16275: exit 0 19:32:01 INFO - Browser initialized. 19:32:01 INFO - Running cycle 1/1 for kraken test... 19:32:01 INFO - TEST-INFO | started process 16372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFeN1gF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:32:14 INFO - PID 16372 | RSS: Main: 149622784 19:32:14 INFO - PID 16372 | 19:32:16 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38219/tests/kraken/driver.html?testName=audio-beat-detection) 19:32:17 INFO - PID 16372 | RSS: Main: 205271040 19:32:17 INFO - PID 16372 | 19:32:20 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38219/tests/kraken/driver.html?testName=audio-dft) 19:32:21 INFO - PID 16372 | RSS: Main: 185208832 19:32:21 INFO - PID 16372 | 19:32:24 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38219/tests/kraken/driver.html?testName=audio-fft) 19:32:24 INFO - PID 16372 | RSS: Main: 266932224 19:32:24 INFO - PID 16372 | 19:32:27 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38219/tests/kraken/driver.html?testName=audio-oscillator) 19:32:27 INFO - PID 16372 | RSS: Main: 177311744 19:32:27 INFO - PID 16372 | 19:32:29 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:32:30 INFO - PID 16372 | RSS: Main: 180563968 19:32:30 INFO - PID 16372 | 19:32:33 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38219/tests/kraken/driver.html?testName=imaging-darkroom) 19:32:34 INFO - PID 16372 | RSS: Main: 219627520 19:32:34 INFO - PID 16372 | 19:32:37 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38219/tests/kraken/driver.html?testName=imaging-desaturate) 19:32:38 INFO - PID 16372 | RSS: Main: 215158784 19:32:38 INFO - PID 16372 | 19:32:41 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38219/tests/kraken/driver.html?testName=json-parse-financial) 19:32:41 INFO - PID 16372 | RSS: Main: 216645632 19:32:41 INFO - PID 16372 | 19:32:43 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:32:44 INFO - PID 16372 | RSS: Main: 180129792 19:32:44 INFO - PID 16372 | 19:32:46 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38219/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:32:46 INFO - PID 16372 | RSS: Main: 187510784 19:32:46 INFO - PID 16372 | 19:32:48 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:32:49 INFO - PID 16372 | RSS: Main: 196599808 19:32:49 INFO - PID 16372 | 19:32:52 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:32:53 INFO - PID 16372 | RSS: Main: 207171584 19:32:53 INFO - PID 16372 | 19:32:56 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:32:56 INFO - PID 16372 | RSS: Main: 202985472 19:32:56 INFO - PID 16372 | 19:32:58 INFO - PID 16372 | Cycle 1(1): loaded http://localhost:38219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38219/tests/kraken/driver.html?testName=ai-astar) 19:32:58 INFO - PID 16372 | RSS: Main: 271757312 19:32:58 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | __start_tp_report 19:32:59 INFO - PID 16372 | _x_x_mozilla_page_load 19:32:59 INFO - PID 16372 | _x_x_mozilla_page_load_details 19:32:59 INFO - PID 16372 | |i|pagename|runs| 19:32:59 INFO - PID 16372 | |0;ai-astar;102;111;111;112;113;112;111;111;111;110 19:32:59 INFO - PID 16372 | |1;audio-beat-detection;149;123;124;163;125;124;136;124;151;125 19:32:59 INFO - PID 16372 | |2;audio-dft;162;161;162;163;162;165;161;162;162;162 19:32:59 INFO - PID 16372 | |3;audio-fft;76;75;75;114;75;76;76;76;92;75 19:32:59 INFO - PID 16372 | |4;audio-oscillator;84;83;106;84;85;84;99;84;85;106 19:32:59 INFO - PID 16372 | |5;imaging-gaussian-blur;113;113;113;114;113;113;113;113;113;113 19:32:59 INFO - PID 16372 | |6;imaging-darkroom;149;149;149;149;149;149;148;149;148;148 19:32:59 INFO - PID 16372 | |7;imaging-desaturate;94;94;94;94;95;96;94;94;94;94 19:32:59 INFO - PID 16372 | |8;json-parse-financial;64;65;65;65;65;66;68;67;67;68 19:32:59 INFO - PID 16372 | |9;json-stringify-tinderbox;55;55;55;55;57;55;58;56;56;55 19:32:59 INFO - PID 16372 | |10;stanford-crypto-aes;81;82;83;81;90;90;81;81;81;81 19:32:59 INFO - PID 16372 | |11;stanford-crypto-ccm;138;146;142;141;133;137;143;149;181;132 19:32:59 INFO - PID 16372 | |12;stanford-crypto-pbkdf2;198;191;191;192;189;191;189;190;191;190 19:32:59 INFO - PID 16372 | |13;stanford-crypto-sha256-iterative;71;70;73;72;71;71;70;70;70;70 19:32:59 INFO - PID 16372 | __end_tp_report 19:32:59 INFO - PID 16372 | __start_cc_report 19:32:59 INFO - PID 16372 | _x_x_mozilla_cycle_collect,1987 19:32:59 INFO - PID 16372 | __end_cc_report 19:32:59 INFO - PID 16372 | __startTimestamp1490495579001__endTimestamp 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | ------- Summary: start ------- 19:32:59 INFO - PID 16372 | Number of tests: 14 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:3.06 (2.8%) stddev-sans-first:0.87 19:32:59 INFO - PID 16372 | Values: 102.0 111.0 111.0 112.0 113.0 112.0 111.0 111.0 111.0 110.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:130.50 stddev:14.68 (11.3%) stddev-sans-first:14.59 19:32:59 INFO - PID 16372 | Values: 149.0 123.0 124.0 163.0 125.0 124.0 136.0 124.0 151.0 125.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 19:32:59 INFO - PID 16372 | Values: 162.0 161.0 162.0 163.0 162.0 165.0 161.0 162.0 162.0 162.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:12.71 (16.7%) stddev-sans-first:13.35 19:32:59 INFO - PID 16372 | Values: 76.0 75.0 75.0 114.0 75.0 76.0 76.0 76.0 92.0 75.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:84.00 stddev:9.64 (11.5%) stddev-sans-first:9.97 19:32:59 INFO - PID 16372 | Values: 84.0 83.0 106.0 84.0 85.0 84.0 99.0 84.0 85.0 106.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:32:59 INFO - PID 16372 | Values: 113.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 113.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 19:32:59 INFO - PID 16372 | Values: 149.0 149.0 149.0 149.0 149.0 149.0 148.0 149.0 148.0 148.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 19:32:59 INFO - PID 16372 | Values: 94.0 94.0 94.0 94.0 95.0 96.0 94.0 94.0 94.0 94.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:66.50 stddev:1.41 (2.1%) stddev-sans-first:1.30 19:32:59 INFO - PID 16372 | Values: 64.0 65.0 65.0 65.0 65.0 66.0 68.0 67.0 67.0 68.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#9] json-stringify-tinderbox Cycles:10 Average:55.70 Median:55.50 stddev:1.06 (1.9%) stddev-sans-first:1.09 19:32:59 INFO - PID 16372 | Values: 55.0 55.0 55.0 55.0 57.0 55.0 58.0 56.0 56.0 55.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:81.50 stddev:3.70 (4.5%) stddev-sans-first:3.84 19:32:59 INFO - PID 16372 | Values: 81.0 82.0 83.0 81.0 90.0 90.0 81.0 81.0 81.0 81.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:142.50 stddev:13.99 (9.8%) stddev-sans-first:14.66 19:32:59 INFO - PID 16372 | Values: 138.0 146.0 142.0 141.0 133.0 137.0 143.0 149.0 181.0 132.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.20 Median:191.00 stddev:2.57 (1.3%) stddev-sans-first:1.01 19:32:59 INFO - PID 16372 | Values: 198.0 191.0 191.0 192.0 189.0 191.0 189.0 190.0 191.0 190.0 19:32:59 INFO - PID 16372 | 19:32:59 INFO - PID 16372 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:71.00 stddev:1.03 (1.5%) stddev-sans-first:1.09 19:32:59 INFO - PID 16372 | Values: 71.0 70.0 73.0 72.0 71.0 71.0 70.0 70.0 70.0 70.0 19:32:59 INFO - PID 16372 | -------- Summary: end -------- 19:32:59 INFO - PID 16372 | 19:33:00 INFO - TEST-INFO | 16372: exit 0 19:33:00 INFO - TEST-OK | kraken | took 64378ms 19:33:00 INFO - SUITE-END | took 1188s 19:33:00 INFO - Completed test suite (00:19:49) 19:33:00 INFO - javascript benchmark 19:33:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6501.005382241787, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2698.0421950207674, 2713.9185824425276, 2728.195182421186, 2743.3827388837503, 2751.339678477166, 5170.844874653757, 5254.658447200929, 5270.051390749663, 5297.046531624311, 5350.518453339204, 5210.609204309682, 5221.164613661815, 5247.790088396469, 5249.081410753112, 5264.710440925757, 4736.573708366246, 4764.404449443819, 4782.928256076161, 4815.5906747926465, 4833.347498087766, 4763.356946812188, 4797.664163508556, 4825.734584597846, 4840.443349014856, 4846.273059041155, 4891.6331275154325, 4892.926606100911, 4895.11887860185, 4914.385701787277, 4941.45643979162, 651.7923955316787, 4725.291206319045, 4867.123917694814, 4886.804527818883, 4891.16850135476, 4208.810603522848, 4689.249720044776, 4801.663883528155, 4802.087603355386, 4813.470518242991, 5274.261603375492, 5280.894382112337, 5285.2600635911, 5290.100682883881, 5293.285406470154, 5664.433556644344, 5775.768969241268, 5783.0747080467345, 5783.392743761887, 5832.912506312408, 5549.1676248562635, 5636.15457681347, 5637.915431268493, 5710.543156547507, 5743.052396354597, 5320.0, 5321.068812957717, 5322.494363035506, 5350.785968561292, 5369.24830523727, 5863.70681465925, 5865.73604187804, 5869.207656966255, 5869.354371019184, 5869.677167755729, 5369.892602148013, 5436.728163591804, 5437.700926449004, 5576.749046292926, 5578.497935185772, 5673.092305231144, 5698.458646428583, 5699.800506982236, 5777.0, 5785.942140578624, 5493.176023596492, 5568.860778480489, 5573.721313934287, 5630.23991761107, 5761.107028410603, 5686.658800471985, 5694.971525142348, 5695.1741997410145, 5729.455701708331, 5749.9425005750245, 4683.1336202802595, 4842.370491836039, 4851.684640498427, 4856.854294371175, 4883.682560633619, 4031.2945234584417, 4051.068254301468, 4052.169305292408, 4065.308897487434, 4070.6539944105084, 4776.450708168605, 4778.808847646124, 4781.192915574622, 4784.425868895685, 4828.782704778293, 5571.0807716727095, 5622.0, 5622.97188514063, 5625.099984002622, 5630.324361076697, 4743.264793956942, 4811.879703007383, 4865.416150061945, 4910.631702622318, 4922.458529561745, 5144.43411224765, 5145.4597267287145, 5145.716985565755, 5146.948530514647, 5189.974050129726, 5307.920381194208, 5353.437889021752, 5353.892922141613, 5360.0, 5407.756650950638, 5548.778048878161, 5556.305461817273, 5614.466625670555, 5697.401772813961, 5702.315722113373, 5201.6098792591465, 5203.843884683465, 5205.141151709925, 5210.583153347648, 5215.739213039257, 5414.0796064669885, 5416.566674665938, 5419.2954915862265, 5438.864028399322, 5467.72661366938, 5563.721813909207, 5567.137093750475, 5568.387477377404, 5570.554355651457, 5571.804986825442, 5440.918386224289, 5501.669899806025, 5502.202180683858, 5504.339479262514, 5504.504594586347, 5490.258815060076, 5512.6968016759465, 5513.58648101386, 5519.089350257163, 5520.723963801875, 5440.020796256554, 5444.8094316698725, 5458.099413596712, 5469.917951230815, 5499.395066542757, 5620.381758006534, 5622.550195984393, 5637.718114094197, 5640.210370548209, 5648.4633959773755, 5252.527272545489, 5325.707086110301, 5415.59383046281, 5424.7558859850615, 5479.369872464717], "value": 5128.75807362335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.050503587782, 737.7823542055091, 1069.1553672598648, 1083.4799296337753, 1085.5277954089972, 30318.545221821663, 31633.683663163363, 31747.04758857238, 31782.205444863863, 31793.52309715356, 32546.0, 32905.17737056575, 32971.505427418546, 33004.0, 33093.17267068318, 5491.231227628115, 5580.218769372291, 7217.06178196835, 7356.1172659280965, 7358.411327093846, 7052.0, 7217.530860494077, 7226.313500217471, 7227.602481863495, 7251.383632391253, 7314.232005639385, 7378.040854688883, 7378.74174403896, 7380.0, 7381.372583330423, 7437.776866694008, 7503.812404689872, 7526.510776799545, 7529.284717951786, 7533.397328213785, 7266.091738532684, 7337.523061001018, 7345.375643070345, 7353.044104266464, 7359.153697324823, 33201.501977470354, 33481.16297092592, 33532.83233583817, 33563.16092097693, 33613.6638633613, 2583.6124581312765, 2604.6744156980376, 2604.9088281910044, 2612.7256638052922, 2661.0420248710448, 22980.65529017066, 23027.42431439211, 23362.182323618763, 23456.413589660395, 23486.882565587235, 2935.7357837794702, 2997.1008697390694, 3004.2939909121337, 3008.247938015496, 3014.954775678367, 154471.99999999886, 154693.22653386774, 154773.0, 155066.99999999886, 155232.2238388812, 34044.14889627729, 34586.30827383438, 36667.26665466676, 41729.0, 41926.79036604797, 38613.42079868804, 38712.61287387146, 38747.41878871819, 38860.0, 38912.0, 10151.0, 10286.125679317192, 11760.76478470443, 11900.464479098462, 11961.10291728124, 9660.227181825438, 9664.855027174597, 9667.226621870233, 9669.468179250209, 9669.485933657159, 11926.1651684383, 12172.513099476098, 12202.511899523919, 12322.876771232352, 12386.3187524687, 4710.057988402334, 4791.31686255698, 4819.783109760069, 4844.491328410537, 4865.9756701216265, 865.1824026295217, 876.8509353409936, 879.4942907828024, 880.9515476648718, 884.9380543362032, 20390.79609203889, 22598.774012259997, 24281.0, 24293.392665183514, 24400.389990250387, 930.7440453875239, 931.1666058877267, 939.4269495607674, 958.9136977672043, 968.5447839515416, 10845.51195196218, 11371.545138194546, 11378.886211137948, 11565.537378504934, 11613.187076904536, 197990.0, 201092.0, 201707.0, 202385.0, 218401.0, 42845.57154428478, 43163.78418107952, 43244.0, 43269.56730432718, 43381.34927976083, 1183.5483823363056, 1217.220978573696, 1217.5255934440895, 1217.945192466341, 1240.0761432732672, 22547.5490490186, 22602.434939126655, 23661.763382365956, 23708.288751337488, 23771.762282377647, 29387.0, 29447.96932107366, 29450.0, 29478.852605736833, 29482.705172948856, 153277.23361383122, 156104.0, 158900.0, 162091.0, 162381.0, 24956.251312460656, 26364.868175658998, 26549.734502654726, 26750.197494075208, 26809.463810723286, 31259.687403126587, 31405.371892562478, 31582.36835263236, 31974.52038219475, 35419.645803541636, 25756.742432575433, 26083.217503474927, 26164.34589135287, 26744.59883101794, 27925.16224513268, 24966.37584060413, 25340.49319013646, 25603.615945760455, 25912.870435647703, 26554.0, 27551.862240688668, 27704.58443123395, 27777.44445111127, 27799.861000694866, 28339.43321133607, 25073.0, 25701.0, 25854.482910342063, 26864.32839179036, 27583.586246206723, 20638.690419643415, 21687.783122168577, 21691.674624880325, 21737.565248695253, 21749.782502174774], "value": 14290.45848475134, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.8580953428165, 408.78538767147245, 411.6048593350383, 413.8944569134871, 430.76092768513485, 11914.285142891422, 13321.600351989444, 17361.392351267692, 18077.09614519272, 18271.54321141969, 16926.40757573485, 17077.57306067349, 17750.556236094104, 17782.199801008952, 18241.179146938386, 5659.943400566003, 5844.766209351621, 5847.093700476433, 5856.853578660525, 5857.26784151981, 5391.892162156754, 5418.078926582471, 5441.455854414547, 5454.781808727646, 5464.9180262295895, 5430.456954304577, 5439.265699130626, 5445.618806683534, 5446.15584584388, 5452.318460192476, 5289.312389389374, 5648.152777083429, 5663.0, 5669.9433005669825, 5680.687562184078, 4965.4000849384265, 5068.82259120929, 5113.437521872628, 5117.385913690381, 5119.283300337956, 20397.38807835767, 20759.0, 20968.79031209699, 21085.156593736232, 21101.0, 1540.614846288428, 1558.2208895552224, 1566.4752307976842, 1587.1588058329105, 1613.0563620282148, 13346.466141354334, 13460.259685717281, 13602.523911663044, 13703.588892333246, 14130.293485325692, 1710.7604935308943, 1764.1091248919213, 1783.8540374827612, 1784.9464516064538, 1787.2046939112101, 67477.66261168662, 75036.62481687557, 75305.6234718823, 75551.622241889, 76915.6154219231, 10054.74863128413, 10081.848772268422, 10266.743331416774, 10451.477426128815, 10524.947895060457, 14207.502737404142, 14216.147031178163, 14216.218108003952, 14248.928755356157, 14267.64330891736, 7000.334968178015, 7307.89038164428, 7318.121825380882, 7397.630118494054, 7500.36246919007, 6897.379235868695, 7002.859942801218, 7115.964420177866, 7131.322524360177, 7138.464615153885, 6418.679066046679, 6613.834654133685, 6617.735290588419, 6621.86756264872, 6629.966850165718, 1822.3166312632763, 1889.6220755848885, 1918.1464248409325, 1931.9806801932082, 1955.9315423960093, 844.9408541402166, 865.5542395666237, 868.1665601023088, 880.7225723896952, 887.2148148888151, 15845.366185352457, 16347.836521634868, 16435.09606971628, 16463.588410289838, 17073.146342682816, 883.9823203535893, 886.9733907982771, 891.0777345447431, 898.8831451911341, 907.3421769217265, 8767.0, 8874.112588741074, 9153.130452606993, 9189.264858811279, 9299.209567186865, 410123.0, 412111.0, 413272.0, 415760.0, 422535.0, 86068.0, 86730.0, 87912.5604371974, 89684.0, 89958.0, 1024.114141267798, 1034.6689059501039, 1063.473580577619, 1064.302881612545, 1065.8667666541683, 14137.363818628392, 14192.219427931563, 14235.64410889736, 14374.209418482083, 15122.621934451727, 40062.79968600255, 41149.382759258035, 41471.37792933169, 41542.0, 43145.0, 51472.74263628658, 51776.0, 52034.0, 52106.0, 54396.456035439136, 7888.684452621831, 7909.446338756462, 7921.366290696846, 7984.321332686786, 8320.500769953822, 11908.97547416924, 12105.152639315163, 12169.39153042362, 12211.26732396059, 12328.0, 10637.202209834139, 10753.892461075602, 10798.946005270238, 10852.620158294421, 10972.780544389227, 8834.734957951272, 8960.462372257685, 9025.909740902769, 9126.908730912606, 9618.182454491447, 10271.7432064199, 10305.639302624671, 10492.52783624754, 10724.678259652223, 10861.619843305447, 7660.0, 7664.501807382502, 7721.150673426031, 7779.494332868572, 7971.681132754625, 9547.0, 9576.329656923948, 9587.089226523469, 9610.807783844144, 9966.950165249127], "value": 8525.371962709018, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1115.6485707002298, 1255.240579449433, 1300.9804852927202, 1319.7492476429475, 1320.0693511074694, 8216.219459151385, 9440.905590944089, 9730.367526110807, 9768.169705575036, 9788.85316720248, 9509.857352139723, 9607.183389411908, 9611.0, 9611.134997850191, 9612.423254604728, 2088.2586681727958, 2127.425595089325, 2150.43013601395, 2151.4943988162768, 2154.547545015541, 1916.2047750183658, 1993.6710442776925, 2207.995362110236, 2266.3880752196897, 2275.101334972685, 2203.636399993999, 2222.388843068153, 2225.276785044859, 2226.8329875259337, 2231.241377931503, 2193.0, 2233.7654546272734, 2234.4525591230254, 2238.2166241815235, 2238.4851484158735, 2028.5334373094124, 2083.260442542891, 2163.113123619324, 2196.176433837311, 2196.6924630551734, 9770.609175633037, 9819.410835349901, 9854.655087071918, 9859.852102218472, 9870.851937220947, 5310.256564081032, 5505.862353441156, 5506.339239291271, 5511.173324001432, 5531.668099913978, 2986.8954586589584, 3205.5351973963643, 3273.2307907641684, 3277.852496637657, 3298.208429976812, 1040.8646875906159, 1044.9101796407185, 1065.4140222877386, 1084.1852045691357, 1086.9782604347947, 14021.369038393297, 14082.366293516714, 14100.365483553265, 14113.364898579588, 14183.645408864757, 4683.742394168354, 4941.802327906844, 5037.420696619935, 5096.210087436453, 5141.897162056738, 12041.939790300992, 12056.336901470504, 12063.336516491501, 12071.336076515876, 12087.093467989936, 5665.5467562594895, 5666.206731057655, 5672.7730890763905, 5677.545796336283, 5678.205051292823, 4263.147370525845, 4705.788239529229, 4724.645651576146, 5016.523430274118, 5126.6923984561045, 3686.262747450467, 4081.6122468365456, 4140.875773726732, 4162.854300099542, 4177.143685544456, 1252.1860790486257, 1286.273255610577, 1291.6060601516629, 1299.5827760365712, 1316.374722007039, 700.6041586503711, 704.4364508393265, 717.0, 726.5023458930649, 745.7986343687174, 5386.245925570423, 5500.917486237709, 5574.439157036037, 5632.127020311858, 5871.295444546597, 802.4984384759525, 804.2520455975881, 808.8180159464074, 827.2017503109408, 833.1751565949667, 3595.227026189382, 3801.8669346572738, 3884.436756670267, 3922.4312474691583, 3943.0142464383903, 12069.637910862688, 12796.61610151697, 12814.871851281554, 12835.101542891907, 12838.486460541473, 12418.75162496763, 12738.745225095632, 12870.871291287154, 12946.417411216518, 13028.348582570834, 8831.55842207887, 9101.635934562675, 9252.676156334495, 9284.62861485533, 9357.859632105525, 5675.602707810413, 5723.771049157987, 5774.82675519735, 5853.268341457318, 5969.104634304889, 11483.425828708698, 11678.591249306233, 11739.47172377228, 11744.236624619372, 11827.763444731228, 11784.410779461165, 11873.287602743863, 11890.34603096838, 11932.880671193177, 11952.58165964187, 8146.2261085196815, 8368.623411946355, 8382.58087095655, 8384.748457546284, 8396.076431592397, 7550.320471157622, 8698.60856261457, 9077.228435583049, 9084.364094513321, 9205.217556507772, 7738.362073259291, 7942.205779422012, 8009.719659811879, 8035.196480352152, 8102.392320575863, 7710.575918324322, 7796.10344810354, 7801.375889928906, 7806.92193078062, 7861.174576669596, 6948.756793512202, 7082.18554866197, 7115.2528984455885, 7116.1460624723295, 7119.786406407816, 5216.426193118829, 5229.294045303835, 5262.789488420421, 5273.762680679455, 5512.062949298709, 5138.3833939927445, 5146.228065790161, 5212.296339994053, 5241.60687948413, 5409.675419474844], "value": 4702.999453297147, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.6506449002428, 401.91559772447783, 407.62905755762245, 407.9877603671891, 407.9979600101998, 5381.56947444204, 5403.83788486345, 5411.323584551931, 5412.512873841353, 5459.344878614562, 5071.847844564669, 5230.320058392414, 5265.736713164336, 5273.209018647195, 5329.840104796853, 4137.151883863816, 4140.31684772013, 4141.792910354486, 4146.60607242313, 4154.127633197032, 4140.275451795938, 4141.813618387165, 4142.17156568686, 4151.398047283142, 4155.0, 3907.257621052005, 4041.6564592009713, 4100.0, 4110.958890411102, 4117.238310912476, 4066.207089617526, 4090.284200264957, 4092.0997380576373, 4094.3858421236755, 4101.097758493127, 3587.515685382466, 3590.6355584877842, 3591.8742844000594, 3594.209273959751, 3603.171270607775, 4937.506249375069, 5177.119889618736, 5179.766910488999, 5180.740962951837, 5194.091034069061, 4697.436307643105, 4732.763361831895, 4742.739149346785, 4744.383230180055, 4745.122152401782, 5593.300837395325, 5611.102223644238, 5619.611665042341, 5629.268195134607, 5641.774329026875, 3829.636184562462, 3958.782266975315, 3973.403989401584, 3976.065624578222, 3983.601639836022, 5553.722313884289, 5653.378128405872, 5775.595708300462, 5863.736131874076, 5876.588638795286, 4029.3351596986167, 4043.3126368516832, 4073.9388909166387, 4082.408050832613, 4099.733517321364, 4096.402403062808, 4110.09577892869, 4122.855700050543, 4123.97938010308, 4127.876163715094, 4383.802728877208, 4537.455505339381, 4556.202664533694, 4628.907421851544, 4693.20215563355, 5542.334919809648, 5551.417101204315, 5564.638298510584, 5575.804846830341, 5588.413216612278, 1411.2308791708338, 1417.518043865091, 1421.9573412797633, 1426.486464872645, 1426.5149849051372, 939.8026414453042, 966.3428868369435, 980.8087422952456, 983.5623147699348, 989.6338354808767, 4855.5387238212315, 4894.0, 4940.061388336205, 4947.505249475023, 4951.282064100757, 5753.539716822645, 5767.913481297784, 5777.855553611109, 5802.94197058024, 5810.854728631733, 4735.887066539361, 4914.36113305268, 4940.555350018516, 4949.356583644103, 4953.380827396575, 3045.746147850619, 3064.249258931576, 3074.723989544314, 3094.520349345855, 3097.798643088192, 5330.520253177233, 5364.14173732201, 5371.328583927009, 5415.783368665209, 5437.429069947678, 5652.378238393773, 5810.331811841608, 5871.706414679249, 5874.9118763218585, 5882.205902203148, 5378.273933019071, 5378.892422151535, 5384.165454354581, 5389.326334208224, 5393.8112166074, 5518.06192947208, 5728.427157284238, 5739.397363276796, 5749.154168686527, 5758.617931696446, 5572.60991730575, 5624.550035997192, 5639.436056394492, 5654.830355089354, 5672.432756724294, 5162.199859021857, 5226.555742761908, 5284.471552844685, 5420.457954204548, 5434.184872269191, 4078.5105787305715, 4081.122558649786, 4094.5700701427113, 4097.692673049473, 4098.6516146127915, 5575.470330318613, 5756.884862302814, 5766.826995190151, 5798.420157984167, 5802.4197580241635, 5542.473465020817, 5546.72266386671, 5557.971340460784, 5560.582051576822, 5568.77724891016, 5556.91664625039, 5564.052581596409, 5588.161775733673, 5588.20002398373, 5610.410906854721, 5352.41123476425, 5500.4224556420995, 5514.476124768141, 5515.200295957144, 5565.719884426686, 5605.663660180402, 5606.318723865618, 5610.691411972381, 5632.85917852057, 5635.126555383922, 5444.8366549003595, 5455.754491047833, 5470.507654311131, 5484.025750729655, 5496.835094947158], "value": 4218.056799015712, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.6537448588465, 702.5538782872874, 703.8768215562276, 705.9894101588477, 706.5125063706042, 12714.427850746715, 13107.279099649515, 13115.6721081973, 13120.47518099276, 13132.606021819336, 12039.0, 12210.145289829716, 12419.937900310508, 12495.250284982907, 12561.309127997956, 838.2832678060247, 860.2472836268265, 862.7023676831483, 863.7365603490932, 864.170396419435, 819.0908092017855, 839.7019058234331, 851.2126283188057, 851.6635928808117, 852.3096292003497, 886.1315910407804, 897.6678628907314, 898.9595468203946, 899.2042042792143, 899.5142622983581, 874.715717391847, 888.2671795768453, 892.8526793079134, 893.7988952485704, 894.3203165594196, 656.238763034882, 656.6421300391298, 658.5094104891838, 658.9637569933652, 659.4131223211346, 13771.0, 13788.379522921392, 13964.162150271017, 13977.161370317814, 14312.21282829444, 13478.797818032737, 14194.858051419456, 14293.999420040602, 14324.355404006845, 14398.568042958728, 10487.37075775456, 10598.152147828156, 10606.68179954595, 10858.0, 10867.891321086767, 2511.1336588876807, 2515.9245222643167, 2534.6071358939394, 2573.1637217904067, 2612.529744645963, 60271.69864150695, 60852.69573652103, 60957.39042609561, 62388.0, 63161.0, 10183.0, 10222.027693776721, 10419.976171564764, 10435.37387756737, 10441.0, 20955.26656567013, 21122.366329010154, 21223.257185998726, 21298.0, 21327.78672213258, 6362.427381535592, 6421.165248517663, 6440.033994900802, 6446.677666116675, 6464.450521705614, 3339.3154403347257, 3417.6924076833207, 3469.774464659789, 3519.2433626770367, 3526.5239192708655, 3429.794212347267, 3467.029231815096, 3482.1990942082957, 3493.825308734604, 3504.3867323218333, 708.7554793596202, 711.697528550364, 713.9643017849087, 724.2431658916445, 729.7117638532856, 468.9740321809749, 487.8658368948497, 496.3720893070269, 503.86899406154663, 505.8710028942596, 8920.86618700707, 8947.284217262604, 8978.326625503114, 9194.448333100036, 9216.400933939272, 528.6801485101458, 550.5540512185142, 551.8344496650989, 556.8051182086318, 559.5495626021092, 3854.730168888207, 4020.8994775130273, 4066.7356621819486, 4166.312558427825, 4218.2196293685765, 56275.437245627836, 58551.0, 59267.70366148228, 61711.691441542505, 61807.690961544904, 52144.21783673248, 52942.20586691203, 53302.46697533053, 53764.19353709697, 54273.72863135659, 47949.520504794506, 48198.5180148201, 48236.0, 49631.25553116706, 49690.50309496931, 7928.365730741642, 8235.423520353517, 8254.917450825415, 8257.42198046155, 8384.413091083565, 26495.205143844945, 26713.866430667724, 26923.73076269212, 27167.592486112328, 27424.314392140357, 32523.51214731828, 32826.17934551655, 32867.34265314728, 33033.0, 33664.663353367134, 10090.646827361006, 10106.292559521056, 10154.54304556282, 10250.231232657432, 10494.108000820015, 11460.94269528647, 11478.196526243417, 11509.36698481558, 11509.654710358367, 11755.588554400554, 10374.42940638242, 10398.116160126474, 10581.788364232827, 10780.622678206226, 11052.778944420907, 10806.621768238074, 10825.404602746925, 10869.130469562258, 10888.606395972236, 11405.771884562428, 10089.19476260274, 10114.949425252827, 10222.335548189343, 10328.535215915452, 10673.146148307962, 7134.785956421316, 7140.483406163197, 7207.891881621885, 7315.817104572428, 7383.15093764224, 8587.742367728979, 8693.130686931509, 8870.822583548423, 8952.462852228888, 9034.23832352685], "value": 6089.649429097443, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 112.0, 113.0, 112.0, 111.0, 111.0, 111.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 123.0, 124.0, 163.0, 125.0, 124.0, 136.0, 124.0, 151.0, 125.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 163.0, 162.0, 165.0, 161.0, 162.0, 162.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 114.0, 75.0, 76.0, 76.0, 76.0, 92.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 106.0, 84.0, 85.0, 84.0, 99.0, 84.0, 85.0, 106.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 148.0, 149.0, 148.0, 148.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 96.0, 94.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 65.0, 65.0, 66.0, 68.0, 67.0, 67.0, 68.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 57.0, 55.0, 58.0, 56.0, 56.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 83.0, 81.0, 90.0, 90.0, 81.0, 81.0, 81.0, 81.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 146.0, 142.0, 141.0, 133.0, 137.0, 143.0, 149.0, 181.0, 132.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 191.0, 191.0, 192.0, 189.0, 191.0, 189.0, 190.0, 191.0, 190.0], "value": 191.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 73.0, 72.0, 71.0, 71.0, 70.0, 70.0, 70.0, 70.0], "value": 70.8, "unit": "score"}]}]} 19:33:00 INFO - Return code: 0 19:33:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:33:00 ERROR - # TBPL SUCCESS # 19:33:00 INFO - Running post-action listener: _resource_record_post_action 19:33:00 INFO - [mozharness: 2017-03-26 02:33:00.784554Z] Finished run-tests step (success) 19:33:00 INFO - Running post-run listener: _resource_record_post_run 19:33:00 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 365948928; Read time: 12; Write time: 179036 19:33:00 INFO - TinderboxPrint: CPU usage
13.0% 19:33:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:33:00 INFO - TinderboxPrint: I/O write bytes / time
365,948,928 / 179,036 19:33:00 INFO - TinderboxPrint: CPU idle
8,370.6 (87.0%) 19:33:00 INFO - TinderboxPrint: CPU user
1,202.0 (12.5%) 19:33:00 INFO - TinderboxPrint: Swap in / out
0 / 0 19:33:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105865216; Read time: 0; Write time: 59132 19:33:00 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 260083712; Read time: 12; Write time: 119904 19:33:01 INFO - Running post-run listener: _upload_blobber_files 19:33:01 INFO - Blob upload gear active. 19:33:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:33:01 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 19:33:01 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'] 19:33:01 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 19:33:01 INFO - (blobuploader) - INFO - Open directory for files ... 19:33:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:33:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:33:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:02 INFO - (blobuploader) - INFO - Done attempting. 19:33:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:33:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:33:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:03 INFO - (blobuploader) - INFO - Done attempting. 19:33:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:33:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:33:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:04 INFO - (blobuploader) - INFO - Done attempting. 19:33:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:33:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:33:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:08 INFO - (blobuploader) - INFO - Done attempting. 19:33:08 INFO - (blobuploader) - INFO - Iteration through files over. 19:33:08 INFO - Return code: 0 19:33:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:33:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:33:08 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30ebf5be71b6ad9b617bc425c256ae0156dfc56ca2a740f2b7f826cad51e6683d8d44320d6848b03555f016e4a67193c0d8594277ec6ae2fc400bfbea7d74ea0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66d325b88202ac5637ac60274ce55d0483e1c5ea449c698636545fe157b22cc21fa5785fb67747b109f85bb3f3926e064aaec2da47bb225405ed59f365a094f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43981c92a2e8b4d3c8cd9230452ad14ac97cdccbc9987336f9889036b205858b0cdca8c0ddf8982b46a65de0f3d4ffcba33477e56fcf8218408ffb3b74210335", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a5b4f5777c8979c472ca6557aefeed0ffc0320dc44163b1260bdf54a4169dd735e8a0be5da2ed2d75378188b8ad276f123f7c28da5caa47831cb8dfee38adf02"} 19:33:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:33:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:33:08 INFO - Contents: 19:33:08 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30ebf5be71b6ad9b617bc425c256ae0156dfc56ca2a740f2b7f826cad51e6683d8d44320d6848b03555f016e4a67193c0d8594277ec6ae2fc400bfbea7d74ea0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66d325b88202ac5637ac60274ce55d0483e1c5ea449c698636545fe157b22cc21fa5785fb67747b109f85bb3f3926e064aaec2da47bb225405ed59f365a094f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43981c92a2e8b4d3c8cd9230452ad14ac97cdccbc9987336f9889036b205858b0cdca8c0ddf8982b46a65de0f3d4ffcba33477e56fcf8218408ffb3b74210335", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a5b4f5777c8979c472ca6557aefeed0ffc0320dc44163b1260bdf54a4169dd735e8a0be5da2ed2d75378188b8ad276f123f7c28da5caa47831cb8dfee38adf02"} 19:33:08 INFO - Running post-run listener: copy_logs_to_upload_dir 19:33:08 INFO - Copying logs to upload dir... 19:33:08 INFO - mkdir: /builds/slave/test/build/upload/logs 19:33:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.908635 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-03-25 19:33:05.696461) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 19:33:05.703755) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30ebf5be71b6ad9b617bc425c256ae0156dfc56ca2a740f2b7f826cad51e6683d8d44320d6848b03555f016e4a67193c0d8594277ec6ae2fc400bfbea7d74ea0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66d325b88202ac5637ac60274ce55d0483e1c5ea449c698636545fe157b22cc21fa5785fb67747b109f85bb3f3926e064aaec2da47bb225405ed59f365a094f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43981c92a2e8b4d3c8cd9230452ad14ac97cdccbc9987336f9889036b205858b0cdca8c0ddf8982b46a65de0f3d4ffcba33477e56fcf8218408ffb3b74210335", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a5b4f5777c8979c472ca6557aefeed0ffc0320dc44163b1260bdf54a4169dd735e8a0be5da2ed2d75378188b8ad276f123f7c28da5caa47831cb8dfee38adf02"} build_url:https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011185 build_url: 'https://queue.taskcluster.net/v1/task/b_iikFB9QCyzN_mea2vFJw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30ebf5be71b6ad9b617bc425c256ae0156dfc56ca2a740f2b7f826cad51e6683d8d44320d6848b03555f016e4a67193c0d8594277ec6ae2fc400bfbea7d74ea0", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66d325b88202ac5637ac60274ce55d0483e1c5ea449c698636545fe157b22cc21fa5785fb67747b109f85bb3f3926e064aaec2da47bb225405ed59f365a094f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43981c92a2e8b4d3c8cd9230452ad14ac97cdccbc9987336f9889036b205858b0cdca8c0ddf8982b46a65de0f3d4ffcba33477e56fcf8218408ffb3b74210335", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a5b4f5777c8979c472ca6557aefeed0ffc0320dc44163b1260bdf54a4169dd735e8a0be5da2ed2d75378188b8ad276f123f7c28da5caa47831cb8dfee38adf02"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 19:33:05.729163) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 19:33:05.729502) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1490477217.759656-887347521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004422 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 19:33:05.815432) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 19:33:05.815786) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 19:33:05.816140) ========= ========= Total master_lag: 0.20 =========