builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-029 starttime: 1490555588.62 results: success (0) revision: 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.616045) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.616568) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.616881) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.632363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.632757) ========= 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=ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-26 12:13:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 211M=0s 2017-03-26 12:13:09 (211 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122342 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.769199) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.769518) ========= 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=ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017381 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.821658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:08.821963) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e --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 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e --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=ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-26 12:13:09,254 truncating revision to first 12 chars 2017-03-26 12:13:09,254 Setting DEBUG logging. 2017-03-26 12:13:09,254 attempt 1/10 2017-03-26 12:13:09,254 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1c0b16d1747c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-26 12:13:09,706 unpacking tar archive at: mozilla-central-1c0b16d1747c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.743972 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:09.580983) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:09.581399) ========= script_repo_revision: 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:09.581825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:09.582131) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 12:13:09.595410) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-03-26 12:13:09.595851) ========= /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=ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:13:10 INFO - MultiFileLogger online at 20170326 12:13:10 in /builds/slave/test 12:13:10 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 12:13:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:13:10 INFO - {'append_to_log': False, 12:13:10 INFO - 'base_work_dir': '/builds/slave/test', 12:13:10 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:13:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:13:10 INFO - 'branch': 'Firefox-Non-PGO', 12:13:10 INFO - 'buildbot_json_path': 'buildprops.json', 12:13:10 INFO - 'config_files': ('talos/linux_config.py',), 12:13:10 INFO - 'default_actions': ('clobber', 12:13:10 INFO - 'read-buildbot-config', 12:13:10 INFO - 'download-and-extract', 12:13:10 INFO - 'populate-webroot', 12:13:10 INFO - 'create-virtualenv', 12:13:10 INFO - 'install', 12:13:10 INFO - 'run-tests'), 12:13:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:13:10 INFO - 'download_minidump_stackwalk': True, 12:13:10 INFO - 'download_symbols': 'ondemand', 12:13:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:13:10 INFO - 'tooltool.py': '/tools/tooltool.py', 12:13:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:13:10 INFO - '/tools/misc-python/virtualenv.py')}, 12:13:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:13:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:13:10 INFO - 'gecko_profile': False, 12:13:10 INFO - 'gecko_profile_interval': 0, 12:13:10 INFO - 'installer_path': 'installer.exe', 12:13:10 INFO - 'log_level': 'info', 12:13:10 INFO - 'log_name': 'talos', 12:13:10 INFO - 'log_to_console': True, 12:13:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:13:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:13:10 INFO - 'opt_config_files': (), 12:13:10 INFO - 'pip_index': False, 12:13:10 INFO - 'suite': 'dromaeojs', 12:13:10 INFO - 'system_bits': '32', 12:13:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:13:10 INFO - 'title': 'talos-linux64-ix-029', 12:13:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:13:10 INFO - 'use_talos_json': True, 12:13:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:13:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:13:10 INFO - 'work_dir': 'build'} 12:13:10 INFO - [mozharness: 2017-03-26 19:13:10.124588Z] Running clobber step. 12:13:10 INFO - Running pre-action listener: _resource_record_pre_action 12:13:10 INFO - Running main action method: clobber 12:13:10 INFO - rmtree: /builds/slave/test/build 12:13:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:13:11 INFO - Running post-action listener: _resource_record_post_action 12:13:11 INFO - [mozharness: 2017-03-26 19:13:11.031624Z] Finished clobber step (success) 12:13:11 INFO - [mozharness: 2017-03-26 19:13:11.031725Z] Running read-buildbot-config step. 12:13:11 INFO - Running pre-action listener: _resource_record_pre_action 12:13:11 INFO - Running main action method: read_buildbot_config 12:13:11 INFO - Using buildbot properties: 12:13:11 INFO - { 12:13:11 INFO - "project": "", 12:13:11 INFO - "product": "firefox", 12:13:11 INFO - "who": "archaeopteryx@coole-files.de", 12:13:11 INFO - "installer_path": "public/build/target.tar.bz2", 12:13:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:13:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:13:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 12:13:11 INFO - "stage_platform": "linux64", 12:13:11 INFO - "basedir": "/builds/slave/test", 12:13:11 INFO - "buildnumber": 1034, 12:13:11 INFO - "platform": "ubuntu64_hw", 12:13:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:13:11 INFO - "slavebuilddir": "test", 12:13:11 INFO - "taskId": "RNHBlnUcRCueL9LSngH5pQ", 12:13:11 INFO - "branch": "mozilla-central", 12:13:11 INFO - "script_repo_revision": "production", 12:13:11 INFO - "revision": "1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e", 12:13:11 INFO - "slavename": "talos-linux64-ix-029", 12:13:11 INFO - "repo_path": "mozilla-central" 12:13:11 INFO - } 12:13:11 INFO - Finding installer, test and symbols from parent task. 12:13:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNHBlnUcRCueL9LSngH5pQ'}, attempt #1 12:13:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNHBlnUcRCueL9LSngH5pQ'}, attempt #1 12:13:12 INFO - Task dependencies: OD1VA49gSqGfLK3faV0-sg 12:13:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg'}, attempt #1 12:13:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.tar.bz2 12:13:12 INFO - Set test_url: https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/test_packages.json 12:13:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.crashreporter-symbols.zip 12:13:12 INFO - Running post-action listener: _resource_record_post_action 12:13:12 INFO - [mozharness: 2017-03-26 19:13:12.536823Z] Finished read-buildbot-config step (success) 12:13:12 INFO - [mozharness: 2017-03-26 19:13:12.537085Z] Running download-and-extract step. 12:13:12 INFO - Running pre-action listener: _resource_record_pre_action 12:13:12 INFO - Running main action method: download_and_extract 12:13:12 INFO - mkdir: /builds/slave/test/build/tests 12:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:12 INFO - trying https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.test_packages.json 12:13:12 INFO - Downloading https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:13:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:13:13 INFO - Downloaded 986 bytes. 12:13:13 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:13:13 INFO - Using the following test package requirements: 12:13:13 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:13:13 INFO - u'common': [u'target.common.tests.zip'], 12:13:13 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:13:13 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:13:13 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:13:13 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:13:13 INFO - u'mozbase': [u'target.common.tests.zip'], 12:13:13 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:13:13 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:13:13 INFO - u'web-platform': [u'target.common.tests.zip', 12:13:13 INFO - u'target.web-platform.tests.zip'], 12:13:13 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:13:13 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:13:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.common.tests.zip 12:13:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:13:13 INFO - Fetch https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.common.tests.zip into memory 12:13:15 INFO - Expected file size: 58128628 12:13:15 INFO - Obtained file size: 58128628 12:13:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:13:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.common.tests.zip 12:13:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:13:25 INFO - Fetch https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.common.tests.zip into memory 12:13:27 INFO - Expected file size: 58128628 12:13:27 INFO - Obtained file size: 58128628 12:13:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.talos.tests.zip 12:13:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:13:38 INFO - Fetch https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.talos.tests.zip into memory 12:13:39 INFO - Expected file size: 14404171 12:13:39 INFO - Obtained file size: 14404171 12:13: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')]} 12:13:40 INFO - trying https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.tar.bz2 12:13:40 INFO - Downloading https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:13:42 INFO - Downloaded 62546189 bytes. 12:13:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.tar.bz2 12:13:42 INFO - mkdir: /builds/slave/test/properties 12:13:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:13:42 INFO - Writing to file /builds/slave/test/properties/build_url 12:13:42 INFO - Contents: 12:13:42 INFO - build_url:https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.tar.bz2 12:13:42 INFO - Running post-action listener: _resource_record_post_action 12:13:42 INFO - Running post-action listener: set_extra_try_arguments 12:13:42 INFO - [mozharness: 2017-03-26 19:13:42.898583Z] Finished download-and-extract step (success) 12:13:42 INFO - [mozharness: 2017-03-26 19:13:42.898679Z] Running populate-webroot step. 12:13:42 INFO - Running pre-action listener: _resource_record_pre_action 12:13:42 INFO - Running main action method: populate_webroot 12:13:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:13:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:13:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:13:42 INFO - '--tppagecycles', 12:13:42 INFO - '7'], 12:13:42 INFO - 'tests': ['tsvgm']}}, 12:13:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:13:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:13:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:13:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:13:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:13:42 INFO - 'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:13:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:13:42 INFO - 'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['damp', 'tps']}, 12:13:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:13:42 INFO - 'tests': ['damp', 'tps']}, 12:13:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['dromaeo_dom']}, 12:13:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:13:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['basic_compositor_video']}, 12:13:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:13:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['a11yr', 12:13:42 INFO - 'ts_paint', 12:13:42 INFO - 'tpaint', 12:13:42 INFO - 'sessionrestore', 12:13:42 INFO - 'sessionrestore_no_auto_restore', 12:13:42 INFO - 'tabpaint']}, 12:13:42 INFO - 'other-e10s': {'tests': ['a11yr', 12:13:42 INFO - 'ts_paint', 12:13:42 INFO - 'tpaint', 12:13:42 INFO - 'sessionrestore', 12:13:42 INFO - 'sessionrestore_no_auto_restore', 12:13:42 INFO - 'tabpaint']}, 12:13:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:13:42 INFO - 'ts_paint', 12:13:42 INFO - 'tpaint', 12:13:42 INFO - 'sessionrestore', 12:13:42 INFO - 'sessionrestore_no_auto_restore', 12:13:42 INFO - 'tabpaint']}, 12:13:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:13:42 INFO - 'ts_paint', 12:13:42 INFO - 'tpaint', 12:13:42 INFO - 'sessionrestore', 12:13:42 INFO - 'sessionrestore_no_auto_restore', 12:13:42 INFO - 'tabpaint']}, 12:13:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['a11yr', 12:13:42 INFO - 'ts_paint', 12:13:42 INFO - 'tpaint', 12:13:42 INFO - 'sessionrestore', 12:13:42 INFO - 'sessionrestore_no_auto_restore', 12:13:42 INFO - 'tabpaint']}, 12:13:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['a11yr', 12:13:42 INFO - 'ts_paint', 12:13:42 INFO - 'tpaint', 12:13:42 INFO - 'sessionrestore', 12:13:42 INFO - 'sessionrestore_no_auto_restore', 12:13:42 INFO - 'tabpaint']}, 12:13:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['tsvgx', 12:13:42 INFO - 'tsvgr_opacity', 12:13:42 INFO - 'tart', 12:13:42 INFO - 'tscrollx', 12:13:42 INFO - 'cart', 12:13:42 INFO - 'tsvg_static']}, 12:13:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:13:42 INFO - 'tsvgr_opacity', 12:13:42 INFO - 'tart', 12:13:42 INFO - 'tscrollx', 12:13:42 INFO - 'cart', 12:13:42 INFO - 'tsvg_static']}, 12:13:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:13:42 INFO - 'talos_options': ['--disable-e10s'], 12:13:42 INFO - 'tests': ['tp5o']}, 12:13:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:13:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:13:42 INFO - 'talos_options': ['--disable-e10s', 12:13:42 INFO - '--xperf_path', 12:13:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:42 INFO - 'tests': ['tp5n']}, 12:13:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:13:42 INFO - 'talos_options': ['--xperf_path', 12:13:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:42 INFO - 'tests': ['tp5n']}}, 12:13:42 INFO - 'talos.zip': {'path': '', 12:13:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:13:42 INFO - Running post-action listener: _resource_record_post_action 12:13:42 INFO - [mozharness: 2017-03-26 19:13:42.911827Z] Finished populate-webroot step (success) 12:13:42 INFO - [mozharness: 2017-03-26 19:13:42.911916Z] Running create-virtualenv step. 12:13:42 INFO - Running pre-action listener: _resource_record_pre_action 12:13:42 INFO - Running main action method: create_virtualenv 12:13:42 INFO - Creating virtualenv /builds/slave/test/build/venv 12:13:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:13:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:13:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:13:43 INFO - Using real prefix '/usr' 12:13:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:13:43 INFO - Installing distribute.............................................................................................................................................................................................done. 12:13:45 INFO - Installing pip.................done. 12:13:45 INFO - Return code: 0 12:13:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:13:45 INFO - Reading from file tmpfile_stdout 12:13:45 INFO - Output received: 12:13:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:13:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:45 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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:13:45 INFO - Using env: {'DISPLAY': ':0', 12:13:45 INFO - 'HOME': '/home/cltbld', 12:13:45 INFO - 'LANG': 'en_US.UTF-8', 12:13:45 INFO - 'LANGUAGE': 'en_US:en', 12:13:45 INFO - 'LOGNAME': 'cltbld', 12:13:45 INFO - 'MAIL': '/var/mail/cltbld', 12:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:45 INFO - 'MOZ_NO_REMOTE': '1', 12:13:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:45 INFO - 'NO_EM_RESTART': '1', 12:13:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:45 INFO - 'PWD': '/builds/slave/test', 12:13:45 INFO - 'SHELL': '/bin/bash', 12:13:45 INFO - 'SHLVL': '1', 12:13:45 INFO - 'TERM': 'linux', 12:13:45 INFO - 'TMOUT': '86400', 12:13:45 INFO - 'USER': 'cltbld', 12:13:45 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:45 INFO - '_': '/tools/buildbot/bin/python'} 12:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:13:45 INFO - Cleaning up... 12:13:45 INFO - Return code: 0 12:13:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:45 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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:13:45 INFO - Using env: {'DISPLAY': ':0', 12:13:45 INFO - 'HOME': '/home/cltbld', 12:13:45 INFO - 'LANG': 'en_US.UTF-8', 12:13:45 INFO - 'LANGUAGE': 'en_US:en', 12:13:45 INFO - 'LOGNAME': 'cltbld', 12:13:45 INFO - 'MAIL': '/var/mail/cltbld', 12:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:45 INFO - 'MOZ_NO_REMOTE': '1', 12:13:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:45 INFO - 'NO_EM_RESTART': '1', 12:13:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:45 INFO - 'PWD': '/builds/slave/test', 12:13:45 INFO - 'SHELL': '/bin/bash', 12:13:45 INFO - 'SHLVL': '1', 12:13:45 INFO - 'TERM': 'linux', 12:13:45 INFO - 'TMOUT': '86400', 12:13:45 INFO - 'USER': 'cltbld', 12:13:45 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:45 INFO - '_': '/tools/buildbot/bin/python'} 12:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:46 INFO - Downloading/unpacking psutil>=3.1.1 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:13:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:48 INFO - Installing collected packages: psutil 12:13:48 INFO - Running setup.py install for psutil 12:13:48 INFO - building 'psutil._psutil_linux' extension 12:13:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:13:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:13:48 INFO - building 'psutil._psutil_posix' extension 12:13:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:13:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:13:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:48 INFO - Successfully installed psutil 12:13:48 INFO - Cleaning up... 12:13:48 INFO - Return code: 0 12:13:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:48 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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:13:48 INFO - Using env: {'DISPLAY': ':0', 12:13:48 INFO - 'HOME': '/home/cltbld', 12:13:48 INFO - 'LANG': 'en_US.UTF-8', 12:13:48 INFO - 'LANGUAGE': 'en_US:en', 12:13:48 INFO - 'LOGNAME': 'cltbld', 12:13:48 INFO - 'MAIL': '/var/mail/cltbld', 12:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:48 INFO - 'MOZ_NO_REMOTE': '1', 12:13:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:48 INFO - 'NO_EM_RESTART': '1', 12:13:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:48 INFO - 'PWD': '/builds/slave/test', 12:13:48 INFO - 'SHELL': '/bin/bash', 12:13:48 INFO - 'SHLVL': '1', 12:13:48 INFO - 'TERM': 'linux', 12:13:48 INFO - 'TMOUT': '86400', 12:13:48 INFO - 'USER': 'cltbld', 12:13:48 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:48 INFO - '_': '/tools/buildbot/bin/python'} 12:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:13:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:13:51 INFO - Installing collected packages: mozsystemmonitor 12:13:51 INFO - Running setup.py install for mozsystemmonitor 12:13:51 INFO - Successfully installed mozsystemmonitor 12:13:51 INFO - Cleaning up... 12:13:51 INFO - Return code: 0 12:13:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:51 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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:13:51 INFO - Using env: {'DISPLAY': ':0', 12:13:51 INFO - 'HOME': '/home/cltbld', 12:13:51 INFO - 'LANG': 'en_US.UTF-8', 12:13:51 INFO - 'LANGUAGE': 'en_US:en', 12:13:51 INFO - 'LOGNAME': 'cltbld', 12:13:51 INFO - 'MAIL': '/var/mail/cltbld', 12:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:51 INFO - 'MOZ_NO_REMOTE': '1', 12:13:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:51 INFO - 'NO_EM_RESTART': '1', 12:13:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:51 INFO - 'PWD': '/builds/slave/test', 12:13:51 INFO - 'SHELL': '/bin/bash', 12:13:51 INFO - 'SHLVL': '1', 12:13:51 INFO - 'TERM': 'linux', 12:13:51 INFO - 'TMOUT': '86400', 12:13:51 INFO - 'USER': 'cltbld', 12:13:51 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:51 INFO - '_': '/tools/buildbot/bin/python'} 12:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:51 INFO - Downloading/unpacking jsonschema==2.5.1 12:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:13:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:53 INFO - Downloading functools32-3.2.3-2.tar.gz 12:13:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:13:53 INFO - warning: no files found matching '*.txt' 12:13:53 INFO - no previously-included directories found matching 'build' 12:13:53 INFO - no previously-included directories found matching 'dist' 12:13:53 INFO - no previously-included directories found matching '.git*' 12:13:53 INFO - Installing collected packages: jsonschema, functools32 12:13:53 INFO - Running setup.py install for functools32 12:13:53 INFO - warning: no files found matching '*.txt' 12:13:53 INFO - no previously-included directories found matching 'build' 12:13:53 INFO - no previously-included directories found matching 'dist' 12:13:53 INFO - no previously-included directories found matching '.git*' 12:13:53 INFO - Successfully installed jsonschema functools32 12:13:53 INFO - Cleaning up... 12:13:53 INFO - Return code: 0 12:13:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:53 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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:13:53 INFO - Using env: {'DISPLAY': ':0', 12:13:53 INFO - 'HOME': '/home/cltbld', 12:13:53 INFO - 'LANG': 'en_US.UTF-8', 12:13:53 INFO - 'LANGUAGE': 'en_US:en', 12:13:53 INFO - 'LOGNAME': 'cltbld', 12:13:53 INFO - 'MAIL': '/var/mail/cltbld', 12:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:53 INFO - 'MOZ_NO_REMOTE': '1', 12:13:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:53 INFO - 'NO_EM_RESTART': '1', 12:13:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:53 INFO - 'PWD': '/builds/slave/test', 12:13:53 INFO - 'SHELL': '/bin/bash', 12:13:53 INFO - 'SHLVL': '1', 12:13:53 INFO - 'TERM': 'linux', 12:13:53 INFO - 'TMOUT': '86400', 12:13:53 INFO - 'USER': 'cltbld', 12:13:53 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:53 INFO - '_': '/tools/buildbot/bin/python'} 12:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:13:54 INFO - Cleaning up... 12:13:54 INFO - Return code: 0 12:13:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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', '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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:13: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:13: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 blobuploader==1.2.4 12:13:54 INFO - Using env: {'DISPLAY': ':0', 12:13:54 INFO - 'HOME': '/home/cltbld', 12:13:54 INFO - 'LANG': 'en_US.UTF-8', 12:13:54 INFO - 'LANGUAGE': 'en_US:en', 12:13:54 INFO - 'LOGNAME': 'cltbld', 12:13:54 INFO - 'MAIL': '/var/mail/cltbld', 12:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:54 INFO - 'MOZ_NO_REMOTE': '1', 12:13:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:54 INFO - 'NO_EM_RESTART': '1', 12:13:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:54 INFO - 'PWD': '/builds/slave/test', 12:13:54 INFO - 'SHELL': '/bin/bash', 12:13:54 INFO - 'SHLVL': '1', 12:13:54 INFO - 'TERM': 'linux', 12:13:54 INFO - 'TMOUT': '86400', 12:13:54 INFO - 'USER': 'cltbld', 12:13:54 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:54 INFO - '_': '/tools/buildbot/bin/python'} 12:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:54 INFO - Downloading/unpacking blobuploader==1.2.4 12:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:56 INFO - Downloading blobuploader-1.2.4.tar.gz 12:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:13:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:13:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:57 INFO - Downloading docopt-0.6.1.tar.gz 12:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:13:57 INFO - Installing collected packages: blobuploader, requests, docopt 12:13:57 INFO - Running setup.py install for blobuploader 12:13:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:13:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:13:57 INFO - Running setup.py install for requests 12:13:57 INFO - Running setup.py install for docopt 12:13:57 INFO - Successfully installed blobuploader requests docopt 12:13:57 INFO - Cleaning up... 12:13:58 INFO - Return code: 0 12:13:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:58 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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:13:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:13:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:13:58 INFO - Using env: {'DISPLAY': ':0', 12:13:58 INFO - 'HOME': '/home/cltbld', 12:13:58 INFO - 'LANG': 'en_US.UTF-8', 12:13:58 INFO - 'LANGUAGE': 'en_US:en', 12:13:58 INFO - 'LOGNAME': 'cltbld', 12:13:58 INFO - 'MAIL': '/var/mail/cltbld', 12:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:58 INFO - 'MOZ_NO_REMOTE': '1', 12:13:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:58 INFO - 'NO_EM_RESTART': '1', 12:13:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:58 INFO - 'PWD': '/builds/slave/test', 12:13:58 INFO - 'SHELL': '/bin/bash', 12:13:58 INFO - 'SHLVL': '1', 12:13:58 INFO - 'TERM': 'linux', 12:13:58 INFO - 'TMOUT': '86400', 12:13:58 INFO - 'USER': 'cltbld', 12:13:58 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:58 INFO - '_': '/tools/buildbot/bin/python'} 12:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:58 INFO - Running setup.py (path:/tmp/pip-jWfoGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:58 INFO - Running setup.py (path:/tmp/pip-dlWwQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:58 INFO - Running setup.py (path:/tmp/pip-riLbcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:58 INFO - Running setup.py (path:/tmp/pip-GXtsZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:58 INFO - Running setup.py (path:/tmp/pip-WlGo07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:58 INFO - Running setup.py (path:/tmp/pip-xLuGuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:58 INFO - Running setup.py (path:/tmp/pip-EcXpqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:58 INFO - Running setup.py (path:/tmp/pip-Uwfnwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:58 INFO - Running setup.py (path:/tmp/pip-wTrT6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:58 INFO - Running setup.py (path:/tmp/pip-Ml2_YD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:58 INFO - Running setup.py (path:/tmp/pip-_iJ7Vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:59 INFO - Running setup.py (path:/tmp/pip-FGRaxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:59 INFO - Running setup.py (path:/tmp/pip-fgw2Ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:59 INFO - Running setup.py (path:/tmp/pip-8HzaRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:59 INFO - Running setup.py (path:/tmp/pip-byBVmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:59 INFO - Running setup.py (path:/tmp/pip-1FC9Sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:59 INFO - Running setup.py (path:/tmp/pip-HJhouF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:13:59 INFO - Running setup.py install for manifestparser 12:13:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:13:59 INFO - Running setup.py install for mozcrash 12:13:59 INFO - Running setup.py install for mozdebug 12:13:59 INFO - Running setup.py install for mozdevice 12:13:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:13:59 INFO - Running setup.py install for mozfile 12:13:59 INFO - Running setup.py install for mozhttpd 12:14:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Running setup.py install for mozinfo 12:14:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Running setup.py install for mozInstall 12:14:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Running setup.py install for mozleak 12:14:00 INFO - Running setup.py install for mozlog 12:14:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Running setup.py install for moznetwork 12:14:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Running setup.py install for mozprocess 12:14:00 INFO - Running setup.py install for mozprofile 12:14:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Running setup.py install for mozrunner 12:14:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:14:00 INFO - Running setup.py install for mozscreenshot 12:14:01 INFO - Running setup.py install for moztest 12:14:01 INFO - Running setup.py install for mozversion 12:14:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:14:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:14:01 INFO - Cleaning up... 12:14:01 INFO - Return code: 0 12:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:01 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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:14:01 INFO - Using env: {'DISPLAY': ':0', 12:14:01 INFO - 'HOME': '/home/cltbld', 12:14:01 INFO - 'LANG': 'en_US.UTF-8', 12:14:01 INFO - 'LANGUAGE': 'en_US:en', 12:14:01 INFO - 'LOGNAME': 'cltbld', 12:14:01 INFO - 'MAIL': '/var/mail/cltbld', 12:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:01 INFO - 'MOZ_NO_REMOTE': '1', 12:14:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:01 INFO - 'NO_EM_RESTART': '1', 12:14:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:01 INFO - 'PWD': '/builds/slave/test', 12:14:01 INFO - 'SHELL': '/bin/bash', 12:14:01 INFO - 'SHLVL': '1', 12:14:01 INFO - 'TERM': 'linux', 12:14:01 INFO - 'TMOUT': '86400', 12:14:01 INFO - 'USER': 'cltbld', 12:14:01 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:01 INFO - '_': '/tools/buildbot/bin/python'} 12:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:01 INFO - Running setup.py (path:/tmp/pip-eloVsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:01 INFO - Running setup.py (path:/tmp/pip-fFCAsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:01 INFO - Running setup.py (path:/tmp/pip-jpU2BE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:01 INFO - Running setup.py (path:/tmp/pip-FkAuzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:01 INFO - Running setup.py (path:/tmp/pip-EEYygx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:01 INFO - Running setup.py (path:/tmp/pip-ocZB99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:01 INFO - Running setup.py (path:/tmp/pip-i34oEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:01 INFO - Running setup.py (path:/tmp/pip-ITOhYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:02 INFO - Running setup.py (path:/tmp/pip-FZFiXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:02 INFO - Running setup.py (path:/tmp/pip-nIem1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:02 INFO - Running setup.py (path:/tmp/pip-DcB187-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:02 INFO - Running setup.py (path:/tmp/pip-iptUMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:02 INFO - Running setup.py (path:/tmp/pip-2jdVfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:02 INFO - Running setup.py (path:/tmp/pip-RIcyQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:02 INFO - Running setup.py (path:/tmp/pip-MPGoUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:02 INFO - Running setup.py (path:/tmp/pip-3NT2wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:02 INFO - Running setup.py (path:/tmp/pip-gA6EzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:14:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:04 INFO - Downloading blessings-1.6.tar.gz 12:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:14:04 INFO - Installing collected packages: blessings 12:14:04 INFO - Running setup.py install for blessings 12:14:04 INFO - Successfully installed blessings 12:14:04 INFO - Cleaning up... 12:14:04 INFO - Return code: 0 12:14:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:14:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:05 INFO - Reading from file tmpfile_stdout 12:14:05 INFO - Current package versions: 12:14:05 INFO - argparse == 1.2.1 12:14:05 INFO - blessings == 1.6 12:14:05 INFO - blobuploader == 1.2.4 12:14:05 INFO - docopt == 0.6.1 12:14:05 INFO - functools32 == 3.2.3-2 12:14:05 INFO - jsonschema == 2.5.1 12:14:05 INFO - manifestparser == 1.1 12:14:05 INFO - mozInstall == 1.12 12:14:05 INFO - mozcrash == 1.0 12:14:05 INFO - mozdebug == 0.1 12:14:05 INFO - mozdevice == 0.49 12:14:05 INFO - mozfile == 1.2 12:14:05 INFO - mozhttpd == 0.7 12:14:05 INFO - mozinfo == 0.9 12:14:05 INFO - mozleak == 0.1 12:14:05 INFO - mozlog == 3.4 12:14:05 INFO - moznetwork == 0.27 12:14:05 INFO - mozprocess == 0.25 12:14:05 INFO - mozprofile == 0.28 12:14:05 INFO - mozrunner == 6.13 12:14:05 INFO - mozscreenshot == 0.1 12:14:05 INFO - mozsystemmonitor == 0.3 12:14:05 INFO - moztest == 0.8 12:14:05 INFO - mozversion == 1.4 12:14:05 INFO - psutil == 3.1.1 12:14:05 INFO - requests == 1.2.3 12:14:05 INFO - wsgiref == 0.1.2 12:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:05 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 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:14:05 INFO - Using env: {'DISPLAY': ':0', 12:14:05 INFO - 'HOME': '/home/cltbld', 12:14:05 INFO - 'LANG': 'en_US.UTF-8', 12:14:05 INFO - 'LANGUAGE': 'en_US:en', 12:14:05 INFO - 'LOGNAME': 'cltbld', 12:14:05 INFO - 'MAIL': '/var/mail/cltbld', 12:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:05 INFO - 'MOZ_NO_REMOTE': '1', 12:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:05 INFO - 'NO_EM_RESTART': '1', 12:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:05 INFO - 'PWD': '/builds/slave/test', 12:14:05 INFO - 'SHELL': '/bin/bash', 12:14:05 INFO - 'SHLVL': '1', 12:14:05 INFO - 'TERM': 'linux', 12:14:05 INFO - 'TMOUT': '86400', 12:14:05 INFO - 'USER': 'cltbld', 12:14:05 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:05 INFO - '_': '/tools/buildbot/bin/python'} 12:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:14:05 INFO - Cleaning up... 12:14:05 INFO - Return code: 0 12:14:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xa921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb8aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:14:05 INFO - Using env: {'DISPLAY': ':0', 12:14:05 INFO - 'HOME': '/home/cltbld', 12:14:05 INFO - 'LANG': 'en_US.UTF-8', 12:14:05 INFO - 'LANGUAGE': 'en_US:en', 12:14:05 INFO - 'LOGNAME': 'cltbld', 12:14:05 INFO - 'MAIL': '/var/mail/cltbld', 12:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:05 INFO - 'MOZ_NO_REMOTE': '1', 12:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:05 INFO - 'NO_EM_RESTART': '1', 12:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:05 INFO - 'PWD': '/builds/slave/test', 12:14:05 INFO - 'SHELL': '/bin/bash', 12:14:05 INFO - 'SHLVL': '1', 12:14:05 INFO - 'TERM': 'linux', 12:14:05 INFO - 'TMOUT': '86400', 12:14:05 INFO - 'USER': 'cltbld', 12:14:05 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:05 INFO - '_': '/tools/buildbot/bin/python'} 12:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:14:05 INFO - Cleaning up... 12:14:05 INFO - Return code: 0 12:14:05 INFO - Running post-action listener: _resource_record_post_action 12:14:05 INFO - Running post-action listener: _start_resource_monitoring 12:14:05 INFO - Starting resource monitoring. 12:14:05 INFO - [mozharness: 2017-03-26 19:14:05.685284Z] Finished create-virtualenv step (success) 12:14:05 INFO - [mozharness: 2017-03-26 19:14:05.685753Z] Running install step. 12:14:05 INFO - Running pre-action listener: _resource_record_pre_action 12:14:05 INFO - Running main action method: install 12:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:05 INFO - Reading from file tmpfile_stdout 12:14:05 INFO - Detecting whether we're running mozinstall >=1.0... 12:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:14:05 INFO - Reading from file tmpfile_stdout 12:14:05 INFO - Output received: 12:14:05 INFO - Usage: mozinstall [options] installer 12:14:05 INFO - Options: 12:14:05 INFO - -h, --help show this help message and exit 12:14:05 INFO - -d DEST, --destination=DEST 12:14:05 INFO - Directory to install application into. [default: 12:14:05 INFO - "/builds/slave/test"] 12:14:05 INFO - --app=APP Application being installed. [default: firefox] 12:14:05 INFO - mkdir: /builds/slave/test/build/application 12:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:14:21 INFO - Reading from file tmpfile_stdout 12:14:21 INFO - Output received: 12:14:21 INFO - /builds/slave/test/build/application/firefox/firefox 12:14:21 INFO - Running post-action listener: _resource_record_post_action 12:14:21 INFO - [mozharness: 2017-03-26 19:14:21.654136Z] Finished install step (success) 12:14:21 INFO - [mozharness: 2017-03-26 19:14:21.654416Z] Running run-tests step. 12:14:21 INFO - Running pre-action listener: _resource_record_pre_action 12:14:21 INFO - Running main action method: run_tests 12:14:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:14:21 INFO - Python 2.7.3 12:14:21 INFO - Return code: 0 12:14:21 INFO - grabbing minidump binary from tooltool 12:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:21 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 0xd2d290>, '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 0xd2db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd2e040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:14:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:14:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:14:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:14:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:14:21 INFO - Return code: 0 12:14:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:14:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:14:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:14:21 INFO - ENV: RUST_BACKTRACE is now 1 12:14:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:14:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:14:21 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/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:14:21 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/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:14:21 INFO - Using env: {'DISPLAY': ':0', 12:14:21 INFO - 'HOME': '/home/cltbld', 12:14:21 INFO - 'LANG': 'en_US.UTF-8', 12:14:21 INFO - 'LANGUAGE': 'en_US:en', 12:14:21 INFO - 'LOGNAME': 'cltbld', 12:14:21 INFO - 'MAIL': '/var/mail/cltbld', 12:14:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:14:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:21 INFO - 'MOZ_NO_REMOTE': '1', 12:14:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:14:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:21 INFO - 'NO_EM_RESTART': '1', 12:14:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:21 INFO - 'PWD': '/builds/slave/test', 12:14:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:14:21 INFO - 'RUST_BACKTRACE': '1', 12:14:21 INFO - 'SHELL': '/bin/bash', 12:14:21 INFO - 'SHLVL': '1', 12:14:21 INFO - 'TERM': 'linux', 12:14:21 INFO - 'TMOUT': '86400', 12:14:21 INFO - 'USER': 'cltbld', 12:14:21 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193', 12:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:21 INFO - '_': '/tools/buildbot/bin/python'} 12:14:21 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/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:14:21 INFO - mozversion application_buildid: 20170326184632 12:14:21 INFO - mozversion application_changeset: 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e 12:14:21 INFO - mozversion application_display_name: Nightly 12:14:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:14:21 INFO - mozversion application_name: Firefox 12:14:21 INFO - mozversion application_remotingname: firefox 12:14:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:14:21 INFO - mozversion application_vendor: Mozilla 12:14:21 INFO - mozversion application_version: 55.0a1 12:14:21 INFO - mozversion platform_buildid: 20170326184632 12:14:21 INFO - mozversion platform_changeset: 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e 12:14:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:14:21 INFO - mozversion platform_version: 55.0a1 12:14:21 INFO - using testdate: 1490555661 12:14:21 INFO - actual date: 1490555661 12:14:21 INFO - starting webserver on 'localhost:39230' 12:14:21 INFO - SUITE-START | Running 2 tests 12:14:21 INFO - TEST-START | dromaeo_css 12:14:21 INFO - operating with platform_type : linux_ 12:14:21 INFO - Initialising browser for dromaeo_css test... 12:14:21 INFO - TEST-INFO | started process 10280 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9bTFja/profile http://localhost:39230/getInfo.html) 12:14:26 INFO - PID 10280 | __metrics Screen width/height:1600/1200 12:14:26 INFO - PID 10280 | colorDepth:24 12:14:26 INFO - PID 10280 | Browser inner width/height: 1024/697 12:14:26 INFO - PID 10280 | __metrics 12:14:27 ERROR - PID 10280 | 1490555667508 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 12:14:27 INFO - PID 10280 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:14:27 INFO - PID 10280 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:14:28 INFO - TEST-INFO | 10280: exit 0 12:14:28 INFO - Browser initialized. 12:14:28 INFO - Running cycle 1/1 for dromaeo_css test... 12:14:28 INFO - TEST-INFO | started process 10370 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9bTFja/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:14:40 INFO - PID 10370 | RSS: Main: 149123072 12:14:40 INFO - PID 10370 | 12:17:48 INFO - PID 10370 | Cycle 1(1): loaded http://localhost:39230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39230/tests/dromaeo/cssquery-ext.html) 12:17:49 INFO - PID 10370 | RSS: Main: 287358976 12:17:49 INFO - PID 10370 | 12:19:30 INFO - PID 10370 | ************************* 12:19:30 INFO - PID 10370 | A coding exception was thrown and uncaught in a Task. 12:19:30 INFO - PID 10370 | 12:19:30 ERROR - PID 10370 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:19:30 INFO - PID 10370 | Full stack: 12:19:30 INFO - PID 10370 | ************************* 12:19:30 INFO - PID 10370 | ************************* 12:19:30 INFO - PID 10370 | A coding exception was thrown and uncaught in a Task. 12:19:30 INFO - PID 10370 | 12:19:30 ERROR - PID 10370 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:19:30 INFO - PID 10370 | Full stack: 12:19:30 INFO - PID 10370 | ************************* 12:19:30 INFO - PID 10370 | ************************* 12:19:30 INFO - PID 10370 | A coding exception was thrown and uncaught in a Task. 12:19:30 INFO - PID 10370 | 12:19:30 ERROR - PID 10370 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:19:30 INFO - PID 10370 | Full stack: 12:19:30 INFO - PID 10370 | ************************* 12:19:30 INFO - PID 10370 | ************************* 12:19:30 INFO - PID 10370 | A coding exception was thrown and uncaught in a Task. 12:19:30 INFO - PID 10370 | 12:19:30 ERROR - PID 10370 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:19:30 INFO - PID 10370 | Full stack: 12:19:30 INFO - PID 10370 | ************************* 12:20:52 INFO - PID 10370 | Cycle 1(1): loaded http://localhost:39230/tests/dromaeo/cssquery-ext.html (next: http://localhost:39230/tests/dromaeo/cssquery-jquery.html) 12:20:52 INFO - PID 10370 | RSS: Main: 234053632 12:20:52 INFO - PID 10370 | 12:23:54 INFO - PID 10370 | Cycle 1(1): loaded http://localhost:39230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39230/tests/dromaeo/cssquery-mootools.html) 12:23:55 INFO - PID 10370 | RSS: Main: 203784192 12:23:55 INFO - PID 10370 | 12:26:57 INFO - PID 10370 | Cycle 1(1): loaded http://localhost:39230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39230/tests/dromaeo/cssquery-prototype.html) 12:26:58 INFO - PID 10370 | RSS: Main: 237645824 12:26:58 INFO - PID 10370 | 12:30:01 INFO - PID 10370 | Cycle 1(1): loaded http://localhost:39230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39230/tests/dromaeo/cssquery-yui.html) 12:30:02 INFO - PID 10370 | RSS: Main: 189394944 12:30:02 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | Cycle 1(1): loaded http://localhost:39230/tests/dromaeo/cssquery-yui.html (next: http://localhost:39230/tests/dromaeo/cssquery-dojo.html) 12:33:05 INFO - PID 10370 | RSS: Main: 278040576 12:33:05 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | __start_tp_report 12:33:05 INFO - PID 10370 | _x_x_mozilla_page_load 12:33:05 INFO - PID 10370 | _x_x_mozilla_page_load_details 12:33:05 INFO - PID 10370 | |i|pagename|runs| 12:33:05 INFO - PID 10370 | |0;dojo.html;2725.414035982264;2754.9173524794264;2782.833030018199;2791.7487426131656;2798.16055183445;5039.924401133986;5132.28148059272;5211.609129315302;5218.3998840133345;5272.525472707461;5196.168613021917;5212.4266330703595;5250.789968401259;5260.789568417259;5303.071962406582;4683.063387322532;4713.10451014307;4726.503717109706;4810.422749270093;4871.220604703248;4770.212914869043;4784.043191361724;4818.710877347353;4826.32431459596;4866.07544566533;4616.030633566921;4670.859874203779;4676.485586585472;4683.508231635663;4760.143174228638;4666.160091183586;4671.976640116812;4672.859814205545;4680.017196388729;4681.204195286809;4630.513796051399;4633.4439867215815;4637.165310244155;4645.0477652081245;4647.163510568096;5027.748612569357;5063.0380227756605;5090;5108.846734597931;5189.351331083614;5676;5679.460451257124;5693.373728889819;5699.373068962411;5705.74324155414;5660.264165658438;5671.744771485293;5675.404082571312;5700.429957004308;5729.971350143264;5142.640015198975;5249.790008399621;5256.92114618281;5279.023380674587;5320.308359913187;5583.525400340935;5647.181158731961;5787.768489260382;5796.94203057964;5802.79690210849;5557.777688892399;5558.610897237155;5593.664380137205;5598.748056337475;5605.691686957175;5635.070213414822;5667.829965101054;5668.036433806263;5670.064439367458;5672.659640421588;5346.4118946915805;5409.0263752524925;5583.944160558342;5594.412586678422;5594.832155035356;5463.235147079412;5475.890482190413;5478.424765399655;5479.424660410679;5480.232767412565;4544.068465964469;4595.356650068993;4629.768511574475;4634.559716826896;4649.209634362167;3962.6829853611644;3971.324874771296;3972.9205415892097;3998.9600103998587;4006.399040144002;4802.399700037496;4815.855524334275;4816.349792778;4821.276808478686;4840.854774356775;5442.6462279951675;5491.2312276281355;5494.752736126884;5496.945030549643;5497.285352904177;4652.441706995182;4709.740964246999;4712.034033023222;4712.175369310357;4712.670113092119;4882.292067650242;4884.95115048852;4885.218365061573;4894.559489645878;4899.485554016848;5221.425643179247;5240.030594340059;5240.187770895517;5246.291750613618;5248.816291429782;5606.131049687305;5612.466815652507;5629.831105066854;5630.8029218977135;5633.718314084197;5243.423223445342;5255.632105752714;5257.763400647056;5261.973690131525;5262.526372626482;5428;5551.722413879371;5566.777328906798;5566.972165139148;5567.805126820542;5605.04714198579;5622.044252477007;5623.3533143689;5623.775048997994;5646.209530665792;5285.497877701612;5386.218998245155;5386.7575959082715;5470.042742519963;5518.144687573433;5454.618176727591;5485.753141108739;5516.696581688046;5520.06158952971;5537.307836520435;5399.217113518596;5411.83764487066;5413.052715774802;5419.160030195326;5432.076546986943;5351.063563876384;5489.600246277157;5526.917096243479;5544.695041772741;5595.664260144405;5186;5252.080885844885;5320.52115309624;5329.946700532945;5354.303940487867 12:33:05 INFO - PID 10370 | |1;ext.html;666.7033170239242;738.0847748791498;752.9058867641543;1067.274253507615;1084.6095405653969;25846.353841153956;31255;32005;32054.51918221223;32079.19802004945;36573.63426365735;37232.25535489295;37297;37346.25307493848;37370;5725.398833122525;7555.659995300225;7725.536467811921;7734.651940662684;7736.883946740775;5805.12923061542;7264.6367681615975;7441.367483763886;7441.59071251081;7452.254774522531;7519.849603007964;7674.923250767504;7680.577568233745;7686.5772382518935;7686.884696729554;7577.469577129603;7610.277023682741;7610.467267291292;7646.961765191167;7662.770116896502;7522.699092036312;7540.547567145989;7568.924310756877;7591.620418979084;7595.126560445509;33887;34108.317833643436;34149.14627134317;34190.82904585461;34206.82896585526;2680.7855371570226;2686.113582517784;2693.46130773846;2699.2762665335663;2748.50526905157;23325.53348933029;23672.40818979522;23733.88133059341;23934.521309573713;24622.630660540108;2894.3777087925987;2967;2973.2120987938215;2991.41667374862;2995.3559984603417;142862;142911;142950;143489.21618621942;143795;40478.392824107665;40533;40618;40856.38715419271;40949.38575921304;43002.35496467556;43065.13869722588;43075.78462107732;43086.13827723428;43094.13811723748;12207.938960305319;12457.563985260473;12468.563600273947;12468.937655311847;12470.875291247154;8615.138486151334;8805.647774088964;9964.451955142391;9966.900330996743;9979.700608981742;10756.354618722902;10823;10979.83530247047;10990.17573681977;11077.556897724162;4738.715677059387;4766.904661906812;4795.976020119877;4806.4712881583;4850.708957462563;880.7401816464159;895.3553441522093;903.1329923273731;912.4388501071793;913.0732306708696;20380.592388152152;21045.89477052605;21255.36233912985;21379.25172618951;21830;970.752458123174;974.848898420746;981.3768257156614;992.3004281981185;1007.6976906928039;10969.341839489656;11034.944825275823;11172.385518796382;11207.215494915277;11213.71965700864;204340;204827;205432;206068;206250;41710;41735.373969390486;41761.164776704296;41859.7907010463;41869.79065104655;1214.180428210954;1252.4301442843484;1253.354522420954;1255.7300180461245;1262.5265525427965;19487.610247795248;19590.804091958897;20782.688259675815;20957.161713531288;21021.474463137933;31015.689843102184;31133.68866311308;31148.065558033297;31159.532607011366;31170;202940;203224;203493;203533;204110;27287.72712272852;27786.861065694542;27891.302717432227;27955.860220698767;28038;35920.820395897856;36167.457488137174;36382;36766.816165919;37453;27163.456730865666;27397.31506712348;27538.862305688344;27581.724182757916;28187.57718634263;26945.191644250703;27095.561399474143;27660.308492287855;28205.71794282031;28756.137315880558;30283.394332113676;30603.081907542808;30919.69080309258;31350.216244594067;31431;26594.202173934813;26607.733922660525;27048.459030818878;27084;28025.579616305364;21010.789892100882;21692.132314707866;22332.553348932604;22409.663855042512;22422.66366004544 12:33:05 INFO - PID 10370 | |2;jquery.html;392.19795518165347;396.90275882408815;421.1555830559727;431.0473852785344;436.7466869215855;12143.635690929275;13543.119697219674;17782;17803.198856051476;17966.910165449186;17226.483205503824;17873.374431894885;18161.818381816178;18204.271829126817;18941.810581894177;5577.832665020046;5788.652680839142;5796;5796.652200867961;5797.652140871562;3713.4615480755274;5677.205191273225;5699.515541179005;5701.401352857953;5702.629329093616;5613.691246981414;5668.546516278688;5678.318601767794;5678.659280443166;5679.744411501492;5595.468430499096;5676.687782171979;5707.5148612368;5712.114622233519;5722.542196624302;5023.497650234984;5185.507376799198;5201.427842937273;5204.9739751301;5220.765065572021;22664.660030099563;22705;22738.431539211488;22862.657060144113;22894.31317060491;1590.300267882128;1603.3586565373828;1608.3566573370629;1624.3340230505555;1630.5271471273315;12826.679333016657;13679.794803077963;13936.51222207228;14326.140431574035;14477.131372117707;1399.4891864469498;1419.6096073579745;1447.7032208397254;1458.9781153282709;1467.9853201467956;72134;72483.2751672487;72980.99999999948;74059.25940740577;75224;11109.944450277697;11160.832587511193;11185.66443006711;11307.49116289769;11353.886461135284;15789.131597762003;15878.206089695468;15888.205589720468;15896.602584935237;15896.920515397349;7318.194998550155;7397.112346518345;7481.289277518627;7527.585982770891;7615.581143037075;6659.433948114464;6711.966440167866;6716.328367163244;6887.690053947585;6888.207856096613;6908.827279318058;7062.858742825115;7195.316444937723;7203.011988506865;7257.637118144072;1936.1190658250462;1946.8929208893646;1961.3429501117284;1984.9801501985085;2016.5865997470482;851.4933614499363;869.6695255802881;881.3433991676242;898.8247291778172;899.7165892743765;16471.423500177436;16748.581285467717;17123.74314385285;17233.138343082795;17326.39357622477;900.5317235037744;900.7297810656777;901.4095767272316;911.8677791720163;919.2324409118328;8696;9466.95266523663;9629.566669499889;9635.421874687541;9663.420194788336;212623;212873;213472;213611;214069;56369.43630563724;59026.40973590295;82849.58575207206;84954.5752271247;87807.56096219478;1046.199657262195;1057.5399701130034;1103.674416047268;1104.1608377632897;1113.1762495753246;16641.500754976885;16692.165391730123;17033.91483042577;17374.826251737322;17810.91094544519;39786;40267.597324026385;40947;41235;41920;49315;51045.74477127591;52067;52434.47565524296;52756.47243527516;7913.802154946173;7986.201379861967;8204.097549269456;8257.876131857907;8617.224449799549;11265.436728163722;11595.420228988685;12222.327771972627;12835.318836746188;12971.35143242853;10044.246681498771;10070.8489372658;10999.670009899715;11116.110711142928;11674.533018679498;9679.70960871175;10021.749456263651;10223.7444063899;10313.432761198206;10401.42792146409;9585.185259253041;10038.548265327932;10084.84872726895;10237.9488102559;10692;7748.186440423674;7910.802229944297;7984.600769961595;8015.919840801517;8394.538300393537;9900.25748068912;9925.156361709336;10066.396016239049;10132.392056476636;10424.739381515523 12:33:05 INFO - PID 10370 | |3;mootools.html;1223.4861358229546;1291.2833377475504;1309.155594641457;1315.7039666075132;1323.9139455935363;9363.11050450207;9664.081912218346;9702.369345992505;9724.951375243132;9743.756406089842;9563.904360956389;9621.470819104963;9884.950575247132;9925.950370248156;9937;2253.54929014197;2291.919782807602;2297.322289924468;2297.597920363938;2300.654901764732;2032.5325175209712;2164;2210.115953618549;2216.002798740565;2222.7221597300336;1883.6421079994825;1953.9511512212166;2179.3897708641525;2252.6395776675736;2254.5265494246228;2280.3273034454874;2296.817139173327;2303.5508075925036;2305.98536643877;2306.2043594959714;2215.9778402215934;2261.7625149359246;2264.0377839418347;2264.8074913632363;2265.0713207584813;9560.904390956071;9762.463064531448;9778.168855647278;9987.400755954593;9994.300398972075;5405.432429594875;5607.607467477278;5609.607327487078;5614.494695477386;5615.887682246333;3116.439040972624;3315.4082899701957;3356.16095976006;3445.620981692012;3446.327966046622;1083.653230966091;1115.3642423818426;1153.117864833403;1155.6706338693432;1181.3207405741732;14484.565463036126;14539.563813085624;14586.781198282035;14608.19654918979;14615.342309596093;4732.7055016827535;4898.069366820293;4912.901741965141;4955.1576232039915;5011.799528018839;10845.891541084646;10865.891341086646;10871.184661150288;10934.234603577674;10963.725906852233;5563.749631266603;5567.3040869891265;5568.582356323292;5571.052921003439;5578.442155784389;4844.297576851337;4926.802927882844;4973.776180071905;5022.296878437021;5093.694378337312;3638.472421498868;3768;3811.6378944000276;3834.808259587065;3858.6141385861188;1091.1598069486452;1099.0383414512303;1118.8098023336051;1124.0333313350511;1124.2636073371955;553.3510139783388;632.193952710298;695.5305169010898;710.6020628448085;717.8026042838156;5413.972930135403;5603.551715862721;5607.635503692247;5720;5738.569607279471;526.2264471227289;527.211818331589;530.7531997621124;537.8682222855425;628.5976974736172;3332.1503016731062;3371.359441706068;3425.400554902882;3518.155642645798;3576.7138628909624;12513.812292815615;13195.274259915797;13240;13245.403956821967;13271.73456530864;12698.492060317483;13083.644943909998;13118.147320424141;13133.540326088541;13133.60599182026;9206;9468.19520340765;9488.146066854017;9493.762655933519;9501.714948551555;5238.292830467838;5257.973710131425;5282.234076058873;5337.466253374631;5477.287952566141;11378.43107844621;11543.422828858356;11599.188056835941;11657.591984280509;11677.708057298296;11918.106142039209;11932.045436365244;12069.27584344942;12131.939340303243;12237.632871013884;7456.552606843607;7650.540967541965;7667.3099421052375;7670.846583068419;7671.53970761756;9199.540022998957;9206.309526785384;9255.981841997509;9303.67437139724;9362.719118426457;7980.241877021674;7992.440529162903;8017.478863873829;8058.315043221158;8280.792980175544;7483.401327893646;7646.082470103401;7718.614069296625;7739.110002349802;7823.2567906048835;6560.803175904731;6584.473242140713;6596.274409815012;6603.504737144637;6604.108445359815;4829.299751536078;4917;4981.601471882168;5087.287779710918;5258.789648414021;4694.671373003857;4964.2305442655;5022.924656130088;5032.899342013066;5058.671186372874 12:33:05 INFO - PID 10370 | |4;prototype.html;386.1312047892242;401.96784257259424;403.7375705791236;409.4288467587715;415.75262718682376;5228.346456692913;5280.2079688046815;5313.495217954288;5355.6251062425645;5357.169638706002;5215.843524694255;5224.4775522447735;5233.764480598373;5253.395859476162;5255.237990491386;3889.572147063821;4214.115035842477;4234.195502854448;4247.447831781864;4248.129133527635;4218.514870789868;4225.936610950838;4226.795363321454;4239.512456067561;4259.76571288579;4235.915281694364;4236.406903033562;4245.23585754564;4247;4249.3201087825955;4123.484564429431;4175.164967006581;4208.200441916057;4229.069604686964;4247.260516966555;3818.3127037133304;3822.73240873139;3823.3500304947947;3824.1204522959597;3836.558795738498;5083.61872859537;5101.693898366073;5107.846764597031;5110.693358398508;5125.641205115606;4781.713097214143;4867.318575399447;4888.071266459361;4895.5838753705975;4902.240152776325;5516.200150978086;5536.2249285100115;5549.19536667185;5562.249096371978;5568.387477377485;4248.617624413787;4250.4899412070445;4256.787160641956;4263.040815816447;4275.636570891478;5144;5441.83674489766;5726.713664316851;5835.9708201458725;5839.970800146015;4350.912981740347;4354.956450435455;4360.430245111757;4370.956290437119;4374.091229332522;4445.73325600465;4455.1757924784015;4470.530594287618;4471.709338892962;4472.776361181928;4468.687191896536;4870.756462176877;4875.805427670243;4892;4906.509349065065;5468.179773033997;5492.517020404468;5517.420670829585;5542.445755424425;5577.107662774018;1411.964700882486;1416.9362378692983;1418.5460652591278;1419.6096073579847;1428.8642578954982;904.1952662130709;960.1118964957526;970.9320347575743;979.4221409368507;979.7991411760572;4840.322354870321;4840.927386089212;4845.1520983828395;4847.418309802846;4866.513348665105;5735.827925162168;5741.138829175574;5753.309602847601;5760.222369979999;5766.913496297559;4620.976895115503;4812.157872372391;4855.75721213945;4865.8615193200185;4883.218685010453;3012.5631783391286;3027.8637461314293;3032.469317869364;3034.544818277276;3059.586955760988;5360.544353729899;5387.164989426645;5392.892142157135;5428.185772134132;5430.647007944471;5603.5237004854225;5628.493435590816;5766.538676905838;5834.883302333929;5872.823815285548;5262.184361423985;5395.244665746799;5399.784008639689;5414.187871819258;5426.430224826416;5727.513161381163;5739.540836732968;5742.28221472316;5742.626729262584;5747.683877386784;5361.678299302054;5575.135853942646;5586.217929489956;5640.492355688008;5678.14827775837;5365.3293338332705;5395.541378982673;5400.2439658447065;5415.40430552631;5420.864478388072;4046.9392959106226;4058.553559108555;4059.3302105153502;4062.7156099073964;4065.593440655911;5519.972400137974;5720.399358067343;5732.799352022659;5741.1388291756575;5757.309122905278;5556.472135147154;5559.805406810742;5563.248961390161;5569.944300557105;5570.192322113352;5464.398916119303;5545.2513910623165;5572.498475137257;5575.498205161555;5575.77696892136;5489.64317319373;5501.697406642673;5512.145617429305;5513.684252613832;5521.613487055867;5641.576881733969;5646.407127251579;5654.038813401649;5670.404607516316;5671.914921276267;5289.709066001406;5434.0490414176575;5446.428125046813;5461.563074953915;5500.28496295495 12:33:05 INFO - PID 10370 | |5;yui.html;697.4420463629092;701.8841891087969;702.2170280137647;708.40140081631;712.4549719464613;12733.299668518228;13067.215967041984;13155.999999999987;13182.077254592183;13182.34088295585;12428.627141185756;13818.447262109503;14381.568552943429;14439.855601443982;14467.421303147861;826.8718348655952;828.8010877389413;843.4517563583689;845.9154084591553;846.2595229174473;836.3852568362249;844.7803571071535;845.3617518773349;847.1782371100023;848.3213429256601;900.6172376739892;901.5987885391004;901.9639214431414;902.1700035966926;903.3811838890381;892.5225004622496;897.8114595934861;901.040391982543;901.779064129286;902.0753727429437;657.7796438193163;662.1954325494547;663.5653646861314;664.7772996046331;664.8404382948106;12823.102382833205;13048.47806087765;14049.5082672107;14187.077839940473;14203.715925681532;13157.671058223525;13727.10116566825;13736.587902362946;13994.370253338522;14105.506307279298;9593.760155996157;9984.450855203033;10048.648297309559;10119.392836429837;10264.024917632813;2544.6946366436146;2562.2185233503774;2579.239124458289;2634.1702363755358;2664.440467501827;50289.24566131511;51089;51239.975200496156;51991.74004129955;52590.21114683283;9986.800263994824;10023.198144148597;10140.239482038876;10155.238357123246;10158.746031349128;17548.736768948474;17599.128736217874;17672.646547068987;17688;17934;6327;6425.453836423862;6497.902531462032;6503.577267477599;6558.245801732862;3323.1858194742445;3365.7812242204177;3432.8626854926024;3437.3812713711272;3438.7249020078834;3622.637736226409;3686.7787932724123;3687.6681098701247;3722.5346831646043;3744.3634582121103;651.8305240637374;653.7842511971133;654.2247436787422;666.2837449741547;672.0322735261208;519.5765451157295;536.2894165231006;540.3920589336996;551.1429726774903;553.1232995701857;8270.710525131592;8776.341774366949;9038.367314287936;9147.897618870584;9374.156325930699;540.4082529630049;560.9579281553899;561.5114850080374;564.8333741546254;565.9179418984306;3761.6238376162714;3808.447775072599;3877.473042779216;3932.960670393317;3932.9985252501187;61742.38257617456;62556.68721656362;62902.370976290564;63079.36920630735;65115.34884651188;55001.09980203522;55009.449905500434;55466.44533554694;55610;57756.422435775945;47051.52948470471;47738.522614774105;47895.28157077647;48929.26606100911;49916.50083499119;7363.450040143428;7410.147832999059;7723.729669461542;7793.844123117619;7894.289513943772;26559.07043253477;26888.193354199404;26935.86532067327;27206.319842003315;27434.5884811732;31695.683043169272;32143.357132856745;32294.35411291808;32665.34669306648;37778.62221377751;10910;11458.4270786462;11505.424728763695;11768.411579421167;12147.574834880737;12847.35763211817;13147.408366623333;13225.995957423534;13367.131136476099;13427.26150061756;12235.694107647025;12256;12339.07456940715;12440.875591243972;12561.937190313989;11124.555017799197;11825.467853946766;12158.75682486363;12689.111762176559;12720.74558508843;10932.726681833017;11013.614523491638;11577.768444631229;11612.647437803615;11621.825672614748;8291.875621865556;8404.226605220385;8460.238578527962;8554.31565474773;8676.479411235347;9233.630654773733;10107.79784404293;10163.44101074448;10402.323848949794;10430.73923151897 12:33:05 INFO - PID 10370 | __end_tp_report 12:33:05 INFO - PID 10370 | __start_cc_report 12:33:05 INFO - PID 10370 | _x_x_mozilla_cycle_collect,1458 12:33:05 INFO - PID 10370 | __end_cc_report 12:33:05 INFO - PID 10370 | __startTimestamp1490556785415__endTimestamp 12:33:05 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | ------- Summary: start ------- 12:33:05 INFO - PID 10370 | Number of tests: 6 12:33:05 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | [#0] dojo.html Cycles:165 Average:5130.76 Median:5262.53 stddev:591.43 (11.2%) stddev-sans-first:562.34 12:33:05 INFO - PID 10370 | Values: 2725.4 2754.9 2782.8 2791.7 2798.2 5039.9 5132.3 5211.6 5218.4 5272.5 5196.2 5212.4 5250.8 5260.8 5303.1 4683.1 4713.1 4726.5 4810.4 4871.2 4770.2 4784.0 4818.7 4826.3 4866.1 4616.0 4670.9 4676.5 4683.5 4760.1 4666.2 4672.0 4672.9 4680.0 4681.2 4630.5 4633.4 4637.2 4645.0 4647.2 5027.7 5063.0 5090.0 5108.8 5189.4 5676.0 5679.5 5693.4 5699.4 5705.7 5660.3 5671.7 5675.4 5700.4 5730.0 5142.6 5249.8 5256.9 5279.0 5320.3 5583.5 5647.2 5787.8 5796.9 5802.8 5557.8 5558.6 5593.7 5598.7 5605.7 5635.1 5667.8 5668.0 5670.1 5672.7 5346.4 5409.0 5583.9 5594.4 5594.8 5463.2 5475.9 5478.4 5479.4 5480.2 4544.1 4595.4 4629.8 4634.6 4649.2 3962.7 3971.3 3972.9 3999.0 4006.4 4802.4 4815.9 4816.3 4821.3 4840.9 5442.6 5491.2 5494.8 5496.9 5497.3 4652.4 4709.7 4712.0 4712.2 4712.7 4882.3 4885.0 4885.2 4894.6 4899.5 5221.4 5240.0 5240.2 5246.3 5248.8 5606.1 5612.5 5629.8 5630.8 5633.7 5243.4 5255.6 5257.8 5262.0 5262.5 5428.0 5551.7 5566.8 5567.0 5567.8 5605.0 5622.0 5623.4 5623.8 5646.2 5285.5 5386.2 5386.8 5470.0 5518.1 5454.6 5485.8 5516.7 5520.1 5537.3 5399.2 5411.8 5413.1 5419.2 5432.1 5351.1 5489.6 5526.9 5544.7 5595.7 5186.0 5252.1 5320.5 5329.9 5354.3 12:33:05 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | [#1] ext.html Cycles:180 Average:32534.00 Median:30761.39 stddev:48366.65 (157.2%) stddev-sans-first:48443.14 12:33:05 INFO - PID 10370 | Values: 666.7 738.1 752.9 1067.3 1084.6 25846.4 31255.0 32005.0 32054.5 32079.2 36573.6 37232.3 37297.0 37346.3 37370.0 5725.4 7555.7 7725.5 7734.7 7736.9 5805.1 7264.6 7441.4 7441.6 7452.3 7519.8 7674.9 7680.6 7686.6 7686.9 7577.5 7610.3 7610.5 7647.0 7662.8 7522.7 7540.5 7568.9 7591.6 7595.1 33887.0 34108.3 34149.1 34190.8 34206.8 2680.8 2686.1 2693.5 2699.3 2748.5 23325.5 23672.4 23733.9 23934.5 24622.6 2894.4 2967.0 2973.2 2991.4 2995.4 142862.0 142911.0 142950.0 143489.2 143795.0 40478.4 40533.0 40618.0 40856.4 40949.4 43002.4 43065.1 43075.8 43086.1 43094.1 12207.9 12457.6 12468.6 12468.9 12470.9 8615.1 8805.6 9964.5 9966.9 9979.7 10756.4 10823.0 10979.8 10990.2 11077.6 4738.7 4766.9 4796.0 4806.5 4850.7 880.7 895.4 903.1 912.4 913.1 20380.6 21045.9 21255.4 21379.3 21830.0 970.8 974.8 981.4 992.3 1007.7 10969.3 11034.9 11172.4 11207.2 11213.7 204340.0 204827.0 205432.0 206068.0 206250.0 41710.0 41735.4 41761.2 41859.8 41869.8 1214.2 1252.4 1253.4 1255.7 1262.5 19487.6 19590.8 20782.7 20957.2 21021.5 31015.7 31133.7 31148.1 31159.5 31170.0 202940.0 203224.0 203493.0 203533.0 204110.0 27287.7 27786.9 27891.3 27955.9 28038.0 35920.8 36167.5 36382.0 36766.8 37453.0 27163.5 27397.3 27538.9 27581.7 28187.6 26945.2 27095.6 27660.3 28205.7 28756.1 30283.4 30603.1 30919.7 31350.2 31431.0 26594.2 26607.7 27048.5 27084.0 28025.6 21010.8 21692.1 22332.6 22409.7 22422.7 12:33:05 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | [#2] jquery.html Cycles:180 Average:20032.37 Median:40607.30 stddev:37307.20 (91.9%) stddev-sans-first:37382.71 12:33:05 INFO - PID 10370 | Values: 392.2 396.9 421.2 431.0 436.7 12143.6 13543.1 17782.0 17803.2 17966.9 17226.5 17873.4 18161.8 18204.3 18941.8 5577.8 5788.7 5796.0 5796.7 5797.7 3713.5 5677.2 5699.5 5701.4 5702.6 5613.7 5668.5 5678.3 5678.7 5679.7 5595.5 5676.7 5707.5 5712.1 5722.5 5023.5 5185.5 5201.4 5205.0 5220.8 22664.7 22705.0 22738.4 22862.7 22894.3 1590.3 1603.4 1608.4 1624.3 1630.5 12826.7 13679.8 13936.5 14326.1 14477.1 1399.5 1419.6 1447.7 1459.0 1468.0 72134.0 72483.3 72981.0 74059.3 75224.0 11109.9 11160.8 11185.7 11307.5 11353.9 15789.1 15878.2 15888.2 15896.6 15896.9 7318.2 7397.1 7481.3 7527.6 7615.6 6659.4 6712.0 6716.3 6887.7 6888.2 6908.8 7062.9 7195.3 7203.0 7257.6 1936.1 1946.9 1961.3 1985.0 2016.6 851.5 869.7 881.3 898.8 899.7 16471.4 16748.6 17123.7 17233.1 17326.4 900.5 900.7 901.4 911.9 919.2 8696.0 9467.0 9629.6 9635.4 9663.4 212623.0 212873.0 213472.0 213611.0 214069.0 56369.4 59026.4 82849.6 84954.6 87807.6 1046.2 1057.5 1103.7 1104.2 1113.2 16641.5 16692.2 17033.9 17374.8 17810.9 39786.0 40267.6 40947.0 41235.0 41920.0 49315.0 51045.7 52067.0 52434.5 52756.5 7913.8 7986.2 8204.1 8257.9 8617.2 11265.4 11595.4 12222.3 12835.3 12971.4 10044.2 10070.8 10999.7 11116.1 11674.5 9679.7 10021.7 10223.7 10313.4 10401.4 9585.2 10038.5 10084.8 10237.9 10692.0 7748.2 7910.8 7984.6 8015.9 8394.5 9900.3 9925.2 10066.4 10132.4 10424.7 12:33:05 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | [#3] mootools.html Cycles:180 Average:6152.00 Median:4914.95 stddev:3962.96 (80.6%) stddev-sans-first:3956.77 12:33:05 INFO - PID 10370 | Values: 1223.5 1291.3 1309.2 1315.7 1323.9 9363.1 9664.1 9702.4 9725.0 9743.8 9563.9 9621.5 9885.0 9926.0 9937.0 2253.5 2291.9 2297.3 2297.6 2300.7 2032.5 2164.0 2210.1 2216.0 2222.7 1883.6 1954.0 2179.4 2252.6 2254.5 2280.3 2296.8 2303.6 2306.0 2306.2 2216.0 2261.8 2264.0 2264.8 2265.1 9560.9 9762.5 9778.2 9987.4 9994.3 5405.4 5607.6 5609.6 5614.5 5615.9 3116.4 3315.4 3356.2 3445.6 3446.3 1083.7 1115.4 1153.1 1155.7 1181.3 14484.6 14539.6 14586.8 14608.2 14615.3 4732.7 4898.1 4912.9 4955.2 5011.8 10845.9 10865.9 10871.2 10934.2 10963.7 5563.7 5567.3 5568.6 5571.1 5578.4 4844.3 4926.8 4973.8 5022.3 5093.7 3638.5 3768.0 3811.6 3834.8 3858.6 1091.2 1099.0 1118.8 1124.0 1124.3 553.4 632.2 695.5 710.6 717.8 5414.0 5603.6 5607.6 5720.0 5738.6 526.2 527.2 530.8 537.9 628.6 3332.2 3371.4 3425.4 3518.2 3576.7 12513.8 13195.3 13240.0 13245.4 13271.7 12698.5 13083.6 13118.1 13133.5 13133.6 9206.0 9468.2 9488.1 9493.8 9501.7 5238.3 5258.0 5282.2 5337.5 5477.3 11378.4 11543.4 11599.2 11657.6 11677.7 11918.1 11932.0 12069.3 12131.9 12237.6 7456.6 7650.5 7667.3 7670.8 7671.5 9199.5 9206.3 9256.0 9303.7 9362.7 7980.2 7992.4 8017.5 8058.3 8280.8 7483.4 7646.1 7718.6 7739.1 7823.3 6560.8 6584.5 6596.3 6603.5 6604.1 4829.3 4917.0 4981.6 5087.3 5258.8 4694.7 4964.2 5022.9 5032.9 5058.7 12:33:05 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | [#4] prototype.html Cycles:180 Average:4656.60 Median:5254.32 stddev:1313.02 (25.0%) stddev-sans-first:1276.98 12:33:05 INFO - PID 10370 | Values: 386.1 402.0 403.7 409.4 415.8 5228.3 5280.2 5313.5 5355.6 5357.2 5215.8 5224.5 5233.8 5253.4 5255.2 3889.6 4214.1 4234.2 4247.4 4248.1 4218.5 4225.9 4226.8 4239.5 4259.8 4235.9 4236.4 4245.2 4247.0 4249.3 4123.5 4175.2 4208.2 4229.1 4247.3 3818.3 3822.7 3823.4 3824.1 3836.6 5083.6 5101.7 5107.8 5110.7 5125.6 4781.7 4867.3 4888.1 4895.6 4902.2 5516.2 5536.2 5549.2 5562.2 5568.4 4248.6 4250.5 4256.8 4263.0 4275.6 5144.0 5441.8 5726.7 5836.0 5840.0 4350.9 4355.0 4360.4 4371.0 4374.1 4445.7 4455.2 4470.5 4471.7 4472.8 4468.7 4870.8 4875.8 4892.0 4906.5 5468.2 5492.5 5517.4 5542.4 5577.1 1412.0 1416.9 1418.5 1419.6 1428.9 904.2 960.1 970.9 979.4 979.8 4840.3 4840.9 4845.2 4847.4 4866.5 5735.8 5741.1 5753.3 5760.2 5766.9 4621.0 4812.2 4855.8 4865.9 4883.2 3012.6 3027.9 3032.5 3034.5 3059.6 5360.5 5387.2 5392.9 5428.2 5430.6 5603.5 5628.5 5766.5 5834.9 5872.8 5262.2 5395.2 5399.8 5414.2 5426.4 5727.5 5739.5 5742.3 5742.6 5747.7 5361.7 5575.1 5586.2 5640.5 5678.1 5365.3 5395.5 5400.2 5415.4 5420.9 4046.9 4058.6 4059.3 4062.7 4065.6 5520.0 5720.4 5732.8 5741.1 5757.3 5556.5 5559.8 5563.2 5569.9 5570.2 5464.4 5545.3 5572.5 5575.5 5575.8 5489.6 5501.7 5512.1 5513.7 5521.6 5641.6 5646.4 5654.0 5670.4 5671.9 5289.7 5434.0 5446.4 5461.6 5500.3 12:33:05 INFO - PID 10370 | 12:33:05 INFO - PID 10370 | [#5] yui.html Cycles:180 Average:13705.18 Median:25492.26 stddev:16335.09 (64.1%) stddev-sans-first:16351.71 12:33:05 INFO - PID 10370 | Values: 697.4 701.9 702.2 708.4 712.5 12733.3 13067.2 13156.0 13182.1 13182.3 12428.6 13818.4 14381.6 14439.9 14467.4 826.9 828.8 843.5 845.9 846.3 836.4 844.8 845.4 847.2 848.3 900.6 901.6 902.0 902.2 903.4 892.5 897.8 901.0 901.8 902.1 657.8 662.2 663.6 664.8 664.8 12823.1 13048.5 14049.5 14187.1 14203.7 13157.7 13727.1 13736.6 13994.4 14105.5 9593.8 9984.5 10048.6 10119.4 10264.0 2544.7 2562.2 2579.2 2634.2 2664.4 50289.2 51089.0 51240.0 51991.7 52590.2 9986.8 10023.2 10140.2 10155.2 10158.7 17548.7 17599.1 17672.6 17688.0 17934.0 6327.0 6425.5 6497.9 6503.6 6558.2 3323.2 3365.8 3432.9 3437.4 3438.7 3622.6 3686.8 3687.7 3722.5 3744.4 651.8 653.8 654.2 666.3 672.0 519.6 536.3 540.4 551.1 553.1 8270.7 8776.3 9038.4 9147.9 9374.2 540.4 561.0 561.5 564.8 565.9 3761.6 3808.4 3877.5 3933.0 3933.0 61742.4 62556.7 62902.4 63079.4 65115.3 55001.1 55009.4 55466.4 55610.0 57756.4 47051.5 47738.5 47895.3 48929.3 49916.5 7363.5 7410.1 7723.7 7793.8 7894.3 26559.1 26888.2 26935.9 27206.3 27434.6 31695.7 32143.4 32294.4 32665.3 37778.6 10910.0 11458.4 11505.4 11768.4 12147.6 12847.4 13147.4 13226.0 13367.1 13427.3 12235.7 12256.0 12339.1 12440.9 12561.9 11124.6 11825.5 12158.8 12689.1 12720.7 10932.7 11013.6 11577.8 11612.6 11621.8 8291.9 8404.2 8460.2 8554.3 8676.5 9233.6 10107.8 10163.4 10402.3 10430.7 12:33:05 INFO - PID 10370 | -------- Summary: end -------- 12:33:05 INFO - PID 10370 | 12:33:06 INFO - TEST-INFO | 10370: exit 0 12:33:06 INFO - TEST-OK | dromaeo_css | took 1124496ms 12:33:06 INFO - TEST-START | kraken 12:33:06 INFO - operating with platform_type : linux_ 12:33:06 INFO - Initialising browser for kraken test... 12:33:06 INFO - TEST-INFO | started process 10697 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4gttBY/profile http://localhost:39230/getInfo.html) 12:33:10 INFO - PID 10697 | __metrics Screen width/height:1600/1200 12:33:10 INFO - PID 10697 | colorDepth:24 12:33:10 INFO - PID 10697 | Browser inner width/height: 1024/697 12:33:10 INFO - PID 10697 | __metrics 12:33:11 ERROR - PID 10697 | 1490556791877 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 12:33:11 INFO - PID 10697 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:33:11 INFO - PID 10697 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:33:13 INFO - TEST-INFO | 10697: exit 0 12:33:13 INFO - Browser initialized. 12:33:13 INFO - Running cycle 1/1 for kraken test... 12:33:13 INFO - TEST-INFO | started process 10792 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4gttBY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:25 INFO - PID 10792 | RSS: Main: 150343680 12:33:25 INFO - PID 10792 | 12:33:27 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39230/tests/kraken/driver.html?testName=audio-beat-detection) 12:33:28 INFO - PID 10792 | RSS: Main: 205193216 12:33:28 INFO - PID 10792 | 12:33:31 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39230/tests/kraken/driver.html?testName=audio-dft) 12:33:32 INFO - PID 10792 | RSS: Main: 184832000 12:33:32 INFO - PID 10792 | 12:33:35 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39230/tests/kraken/driver.html?testName=audio-fft) 12:33:35 INFO - PID 10792 | RSS: Main: 265912320 12:33:35 INFO - PID 10792 | 12:33:38 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39230/tests/kraken/driver.html?testName=audio-oscillator) 12:33:38 INFO - PID 10792 | RSS: Main: 177152000 12:33:38 INFO - PID 10792 | 12:33:40 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:33:41 INFO - PID 10792 | RSS: Main: 180150272 12:33:41 INFO - PID 10792 | 12:33:44 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39230/tests/kraken/driver.html?testName=imaging-darkroom) 12:33:45 INFO - PID 10792 | RSS: Main: 219254784 12:33:45 INFO - PID 10792 | 12:33:48 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39230/tests/kraken/driver.html?testName=imaging-desaturate) 12:33:49 INFO - PID 10792 | RSS: Main: 214872064 12:33:49 INFO - PID 10792 | 12:33:52 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39230/tests/kraken/driver.html?testName=json-parse-financial) 12:33:52 INFO - PID 10792 | RSS: Main: 216190976 12:33:52 INFO - PID 10792 | 12:33:54 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:33:55 INFO - PID 10792 | RSS: Main: 180744192 12:33:55 INFO - PID 10792 | 12:33:57 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39230/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:33:57 INFO - PID 10792 | RSS: Main: 187281408 12:33:57 INFO - PID 10792 | 12:33:59 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:34:00 INFO - PID 10792 | RSS: Main: 196460544 12:34:00 INFO - PID 10792 | 12:34:03 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:34:04 INFO - PID 10792 | RSS: Main: 200306688 12:34:04 INFO - PID 10792 | 12:34:07 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:34:07 INFO - PID 10792 | RSS: Main: 202162176 12:34:07 INFO - PID 10792 | 12:34:09 INFO - PID 10792 | Cycle 1(1): loaded http://localhost:39230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39230/tests/kraken/driver.html?testName=ai-astar) 12:34:10 INFO - PID 10792 | RSS: Main: 270299136 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | __start_tp_report 12:34:10 INFO - PID 10792 | _x_x_mozilla_page_load 12:34:10 INFO - PID 10792 | _x_x_mozilla_page_load_details 12:34:10 INFO - PID 10792 | |i|pagename|runs| 12:34:10 INFO - PID 10792 | |0;ai-astar;104;113;112;114;112;111;112;112;113;114 12:34:10 INFO - PID 10792 | |1;audio-beat-detection;149;125;124;169;124;123;124;124;153;122 12:34:10 INFO - PID 10792 | |2;audio-dft;161;161;161;161;163;164;163;164;166;160 12:34:10 INFO - PID 10792 | |3;audio-fft;74;75;74;112;75;75;75;75;91;76 12:34:10 INFO - PID 10792 | |4;audio-oscillator;84;83;114;85;84;85;107;84;83;112 12:34:10 INFO - PID 10792 | |5;imaging-gaussian-blur;113;113;113;112;113;113;115;113;113;113 12:34:10 INFO - PID 10792 | |6;imaging-darkroom;149;148;150;150;150;148;149;148;149;149 12:34:10 INFO - PID 10792 | |7;imaging-desaturate;94;94;94;95;94;94;94;94;94;94 12:34:10 INFO - PID 10792 | |8;json-parse-financial;65;66;65;64;66;65;65;68;76;65 12:34:10 INFO - PID 10792 | |9;json-stringify-tinderbox;54;55;54;55;55;55;55;55;55;56 12:34:10 INFO - PID 10792 | |10;stanford-crypto-aes;81;83;82;83;84;82;82;81;81;83 12:34:10 INFO - PID 10792 | |11;stanford-crypto-ccm;132;132;137;131;131;137;143;131;224;130 12:34:10 INFO - PID 10792 | |12;stanford-crypto-pbkdf2;199;192;207;194;193;196;190;202;193;196 12:34:10 INFO - PID 10792 | |13;stanford-crypto-sha256-iterative;73;70;71;70;70;69;73;71;71;72 12:34:10 INFO - PID 10792 | __end_tp_report 12:34:10 INFO - PID 10792 | __start_cc_report 12:34:10 INFO - PID 10792 | _x_x_mozilla_cycle_collect,1996 12:34:10 INFO - PID 10792 | __end_cc_report 12:34:10 INFO - PID 10792 | __startTimestamp1490556850056__endTimestamp 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | ------- Summary: start ------- 12:34:10 INFO - PID 10792 | Number of tests: 14 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.50 stddev:2.87 (2.6%) stddev-sans-first:1.01 12:34:10 INFO - PID 10792 | Values: 104.0 113.0 112.0 114.0 112.0 111.0 112.0 112.0 113.0 114.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:124.50 stddev:16.85 (13.5%) stddev-sans-first:16.94 12:34:10 INFO - PID 10792 | Values: 149.0 125.0 124.0 169.0 124.0 123.0 124.0 124.0 153.0 122.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:1.90 (1.2%) stddev-sans-first:1.94 12:34:10 INFO - PID 10792 | Values: 161.0 161.0 161.0 161.0 163.0 164.0 163.0 164.0 166.0 160.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#3] audio-fft Cycles:10 Average:80.20 Median:75.00 stddev:12.28 (16.4%) stddev-sans-first:12.82 12:34:10 INFO - PID 10792 | Values: 74.0 75.0 74.0 112.0 75.0 75.0 75.0 75.0 91.0 76.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:84.00 stddev:13.17 (15.7%) stddev-sans-first:13.64 12:34:10 INFO - PID 10792 | Values: 84.0 83.0 114.0 85.0 84.0 85.0 107.0 84.0 83.0 112.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.74 (0.7%) stddev-sans-first:0.78 12:34:10 INFO - PID 10792 | Values: 113.0 113.0 113.0 112.0 113.0 113.0 115.0 113.0 113.0 113.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 12:34:10 INFO - PID 10792 | Values: 149.0 148.0 150.0 150.0 150.0 148.0 149.0 148.0 149.0 149.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:34:10 INFO - PID 10792 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:65.50 stddev:3.50 (5.3%) stddev-sans-first:3.67 12:34:10 INFO - PID 10792 | Values: 65.0 66.0 65.0 64.0 66.0 65.0 65.0 68.0 76.0 65.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#9] json-stringify-tinderbox Cycles:10 Average:54.90 Median:55.00 stddev:0.57 (1.0%) stddev-sans-first:0.50 12:34:10 INFO - PID 10792 | Values: 54.0 55.0 54.0 55.0 55.0 55.0 55.0 55.0 55.0 56.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:82.50 stddev:1.03 (1.3%) stddev-sans-first:1.00 12:34:10 INFO - PID 10792 | Values: 81.0 83.0 82.0 83.0 84.0 82.0 82.0 81.0 81.0 83.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:134.50 stddev:28.82 (21.4%) stddev-sans-first:30.30 12:34:10 INFO - PID 10792 | Values: 132.0 132.0 137.0 131.0 131.0 137.0 143.0 131.0 224.0 130.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.20 Median:196.00 stddev:5.16 (2.6%) stddev-sans-first:5.37 12:34:10 INFO - PID 10792 | Values: 199.0 192.0 207.0 194.0 193.0 196.0 190.0 202.0 193.0 196.0 12:34:10 INFO - PID 10792 | 12:34:10 INFO - PID 10792 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:71.00 stddev:1.33 (1.9%) stddev-sans-first:1.20 12:34:10 INFO - PID 10792 | Values: 73.0 70.0 71.0 70.0 70.0 69.0 73.0 71.0 71.0 72.0 12:34:10 INFO - PID 10792 | -------- Summary: end -------- 12:34:10 INFO - PID 10792 | 12:34:11 INFO - TEST-INFO | 10792: exit 0 12:34:11 INFO - TEST-OK | kraken | took 64889ms 12:34:11 INFO - SUITE-END | took 1189s 12:34:11 INFO - Completed test suite (00:19:49) 12:34:11 INFO - javascript benchmark 12:34:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6520.963903642604, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2725.414035982264, 2754.9173524794264, 2782.833030018199, 2791.7487426131656, 2798.16055183445, 5039.924401133986, 5132.28148059272, 5211.609129315302, 5218.3998840133345, 5272.525472707461, 5196.168613021917, 5212.4266330703595, 5250.789968401259, 5260.789568417259, 5303.071962406582, 4683.063387322532, 4713.10451014307, 4726.503717109706, 4810.422749270093, 4871.220604703248, 4770.212914869043, 4784.043191361724, 4818.710877347353, 4826.32431459596, 4866.07544566533, 4616.030633566921, 4670.859874203779, 4676.485586585472, 4683.508231635663, 4760.143174228638, 4666.160091183586, 4671.976640116812, 4672.859814205545, 4680.017196388729, 4681.204195286809, 4630.513796051399, 4633.4439867215815, 4637.165310244155, 4645.0477652081245, 4647.163510568096, 5027.748612569357, 5063.0380227756605, 5090.0, 5108.846734597931, 5189.351331083614, 5676.0, 5679.460451257124, 5693.373728889819, 5699.373068962411, 5705.74324155414, 5660.264165658438, 5671.744771485293, 5675.404082571312, 5700.429957004308, 5729.971350143264, 5142.640015198975, 5249.790008399621, 5256.92114618281, 5279.023380674587, 5320.308359913187, 5583.525400340935, 5647.181158731961, 5787.768489260382, 5796.94203057964, 5802.79690210849, 5557.777688892399, 5558.610897237155, 5593.664380137205, 5598.748056337475, 5605.691686957175, 5635.070213414822, 5667.829965101054, 5668.036433806263, 5670.064439367458, 5672.659640421588, 5346.4118946915805, 5409.0263752524925, 5583.944160558342, 5594.412586678422, 5594.832155035356, 5463.235147079412, 5475.890482190413, 5478.424765399655, 5479.424660410679, 5480.232767412565, 4544.068465964469, 4595.356650068993, 4629.768511574475, 4634.559716826896, 4649.209634362167, 3962.6829853611644, 3971.324874771296, 3972.9205415892097, 3998.9600103998587, 4006.399040144002, 4802.399700037496, 4815.855524334275, 4816.349792778, 4821.276808478686, 4840.854774356775, 5442.6462279951675, 5491.2312276281355, 5494.752736126884, 5496.945030549643, 5497.285352904177, 4652.441706995182, 4709.740964246999, 4712.034033023222, 4712.175369310357, 4712.670113092119, 4882.292067650242, 4884.95115048852, 4885.218365061573, 4894.559489645878, 4899.485554016848, 5221.425643179247, 5240.030594340059, 5240.187770895517, 5246.291750613618, 5248.816291429782, 5606.131049687305, 5612.466815652507, 5629.831105066854, 5630.8029218977135, 5633.718314084197, 5243.423223445342, 5255.632105752714, 5257.763400647056, 5261.973690131525, 5262.526372626482, 5428.0, 5551.722413879371, 5566.777328906798, 5566.972165139148, 5567.805126820542, 5605.04714198579, 5622.044252477007, 5623.3533143689, 5623.775048997994, 5646.209530665792, 5285.497877701612, 5386.218998245155, 5386.7575959082715, 5470.042742519963, 5518.144687573433, 5454.618176727591, 5485.753141108739, 5516.696581688046, 5520.06158952971, 5537.307836520435, 5399.217113518596, 5411.83764487066, 5413.052715774802, 5419.160030195326, 5432.076546986943, 5351.063563876384, 5489.600246277157, 5526.917096243479, 5544.695041772741, 5595.664260144405, 5186.0, 5252.080885844885, 5320.52115309624, 5329.946700532945, 5354.303940487867], "value": 5088.962855437443, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.7033170239242, 738.0847748791498, 752.9058867641543, 1067.274253507615, 1084.6095405653969, 25846.353841153956, 31255.0, 32005.0, 32054.51918221223, 32079.19802004945, 36573.63426365735, 37232.25535489295, 37297.0, 37346.25307493848, 37370.0, 5725.398833122525, 7555.659995300225, 7725.536467811921, 7734.651940662684, 7736.883946740775, 5805.12923061542, 7264.6367681615975, 7441.367483763886, 7441.59071251081, 7452.254774522531, 7519.849603007964, 7674.923250767504, 7680.577568233745, 7686.5772382518935, 7686.884696729554, 7577.469577129603, 7610.277023682741, 7610.467267291292, 7646.961765191167, 7662.770116896502, 7522.699092036312, 7540.547567145989, 7568.924310756877, 7591.620418979084, 7595.126560445509, 33887.0, 34108.317833643436, 34149.14627134317, 34190.82904585461, 34206.82896585526, 2680.7855371570226, 2686.113582517784, 2693.46130773846, 2699.2762665335663, 2748.50526905157, 23325.53348933029, 23672.40818979522, 23733.88133059341, 23934.521309573713, 24622.630660540108, 2894.3777087925987, 2967.0, 2973.2120987938215, 2991.41667374862, 2995.3559984603417, 142862.0, 142911.0, 142950.0, 143489.21618621942, 143795.0, 40478.392824107665, 40533.0, 40618.0, 40856.38715419271, 40949.38575921304, 43002.35496467556, 43065.13869722588, 43075.78462107732, 43086.13827723428, 43094.13811723748, 12207.938960305319, 12457.563985260473, 12468.563600273947, 12468.937655311847, 12470.875291247154, 8615.138486151334, 8805.647774088964, 9964.451955142391, 9966.900330996743, 9979.700608981742, 10756.354618722902, 10823.0, 10979.83530247047, 10990.17573681977, 11077.556897724162, 4738.715677059387, 4766.904661906812, 4795.976020119877, 4806.4712881583, 4850.708957462563, 880.7401816464159, 895.3553441522093, 903.1329923273731, 912.4388501071793, 913.0732306708696, 20380.592388152152, 21045.89477052605, 21255.36233912985, 21379.25172618951, 21830.0, 970.752458123174, 974.848898420746, 981.3768257156614, 992.3004281981185, 1007.6976906928039, 10969.341839489656, 11034.944825275823, 11172.385518796382, 11207.215494915277, 11213.71965700864, 204340.0, 204827.0, 205432.0, 206068.0, 206250.0, 41710.0, 41735.373969390486, 41761.164776704296, 41859.7907010463, 41869.79065104655, 1214.180428210954, 1252.4301442843484, 1253.354522420954, 1255.7300180461245, 1262.5265525427965, 19487.610247795248, 19590.804091958897, 20782.688259675815, 20957.161713531288, 21021.474463137933, 31015.689843102184, 31133.68866311308, 31148.065558033297, 31159.532607011366, 31170.0, 202940.0, 203224.0, 203493.0, 203533.0, 204110.0, 27287.72712272852, 27786.861065694542, 27891.302717432227, 27955.860220698767, 28038.0, 35920.820395897856, 36167.457488137174, 36382.0, 36766.816165919, 37453.0, 27163.456730865666, 27397.31506712348, 27538.862305688344, 27581.724182757916, 28187.57718634263, 26945.191644250703, 27095.561399474143, 27660.308492287855, 28205.71794282031, 28756.137315880558, 30283.394332113676, 30603.081907542808, 30919.69080309258, 31350.216244594067, 31431.0, 26594.202173934813, 26607.733922660525, 27048.459030818878, 27084.0, 28025.579616305364, 21010.789892100882, 21692.132314707866, 22332.553348932604, 22409.663855042512, 22422.66366004544], "value": 14649.208061375088, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.19795518165347, 396.90275882408815, 421.1555830559727, 431.0473852785344, 436.7466869215855, 12143.635690929275, 13543.119697219674, 17782.0, 17803.198856051476, 17966.910165449186, 17226.483205503824, 17873.374431894885, 18161.818381816178, 18204.271829126817, 18941.810581894177, 5577.832665020046, 5788.652680839142, 5796.0, 5796.652200867961, 5797.652140871562, 3713.4615480755274, 5677.205191273225, 5699.515541179005, 5701.401352857953, 5702.629329093616, 5613.691246981414, 5668.546516278688, 5678.318601767794, 5678.659280443166, 5679.744411501492, 5595.468430499096, 5676.687782171979, 5707.5148612368, 5712.114622233519, 5722.542196624302, 5023.497650234984, 5185.507376799198, 5201.427842937273, 5204.9739751301, 5220.765065572021, 22664.660030099563, 22705.0, 22738.431539211488, 22862.657060144113, 22894.31317060491, 1590.300267882128, 1603.3586565373828, 1608.3566573370629, 1624.3340230505555, 1630.5271471273315, 12826.679333016657, 13679.794803077963, 13936.51222207228, 14326.140431574035, 14477.131372117707, 1399.4891864469498, 1419.6096073579745, 1447.7032208397254, 1458.9781153282709, 1467.9853201467956, 72134.0, 72483.2751672487, 72980.99999999948, 74059.25940740577, 75224.0, 11109.944450277697, 11160.832587511193, 11185.66443006711, 11307.49116289769, 11353.886461135284, 15789.131597762003, 15878.206089695468, 15888.205589720468, 15896.602584935237, 15896.920515397349, 7318.194998550155, 7397.112346518345, 7481.289277518627, 7527.585982770891, 7615.581143037075, 6659.433948114464, 6711.966440167866, 6716.328367163244, 6887.690053947585, 6888.207856096613, 6908.827279318058, 7062.858742825115, 7195.316444937723, 7203.011988506865, 7257.637118144072, 1936.1190658250462, 1946.8929208893646, 1961.3429501117284, 1984.9801501985085, 2016.5865997470482, 851.4933614499363, 869.6695255802881, 881.3433991676242, 898.8247291778172, 899.7165892743765, 16471.423500177436, 16748.581285467717, 17123.74314385285, 17233.138343082795, 17326.39357622477, 900.5317235037744, 900.7297810656777, 901.4095767272316, 911.8677791720163, 919.2324409118328, 8696.0, 9466.95266523663, 9629.566669499889, 9635.421874687541, 9663.420194788336, 212623.0, 212873.0, 213472.0, 213611.0, 214069.0, 56369.43630563724, 59026.40973590295, 82849.58575207206, 84954.5752271247, 87807.56096219478, 1046.199657262195, 1057.5399701130034, 1103.674416047268, 1104.1608377632897, 1113.1762495753246, 16641.500754976885, 16692.165391730123, 17033.91483042577, 17374.826251737322, 17810.91094544519, 39786.0, 40267.597324026385, 40947.0, 41235.0, 41920.0, 49315.0, 51045.74477127591, 52067.0, 52434.47565524296, 52756.47243527516, 7913.802154946173, 7986.201379861967, 8204.097549269456, 8257.876131857907, 8617.224449799549, 11265.436728163722, 11595.420228988685, 12222.327771972627, 12835.318836746188, 12971.35143242853, 10044.246681498771, 10070.8489372658, 10999.670009899715, 11116.110711142928, 11674.533018679498, 9679.70960871175, 10021.749456263651, 10223.7444063899, 10313.432761198206, 10401.42792146409, 9585.185259253041, 10038.548265327932, 10084.84872726895, 10237.9488102559, 10692.0, 7748.186440423674, 7910.802229944297, 7984.600769961595, 8015.919840801517, 8394.538300393537, 9900.25748068912, 9925.156361709336, 10066.396016239049, 10132.392056476636, 10424.739381515523], "value": 8501.724070491211, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1223.4861358229546, 1291.2833377475504, 1309.155594641457, 1315.7039666075132, 1323.9139455935363, 9363.11050450207, 9664.081912218346, 9702.369345992505, 9724.951375243132, 9743.756406089842, 9563.904360956389, 9621.470819104963, 9884.950575247132, 9925.950370248156, 9937.0, 2253.54929014197, 2291.919782807602, 2297.322289924468, 2297.597920363938, 2300.654901764732, 2032.5325175209712, 2164.0, 2210.115953618549, 2216.002798740565, 2222.7221597300336, 1883.6421079994825, 1953.9511512212166, 2179.3897708641525, 2252.6395776675736, 2254.5265494246228, 2280.3273034454874, 2296.817139173327, 2303.5508075925036, 2305.98536643877, 2306.2043594959714, 2215.9778402215934, 2261.7625149359246, 2264.0377839418347, 2264.8074913632363, 2265.0713207584813, 9560.904390956071, 9762.463064531448, 9778.168855647278, 9987.400755954593, 9994.300398972075, 5405.432429594875, 5607.607467477278, 5609.607327487078, 5614.494695477386, 5615.887682246333, 3116.439040972624, 3315.4082899701957, 3356.16095976006, 3445.620981692012, 3446.327966046622, 1083.653230966091, 1115.3642423818426, 1153.117864833403, 1155.6706338693432, 1181.3207405741732, 14484.565463036126, 14539.563813085624, 14586.781198282035, 14608.19654918979, 14615.342309596093, 4732.7055016827535, 4898.069366820293, 4912.901741965141, 4955.1576232039915, 5011.799528018839, 10845.891541084646, 10865.891341086646, 10871.184661150288, 10934.234603577674, 10963.725906852233, 5563.749631266603, 5567.3040869891265, 5568.582356323292, 5571.052921003439, 5578.442155784389, 4844.297576851337, 4926.802927882844, 4973.776180071905, 5022.296878437021, 5093.694378337312, 3638.472421498868, 3768.0, 3811.6378944000276, 3834.808259587065, 3858.6141385861188, 1091.1598069486452, 1099.0383414512303, 1118.8098023336051, 1124.0333313350511, 1124.2636073371955, 553.3510139783388, 632.193952710298, 695.5305169010898, 710.6020628448085, 717.8026042838156, 5413.972930135403, 5603.551715862721, 5607.635503692247, 5720.0, 5738.569607279471, 526.2264471227289, 527.211818331589, 530.7531997621124, 537.8682222855425, 628.5976974736172, 3332.1503016731062, 3371.359441706068, 3425.400554902882, 3518.155642645798, 3576.7138628909624, 12513.812292815615, 13195.274259915797, 13240.0, 13245.403956821967, 13271.73456530864, 12698.492060317483, 13083.644943909998, 13118.147320424141, 13133.540326088541, 13133.60599182026, 9206.0, 9468.19520340765, 9488.146066854017, 9493.762655933519, 9501.714948551555, 5238.292830467838, 5257.973710131425, 5282.234076058873, 5337.466253374631, 5477.287952566141, 11378.43107844621, 11543.422828858356, 11599.188056835941, 11657.591984280509, 11677.708057298296, 11918.106142039209, 11932.045436365244, 12069.27584344942, 12131.939340303243, 12237.632871013884, 7456.552606843607, 7650.540967541965, 7667.3099421052375, 7670.846583068419, 7671.53970761756, 9199.540022998957, 9206.309526785384, 9255.981841997509, 9303.67437139724, 9362.719118426457, 7980.241877021674, 7992.440529162903, 8017.478863873829, 8058.315043221158, 8280.792980175544, 7483.401327893646, 7646.082470103401, 7718.614069296625, 7739.110002349802, 7823.2567906048835, 6560.803175904731, 6584.473242140713, 6596.274409815012, 6603.504737144637, 6604.108445359815, 4829.299751536078, 4917.0, 4981.601471882168, 5087.287779710918, 5258.789648414021, 4694.671373003857, 4964.2305442655, 5022.924656130088, 5032.899342013066, 5058.671186372874], "value": 4604.9416460344455, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.1312047892242, 401.96784257259424, 403.7375705791236, 409.4288467587715, 415.75262718682376, 5228.346456692913, 5280.2079688046815, 5313.495217954288, 5355.6251062425645, 5357.169638706002, 5215.843524694255, 5224.4775522447735, 5233.764480598373, 5253.395859476162, 5255.237990491386, 3889.572147063821, 4214.115035842477, 4234.195502854448, 4247.447831781864, 4248.129133527635, 4218.514870789868, 4225.936610950838, 4226.795363321454, 4239.512456067561, 4259.76571288579, 4235.915281694364, 4236.406903033562, 4245.23585754564, 4247.0, 4249.3201087825955, 4123.484564429431, 4175.164967006581, 4208.200441916057, 4229.069604686964, 4247.260516966555, 3818.3127037133304, 3822.73240873139, 3823.3500304947947, 3824.1204522959597, 3836.558795738498, 5083.61872859537, 5101.693898366073, 5107.846764597031, 5110.693358398508, 5125.641205115606, 4781.713097214143, 4867.318575399447, 4888.071266459361, 4895.5838753705975, 4902.240152776325, 5516.200150978086, 5536.2249285100115, 5549.19536667185, 5562.249096371978, 5568.387477377485, 4248.617624413787, 4250.4899412070445, 4256.787160641956, 4263.040815816447, 4275.636570891478, 5144.0, 5441.83674489766, 5726.713664316851, 5835.9708201458725, 5839.970800146015, 4350.912981740347, 4354.956450435455, 4360.430245111757, 4370.956290437119, 4374.091229332522, 4445.73325600465, 4455.1757924784015, 4470.530594287618, 4471.709338892962, 4472.776361181928, 4468.687191896536, 4870.756462176877, 4875.805427670243, 4892.0, 4906.509349065065, 5468.179773033997, 5492.517020404468, 5517.420670829585, 5542.445755424425, 5577.107662774018, 1411.964700882486, 1416.9362378692983, 1418.5460652591278, 1419.6096073579847, 1428.8642578954982, 904.1952662130709, 960.1118964957526, 970.9320347575743, 979.4221409368507, 979.7991411760572, 4840.322354870321, 4840.927386089212, 4845.1520983828395, 4847.418309802846, 4866.513348665105, 5735.827925162168, 5741.138829175574, 5753.309602847601, 5760.222369979999, 5766.913496297559, 4620.976895115503, 4812.157872372391, 4855.75721213945, 4865.8615193200185, 4883.218685010453, 3012.5631783391286, 3027.8637461314293, 3032.469317869364, 3034.544818277276, 3059.586955760988, 5360.544353729899, 5387.164989426645, 5392.892142157135, 5428.185772134132, 5430.647007944471, 5603.5237004854225, 5628.493435590816, 5766.538676905838, 5834.883302333929, 5872.823815285548, 5262.184361423985, 5395.244665746799, 5399.784008639689, 5414.187871819258, 5426.430224826416, 5727.513161381163, 5739.540836732968, 5742.28221472316, 5742.626729262584, 5747.683877386784, 5361.678299302054, 5575.135853942646, 5586.217929489956, 5640.492355688008, 5678.14827775837, 5365.3293338332705, 5395.541378982673, 5400.2439658447065, 5415.40430552631, 5420.864478388072, 4046.9392959106226, 4058.553559108555, 4059.3302105153502, 4062.7156099073964, 4065.593440655911, 5519.972400137974, 5720.399358067343, 5732.799352022659, 5741.1388291756575, 5757.309122905278, 5556.472135147154, 5559.805406810742, 5563.248961390161, 5569.944300557105, 5570.192322113352, 5464.398916119303, 5545.2513910623165, 5572.498475137257, 5575.498205161555, 5575.77696892136, 5489.64317319373, 5501.697406642673, 5512.145617429305, 5513.684252613832, 5521.613487055867, 5641.576881733969, 5646.407127251579, 5654.038813401649, 5670.404607516316, 5671.914921276267, 5289.709066001406, 5434.0490414176575, 5446.428125046813, 5461.563074953915, 5500.28496295495], "value": 4255.795098041358, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.4420463629092, 701.8841891087969, 702.2170280137647, 708.40140081631, 712.4549719464613, 12733.299668518228, 13067.215967041984, 13155.999999999987, 13182.077254592183, 13182.34088295585, 12428.627141185756, 13818.447262109503, 14381.568552943429, 14439.855601443982, 14467.421303147861, 826.8718348655952, 828.8010877389413, 843.4517563583689, 845.9154084591553, 846.2595229174473, 836.3852568362249, 844.7803571071535, 845.3617518773349, 847.1782371100023, 848.3213429256601, 900.6172376739892, 901.5987885391004, 901.9639214431414, 902.1700035966926, 903.3811838890381, 892.5225004622496, 897.8114595934861, 901.040391982543, 901.779064129286, 902.0753727429437, 657.7796438193163, 662.1954325494547, 663.5653646861314, 664.7772996046331, 664.8404382948106, 12823.102382833205, 13048.47806087765, 14049.5082672107, 14187.077839940473, 14203.715925681532, 13157.671058223525, 13727.10116566825, 13736.587902362946, 13994.370253338522, 14105.506307279298, 9593.760155996157, 9984.450855203033, 10048.648297309559, 10119.392836429837, 10264.024917632813, 2544.6946366436146, 2562.2185233503774, 2579.239124458289, 2634.1702363755358, 2664.440467501827, 50289.24566131511, 51089.0, 51239.975200496156, 51991.74004129955, 52590.21114683283, 9986.800263994824, 10023.198144148597, 10140.239482038876, 10155.238357123246, 10158.746031349128, 17548.736768948474, 17599.128736217874, 17672.646547068987, 17688.0, 17934.0, 6327.0, 6425.453836423862, 6497.902531462032, 6503.577267477599, 6558.245801732862, 3323.1858194742445, 3365.7812242204177, 3432.8626854926024, 3437.3812713711272, 3438.7249020078834, 3622.637736226409, 3686.7787932724123, 3687.6681098701247, 3722.5346831646043, 3744.3634582121103, 651.8305240637374, 653.7842511971133, 654.2247436787422, 666.2837449741547, 672.0322735261208, 519.5765451157295, 536.2894165231006, 540.3920589336996, 551.1429726774903, 553.1232995701857, 8270.710525131592, 8776.341774366949, 9038.367314287936, 9147.897618870584, 9374.156325930699, 540.4082529630049, 560.9579281553899, 561.5114850080374, 564.8333741546254, 565.9179418984306, 3761.6238376162714, 3808.447775072599, 3877.473042779216, 3932.960670393317, 3932.9985252501187, 61742.38257617456, 62556.68721656362, 62902.370976290564, 63079.36920630735, 65115.34884651188, 55001.09980203522, 55009.449905500434, 55466.44533554694, 55610.0, 57756.422435775945, 47051.52948470471, 47738.522614774105, 47895.28157077647, 48929.26606100911, 49916.50083499119, 7363.450040143428, 7410.147832999059, 7723.729669461542, 7793.844123117619, 7894.289513943772, 26559.07043253477, 26888.193354199404, 26935.86532067327, 27206.319842003315, 27434.5884811732, 31695.683043169272, 32143.357132856745, 32294.35411291808, 32665.34669306648, 37778.62221377751, 10910.0, 11458.4270786462, 11505.424728763695, 11768.411579421167, 12147.574834880737, 12847.35763211817, 13147.408366623333, 13225.995957423534, 13367.131136476099, 13427.26150061756, 12235.694107647025, 12256.0, 12339.07456940715, 12440.875591243972, 12561.937190313989, 11124.555017799197, 11825.467853946766, 12158.75682486363, 12689.111762176559, 12720.74558508843, 10932.726681833017, 11013.614523491638, 11577.768444631229, 11612.647437803615, 11621.825672614748, 8291.875621865556, 8404.226605220385, 8460.238578527962, 8554.31565474773, 8676.479411235347, 9233.630654773733, 10107.79784404293, 10163.44101074448, 10402.323848949794, 10430.73923151897], "value": 6189.884141450727, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 112.0, 114.0, 112.0, 111.0, 112.0, 112.0, 113.0, 114.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 125.0, 124.0, 169.0, 124.0, 123.0, 124.0, 124.0, 153.0, 122.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 163.0, 164.0, 163.0, 164.0, 166.0, 160.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 112.0, 75.0, 75.0, 75.0, 75.0, 91.0, 76.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 114.0, 85.0, 84.0, 85.0, 107.0, 84.0, 83.0, 112.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 115.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 150.0, 150.0, 150.0, 148.0, 149.0, 148.0, 149.0, 149.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 64.0, 66.0, 65.0, 65.0, 68.0, 76.0, 65.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0], "value": 54.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 83.0, 84.0, 82.0, 82.0, 81.0, 81.0, 83.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 137.0, 131.0, 131.0, 137.0, 143.0, 131.0, 224.0, 130.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 192.0, 207.0, 194.0, 193.0, 196.0, 190.0, 202.0, 193.0, 196.0], "value": 196.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 70.0, 71.0, 70.0, 70.0, 69.0, 73.0, 71.0, 71.0, 72.0], "value": 71.0, "unit": "score"}]}]} 12:34:11 INFO - Return code: 0 12:34:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:34:11 ERROR - # TBPL SUCCESS # 12:34:11 INFO - Running post-action listener: _resource_record_post_action 12:34:11 INFO - [mozharness: 2017-03-26 19:34:11.842483Z] Finished run-tests step (success) 12:34:11 INFO - Running post-run listener: _resource_record_post_run 12:34:11 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 147456; Write bytes: 362438656; Read time: 40; Write time: 398720 12:34:11 INFO - TinderboxPrint: CPU usage
12.9% 12:34:11 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 40 12:34:11 INFO - TinderboxPrint: I/O write bytes / time
362,438,656 / 398,720 12:34:11 INFO - TinderboxPrint: CPU idle
8,379.2 (87.0%) 12:34:11 INFO - TinderboxPrint: CPU user
1,199.1 (12.5%) 12:34:11 INFO - TinderboxPrint: Swap in / out
0 / 0 12:34:11 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106704896; Read time: 0; Write time: 129804 12:34:11 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 147456; Write bytes: 255733760; Read time: 40; Write time: 268916 12:34:12 INFO - Running post-run listener: _upload_blobber_files 12:34:12 INFO - Blob upload gear active. 12:34:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:34:12 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 12:34:12 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'] 12:34:12 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 12:34:12 INFO - (blobuploader) - INFO - Open directory for files ... 12:34:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:34:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:34:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:13 INFO - (blobuploader) - INFO - Done attempting. 12:34:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:34:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:34:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:14 INFO - (blobuploader) - INFO - Done attempting. 12:34:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:34:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:34:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:17 INFO - (blobuploader) - INFO - Done attempting. 12:34:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:34:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:34:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:18 INFO - (blobuploader) - INFO - Done attempting. 12:34:18 INFO - (blobuploader) - INFO - Iteration through files over. 12:34:18 INFO - Return code: 0 12:34:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:34:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:34:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b303fc416f007b125251eaf377b765e5c875248c074b46cf08578a5a1d8f629a1596286fa92e78639cb71aec053233c91d22ecba06ed6111481625816f36ee90", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e0e02ba3e344fce1aa14e3efc63394b6a1d5c1489fa3681924a2c70be1ccfc656f2c329429139cc2b20a4ca3b891c5215589e1a26e42b1c1b91b58395971e1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/35c6d3396960d1d93926924789efb2db434e694c84aed91f1c71412ca68dca9a92275b2d596775f4efb96f3b000b138f19624bcbfae8d5296c6ba26a906f456b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/07de1c6d022f4f7259cab703c86820fd4e364520107d74048f325266e7dfa757f799c2b4a9ab2fca97ec0c6e91629d3159cefc7d5fc57c8a4182306ff41beb28"} 12:34:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:34:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:34:18 INFO - Contents: 12:34:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b303fc416f007b125251eaf377b765e5c875248c074b46cf08578a5a1d8f629a1596286fa92e78639cb71aec053233c91d22ecba06ed6111481625816f36ee90", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e0e02ba3e344fce1aa14e3efc63394b6a1d5c1489fa3681924a2c70be1ccfc656f2c329429139cc2b20a4ca3b891c5215589e1a26e42b1c1b91b58395971e1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/35c6d3396960d1d93926924789efb2db434e694c84aed91f1c71412ca68dca9a92275b2d596775f4efb96f3b000b138f19624bcbfae8d5296c6ba26a906f456b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/07de1c6d022f4f7259cab703c86820fd4e364520107d74048f325266e7dfa757f799c2b4a9ab2fca97ec0c6e91629d3159cefc7d5fc57c8a4182306ff41beb28"} 12:34:18 INFO - Running post-run listener: copy_logs_to_upload_dir 12:34:18 INFO - Copying logs to upload dir... 12:34:18 INFO - mkdir: /builds/slave/test/build/upload/logs 12:34:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.684114 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-03-26 12:34:18.291574) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 12:34:18.295509) ========= 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=ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b303fc416f007b125251eaf377b765e5c875248c074b46cf08578a5a1d8f629a1596286fa92e78639cb71aec053233c91d22ecba06ed6111481625816f36ee90", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e0e02ba3e344fce1aa14e3efc63394b6a1d5c1489fa3681924a2c70be1ccfc656f2c329429139cc2b20a4ca3b891c5215589e1a26e42b1c1b91b58395971e1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/35c6d3396960d1d93926924789efb2db434e694c84aed91f1c71412ca68dca9a92275b2d596775f4efb96f3b000b138f19624bcbfae8d5296c6ba26a906f456b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/07de1c6d022f4f7259cab703c86820fd4e364520107d74048f325266e7dfa757f799c2b4a9ab2fca97ec0c6e91629d3159cefc7d5fc57c8a4182306ff41beb28"} build_url:https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/OD1VA49gSqGfLK3faV0-sg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b303fc416f007b125251eaf377b765e5c875248c074b46cf08578a5a1d8f629a1596286fa92e78639cb71aec053233c91d22ecba06ed6111481625816f36ee90", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e0e02ba3e344fce1aa14e3efc63394b6a1d5c1489fa3681924a2c70be1ccfc656f2c329429139cc2b20a4ca3b891c5215589e1a26e42b1c1b91b58395971e1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/35c6d3396960d1d93926924789efb2db434e694c84aed91f1c71412ca68dca9a92275b2d596775f4efb96f3b000b138f19624bcbfae8d5296c6ba26a906f456b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/07de1c6d022f4f7259cab703c86820fd4e364520107d74048f325266e7dfa757f799c2b4a9ab2fca97ec0c6e91629d3159cefc7d5fc57c8a4182306ff41beb28"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 12:34:18.321295) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:34:18.321662) ========= 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=ba164b0a7b0ec7655860e956000001c1-1490543319.268572-1113431193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:34:18.408708) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 12:34:18.409289) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 12:34:18.409852) ========= ========= Total master_lag: 0.17 =========