builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1490354101.46 results: success (0) revision: 72bc265f157f3937f25d18be68b60fb982df695a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:01.458885) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:01.464729) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:01.465141) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:01.481088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:01.481443) ========= 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=7de08804244eb36560b44971000001c3-1490354100.757830-2095725649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-24 04:15:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 99.9M=0s 2017-03-24 04:15:01 (99.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138221 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:01.633651) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:01.634006) ========= 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=7de08804244eb36560b44971000001c3-1490354100.757830-2095725649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015231 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:01.683317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 04:15:01.683797) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 72bc265f157f3937f25d18be68b60fb982df695a --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 72bc265f157f3937f25d18be68b60fb982df695a --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=7de08804244eb36560b44971000001c3-1490354100.757830-2095725649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-24 04:15:02,100 truncating revision to first 12 chars 2017-03-24 04:15:02,100 Setting DEBUG logging. 2017-03-24 04:15:02,100 attempt 1/10 2017-03-24 04:15:02,100 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/72bc265f157f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-24 04:15:03,515 unpacking tar archive at: mozilla-central-72bc265f157f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.695076 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 04:15:03.393150) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:03.393506) ========= script_repo_revision: 72bc265f157f3937f25d18be68b60fb982df695a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:03.393867) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:03.394127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 04:15:03.403911) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-03-24 04:15:03.404250) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1490354100.757830-2095725649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:15:03 INFO - MultiFileLogger online at 20170324 04:15:03 in /builds/slave/test 04:15:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:15:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:15:03 INFO - {'append_to_log': False, 04:15:03 INFO - 'base_work_dir': '/builds/slave/test', 04:15:03 INFO - 'blob_upload_branch': 'Firefox', 04:15:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:15:03 INFO - 'branch': 'Firefox', 04:15:03 INFO - 'buildbot_json_path': 'buildprops.json', 04:15:03 INFO - 'config_files': ('talos/linux_config.py',), 04:15:03 INFO - 'default_actions': ('clobber', 04:15:03 INFO - 'read-buildbot-config', 04:15:03 INFO - 'download-and-extract', 04:15:03 INFO - 'populate-webroot', 04:15:03 INFO - 'create-virtualenv', 04:15:03 INFO - 'install', 04:15:03 INFO - 'run-tests'), 04:15:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:15:03 INFO - 'download_minidump_stackwalk': True, 04:15:03 INFO - 'download_symbols': 'ondemand', 04:15:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:15:03 INFO - 'tooltool.py': '/tools/tooltool.py', 04:15:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:15:03 INFO - '/tools/misc-python/virtualenv.py')}, 04:15:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:15:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:15:03 INFO - 'gecko_profile': False, 04:15:03 INFO - 'gecko_profile_interval': 0, 04:15:03 INFO - 'installer_path': 'installer.exe', 04:15:03 INFO - 'log_level': 'info', 04:15:03 INFO - 'log_name': 'talos', 04:15:03 INFO - 'log_to_console': True, 04:15:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:15:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:15:03 INFO - 'opt_config_files': (), 04:15:03 INFO - 'pip_index': False, 04:15:03 INFO - 'suite': 'dromaeojs', 04:15:03 INFO - 'system_bits': '32', 04:15:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:15:03 INFO - 'title': 'talos-linux64-ix-003', 04:15:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:15:03 INFO - 'use_talos_json': True, 04:15:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:15:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:15:03 INFO - 'work_dir': 'build'} 04:15:03 INFO - [mozharness: 2017-03-24 11:15:03.918379Z] Running clobber step. 04:15:03 INFO - Running pre-action listener: _resource_record_pre_action 04:15:03 INFO - Running main action method: clobber 04:15:03 INFO - rmtree: /builds/slave/test/build 04:15:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:15:04 INFO - Running post-action listener: _resource_record_post_action 04:15:04 INFO - [mozharness: 2017-03-24 11:15:04.943305Z] Finished clobber step (success) 04:15:04 INFO - [mozharness: 2017-03-24 11:15:04.943405Z] Running read-buildbot-config step. 04:15:04 INFO - Running pre-action listener: _resource_record_pre_action 04:15:04 INFO - Running main action method: read_buildbot_config 04:15:04 INFO - Using buildbot properties: 04:15:04 INFO - { 04:15:04 INFO - "project": "", 04:15:04 INFO - "product": "firefox", 04:15:04 INFO - "who": "cbook@mozilla.com", 04:15:04 INFO - "installer_path": "public/build/target.tar.bz2", 04:15:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:15:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:15:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:15:04 INFO - "stage_platform": "linux64-pgo", 04:15:04 INFO - "basedir": "/builds/slave/test", 04:15:04 INFO - "buildnumber": 93, 04:15:04 INFO - "platform": "ubuntu64_hw", 04:15:04 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:15:04 INFO - "slavebuilddir": "test", 04:15:04 INFO - "taskId": "Qt1hrTw0TU2dd9J91h3n9Q", 04:15:04 INFO - "branch": "mozilla-central", 04:15:04 INFO - "script_repo_revision": "production", 04:15:04 INFO - "revision": "72bc265f157f3937f25d18be68b60fb982df695a", 04:15:04 INFO - "slavename": "talos-linux64-ix-003", 04:15:04 INFO - "repo_path": "mozilla-central" 04:15:04 INFO - } 04:15:04 INFO - Finding installer, test and symbols from parent task. 04:15:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qt1hrTw0TU2dd9J91h3n9Q'}, attempt #1 04:15:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qt1hrTw0TU2dd9J91h3n9Q'}, attempt #1 04:15:05 INFO - Task dependencies: J7lBUM6WQoa1qEKoGqAQWQ 04:15:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ'}, attempt #1 04:15:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.tar.bz2 04:15:06 INFO - Set test_url: https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/test_packages.json 04:15:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.crashreporter-symbols.zip 04:15:06 INFO - Running post-action listener: _resource_record_post_action 04:15:06 INFO - [mozharness: 2017-03-24 11:15:06.342971Z] Finished read-buildbot-config step (success) 04:15:06 INFO - [mozharness: 2017-03-24 11:15:06.343189Z] Running download-and-extract step. 04:15:06 INFO - Running pre-action listener: _resource_record_pre_action 04:15:06 INFO - Running main action method: download_and_extract 04:15:06 INFO - mkdir: /builds/slave/test/build/tests 04:15:06 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')]} 04:15:06 INFO - trying https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.test_packages.json 04:15:06 INFO - Downloading https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:15:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:15:06 INFO - Downloaded 986 bytes. 04:15:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:15:06 INFO - Using the following test package requirements: 04:15:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:15:06 INFO - u'common': [u'target.common.tests.zip'], 04:15:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:15:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:15:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:15:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:15:06 INFO - u'mozbase': [u'target.common.tests.zip'], 04:15:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:15:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:15:06 INFO - u'web-platform': [u'target.common.tests.zip', 04:15:06 INFO - u'target.web-platform.tests.zip'], 04:15:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:15:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:15:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.common.tests.zip 04:15:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:15:06 INFO - Fetch https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.common.tests.zip into memory 04:15:08 INFO - Expected file size: 60248473 04:15:08 INFO - Obtained file size: 60248473 04:15:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:15:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.common.tests.zip 04:15:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:15:18 INFO - Fetch https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.common.tests.zip into memory 04:15:19 INFO - Expected file size: 60248473 04:15:19 INFO - Obtained file size: 60248473 04:15:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.talos.tests.zip 04:15:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:15:31 INFO - Fetch https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.talos.tests.zip into memory 04:15:32 INFO - Expected file size: 14404171 04:15:32 INFO - Obtained file size: 14404171 04:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:32 INFO - trying https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.tar.bz2 04:15:32 INFO - Downloading https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:15:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:15:34 INFO - Downloaded 67743965 bytes. 04:15:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.tar.bz2 04:15:34 INFO - mkdir: /builds/slave/test/properties 04:15:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:15:34 INFO - Writing to file /builds/slave/test/properties/build_url 04:15:34 INFO - Contents: 04:15:34 INFO - build_url:https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.tar.bz2 04:15:34 INFO - Running post-action listener: _resource_record_post_action 04:15:34 INFO - Running post-action listener: set_extra_try_arguments 04:15:34 INFO - [mozharness: 2017-03-24 11:15:34.096907Z] Finished download-and-extract step (success) 04:15:34 INFO - [mozharness: 2017-03-24 11:15:34.097004Z] Running populate-webroot step. 04:15:34 INFO - Running pre-action listener: _resource_record_pre_action 04:15:34 INFO - Running main action method: populate_webroot 04:15:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:15:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:15:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:15:34 INFO - '--tppagecycles', 04:15:34 INFO - '7'], 04:15:34 INFO - 'tests': ['tsvgm']}}, 04:15:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:15:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:15:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:15:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:15:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:15:34 INFO - 'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:15:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:15:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:15:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:15:34 INFO - 'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['damp', 'tps']}, 04:15:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:15:34 INFO - 'tests': ['damp', 'tps']}, 04:15:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['dromaeo_dom']}, 04:15:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:15:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['basic_compositor_video']}, 04:15:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:15:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['a11yr', 04:15:34 INFO - 'ts_paint', 04:15:34 INFO - 'tpaint', 04:15:34 INFO - 'sessionrestore', 04:15:34 INFO - 'sessionrestore_no_auto_restore', 04:15:34 INFO - 'tabpaint']}, 04:15:34 INFO - 'other-e10s': {'tests': ['a11yr', 04:15:34 INFO - 'ts_paint', 04:15:34 INFO - 'tpaint', 04:15:34 INFO - 'sessionrestore', 04:15:34 INFO - 'sessionrestore_no_auto_restore', 04:15:34 INFO - 'tabpaint']}, 04:15:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:15:34 INFO - 'ts_paint', 04:15:34 INFO - 'tpaint', 04:15:34 INFO - 'sessionrestore', 04:15:34 INFO - 'sessionrestore_no_auto_restore', 04:15:34 INFO - 'tabpaint']}, 04:15:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:15:34 INFO - 'ts_paint', 04:15:34 INFO - 'tpaint', 04:15:34 INFO - 'sessionrestore', 04:15:34 INFO - 'sessionrestore_no_auto_restore', 04:15:34 INFO - 'tabpaint']}, 04:15:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['a11yr', 04:15:34 INFO - 'ts_paint', 04:15:34 INFO - 'tpaint', 04:15:34 INFO - 'sessionrestore', 04:15:34 INFO - 'sessionrestore_no_auto_restore', 04:15:34 INFO - 'tabpaint']}, 04:15:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['a11yr', 04:15:34 INFO - 'ts_paint', 04:15:34 INFO - 'tpaint', 04:15:34 INFO - 'sessionrestore', 04:15:34 INFO - 'sessionrestore_no_auto_restore', 04:15:34 INFO - 'tabpaint']}, 04:15:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['tsvgx', 04:15:34 INFO - 'tsvgr_opacity', 04:15:34 INFO - 'tart', 04:15:34 INFO - 'tscrollx', 04:15:34 INFO - 'cart', 04:15:34 INFO - 'tsvg_static']}, 04:15:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:15:34 INFO - 'tsvgr_opacity', 04:15:34 INFO - 'tart', 04:15:34 INFO - 'tscrollx', 04:15:34 INFO - 'cart', 04:15:34 INFO - 'tsvg_static']}, 04:15:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:15:34 INFO - 'talos_options': ['--disable-e10s'], 04:15:34 INFO - 'tests': ['tp5o']}, 04:15:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:15:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:15:34 INFO - 'talos_options': ['--disable-e10s', 04:15:34 INFO - '--xperf_path', 04:15:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:15:34 INFO - 'tests': ['tp5n']}, 04:15:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:15:34 INFO - 'talos_options': ['--xperf_path', 04:15:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:15:34 INFO - 'tests': ['tp5n']}}, 04:15:34 INFO - 'talos.zip': {'path': '', 04:15:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:15:34 INFO - Running post-action listener: _resource_record_post_action 04:15:34 INFO - [mozharness: 2017-03-24 11:15:34.110188Z] Finished populate-webroot step (success) 04:15:34 INFO - [mozharness: 2017-03-24 11:15:34.110280Z] Running create-virtualenv step. 04:15:34 INFO - Running pre-action listener: _resource_record_pre_action 04:15:34 INFO - Running main action method: create_virtualenv 04:15:34 INFO - Creating virtualenv /builds/slave/test/build/venv 04:15:34 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 04:15:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:15:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:15:34 INFO - Using real prefix '/usr' 04:15:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:15:34 INFO - Installing distribute.............................................................................................................................................................................................done. 04:15:36 INFO - Installing pip.................done. 04:15:36 INFO - Return code: 0 04:15:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:15:37 INFO - Reading from file tmpfile_stdout 04:15:37 INFO - Output received: 04:15:37 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) 04:15:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:15:37 INFO - Using env: {'DISPLAY': ':0', 04:15:37 INFO - 'HOME': '/home/cltbld', 04:15:37 INFO - 'LANG': 'en_US.UTF-8', 04:15:37 INFO - 'LANGUAGE': 'en_US:en', 04:15:37 INFO - 'LOGNAME': 'cltbld', 04:15:37 INFO - 'MAIL': '/var/mail/cltbld', 04:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:37 INFO - 'MOZ_NO_REMOTE': '1', 04:15:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:37 INFO - 'NO_EM_RESTART': '1', 04:15:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:37 INFO - 'PWD': '/builds/slave/test', 04:15:37 INFO - 'SHELL': '/bin/bash', 04:15:37 INFO - 'SHLVL': '1', 04:15:37 INFO - 'TERM': 'linux', 04:15:37 INFO - 'TMOUT': '86400', 04:15:37 INFO - 'USER': 'cltbld', 04:15:37 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:37 INFO - '_': '/tools/buildbot/bin/python'} 04:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:37 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 04:15:37 INFO - Cleaning up... 04:15:37 INFO - Return code: 0 04:15:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:15:37 INFO - Using env: {'DISPLAY': ':0', 04:15:37 INFO - 'HOME': '/home/cltbld', 04:15:37 INFO - 'LANG': 'en_US.UTF-8', 04:15:37 INFO - 'LANGUAGE': 'en_US:en', 04:15:37 INFO - 'LOGNAME': 'cltbld', 04:15:37 INFO - 'MAIL': '/var/mail/cltbld', 04:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:37 INFO - 'MOZ_NO_REMOTE': '1', 04:15:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:37 INFO - 'NO_EM_RESTART': '1', 04:15:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:37 INFO - 'PWD': '/builds/slave/test', 04:15:37 INFO - 'SHELL': '/bin/bash', 04:15:37 INFO - 'SHLVL': '1', 04:15:37 INFO - 'TERM': 'linux', 04:15:37 INFO - 'TMOUT': '86400', 04:15:37 INFO - 'USER': 'cltbld', 04:15:37 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:37 INFO - '_': '/tools/buildbot/bin/python'} 04:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:37 INFO - Downloading/unpacking psutil>=3.1.1 04:15:37 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 04:15:37 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 04:15:37 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 04:15:37 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 04:15:37 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 04:15:37 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 04:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:15:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:15:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:15:39 INFO - Installing collected packages: psutil 04:15:39 INFO - Running setup.py install for psutil 04:15:39 INFO - building 'psutil._psutil_linux' extension 04:15:39 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 04:15:39 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 04:15:39 INFO - building 'psutil._psutil_posix' extension 04:15:39 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 04:15:40 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 04:15:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:15:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:15:40 INFO - Successfully installed psutil 04:15:40 INFO - Cleaning up... 04:15:40 INFO - Return code: 0 04:15:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:15: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')]} 04:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15: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')]} 04:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:15:40 INFO - Using env: {'DISPLAY': ':0', 04:15:40 INFO - 'HOME': '/home/cltbld', 04:15:40 INFO - 'LANG': 'en_US.UTF-8', 04:15:40 INFO - 'LANGUAGE': 'en_US:en', 04:15:40 INFO - 'LOGNAME': 'cltbld', 04:15:40 INFO - 'MAIL': '/var/mail/cltbld', 04:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:40 INFO - 'MOZ_NO_REMOTE': '1', 04:15:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:40 INFO - 'NO_EM_RESTART': '1', 04:15:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:40 INFO - 'PWD': '/builds/slave/test', 04:15:40 INFO - 'SHELL': '/bin/bash', 04:15:40 INFO - 'SHLVL': '1', 04:15:40 INFO - 'TERM': 'linux', 04:15:40 INFO - 'TMOUT': '86400', 04:15:40 INFO - 'USER': 'cltbld', 04:15:40 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:40 INFO - '_': '/tools/buildbot/bin/python'} 04:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:15:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:15:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:15:42 INFO - Installing collected packages: mozsystemmonitor 04:15:42 INFO - Running setup.py install for mozsystemmonitor 04:15:42 INFO - Successfully installed mozsystemmonitor 04:15:42 INFO - Cleaning up... 04:15:42 INFO - Return code: 0 04:15:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:42 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15:42 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 04:15:42 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 04:15:42 INFO - Using env: {'DISPLAY': ':0', 04:15:42 INFO - 'HOME': '/home/cltbld', 04:15:42 INFO - 'LANG': 'en_US.UTF-8', 04:15:42 INFO - 'LANGUAGE': 'en_US:en', 04:15:42 INFO - 'LOGNAME': 'cltbld', 04:15:42 INFO - 'MAIL': '/var/mail/cltbld', 04:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:42 INFO - 'MOZ_NO_REMOTE': '1', 04:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:42 INFO - 'NO_EM_RESTART': '1', 04:15:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:42 INFO - 'PWD': '/builds/slave/test', 04:15:42 INFO - 'SHELL': '/bin/bash', 04:15:42 INFO - 'SHLVL': '1', 04:15:42 INFO - 'TERM': 'linux', 04:15:42 INFO - 'TMOUT': '86400', 04:15:42 INFO - 'USER': 'cltbld', 04:15:42 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:42 INFO - '_': '/tools/buildbot/bin/python'} 04:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:43 INFO - Downloading/unpacking jsonschema==2.5.1 04:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:15:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:15:45 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 04:15:45 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 04:15:45 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 04:15:45 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 04:15:45 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 04:15:45 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 04:15:45 INFO - Downloading functools32-3.2.3-2.tar.gz 04:15:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:15:45 INFO - warning: no files found matching '*.txt' 04:15:45 INFO - no previously-included directories found matching 'build' 04:15:45 INFO - no previously-included directories found matching 'dist' 04:15:45 INFO - no previously-included directories found matching '.git*' 04:15:45 INFO - Installing collected packages: jsonschema, functools32 04:15:45 INFO - Running setup.py install for functools32 04:15:45 INFO - warning: no files found matching '*.txt' 04:15:45 INFO - no previously-included directories found matching 'build' 04:15:45 INFO - no previously-included directories found matching 'dist' 04:15:45 INFO - no previously-included directories found matching '.git*' 04:15:45 INFO - Successfully installed jsonschema functools32 04:15:45 INFO - Cleaning up... 04:15:45 INFO - Return code: 0 04:15:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:15: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')]} 04:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15: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')]} 04:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15: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', '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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:15: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 functools32==3.2.3-2 04:15:45 INFO - Using env: {'DISPLAY': ':0', 04:15:45 INFO - 'HOME': '/home/cltbld', 04:15:45 INFO - 'LANG': 'en_US.UTF-8', 04:15:45 INFO - 'LANGUAGE': 'en_US:en', 04:15:45 INFO - 'LOGNAME': 'cltbld', 04:15:45 INFO - 'MAIL': '/var/mail/cltbld', 04:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:45 INFO - 'MOZ_NO_REMOTE': '1', 04:15:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:45 INFO - 'NO_EM_RESTART': '1', 04:15:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:45 INFO - 'PWD': '/builds/slave/test', 04:15:45 INFO - 'SHELL': '/bin/bash', 04:15:45 INFO - 'SHLVL': '1', 04:15:45 INFO - 'TERM': 'linux', 04:15:45 INFO - 'TMOUT': '86400', 04:15:45 INFO - 'USER': 'cltbld', 04:15:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:45 INFO - '_': '/tools/buildbot/bin/python'} 04:15:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:15:45 INFO - Cleaning up... 04:15:45 INFO - Return code: 0 04:15:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:15: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')]} 04:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15: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')]} 04:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15: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', '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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:15: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 blobuploader==1.2.4 04:15:45 INFO - Using env: {'DISPLAY': ':0', 04:15:45 INFO - 'HOME': '/home/cltbld', 04:15:45 INFO - 'LANG': 'en_US.UTF-8', 04:15:45 INFO - 'LANGUAGE': 'en_US:en', 04:15:45 INFO - 'LOGNAME': 'cltbld', 04:15:45 INFO - 'MAIL': '/var/mail/cltbld', 04:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:45 INFO - 'MOZ_NO_REMOTE': '1', 04:15:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:45 INFO - 'NO_EM_RESTART': '1', 04:15:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:45 INFO - 'PWD': '/builds/slave/test', 04:15:45 INFO - 'SHELL': '/bin/bash', 04:15:45 INFO - 'SHLVL': '1', 04:15:45 INFO - 'TERM': 'linux', 04:15:45 INFO - 'TMOUT': '86400', 04:15:45 INFO - 'USER': 'cltbld', 04:15:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:45 INFO - '_': '/tools/buildbot/bin/python'} 04:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:46 INFO - Downloading/unpacking blobuploader==1.2.4 04:15: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 04:15: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 04:15: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 04:15: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 04:15: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 04:15: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 04:15:48 INFO - Downloading blobuploader-1.2.4.tar.gz 04:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:15:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:15:48 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 04:15:48 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 04:15:48 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 04:15:48 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 04:15:48 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 04:15:48 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 04:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:15:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:15:48 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 04:15:48 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 04:15:48 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 04:15:48 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 04:15:48 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 04:15:48 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 04:15:48 INFO - Downloading docopt-0.6.1.tar.gz 04:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:15:48 INFO - Installing collected packages: blobuploader, requests, docopt 04:15:48 INFO - Running setup.py install for blobuploader 04:15:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:15:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:15:48 INFO - Running setup.py install for requests 04:15:49 INFO - Running setup.py install for docopt 04:15:49 INFO - Successfully installed blobuploader requests docopt 04:15:49 INFO - Cleaning up... 04:15:49 INFO - Return code: 0 04:15:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:49 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15:49 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 04:15:49 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 04:15:49 INFO - Using env: {'DISPLAY': ':0', 04:15:49 INFO - 'HOME': '/home/cltbld', 04:15:49 INFO - 'LANG': 'en_US.UTF-8', 04:15:49 INFO - 'LANGUAGE': 'en_US:en', 04:15:49 INFO - 'LOGNAME': 'cltbld', 04:15:49 INFO - 'MAIL': '/var/mail/cltbld', 04:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:49 INFO - 'MOZ_NO_REMOTE': '1', 04:15:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:49 INFO - 'NO_EM_RESTART': '1', 04:15:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:49 INFO - 'PWD': '/builds/slave/test', 04:15:49 INFO - 'SHELL': '/bin/bash', 04:15:49 INFO - 'SHLVL': '1', 04:15:49 INFO - 'TERM': 'linux', 04:15:49 INFO - 'TMOUT': '86400', 04:15:49 INFO - 'USER': 'cltbld', 04:15:49 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:49 INFO - '_': '/tools/buildbot/bin/python'} 04:15:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:15:49 INFO - Running setup.py (path:/tmp/pip-T1rsGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:15:49 INFO - Running setup.py (path:/tmp/pip-H81Ymh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:15:49 INFO - Running setup.py (path:/tmp/pip-E0sx7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:15:49 INFO - Running setup.py (path:/tmp/pip-vBIYfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:15:49 INFO - Running setup.py (path:/tmp/pip-ADt3QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:15:50 INFO - Running setup.py (path:/tmp/pip-qE8yc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:15:50 INFO - Running setup.py (path:/tmp/pip-6RfrCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:15:50 INFO - Running setup.py (path:/tmp/pip-oo94Fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:15:50 INFO - Running setup.py (path:/tmp/pip-i7yOBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:15:50 INFO - Running setup.py (path:/tmp/pip-3C7HCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:15:50 INFO - Running setup.py (path:/tmp/pip-9_Axjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:15:50 INFO - Running setup.py (path:/tmp/pip-JkLbWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:15:50 INFO - Running setup.py (path:/tmp/pip-y2SrjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:15:50 INFO - Running setup.py (path:/tmp/pip-eOdQsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:50 INFO - Running setup.py (path:/tmp/pip-4z3YQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:15:50 INFO - Running setup.py (path:/tmp/pip-gZCiBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:15:50 INFO - Running setup.py (path:/tmp/pip-j2NBSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:15:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:15:50 INFO - Running setup.py install for manifestparser 04:15:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Running setup.py install for mozcrash 04:15:51 INFO - Running setup.py install for mozdebug 04:15:51 INFO - Running setup.py install for mozdevice 04:15:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Running setup.py install for mozfile 04:15:51 INFO - Running setup.py install for mozhttpd 04:15:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Running setup.py install for mozinfo 04:15:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Running setup.py install for mozInstall 04:15:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Running setup.py install for mozleak 04:15:51 INFO - Running setup.py install for mozlog 04:15:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:15:51 INFO - Running setup.py install for moznetwork 04:15:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:15:52 INFO - Running setup.py install for mozprocess 04:15:52 INFO - Running setup.py install for mozprofile 04:15:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:15:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:15:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:15:52 INFO - Running setup.py install for mozrunner 04:15:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:15:52 INFO - Running setup.py install for mozscreenshot 04:15:52 INFO - Running setup.py install for moztest 04:15:52 INFO - Running setup.py install for mozversion 04:15:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:15:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:15:52 INFO - Cleaning up... 04:15:52 INFO - Return code: 0 04:15:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:52 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')]} 04:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:52 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')]} 04:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:52 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15:52 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 04:15:52 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 04:15:52 INFO - Using env: {'DISPLAY': ':0', 04:15:52 INFO - 'HOME': '/home/cltbld', 04:15:52 INFO - 'LANG': 'en_US.UTF-8', 04:15:52 INFO - 'LANGUAGE': 'en_US:en', 04:15:52 INFO - 'LOGNAME': 'cltbld', 04:15:52 INFO - 'MAIL': '/var/mail/cltbld', 04:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:52 INFO - 'MOZ_NO_REMOTE': '1', 04:15:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:52 INFO - 'NO_EM_RESTART': '1', 04:15:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:52 INFO - 'PWD': '/builds/slave/test', 04:15:52 INFO - 'SHELL': '/bin/bash', 04:15:52 INFO - 'SHLVL': '1', 04:15:52 INFO - 'TERM': 'linux', 04:15:52 INFO - 'TMOUT': '86400', 04:15:52 INFO - 'USER': 'cltbld', 04:15:52 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:52 INFO - '_': '/tools/buildbot/bin/python'} 04:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:15:52 INFO - Running setup.py (path:/tmp/pip-ISJcWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:15:52 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)) 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:15:52 INFO - Running setup.py (path:/tmp/pip-KzO2Fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:15:53 INFO - Running setup.py (path:/tmp/pip-gMorX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:15:53 INFO - Running setup.py (path:/tmp/pip-gu_dlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:15:53 INFO - Running setup.py (path:/tmp/pip-CJjt8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:15:53 INFO - Running setup.py (path:/tmp/pip-EbGxgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:15:53 INFO - Running setup.py (path:/tmp/pip-6vcoIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:15:53 INFO - Running setup.py (path:/tmp/pip-g3bqpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:15:53 INFO - Running setup.py (path:/tmp/pip-dw3qIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:15:53 INFO - Running setup.py (path:/tmp/pip-Z_dPjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:15:53 INFO - Running setup.py (path:/tmp/pip-RWgqPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:15:53 INFO - Running setup.py (path:/tmp/pip-DhAGKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:15:53 INFO - Running setup.py (path:/tmp/pip-VUtn6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:15:53 INFO - Running setup.py (path:/tmp/pip-GdAKtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:53 INFO - Running setup.py (path:/tmp/pip-gk4N1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:53 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)) 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:15:54 INFO - Running setup.py (path:/tmp/pip-El3vtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:15:54 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)) 04:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:15:54 INFO - Running setup.py (path:/tmp/pip-3y2EGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:15:54 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)) 04:15:54 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)) 04:15:54 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)) 04:15:54 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)) 04:15:54 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)) 04:15:54 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)) 04:15:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:15: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 04:15: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 04:15: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 04:15: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 04:15: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 04:15: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 04:15:56 INFO - Downloading blessings-1.6.tar.gz 04:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:15:56 INFO - Installing collected packages: blessings 04:15:56 INFO - Running setup.py install for blessings 04:15:56 INFO - Successfully installed blessings 04:15:56 INFO - Cleaning up... 04:15:56 INFO - Return code: 0 04:15:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:15:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:15:56 INFO - Reading from file tmpfile_stdout 04:15:56 INFO - Current package versions: 04:15:56 INFO - argparse == 1.2.1 04:15:56 INFO - blessings == 1.6 04:15:56 INFO - blobuploader == 1.2.4 04:15:56 INFO - docopt == 0.6.1 04:15:56 INFO - functools32 == 3.2.3-2 04:15:56 INFO - jsonschema == 2.5.1 04:15:56 INFO - manifestparser == 1.1 04:15:56 INFO - mozInstall == 1.12 04:15:56 INFO - mozcrash == 1.0 04:15:56 INFO - mozdebug == 0.1 04:15:56 INFO - mozdevice == 0.49 04:15:56 INFO - mozfile == 1.2 04:15:56 INFO - mozhttpd == 0.7 04:15:56 INFO - mozinfo == 0.9 04:15:56 INFO - mozleak == 0.1 04:15:56 INFO - mozlog == 3.4 04:15:56 INFO - moznetwork == 0.27 04:15:56 INFO - mozprocess == 0.25 04:15:56 INFO - mozprofile == 0.28 04:15:56 INFO - mozrunner == 6.13 04:15:56 INFO - mozscreenshot == 0.1 04:15:56 INFO - mozsystemmonitor == 0.3 04:15:56 INFO - moztest == 0.8 04:15:56 INFO - mozversion == 1.4 04:15:56 INFO - psutil == 3.1.1 04:15:56 INFO - requests == 1.2.3 04:15:56 INFO - wsgiref == 0.1.2 04:15:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:56 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')]} 04:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:56 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')]} 04:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:56 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15:56 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 04:15:56 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 04:15:56 INFO - Using env: {'DISPLAY': ':0', 04:15:56 INFO - 'HOME': '/home/cltbld', 04:15:56 INFO - 'LANG': 'en_US.UTF-8', 04:15:56 INFO - 'LANGUAGE': 'en_US:en', 04:15:56 INFO - 'LOGNAME': 'cltbld', 04:15:56 INFO - 'MAIL': '/var/mail/cltbld', 04:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:56 INFO - 'MOZ_NO_REMOTE': '1', 04:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:56 INFO - 'NO_EM_RESTART': '1', 04:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:56 INFO - 'PWD': '/builds/slave/test', 04:15:56 INFO - 'SHELL': '/bin/bash', 04:15:56 INFO - 'SHLVL': '1', 04:15:56 INFO - 'TERM': 'linux', 04:15:56 INFO - 'TMOUT': '86400', 04:15:56 INFO - 'USER': 'cltbld', 04:15:56 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:56 INFO - '_': '/tools/buildbot/bin/python'} 04:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 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)) 04:15:56 INFO - Cleaning up... 04:15:56 INFO - Return code: 0 04:15:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:15:56 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')]} 04:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:56 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')]} 04:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:56 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, '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': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', '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 04:15:56 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 04:15:56 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 04:15:56 INFO - Using env: {'DISPLAY': ':0', 04:15:56 INFO - 'HOME': '/home/cltbld', 04:15:56 INFO - 'LANG': 'en_US.UTF-8', 04:15:56 INFO - 'LANGUAGE': 'en_US:en', 04:15:56 INFO - 'LOGNAME': 'cltbld', 04:15:56 INFO - 'MAIL': '/var/mail/cltbld', 04:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:56 INFO - 'MOZ_NO_REMOTE': '1', 04:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:56 INFO - 'NO_EM_RESTART': '1', 04:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:56 INFO - 'PWD': '/builds/slave/test', 04:15:56 INFO - 'SHELL': '/bin/bash', 04:15:56 INFO - 'SHLVL': '1', 04:15:56 INFO - 'TERM': 'linux', 04:15:56 INFO - 'TMOUT': '86400', 04:15:56 INFO - 'USER': 'cltbld', 04:15:56 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:56 INFO - '_': '/tools/buildbot/bin/python'} 04:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:15:57 INFO - Cleaning up... 04:15:57 INFO - Return code: 0 04:15:57 INFO - Running post-action listener: _resource_record_post_action 04:15:57 INFO - Running post-action listener: _start_resource_monitoring 04:15:57 INFO - Starting resource monitoring. 04:15:57 INFO - [mozharness: 2017-03-24 11:15:57.138895Z] Finished create-virtualenv step (success) 04:15:57 INFO - [mozharness: 2017-03-24 11:15:57.139369Z] Running install step. 04:15:57 INFO - Running pre-action listener: _resource_record_pre_action 04:15:57 INFO - Running main action method: install 04:15:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:15:57 INFO - Reading from file tmpfile_stdout 04:15:57 INFO - Detecting whether we're running mozinstall >=1.0... 04:15:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:15:57 INFO - Reading from file tmpfile_stdout 04:15:57 INFO - Output received: 04:15:57 INFO - Usage: mozinstall [options] installer 04:15:57 INFO - Options: 04:15:57 INFO - -h, --help show this help message and exit 04:15:57 INFO - -d DEST, --destination=DEST 04:15:57 INFO - Directory to install application into. [default: 04:15:57 INFO - "/builds/slave/test"] 04:15:57 INFO - --app=APP Application being installed. [default: firefox] 04:15:57 INFO - mkdir: /builds/slave/test/build/application 04:15:57 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'] 04:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:16:14 INFO - Reading from file tmpfile_stdout 04:16:14 INFO - Output received: 04:16:14 INFO - /builds/slave/test/build/application/firefox/firefox 04:16:14 INFO - Running post-action listener: _resource_record_post_action 04:16:14 INFO - [mozharness: 2017-03-24 11:16:14.321188Z] Finished install step (success) 04:16:14 INFO - [mozharness: 2017-03-24 11:16:14.321466Z] Running run-tests step. 04:16:14 INFO - Running pre-action listener: _resource_record_pre_action 04:16:14 INFO - Running main action method: run_tests 04:16:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:16:14 INFO - Python 2.7.3 04:16:14 INFO - Return code: 0 04:16:14 INFO - grabbing minidump binary from tooltool 04:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:14 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263a400>, '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 0x263b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263b840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:16:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:16:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:16:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:16:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:16:14 INFO - Return code: 0 04:16:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:16:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:16:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:16:14 INFO - ENV: RUST_BACKTRACE is now 1 04:16:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:16:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:16:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 04:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 04:16:14 INFO - Using env: {'DISPLAY': ':0', 04:16:14 INFO - 'HOME': '/home/cltbld', 04:16:14 INFO - 'LANG': 'en_US.UTF-8', 04:16:14 INFO - 'LANGUAGE': 'en_US:en', 04:16:14 INFO - 'LOGNAME': 'cltbld', 04:16:14 INFO - 'MAIL': '/var/mail/cltbld', 04:16:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:16:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:14 INFO - 'MOZ_NO_REMOTE': '1', 04:16:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:16:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:16:14 INFO - 'NO_EM_RESTART': '1', 04:16:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:14 INFO - 'PWD': '/builds/slave/test', 04:16:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:16:14 INFO - 'RUST_BACKTRACE': '1', 04:16:14 INFO - 'SHELL': '/bin/bash', 04:16:14 INFO - 'SHLVL': '1', 04:16:14 INFO - 'TERM': 'linux', 04:16:14 INFO - 'TMOUT': '86400', 04:16:14 INFO - 'USER': 'cltbld', 04:16:14 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490354100.757830-2095725649', 04:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:14 INFO - '_': '/tools/buildbot/bin/python'} 04:16:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 04:16:14 INFO - mozversion application_buildid: 20170324102439 04:16:14 INFO - mozversion application_changeset: 72bc265f157f3937f25d18be68b60fb982df695a 04:16:14 INFO - mozversion application_display_name: Nightly 04:16:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:16:14 INFO - mozversion application_name: Firefox 04:16:14 INFO - mozversion application_remotingname: firefox 04:16:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:16:14 INFO - mozversion application_vendor: Mozilla 04:16:14 INFO - mozversion application_version: 55.0a1 04:16:14 INFO - mozversion platform_buildid: 20170324102439 04:16:14 INFO - mozversion platform_changeset: 72bc265f157f3937f25d18be68b60fb982df695a 04:16:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:16:14 INFO - mozversion platform_version: 55.0a1 04:16:14 INFO - using testdate: 1490354174 04:16:14 INFO - actual date: 1490354174 04:16:14 INFO - starting webserver on 'localhost:36251' 04:16:14 INFO - SUITE-START | Running 2 tests 04:16:14 INFO - TEST-START | dromaeo_css 04:16:14 INFO - operating with platform_type : linux_ 04:16:14 INFO - Initialising browser for dromaeo_css test... 04:16:14 INFO - TEST-INFO | started process 25022 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpexC3Zz/profile http://localhost:36251/getInfo.html) 04:16:18 INFO - PID 25022 | __metrics Screen width/height:1600/1200 04:16:18 INFO - PID 25022 | colorDepth:24 04:16:18 INFO - PID 25022 | Browser inner width/height: 1024/697 04:16:18 INFO - PID 25022 | __metrics 04:16:19 INFO - PID 25022 | 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" 04:16:19 INFO - PID 25022 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:16:20 INFO - TEST-INFO | 25022: exit 0 04:16:20 INFO - Browser initialized. 04:16:20 INFO - Running cycle 1/1 for dromaeo_css test... 04:16:20 INFO - TEST-INFO | started process 25115 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpexC3Zz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:32 INFO - PID 25115 | RSS: Main: 145297408 04:16:32 INFO - PID 25115 | 04:19:35 INFO - PID 25115 | Cycle 1(1): loaded http://localhost:36251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36251/tests/dromaeo/cssquery-ext.html) 04:19:36 INFO - PID 25115 | RSS: Main: 285290496 04:19:36 INFO - PID 25115 | 04:21:22 INFO - PID 25115 | ************************* 04:21:22 INFO - PID 25115 | A coding exception was thrown and uncaught in a Task. 04:21:22 INFO - PID 25115 | 04:21:22 ERROR - PID 25115 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:21:22 INFO - PID 25115 | Full stack: 04:21:22 INFO - PID 25115 | ************************* 04:22:39 INFO - PID 25115 | Cycle 1(1): loaded http://localhost:36251/tests/dromaeo/cssquery-ext.html (next: http://localhost:36251/tests/dromaeo/cssquery-jquery.html) 04:22:39 INFO - PID 25115 | RSS: Main: 232808448 04:22:39 INFO - PID 25115 | 04:25:41 INFO - PID 25115 | Cycle 1(1): loaded http://localhost:36251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36251/tests/dromaeo/cssquery-mootools.html) 04:25:42 INFO - PID 25115 | RSS: Main: 208195584 04:25:42 INFO - PID 25115 | 04:28:44 INFO - PID 25115 | Cycle 1(1): loaded http://localhost:36251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36251/tests/dromaeo/cssquery-prototype.html) 04:28:44 INFO - PID 25115 | RSS: Main: 178446336 04:28:44 INFO - PID 25115 | 04:31:47 INFO - PID 25115 | Cycle 1(1): loaded http://localhost:36251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36251/tests/dromaeo/cssquery-yui.html) 04:31:48 INFO - PID 25115 | RSS: Main: 184516608 04:31:48 INFO - PID 25115 | 04:34:50 INFO - PID 25115 | Cycle 1(1): loaded http://localhost:36251/tests/dromaeo/cssquery-yui.html (next: http://localhost:36251/tests/dromaeo/cssquery-dojo.html) 04:34:51 INFO - PID 25115 | RSS: Main: 270426112 04:34:51 INFO - PID 25115 | 04:34:51 INFO - PID 25115 | __start_tp_report 04:34:51 INFO - PID 25115 | _x_x_mozilla_page_load 04:34:51 INFO - PID 25115 | _x_x_mozilla_page_load_details 04:34:51 INFO - PID 25115 | |i|pagename|runs| 04:34:51 INFO - PID 25115 | |0;dojo.html;3347.882824101157;3357.3956687796194;3373.881914133006;3379;3402.098443912362;5666;5727.541796656263;5773.797917072898;5796.942030579693;5820.650760954346;5807.651540907548;5889.293284805819;5893.322267939188;5896.646201227929;5941.613795103315;5119;5145.073886700402;5229.424763276036;5230.058589453897;5318.574514038867;5261.184516399964;5278.392984806739;5288.15389537674;5297.629165958365;5304.814331498398;5210.140326846066;5269.473052694719;5271.973640131775;5286.3127793387;5290.735463226823;5261.86845328866;5298.15229563268;5304.867378315535;5370.409254981949;5380.139177731583;5258.0535503609335;5266.526012658841;5269.973650131763;5281.049411106;5351.09031464652;5737.569682273805;5797.246357973479;5865.442782935615;5876.11858221266;5881.85295367615;6251.499880009588;6273.686315684243;6273.968630156865;6274.874502509924;6279.340669229757;6244.469220116295;6260.624362538262;6312.810615681537;6316.74733010679;6411.070394792776;5925.792597259161;5968.522518198533;5993.820185394445;6015.54883383748;6025.0058740307795;6501.512386571026;6546.149000629887;6557.377049180321;6558.344165583403;6565.146530950946;6135.754569817157;6142.406692085091;6144.109104179869;6145.20112385396;6283.654399008007;6275.058741188767;6322.209723784527;6340.587861788969;6352.333005034498;6354.459870910932;6319.33646967071;6332;6333.240011198685;6337.112804207414;6341.207349081365;6163.3836616338;6333.20834895638;6334.619922804646;6336.52476064297;6357.841053973688;4807.206810876236;4815.012922350909;4870.780814863339;4874.878128046827;4876.683015603974;4261.701680882308;4267.4238977737605;4276.657867370665;4278.058827058042;4305.203537345601;5907.1139329100115;5907.556933230025;5907.822765317047;5910.793122240787;5915.231019967463;6093.413838573174;6240.563160578716;6254.437100660963;6338.461230795342;6341;5899.997000509924;5951.613145145551;5953.851153721192;5957.195778569924;5989.760409583568;5364.973175134178;5390.272313237662;5392.56859451243;5402.594805389612;5404.189371594213;6390.265119511316;6462.127612772216;6511.186101737283;6530.216374034956;6639.634820084942;6275.96862015687;6276.246850377984;6281.309056003927;6284.622922624657;6294.653794041188;6400.423961843457;6487.351264873475;6502.349765023459;6505.251896032017;6506.804795856132;6116.5106791457465;6128.258691089361;6131.13200488804;6154.815355539341;6182.845428864314;6371.39471750183;6372.267189273108;6400;6405.967970160119;6413.422791948747;6198.81403557894;6204;6207.937920620736;6210.975189093749;6222.377762223741;5904.498117660047;5905.4685078343155;5941.554383421174;5951.434613711691;5975.790847320323;6080.270367555921;6094.421030002142;6109.876340637589;6138.357744607992;6147;6205.286392064971;6228.314885362697;6267.091271765659;6269.12232287471;6270.529710271839;6036.336003039574;6074.179985701874;6099.908501372394;6100.786472473442;6102.389761023862 04:34:51 INFO - PID 25115 | |1;ext.html;609.5885277437728;670.6009924095163;685.5852209413304;1075.5643964194503;1085.7991271614746;28976.275593110182;36738.448923266136;37350.439743403804;37370.626293737056;37427.81286093572;42036.57963420365;42731.572684273146;42744.57255427445;42750.572494275126;42779.572204277945;5999.490043346299;6122.816315510542;8144.10414854367;8295.709650162245;8298.419110662257;7786.571738554406;7946.523208607473;7955.4828936118965;7957.32362749167;7960.442769006172;6599.307072757363;6603.669816509156;6605.405513503783;6619.900701489458;6714.362135597111;6534.085228068075;6593.505487088488;6727.899081513781;6730.966345168243;6737.831554211135;8076.353891688651;8170.754877353689;8171.305439037689;8180.6318715657935;8183.754487365389;40089;40191.397129043085;40197.79901100505;40407.7979610103;40411.7979410104;2855.8000939934213;2863.957040644392;2869.0675530452477;2886.826790392583;2904.114245155227;28624.14127576176;28640.283992900135;28747.28131796722;29015.274618134714;29100.70899291001;3571.4107172316794;3597.1366871950804;3601.873934412309;3604.4593311003296;3724.832382542792;222719;222949.99999999837;223376;223517;223717;48696.51303486991;48969.2654610174;49007;49010;49086.509134908905;52095.47904520906;52140.739296303276;52150.73924630353;52213.7389313051;52254.47745522572;14916.70166596662;15264.465743698916;15287;15307.46423875159;15321.693566128615;10518.316309440015;10681.946590266998;10914.126869850394;12296.938515307365;12355.444005019795;13622.93188534051;13636.86363136356;13644.317784110954;13668.863311366758;13732.794008089886;5127.2052831811125;5152.587792976553;5201.323827902348;5219.478052194826;5222.929299493595;992.7617371830853;994.3834822410151;1007.9848802267971;1008.6570566007592;1015.5176291261591;24780;24943.875280623844;24999.250022499353;25159.62260566093;25405;1125;1139.157023802397;1141.2652767792908;1152.153167421944;1168.8013037783596;12209.755804883853;12930.224186548838;13080.149790263602;13121.606351809462;13157.276349800853;290725;291681;291838;291940;293040;52414.47585524172;52580;52687.473125269025;52881.56269842448;52935.73532132315;1417.4542801021475;1463.4292625875917;1464.3776395032069;1486.1900264355952;1486.9628259293604;23042.539149217257;24133;24443;24482.387940301636;24723.62914556247;39557.40663889986;39567.604323956395;39567.80216098901;39570;39608.2078358437;304320;305652;305672;305887;306522;25656.358591035372;26124.73875261299;30222.84888575543;33213.833930830195;34729.47905781466;36417.27165456729;36671.266574668894;40122.59877401189;40347;40410.79794601008;30728.539071914387;30868.22829429282;30962.97481327413;31235;31375.21561960969;30591.2352191197;30591.847040765544;30900.381992360475;31697.366052678357;32018.359632806747;35312.29375412529;35351;35374.646253538165;35664.28671426505;35982.28035439224;31179;32163.35673286568;32307;32362.676373235965;32464.51303230406;24831.627525587493;25964.61053084243;26271.737282627695;26372.208833735018;26700.332491687863 04:34:51 INFO - PID 25115 | |2;jquery.html;432.334205323801;446.6449172907538;446.8704075818013;458.3903408466737;461.9561141691539;19456.319028833976;20063.197472101117;20712.48218794531;21688.674669879965;21800.454988625253;21787.346379608596;22144.778552214473;22539.887300563474;22768.43078923028;23001.309960701165;5995.160677505153;6163.938360616381;6218.782342618009;6223.844403889893;6224.470919971785;6066.60468092339;6232.407921247497;6240.6879656017245;6257.124002639634;6272.5922815016875;6101.20684311039;6117.938820611804;6231.283402408713;6247.562670613059;6248.125262463259;6076.088586711984;6179.134921111048;6182.474489668383;6201.844953876144;6205.906911396332;5490.203920431515;5537.446255374471;5589.664620122806;5589.944100558983;5611;25427.36431589225;25455.61816572753;25504.872475637687;25576.23271301864;25592.616110758354;1996.9800301997084;2044.5910817836343;2062.6390381683295;2074.8236399906027;2080.6358887194833;15166.165860877647;15659.843401565953;15721.292541835644;15815.209239537977;16216.837831621651;1864.673682105626;1868.112646492905;1869.7288893110558;1881.397952655151;1914.7606549181353;93802.53098734462;108310.45844770805;108952.45523772409;109590.45204773925;113539.4323028396;12412;12438.502459901683;12597;12721.681957951125;13154.144980576231;17876.106194690212;17897.731534027;17902.91048544775;17924.820751792315;17940;8868.600912958933;9133.634654613741;9192.264618830477;9258.583363748647;9271.072892710676;8456.619452124669;8459.069502354736;8477.74566762998;8636.697715579925;8658.653653853775;7798.415118866108;7967.721129760546;7991.320737737357;8044.477108987897;8168.264856162974;2196.1544805250096;2218.5562887422257;2255.5037891663806;2258.6612008198904;2334.988325058398;971.3346357744967;972.3242346569208;977.2133432586694;979.4466126638426;991.4943378876825;19703.704444433344;19869.205231790856;19984.300549480697;20028.799712002983;20129.899350503154;1006.8540061691014;1013.2350075692952;1020.4183615339185;1032.2155162076876;1044.5038606661926;10277.434741089312;10915.617953371753;10937.945310273397;11010;11057.391843448688;581638;582846;583443;590881;591836;91391.08608913826;93951.53024234835;95376;95474;111731;1185.247367921376;1225.6139316115396;1248.1637303007008;1254.9623511294676;1261.3441010674399;18460;19747.210111595374;20460.18159273672;20496.59006819885;20497.69253461229;48717.75641121913;49332.75333623309;49350.50649493459;49877.50122498729;50667;58334.12498812606;61512.69243653753;68226;68888;70489;9439.292053096182;9547.761305967406;9589.568469418753;9609.759756005877;9740.17208537254;14415;14820.25898705082;14885.702285954003;15248.237588120328;15283.694326113193;7516;11962.521499140285;12196.146269761031;12517.937410313254;12780.744385112059;11495.597654082067;11802.881971180523;11836.46126003614;12153;12279.324637145042;12163.75672486563;13092.672683182996;13095.934520327337;13114.803277951029;13251.536196233086;9013.504257265913;9467.337286389886;9511.524423778921;9851.65519206853;9940.701778946643;11501.884981150082;12365.319907405179;12526.24842509416;12647.92492638136;13205.405756740778 04:34:51 INFO - PID 25115 | |3;mootools.html;1181.704573856536;1328.953486627968;1349.8650134986497;1377.2975782351002;1391.7355702416535;9045.321600879935;10465.215108866836;10858.619948301812;10872.075873550757;10873.293235939658;10642.893571064287;10736.516856741446;10746.892531074687;10754.462276886163;10758.515866785994;1983.186893373717;2060.649689552772;2363.2319496163773;2405.050005247926;2407.7953373963232;2346.5424242272766;2416.214730212679;2423.127674037345;2430.283066495388;2430.4531480416854;2322.500662357593;2332.4985128197436;2332.7783860533223;2333.218371845434;2337;2323.512062466884;2325.755795641467;2334.241371554243;2342.5314937012668;2346.202291220976;2178.5316157026314;2208.569328980849;2213.0262684418803;2217.345882964529;2218.545198234358;10579.62971296001;10742;10793;10794.622188223453;10795.190360722978;6131.47882429994;6324.778632747832;6326.051092336186;6340.334264902165;6351.650659213741;3766.981165094157;3819.1451236403427;3894.532656081288;3962.5244970603712;3966.7818269995137;1356.2066191278113;1390.6592884743202;1394.1286695815115;1416.5750274917484;1450.8259008918892;16265.593360165973;16282.51152465428;16357.672846543002;16375.754363684555;16386.426475073433;5320.52115309624;5488;5513.255710479034;5541.556675465953;5541.672497550958;13256.337183140804;13385.397657105454;13389.255202683622;13394.263315517741;13403.195808251536;6295.213098362705;6315.715792789336;6316.399942005502;6325.620462772248;6331.683415829282;4940.308356830046;5065.898682026339;5656.151577263443;5815.127730840323;5854.912176317359;4320.373545835899;4325.891852703708;4365.236083685342;4397.912041759147;4455.576720211575;1399.3283224052511;1400.6848459096623;1402.382951501336;1430.3134495442039;1438.050886414961;818.6900958466406;828.98342033159;839.7522730794431;843.3801156150224;849.218718778725;5806.709664516758;6027.186329845503;7021;7059.082319298458;7063.046488723957;939.7744541310036;939.9953000235092;944.3531181140941;962.0379620379621;975.2100798353357;3760.7743535387963;4075.2868248057066;4214.515330737005;4351.6301114405;4354.390385345991;14124.717505649829;14607.926960365343;14713.485028023968;14761.188134652697;14769.630759231106;14395.42418303256;14569.708605827824;14574.271286435636;14646.633834154018;14670.33983470725;10048.145907597936;10296.433696146783;10361.896381036093;10373.740656483498;10398.376097434179;6489.675516224264;6545.345465453416;6625.801225963229;6627.569208001464;6675.899861501985;12729.872701272869;12805.167664101804;13007.674808129872;13009.544665936646;13032.54386096482;12998.155119917175;13066.346682666019;13115.934420327838;13177.47290108385;13273;7638.350740186924;7841.451098423284;8626;8826.514541700011;8843.91156088431;9809.313348065567;10187.796244074929;10198.082172604501;10240.027197416233;10253.33353332031;8332.416730828783;8396.202360775716;8463.322934165375;8643.265322447662;8731.257843083155;8292.83414331722;8398.244158025807;8413.95793021031;8428.988521377474;8544.487330760174;7739.613019348897;7878.72424465141;7883.763487095396;7896.565688887166;7897.763067107766;5904.468597826044;5928.614640048383;6006.579539432198;6048.939510605013;6200.596961197508;5820.388859169896;5914.7413862019275;5927.940720592739;6185.536084793568;6197.845053873509 04:34:51 INFO - PID 25115 | |4;prototype.html;400.9799510024498;431.0925501818672;431.72369683402627;432.8961149069871;435.9782010899456;5736.7131643417815;5799.3620701722775;5805.825825225239;5811;5823.5341172706185;5681.33765154794;5778.855528611776;5821;5828.883422331551;5849.473547380735;4587.518310577383;4617.445906491209;4643.767811609423;4648;4649.443175970386;4604.217283061887;4631.6336680679115;4642.837500687477;4660.347551342815;4665.510121437251;4284.18600465912;4614.3309220163055;4616.953830461703;4618.261078227484;4630.583247507724;4456.2201614717615;4459.665525085632;4467.620252278561;4471.1728330259;4486.237339652267;3935.76385416873;3937.1141493164096;3948;3948.940765888514;3962.7027972902147;5489.368722596912;5705.372409035002;5730.570207234432;5741.741621627037;5762.481376676119;5170.560502357304;5188.9881473112755;5209.7134657593815;5210.488958202117;5222.738863056832;6075.635461872302;6078.75684972605;6090.116933044684;6117.919907231518;6131.785387511416;4265.338872474771;4390.407295015196;4406.4271644686305;4421.336799480072;4423.159599676052;6071.848203794941;6301.212348456397;6304.495640348761;6369.267534233576;6484.967575162094;4514.480834703985;4514.525974772601;4519.84180553679;4529.184746745617;4529.886752831206;4578.198815207325;4589.426321709786;4592.150452166293;4602.309653551927;4605.815767369268;4891.217405215149;4936.308916751657;5083.161278389024;5141.948580514222;5248.7900483980975;6077.24034495688;6104.292486525113;6151.0773383992755;6164.445199931938;6168.784092556739;1529.0122840307556;1570.6309017381134;1576.9605759856095;1581.4781122229638;1587.3015873015836;1053.773438710681;1055.7993981143559;1072.265498133781;1081.7782354617284;1089.7740042452242;5824.767009319579;5833.8997960734105;5834;5835.503982161564;5855.448306198776;6227.007357645601;6247.812565623039;6253.4053816277465;6283.96858015707;6306.148669929593;5615.9157612635845;5834.854128646818;5874.676892770854;5876.6180198286975;5877.500412464989;3332.750043746729;3354.865805367807;3359.6640335966695;3366.81157247786;3371.190914180628;6384.425401713868;6562.80311590653;6602;6604.405603495708;6622.735090596322;6501.252355979028;6519.217693876765;6525.64108974011;6526.575772574767;6537.28089910119;6319.936800632118;6474.190726159136;6566.4418524425;6569.441597464173;6570;6323.36766323364;6341.334159913143;6355.52333574993;6358.1734374531015;6361.173047503795;6226.25284965807;6226.968865155645;6277.71750271248;6278.4035516625845;6282.052563705266;6069.635821850703;6131.724072416841;6421.871562568629;6507.349265073455;6520.608763474207;4452.888677783081;4476.768888555674;4482.663800215062;4486.7083639563325;4487.147441986142;6136.23297087864;6274.01984057569;6287.905681414874;6288.748450061947;6334.493240540838;6127.969360153171;6133.417325354084;6162.7534898605345;6172.4136207060255;6174.722137503913;5950.732217050157;6138.508919286535;6149.84625384369;6159.1069294951085;6171.7531298749345;6034.909476357946;6100.359462256577;6101.206843110367;6116.29662588808;6120.785772497941;6118;6158.768246350837;6161.291451483136;6190.380961903954;6197.628142311476;5874.412558744091;5994.220751302477;6011.7595296187665;6024.216851809237;6041.033434650435 04:34:51 INFO - PID 25115 | |5;yui.html;721.6071349029197;726.5422783646309;726.7674344209853;734.313416955147;754.8905408715733;15352.692946141071;15982.520524384257;16043.83956160438;16048.83951160491;16057.437989670363;14527.055741376804;14671.752901003427;14672.26638668065;14726.48457303992;15343.769843452357;795.3915254830059;803.9115697273324;814.6130587970725;814.6863457568849;815.4210510537525;819.4018366592361;819.4837252530926;820.9261166495014;821.4003777242586;821.5317269156583;855.8801767752519;856.1652388920814;856.3577317012241;856.5503110866782;857.3055824781916;827.9213474719892;828.6312590897053;828.8673812190051;828.983420331593;829.8174401631632;613.8680183760513;614.8616561273677;615.3446579392977;615.7721642992077;615.9106929495243;14824.184669843155;14939;15139.848601514064;15375.077495350315;15490.302936367778;12485.313307768069;12675.619731408073;13430.93284533571;13442.327883605878;13513;12504.874951250462;12954.417051232624;12988.675283117904;13059.347032648424;13099.148555343967;4567.474740404863;4573.725576465389;4585.656075794329;4657.4411070671395;4735.431748190239;46280.30579541309;52096.21855672168;52931.735341323045;54620.45379546194;61213.693931530506;11646.24299420535;12220.5946316173;12242.510299588095;12247.632571022883;12309.876901231051;22658.886705566365;22863.657045144337;22929.41411717663;23133.190338338078;23158.42103947415;8306.335493160532;8355.415120941476;8381.916180838236;8550.828983420297;8577.356698247531;4349.325854492558;4382.282533813727;4440;4502.4371953505815;4509.503954564995;4151.169766046803;4254.404383386328;4261.083866968616;4265.936010959838;4268.893277668021;801.3949468151508;807.8263173417744;819.5615345789973;822.7943014246439;835.8829763833069;622.0033691849214;632.3992207402986;656.1535619051466;661.4377778888003;662.6156829039146;10636.46817659114;10770.192235582363;10955.83566246507;10983.231173817916;11000.504977276041;637.9904301435482;664.5613894829389;665.4011389749264;666.167290886392;680.5304340005381;4287.164003019381;4663.358827815603;4683.859484215479;4720.645951553647;4743.0988112258565;76604.61697691475;78657.60671196607;79178;80351.59824200842;81807.59096204481;51260.74369628128;52802.20796688053;55387;66684.33315666878;70999.62560838637;60883.39116608866;60906;61930;61976;63908.360916390244;9331.860022099538;9573.377730447499;9636.807263854544;9686.951565242129;9689.418634881647;33415.66584334125;33583.16042098967;33933.66066339305;34480.65519344775;35490.11274718153;39379;40306;41030.79484602558;41332.58667413287;41717.37423938583;11872.347020913932;11918.463669135082;12118.757624847278;12276.386180690752;12389.75220495603;13160.868391315964;13394.397252123483;13676.452941882213;14086;14610.342534586182;12606.06421694951;13043.739125217633;13063.738725225252;13143.34283285851;13686.45254189861;12322.445489952794;12485.438155283216;12544.749105017667;12659.177153484994;12698.492060317853;12243.816342754688;12285.631431057083;12330;12447.875521244672;12907.225566465666;8132.593370331578;8157.918420815716;8344.707935222237;8486.787830304293;8877.245434137913;10429.58281668755;10619.36283822973;10731.248812583357;10768.353898765785;10771.730706732395 04:34:51 INFO - PID 25115 | __end_tp_report 04:34:51 INFO - PID 25115 | __start_cc_report 04:34:51 INFO - PID 25115 | _x_x_mozilla_cycle_collect,1396 04:34:51 INFO - PID 25115 | __end_cc_report 04:34:51 INFO - PID 25115 | __startTimestamp1490355291022__endTimestamp 04:34:51 INFO - PID 25115 | 04:34:51 INFO - PID 25115 | ------- Summary: start ------- 04:34:51 INFO - PID 25115 | Number of tests: 6 04:34:51 INFO - PID 25115 | 04:34:51 INFO - PID 25115 | [#0] dojo.html Cycles:165 Average:5833.00 Median:6080.27 stddev:680.76 (11.2%) stddev-sans-first:654.34 04:34:51 INFO - PID 25115 | Values: 3347.9 3357.4 3373.9 3379.0 3402.1 5666.0 5727.5 5773.8 5796.9 5820.7 5807.7 5889.3 5893.3 5896.6 5941.6 5119.0 5145.1 5229.4 5230.1 5318.6 5261.2 5278.4 5288.2 5297.6 5304.8 5210.1 5269.5 5272.0 5286.3 5290.7 5261.9 5298.2 5304.9 5370.4 5380.1 5258.1 5266.5 5270.0 5281.0 5351.1 5737.6 5797.2 5865.4 5876.1 5881.9 6251.5 6273.7 6274.0 6274.9 6279.3 6244.5 6260.6 6312.8 6316.7 6411.1 5925.8 5968.5 5993.8 6015.5 6025.0 6501.5 6546.1 6557.4 6558.3 6565.1 6135.8 6142.4 6144.1 6145.2 6283.7 6275.1 6322.2 6340.6 6352.3 6354.5 6319.3 6332.0 6333.2 6337.1 6341.2 6163.4 6333.2 6334.6 6336.5 6357.8 4807.2 4815.0 4870.8 4874.9 4876.7 4261.7 4267.4 4276.7 4278.1 4305.2 5907.1 5907.6 5907.8 5910.8 5915.2 6093.4 6240.6 6254.4 6338.5 6341.0 5900.0 5951.6 5953.9 5957.2 5989.8 5365.0 5390.3 5392.6 5402.6 5404.2 6390.3 6462.1 6511.2 6530.2 6639.6 6276.0 6276.2 6281.3 6284.6 6294.7 6400.4 6487.4 6502.3 6505.3 6506.8 6116.5 6128.3 6131.1 6154.8 6182.8 6371.4 6372.3 6400.0 6406.0 6413.4 6198.8 6204.0 6207.9 6211.0 6222.4 5904.5 5905.5 5941.6 5951.4 5975.8 6080.3 6094.4 6109.9 6138.4 6147.0 6205.3 6228.3 6267.1 6269.1 6270.5 6036.3 6074.2 6099.9 6100.8 6102.4 04:34:51 INFO - PID 25115 | 04:34:51 INFO - PID 25115 | [#1] ext.html Cycles:180 Average:42812.01 Median:32235.18 stddev:72276.94 (224.2%) stddev-sans-first:72410.23 04:34:51 INFO - PID 25115 | Values: 609.6 670.6 685.6 1075.6 1085.8 28976.3 36738.4 37350.4 37370.6 37427.8 42036.6 42731.6 42744.6 42750.6 42779.6 5999.5 6122.8 8144.1 8295.7 8298.4 7786.6 7946.5 7955.5 7957.3 7960.4 6599.3 6603.7 6605.4 6619.9 6714.4 6534.1 6593.5 6727.9 6731.0 6737.8 8076.4 8170.8 8171.3 8180.6 8183.8 40089.0 40191.4 40197.8 40407.8 40411.8 2855.8 2864.0 2869.1 2886.8 2904.1 28624.1 28640.3 28747.3 29015.3 29100.7 3571.4 3597.1 3601.9 3604.5 3724.8 222719.0 222950.0 223376.0 223517.0 223717.0 48696.5 48969.3 49007.0 49010.0 49086.5 52095.5 52140.7 52150.7 52213.7 52254.5 14916.7 15264.5 15287.0 15307.5 15321.7 10518.3 10681.9 10914.1 12296.9 12355.4 13622.9 13636.9 13644.3 13668.9 13732.8 5127.2 5152.6 5201.3 5219.5 5222.9 992.8 994.4 1008.0 1008.7 1015.5 24780.0 24943.9 24999.3 25159.6 25405.0 1125.0 1139.2 1141.3 1152.2 1168.8 12209.8 12930.2 13080.1 13121.6 13157.3 290725.0 291681.0 291838.0 291940.0 293040.0 52414.5 52580.0 52687.5 52881.6 52935.7 1417.5 1463.4 1464.4 1486.2 1487.0 23042.5 24133.0 24443.0 24482.4 24723.6 39557.4 39567.6 39567.8 39570.0 39608.2 304320.0 305652.0 305672.0 305887.0 306522.0 25656.4 26124.7 30222.8 33213.8 34729.5 36417.3 36671.3 40122.6 40347.0 40410.8 30728.5 30868.2 30963.0 31235.0 31375.2 30591.2 30591.8 30900.4 31697.4 32018.4 35312.3 35351.0 35374.6 35664.3 35982.3 31179.0 32163.4 32307.0 32362.7 32464.5 24831.6 25964.6 26271.7 26372.2 26700.3 04:34:51 INFO - PID 25115 | 04:34:51 INFO - PID 25115 | [#2] jquery.html Cycles:180 Average:33887.83 Median:12513.55 stddev:96688.67 (772.7%) stddev-sans-first:96927.28 04:34:51 INFO - PID 25115 | Values: 432.3 446.6 446.9 458.4 462.0 19456.3 20063.2 20712.5 21688.7 21800.5 21787.3 22144.8 22539.9 22768.4 23001.3 5995.2 6163.9 6218.8 6223.8 6224.5 6066.6 6232.4 6240.7 6257.1 6272.6 6101.2 6117.9 6231.3 6247.6 6248.1 6076.1 6179.1 6182.5 6201.8 6205.9 5490.2 5537.4 5589.7 5589.9 5611.0 25427.4 25455.6 25504.9 25576.2 25592.6 1997.0 2044.6 2062.6 2074.8 2080.6 15166.2 15659.8 15721.3 15815.2 16216.8 1864.7 1868.1 1869.7 1881.4 1914.8 93802.5 108310.5 108952.5 109590.5 113539.4 12412.0 12438.5 12597.0 12721.7 13154.1 17876.1 17897.7 17902.9 17924.8 17940.0 8868.6 9133.6 9192.3 9258.6 9271.1 8456.6 8459.1 8477.7 8636.7 8658.7 7798.4 7967.7 7991.3 8044.5 8168.3 2196.2 2218.6 2255.5 2258.7 2335.0 971.3 972.3 977.2 979.4 991.5 19703.7 19869.2 19984.3 20028.8 20129.9 1006.9 1013.2 1020.4 1032.2 1044.5 10277.4 10915.6 10937.9 11010.0 11057.4 581638.0 582846.0 583443.0 590881.0 591836.0 91391.1 93951.5 95376.0 95474.0 111731.0 1185.2 1225.6 1248.2 1255.0 1261.3 18460.0 19747.2 20460.2 20496.6 20497.7 48717.8 49332.8 49350.5 49877.5 50667.0 58334.1 61512.7 68226.0 68888.0 70489.0 9439.3 9547.8 9589.6 9609.8 9740.2 14415.0 14820.3 14885.7 15248.2 15283.7 7516.0 11962.5 12196.1 12517.9 12780.7 11495.6 11802.9 11836.5 12153.0 12279.3 12163.8 13092.7 13095.9 13114.8 13251.5 9013.5 9467.3 9511.5 9851.7 9940.7 11501.9 12365.3 12526.2 12647.9 13205.4 04:34:51 INFO - PID 25115 | 04:34:51 INFO - PID 25115 | [#3] mootools.html Cycles:180 Average:6917.09 Median:2426.71 stddev:4408.78 (181.7%) stddev-sans-first:4400.08 04:34:51 INFO - PID 25115 | Values: 1181.7 1329.0 1349.9 1377.3 1391.7 9045.3 10465.2 10858.6 10872.1 10873.3 10642.9 10736.5 10746.9 10754.5 10758.5 1983.2 2060.6 2363.2 2405.1 2407.8 2346.5 2416.2 2423.1 2430.3 2430.5 2322.5 2332.5 2332.8 2333.2 2337.0 2323.5 2325.8 2334.2 2342.5 2346.2 2178.5 2208.6 2213.0 2217.3 2218.5 10579.6 10742.0 10793.0 10794.6 10795.2 6131.5 6324.8 6326.1 6340.3 6351.7 3767.0 3819.1 3894.5 3962.5 3966.8 1356.2 1390.7 1394.1 1416.6 1450.8 16265.6 16282.5 16357.7 16375.8 16386.4 5320.5 5488.0 5513.3 5541.6 5541.7 13256.3 13385.4 13389.3 13394.3 13403.2 6295.2 6315.7 6316.4 6325.6 6331.7 4940.3 5065.9 5656.2 5815.1 5854.9 4320.4 4325.9 4365.2 4397.9 4455.6 1399.3 1400.7 1402.4 1430.3 1438.1 818.7 829.0 839.8 843.4 849.2 5806.7 6027.2 7021.0 7059.1 7063.0 939.8 940.0 944.4 962.0 975.2 3760.8 4075.3 4214.5 4351.6 4354.4 14124.7 14607.9 14713.5 14761.2 14769.6 14395.4 14569.7 14574.3 14646.6 14670.3 10048.1 10296.4 10361.9 10373.7 10398.4 6489.7 6545.3 6625.8 6627.6 6675.9 12729.9 12805.2 13007.7 13009.5 13032.5 12998.2 13066.3 13115.9 13177.5 13273.0 7638.4 7841.5 8626.0 8826.5 8843.9 9809.3 10187.8 10198.1 10240.0 10253.3 8332.4 8396.2 8463.3 8643.3 8731.3 8292.8 8398.2 8414.0 8429.0 8544.5 7739.6 7878.7 7883.8 7896.6 7897.8 5904.5 5928.6 6006.6 6048.9 6200.6 5820.4 5914.7 5927.9 6185.5 6197.8 04:34:51 INFO - PID 25115 | 04:34:51 INFO - PID 25115 | [#4] prototype.html Cycles:180 Average:5164.41 Median:5831.39 stddev:1510.83 (25.9%) stddev-sans-first:1472.16 04:34:51 INFO - PID 25115 | Values: 401.0 431.1 431.7 432.9 436.0 5736.7 5799.4 5805.8 5811.0 5823.5 5681.3 5778.9 5821.0 5828.9 5849.5 4587.5 4617.4 4643.8 4648.0 4649.4 4604.2 4631.6 4642.8 4660.3 4665.5 4284.2 4614.3 4617.0 4618.3 4630.6 4456.2 4459.7 4467.6 4471.2 4486.2 3935.8 3937.1 3948.0 3948.9 3962.7 5489.4 5705.4 5730.6 5741.7 5762.5 5170.6 5189.0 5209.7 5210.5 5222.7 6075.6 6078.8 6090.1 6117.9 6131.8 4265.3 4390.4 4406.4 4421.3 4423.2 6071.8 6301.2 6304.5 6369.3 6485.0 4514.5 4514.5 4519.8 4529.2 4529.9 4578.2 4589.4 4592.2 4602.3 4605.8 4891.2 4936.3 5083.2 5141.9 5248.8 6077.2 6104.3 6151.1 6164.4 6168.8 1529.0 1570.6 1577.0 1581.5 1587.3 1053.8 1055.8 1072.3 1081.8 1089.8 5824.8 5833.9 5834.0 5835.5 5855.4 6227.0 6247.8 6253.4 6284.0 6306.1 5615.9 5834.9 5874.7 5876.6 5877.5 3332.8 3354.9 3359.7 3366.8 3371.2 6384.4 6562.8 6602.0 6604.4 6622.7 6501.3 6519.2 6525.6 6526.6 6537.3 6319.9 6474.2 6566.4 6569.4 6570.0 6323.4 6341.3 6355.5 6358.2 6361.2 6226.3 6227.0 6277.7 6278.4 6282.1 6069.6 6131.7 6421.9 6507.3 6520.6 4452.9 4476.8 4482.7 4486.7 4487.1 6136.2 6274.0 6287.9 6288.7 6334.5 6128.0 6133.4 6162.8 6172.4 6174.7 5950.7 6138.5 6149.8 6159.1 6171.8 6034.9 6100.4 6101.2 6116.3 6120.8 6118.0 6158.8 6161.3 6190.4 6197.6 5874.4 5994.2 6011.8 6024.2 6041.0 04:34:51 INFO - PID 25115 | 04:34:51 INFO - PID 25115 | [#5] yui.html Cycles:180 Average:15906.60 Median:4538.49 stddev:19354.32 (426.4%) stddev-sans-first:19375.02 04:34:51 INFO - PID 25115 | Values: 721.6 726.5 726.8 734.3 754.9 15352.7 15982.5 16043.8 16048.8 16057.4 14527.1 14671.8 14672.3 14726.5 15343.8 795.4 803.9 814.6 814.7 815.4 819.4 819.5 820.9 821.4 821.5 855.9 856.2 856.4 856.6 857.3 827.9 828.6 828.9 829.0 829.8 613.9 614.9 615.3 615.8 615.9 14824.2 14939.0 15139.8 15375.1 15490.3 12485.3 12675.6 13430.9 13442.3 13513.0 12504.9 12954.4 12988.7 13059.3 13099.1 4567.5 4573.7 4585.7 4657.4 4735.4 46280.3 52096.2 52931.7 54620.5 61213.7 11646.2 12220.6 12242.5 12247.6 12309.9 22658.9 22863.7 22929.4 23133.2 23158.4 8306.3 8355.4 8381.9 8550.8 8577.4 4349.3 4382.3 4440.0 4502.4 4509.5 4151.2 4254.4 4261.1 4265.9 4268.9 801.4 807.8 819.6 822.8 835.9 622.0 632.4 656.2 661.4 662.6 10636.5 10770.2 10955.8 10983.2 11000.5 638.0 664.6 665.4 666.2 680.5 4287.2 4663.4 4683.9 4720.6 4743.1 76604.6 78657.6 79178.0 80351.6 81807.6 51260.7 52802.2 55387.0 66684.3 70999.6 60883.4 60906.0 61930.0 61976.0 63908.4 9331.9 9573.4 9636.8 9687.0 9689.4 33415.7 33583.2 33933.7 34480.7 35490.1 39379.0 40306.0 41030.8 41332.6 41717.4 11872.3 11918.5 12118.8 12276.4 12389.8 13160.9 13394.4 13676.5 14086.0 14610.3 12606.1 13043.7 13063.7 13143.3 13686.5 12322.4 12485.4 12544.7 12659.2 12698.5 12243.8 12285.6 12330.0 12447.9 12907.2 8132.6 8157.9 8344.7 8486.8 8877.2 10429.6 10619.4 10731.2 10768.4 10771.7 04:34:51 INFO - PID 25115 | -------- Summary: end -------- 04:34:51 INFO - PID 25115 | 04:34:51 INFO - TEST-INFO | 25115: exit 0 04:34:51 INFO - TEST-OK | dromaeo_css | took 1117273ms 04:34:51 INFO - TEST-START | kraken 04:34:51 INFO - operating with platform_type : linux_ 04:34:51 INFO - Initialising browser for kraken test... 04:34:51 INFO - TEST-INFO | started process 25443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbyPwjr/profile http://localhost:36251/getInfo.html) 04:34:55 INFO - PID 25443 | __metrics Screen width/height:1600/1200 04:34:55 INFO - PID 25443 | colorDepth:24 04:34:55 INFO - PID 25443 | Browser inner width/height: 1024/697 04:34:55 INFO - PID 25443 | __metrics 04:34:56 INFO - PID 25443 | 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" 04:34:56 INFO - PID 25443 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:34:57 INFO - TEST-INFO | 25443: exit 0 04:34:57 INFO - Browser initialized. 04:34:57 INFO - Running cycle 1/1 for kraken test... 04:34:57 INFO - TEST-INFO | started process 25539 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbyPwjr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:09 INFO - PID 25539 | RSS: Main: 145063936 04:35:09 INFO - PID 25539 | 04:35:12 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36251/tests/kraken/driver.html?testName=audio-beat-detection) 04:35:13 INFO - PID 25539 | RSS: Main: 200077312 04:35:13 INFO - PID 25539 | 04:35:15 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36251/tests/kraken/driver.html?testName=audio-dft) 04:35:16 INFO - PID 25539 | RSS: Main: 179597312 04:35:16 INFO - PID 25539 | 04:35:19 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36251/tests/kraken/driver.html?testName=audio-fft) 04:35:19 INFO - PID 25539 | RSS: Main: 260759552 04:35:19 INFO - PID 25539 | 04:35:22 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36251/tests/kraken/driver.html?testName=audio-oscillator) 04:35:22 INFO - PID 25539 | RSS: Main: 172687360 04:35:22 INFO - PID 25539 | 04:35:24 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:35:25 INFO - PID 25539 | RSS: Main: 176525312 04:35:25 INFO - PID 25539 | 04:35:28 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36251/tests/kraken/driver.html?testName=imaging-darkroom) 04:35:28 INFO - PID 25539 | RSS: Main: 215535616 04:35:28 INFO - PID 25539 | 04:35:32 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36251/tests/kraken/driver.html?testName=imaging-desaturate) 04:35:32 INFO - PID 25539 | RSS: Main: 227487744 04:35:32 INFO - PID 25539 | 04:35:35 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36251/tests/kraken/driver.html?testName=json-parse-financial) 04:35:36 INFO - PID 25539 | RSS: Main: 237727744 04:35:36 INFO - PID 25539 | 04:35:37 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:35:38 INFO - PID 25539 | RSS: Main: 178593792 04:35:38 INFO - PID 25539 | 04:35:40 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36251/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:35:40 INFO - PID 25539 | RSS: Main: 184008704 04:35:40 INFO - PID 25539 | 04:35:42 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:35:43 INFO - PID 25539 | RSS: Main: 193290240 04:35:43 INFO - PID 25539 | 04:35:46 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:35:46 INFO - PID 25539 | RSS: Main: 203096064 04:35:46 INFO - PID 25539 | 04:35:49 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:35:50 INFO - PID 25539 | RSS: Main: 199426048 04:35:50 INFO - PID 25539 | 04:35:51 INFO - PID 25539 | Cycle 1(1): loaded http://localhost:36251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36251/tests/kraken/driver.html?testName=ai-astar) 04:35:52 INFO - PID 25539 | RSS: Main: 268214272 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | __start_tp_report 04:35:52 INFO - PID 25539 | _x_x_mozilla_page_load 04:35:52 INFO - PID 25539 | _x_x_mozilla_page_load_details 04:35:52 INFO - PID 25539 | |i|pagename|runs| 04:35:52 INFO - PID 25539 | |0;ai-astar;103;109;109;108;109;110;109;109;109;110 04:35:52 INFO - PID 25539 | |1;audio-beat-detection;150;122;115;148;120;117;117;120;138;117 04:35:52 INFO - PID 25539 | |2;audio-dft;160;161;163;160;162;162;161;162;162;162 04:35:52 INFO - PID 25539 | |3;audio-fft;75;74;76;111;75;77;75;76;94;74 04:35:52 INFO - PID 25539 | |4;audio-oscillator;81;82;103;82;82;83;97;83;83;99 04:35:52 INFO - PID 25539 | |5;imaging-gaussian-blur;113;113;113;112;113;113;113;113;113;113 04:35:52 INFO - PID 25539 | |6;imaging-darkroom;148;148;147;147;147;147;147;147;148;146 04:35:52 INFO - PID 25539 | |7;imaging-desaturate;94;94;94;93;93;94;94;93;94;93 04:35:52 INFO - PID 25539 | |8;json-parse-financial;57;56;57;57;57;57;67;57;57;59 04:35:52 INFO - PID 25539 | |9;json-stringify-tinderbox;55;54;55;54;55;54;55;55;55;54 04:35:52 INFO - PID 25539 | |10;stanford-crypto-aes;79;79;79;80;85;80;79;80;78;80 04:35:52 INFO - PID 25539 | |11;stanford-crypto-ccm;136;137;124;133;128;125;125;126;177;128 04:35:52 INFO - PID 25539 | |12;stanford-crypto-pbkdf2;188;177;180;183;179;178;179;182;176;180 04:35:52 INFO - PID 25539 | |13;stanford-crypto-sha256-iterative;71;64;64;63;62;65;67;64;63;66 04:35:52 INFO - PID 25539 | __end_tp_report 04:35:52 INFO - PID 25539 | __start_cc_report 04:35:52 INFO - PID 25539 | _x_x_mozilla_cycle_collect,1759 04:35:52 INFO - PID 25539 | __end_cc_report 04:35:52 INFO - PID 25539 | __startTimestamp1490355352238__endTimestamp 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | ------- Summary: start ------- 04:35:52 INFO - PID 25539 | Number of tests: 14 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.00 stddev:2.01 (1.8%) stddev-sans-first:0.60 04:35:52 INFO - PID 25539 | Values: 103.0 109.0 109.0 108.0 109.0 110.0 109.0 109.0 109.0 110.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:121.00 stddev:13.56 (11.2%) stddev-sans-first:11.38 04:35:52 INFO - PID 25539 | Values: 150.0 122.0 115.0 148.0 120.0 117.0 117.0 120.0 138.0 117.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:0.87 04:35:52 INFO - PID 25539 | Values: 160.0 161.0 163.0 160.0 162.0 162.0 161.0 162.0 162.0 162.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#3] audio-fft Cycles:10 Average:80.70 Median:75.50 stddev:12.20 (16.2%) stddev-sans-first:12.77 04:35:52 INFO - PID 25539 | Values: 75.0 74.0 76.0 111.0 75.0 77.0 75.0 76.0 94.0 74.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#4] audio-oscillator Cycles:10 Average:87.50 Median:83.00 stddev:8.54 (10.3%) stddev-sans-first:8.73 04:35:52 INFO - PID 25539 | Values: 81.0 82.0 103.0 82.0 82.0 83.0 97.0 83.0 83.0 99.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:35:52 INFO - PID 25539 | Values: 113.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 04:35:52 INFO - PID 25539 | Values: 148.0 148.0 147.0 147.0 147.0 147.0 147.0 147.0 148.0 146.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 04:35:52 INFO - PID 25539 | Values: 94.0 94.0 94.0 93.0 93.0 94.0 94.0 93.0 94.0 93.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:57.00 stddev:3.21 (5.6%) stddev-sans-first:3.38 04:35:52 INFO - PID 25539 | Values: 57.0 56.0 57.0 57.0 57.0 57.0 67.0 57.0 57.0 59.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#9] json-stringify-tinderbox Cycles:10 Average:54.60 Median:55.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 04:35:52 INFO - PID 25539 | Values: 55.0 54.0 55.0 54.0 55.0 54.0 55.0 55.0 55.0 54.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.00 stddev:1.91 (2.4%) stddev-sans-first:2.00 04:35:52 INFO - PID 25539 | Values: 79.0 79.0 79.0 80.0 85.0 80.0 79.0 80.0 78.0 80.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#11] stanford-crypto-ccm Cycles:10 Average:133.90 Median:130.50 stddev:15.85 (12.1%) stddev-sans-first:16.79 04:35:52 INFO - PID 25539 | Values: 136.0 137.0 124.0 133.0 128.0 125.0 125.0 126.0 177.0 128.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.20 Median:180.00 stddev:3.46 (1.9%) stddev-sans-first:2.24 04:35:52 INFO - PID 25539 | Values: 188.0 177.0 180.0 183.0 179.0 178.0 179.0 182.0 176.0 180.0 04:35:52 INFO - PID 25539 | 04:35:52 INFO - PID 25539 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:64.50 stddev:2.60 (4.0%) stddev-sans-first:1.56 04:35:52 INFO - PID 25539 | Values: 71.0 64.0 64.0 63.0 62.0 65.0 67.0 64.0 63.0 66.0 04:35:52 INFO - PID 25539 | -------- Summary: end -------- 04:35:52 INFO - PID 25539 | 04:35:53 INFO - TEST-INFO | 25539: exit 0 04:35:53 INFO - TEST-OK | kraken | took 61447ms 04:35:53 INFO - SUITE-END | took 1178s 04:35:53 INFO - Completed test suite (00:19:38) 04:35:53 INFO - javascript benchmark 04:35:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7477.08640789081, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3347.882824101157, 3357.3956687796194, 3373.881914133006, 3379.0, 3402.098443912362, 5666.0, 5727.541796656263, 5773.797917072898, 5796.942030579693, 5820.650760954346, 5807.651540907548, 5889.293284805819, 5893.322267939188, 5896.646201227929, 5941.613795103315, 5119.0, 5145.073886700402, 5229.424763276036, 5230.058589453897, 5318.574514038867, 5261.184516399964, 5278.392984806739, 5288.15389537674, 5297.629165958365, 5304.814331498398, 5210.140326846066, 5269.473052694719, 5271.973640131775, 5286.3127793387, 5290.735463226823, 5261.86845328866, 5298.15229563268, 5304.867378315535, 5370.409254981949, 5380.139177731583, 5258.0535503609335, 5266.526012658841, 5269.973650131763, 5281.049411106, 5351.09031464652, 5737.569682273805, 5797.246357973479, 5865.442782935615, 5876.11858221266, 5881.85295367615, 6251.499880009588, 6273.686315684243, 6273.968630156865, 6274.874502509924, 6279.340669229757, 6244.469220116295, 6260.624362538262, 6312.810615681537, 6316.74733010679, 6411.070394792776, 5925.792597259161, 5968.522518198533, 5993.820185394445, 6015.54883383748, 6025.0058740307795, 6501.512386571026, 6546.149000629887, 6557.377049180321, 6558.344165583403, 6565.146530950946, 6135.754569817157, 6142.406692085091, 6144.109104179869, 6145.20112385396, 6283.654399008007, 6275.058741188767, 6322.209723784527, 6340.587861788969, 6352.333005034498, 6354.459870910932, 6319.33646967071, 6332.0, 6333.240011198685, 6337.112804207414, 6341.207349081365, 6163.3836616338, 6333.20834895638, 6334.619922804646, 6336.52476064297, 6357.841053973688, 4807.206810876236, 4815.012922350909, 4870.780814863339, 4874.878128046827, 4876.683015603974, 4261.701680882308, 4267.4238977737605, 4276.657867370665, 4278.058827058042, 4305.203537345601, 5907.1139329100115, 5907.556933230025, 5907.822765317047, 5910.793122240787, 5915.231019967463, 6093.413838573174, 6240.563160578716, 6254.437100660963, 6338.461230795342, 6341.0, 5899.997000509924, 5951.613145145551, 5953.851153721192, 5957.195778569924, 5989.760409583568, 5364.973175134178, 5390.272313237662, 5392.56859451243, 5402.594805389612, 5404.189371594213, 6390.265119511316, 6462.127612772216, 6511.186101737283, 6530.216374034956, 6639.634820084942, 6275.96862015687, 6276.246850377984, 6281.309056003927, 6284.622922624657, 6294.653794041188, 6400.423961843457, 6487.351264873475, 6502.349765023459, 6505.251896032017, 6506.804795856132, 6116.5106791457465, 6128.258691089361, 6131.13200488804, 6154.815355539341, 6182.845428864314, 6371.39471750183, 6372.267189273108, 6400.0, 6405.967970160119, 6413.422791948747, 6198.81403557894, 6204.0, 6207.937920620736, 6210.975189093749, 6222.377762223741, 5904.498117660047, 5905.4685078343155, 5941.554383421174, 5951.434613711691, 5975.790847320323, 6080.270367555921, 6094.421030002142, 6109.876340637589, 6138.357744607992, 6147.0, 6205.286392064971, 6228.314885362697, 6267.091271765659, 6269.12232287471, 6270.529710271839, 6036.336003039574, 6074.179985701874, 6099.908501372394, 6100.786472473442, 6102.389761023862], "value": 5785.829540996245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.5885277437728, 670.6009924095163, 685.5852209413304, 1075.5643964194503, 1085.7991271614746, 28976.275593110182, 36738.448923266136, 37350.439743403804, 37370.626293737056, 37427.81286093572, 42036.57963420365, 42731.572684273146, 42744.57255427445, 42750.572494275126, 42779.572204277945, 5999.490043346299, 6122.816315510542, 8144.10414854367, 8295.709650162245, 8298.419110662257, 7786.571738554406, 7946.523208607473, 7955.4828936118965, 7957.32362749167, 7960.442769006172, 6599.307072757363, 6603.669816509156, 6605.405513503783, 6619.900701489458, 6714.362135597111, 6534.085228068075, 6593.505487088488, 6727.899081513781, 6730.966345168243, 6737.831554211135, 8076.353891688651, 8170.754877353689, 8171.305439037689, 8180.6318715657935, 8183.754487365389, 40089.0, 40191.397129043085, 40197.79901100505, 40407.7979610103, 40411.7979410104, 2855.8000939934213, 2863.957040644392, 2869.0675530452477, 2886.826790392583, 2904.114245155227, 28624.14127576176, 28640.283992900135, 28747.28131796722, 29015.274618134714, 29100.70899291001, 3571.4107172316794, 3597.1366871950804, 3601.873934412309, 3604.4593311003296, 3724.832382542792, 222719.0, 222949.99999999837, 223376.0, 223517.0, 223717.0, 48696.51303486991, 48969.2654610174, 49007.0, 49010.0, 49086.509134908905, 52095.47904520906, 52140.739296303276, 52150.73924630353, 52213.7389313051, 52254.47745522572, 14916.70166596662, 15264.465743698916, 15287.0, 15307.46423875159, 15321.693566128615, 10518.316309440015, 10681.946590266998, 10914.126869850394, 12296.938515307365, 12355.444005019795, 13622.93188534051, 13636.86363136356, 13644.317784110954, 13668.863311366758, 13732.794008089886, 5127.2052831811125, 5152.587792976553, 5201.323827902348, 5219.478052194826, 5222.929299493595, 992.7617371830853, 994.3834822410151, 1007.9848802267971, 1008.6570566007592, 1015.5176291261591, 24780.0, 24943.875280623844, 24999.250022499353, 25159.62260566093, 25405.0, 1125.0, 1139.157023802397, 1141.2652767792908, 1152.153167421944, 1168.8013037783596, 12209.755804883853, 12930.224186548838, 13080.149790263602, 13121.606351809462, 13157.276349800853, 290725.0, 291681.0, 291838.0, 291940.0, 293040.0, 52414.47585524172, 52580.0, 52687.473125269025, 52881.56269842448, 52935.73532132315, 1417.4542801021475, 1463.4292625875917, 1464.3776395032069, 1486.1900264355952, 1486.9628259293604, 23042.539149217257, 24133.0, 24443.0, 24482.387940301636, 24723.62914556247, 39557.40663889986, 39567.604323956395, 39567.80216098901, 39570.0, 39608.2078358437, 304320.0, 305652.0, 305672.0, 305887.0, 306522.0, 25656.358591035372, 26124.73875261299, 30222.84888575543, 33213.833930830195, 34729.47905781466, 36417.27165456729, 36671.266574668894, 40122.59877401189, 40347.0, 40410.79794601008, 30728.539071914387, 30868.22829429282, 30962.97481327413, 31235.0, 31375.21561960969, 30591.2352191197, 30591.847040765544, 30900.381992360475, 31697.366052678357, 32018.359632806747, 35312.29375412529, 35351.0, 35374.646253538165, 35664.28671426505, 35982.28035439224, 31179.0, 32163.35673286568, 32307.0, 32362.676373235965, 32464.51303230406, 24831.627525587493, 25964.61053084243, 26271.737282627695, 26372.208833735018, 26700.332491687863], "value": 16960.729104251073, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.334205323801, 446.6449172907538, 446.8704075818013, 458.3903408466737, 461.9561141691539, 19456.319028833976, 20063.197472101117, 20712.48218794531, 21688.674669879965, 21800.454988625253, 21787.346379608596, 22144.778552214473, 22539.887300563474, 22768.43078923028, 23001.309960701165, 5995.160677505153, 6163.938360616381, 6218.782342618009, 6223.844403889893, 6224.470919971785, 6066.60468092339, 6232.407921247497, 6240.6879656017245, 6257.124002639634, 6272.5922815016875, 6101.20684311039, 6117.938820611804, 6231.283402408713, 6247.562670613059, 6248.125262463259, 6076.088586711984, 6179.134921111048, 6182.474489668383, 6201.844953876144, 6205.906911396332, 5490.203920431515, 5537.446255374471, 5589.664620122806, 5589.944100558983, 5611.0, 25427.36431589225, 25455.61816572753, 25504.872475637687, 25576.23271301864, 25592.616110758354, 1996.9800301997084, 2044.5910817836343, 2062.6390381683295, 2074.8236399906027, 2080.6358887194833, 15166.165860877647, 15659.843401565953, 15721.292541835644, 15815.209239537977, 16216.837831621651, 1864.673682105626, 1868.112646492905, 1869.7288893110558, 1881.397952655151, 1914.7606549181353, 93802.53098734462, 108310.45844770805, 108952.45523772409, 109590.45204773925, 113539.4323028396, 12412.0, 12438.502459901683, 12597.0, 12721.681957951125, 13154.144980576231, 17876.106194690212, 17897.731534027, 17902.91048544775, 17924.820751792315, 17940.0, 8868.600912958933, 9133.634654613741, 9192.264618830477, 9258.583363748647, 9271.072892710676, 8456.619452124669, 8459.069502354736, 8477.74566762998, 8636.697715579925, 8658.653653853775, 7798.415118866108, 7967.721129760546, 7991.320737737357, 8044.477108987897, 8168.264856162974, 2196.1544805250096, 2218.5562887422257, 2255.5037891663806, 2258.6612008198904, 2334.988325058398, 971.3346357744967, 972.3242346569208, 977.2133432586694, 979.4466126638426, 991.4943378876825, 19703.704444433344, 19869.205231790856, 19984.300549480697, 20028.799712002983, 20129.899350503154, 1006.8540061691014, 1013.2350075692952, 1020.4183615339185, 1032.2155162076876, 1044.5038606661926, 10277.434741089312, 10915.617953371753, 10937.945310273397, 11010.0, 11057.391843448688, 581638.0, 582846.0, 583443.0, 590881.0, 591836.0, 91391.08608913826, 93951.53024234835, 95376.0, 95474.0, 111731.0, 1185.247367921376, 1225.6139316115396, 1248.1637303007008, 1254.9623511294676, 1261.3441010674399, 18460.0, 19747.210111595374, 20460.18159273672, 20496.59006819885, 20497.69253461229, 48717.75641121913, 49332.75333623309, 49350.50649493459, 49877.50122498729, 50667.0, 58334.12498812606, 61512.69243653753, 68226.0, 68888.0, 70489.0, 9439.292053096182, 9547.761305967406, 9589.568469418753, 9609.759756005877, 9740.17208537254, 14415.0, 14820.25898705082, 14885.702285954003, 15248.237588120328, 15283.694326113193, 7516.0, 11962.521499140285, 12196.146269761031, 12517.937410313254, 12780.744385112059, 11495.597654082067, 11802.881971180523, 11836.46126003614, 12153.0, 12279.324637145042, 12163.75672486563, 13092.672683182996, 13095.934520327337, 13114.803277951029, 13251.536196233086, 9013.504257265913, 9467.337286389886, 9511.524423778921, 9851.65519206853, 9940.701778946643, 11501.884981150082, 12365.319907405179, 12526.24842509416, 12647.92492638136, 13205.405756740778], "value": 10276.6364901399, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1181.704573856536, 1328.953486627968, 1349.8650134986497, 1377.2975782351002, 1391.7355702416535, 9045.321600879935, 10465.215108866836, 10858.619948301812, 10872.075873550757, 10873.293235939658, 10642.893571064287, 10736.516856741446, 10746.892531074687, 10754.462276886163, 10758.515866785994, 1983.186893373717, 2060.649689552772, 2363.2319496163773, 2405.050005247926, 2407.7953373963232, 2346.5424242272766, 2416.214730212679, 2423.127674037345, 2430.283066495388, 2430.4531480416854, 2322.500662357593, 2332.4985128197436, 2332.7783860533223, 2333.218371845434, 2337.0, 2323.512062466884, 2325.755795641467, 2334.241371554243, 2342.5314937012668, 2346.202291220976, 2178.5316157026314, 2208.569328980849, 2213.0262684418803, 2217.345882964529, 2218.545198234358, 10579.62971296001, 10742.0, 10793.0, 10794.622188223453, 10795.190360722978, 6131.47882429994, 6324.778632747832, 6326.051092336186, 6340.334264902165, 6351.650659213741, 3766.981165094157, 3819.1451236403427, 3894.532656081288, 3962.5244970603712, 3966.7818269995137, 1356.2066191278113, 1390.6592884743202, 1394.1286695815115, 1416.5750274917484, 1450.8259008918892, 16265.593360165973, 16282.51152465428, 16357.672846543002, 16375.754363684555, 16386.426475073433, 5320.52115309624, 5488.0, 5513.255710479034, 5541.556675465953, 5541.672497550958, 13256.337183140804, 13385.397657105454, 13389.255202683622, 13394.263315517741, 13403.195808251536, 6295.213098362705, 6315.715792789336, 6316.399942005502, 6325.620462772248, 6331.683415829282, 4940.308356830046, 5065.898682026339, 5656.151577263443, 5815.127730840323, 5854.912176317359, 4320.373545835899, 4325.891852703708, 4365.236083685342, 4397.912041759147, 4455.576720211575, 1399.3283224052511, 1400.6848459096623, 1402.382951501336, 1430.3134495442039, 1438.050886414961, 818.6900958466406, 828.98342033159, 839.7522730794431, 843.3801156150224, 849.218718778725, 5806.709664516758, 6027.186329845503, 7021.0, 7059.082319298458, 7063.046488723957, 939.7744541310036, 939.9953000235092, 944.3531181140941, 962.0379620379621, 975.2100798353357, 3760.7743535387963, 4075.2868248057066, 4214.515330737005, 4351.6301114405, 4354.390385345991, 14124.717505649829, 14607.926960365343, 14713.485028023968, 14761.188134652697, 14769.630759231106, 14395.42418303256, 14569.708605827824, 14574.271286435636, 14646.633834154018, 14670.33983470725, 10048.145907597936, 10296.433696146783, 10361.896381036093, 10373.740656483498, 10398.376097434179, 6489.675516224264, 6545.345465453416, 6625.801225963229, 6627.569208001464, 6675.899861501985, 12729.872701272869, 12805.167664101804, 13007.674808129872, 13009.544665936646, 13032.54386096482, 12998.155119917175, 13066.346682666019, 13115.934420327838, 13177.47290108385, 13273.0, 7638.350740186924, 7841.451098423284, 8626.0, 8826.514541700011, 8843.91156088431, 9809.313348065567, 10187.796244074929, 10198.082172604501, 10240.027197416233, 10253.33353332031, 8332.416730828783, 8396.202360775716, 8463.322934165375, 8643.265322447662, 8731.257843083155, 8292.83414331722, 8398.244158025807, 8413.95793021031, 8428.988521377474, 8544.487330760174, 7739.613019348897, 7878.72424465141, 7883.763487095396, 7896.565688887166, 7897.763067107766, 5904.468597826044, 5928.614640048383, 6006.579539432198, 6048.939510605013, 6200.596961197508, 5820.388859169896, 5914.7413862019275, 5927.940720592739, 6185.536084793568, 6197.845053873509], "value": 5248.750128851978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.9799510024498, 431.0925501818672, 431.72369683402627, 432.8961149069871, 435.9782010899456, 5736.7131643417815, 5799.3620701722775, 5805.825825225239, 5811.0, 5823.5341172706185, 5681.33765154794, 5778.855528611776, 5821.0, 5828.883422331551, 5849.473547380735, 4587.518310577383, 4617.445906491209, 4643.767811609423, 4648.0, 4649.443175970386, 4604.217283061887, 4631.6336680679115, 4642.837500687477, 4660.347551342815, 4665.510121437251, 4284.18600465912, 4614.3309220163055, 4616.953830461703, 4618.261078227484, 4630.583247507724, 4456.2201614717615, 4459.665525085632, 4467.620252278561, 4471.1728330259, 4486.237339652267, 3935.76385416873, 3937.1141493164096, 3948.0, 3948.940765888514, 3962.7027972902147, 5489.368722596912, 5705.372409035002, 5730.570207234432, 5741.741621627037, 5762.481376676119, 5170.560502357304, 5188.9881473112755, 5209.7134657593815, 5210.488958202117, 5222.738863056832, 6075.635461872302, 6078.75684972605, 6090.116933044684, 6117.919907231518, 6131.785387511416, 4265.338872474771, 4390.407295015196, 4406.4271644686305, 4421.336799480072, 4423.159599676052, 6071.848203794941, 6301.212348456397, 6304.495640348761, 6369.267534233576, 6484.967575162094, 4514.480834703985, 4514.525974772601, 4519.84180553679, 4529.184746745617, 4529.886752831206, 4578.198815207325, 4589.426321709786, 4592.150452166293, 4602.309653551927, 4605.815767369268, 4891.217405215149, 4936.308916751657, 5083.161278389024, 5141.948580514222, 5248.7900483980975, 6077.24034495688, 6104.292486525113, 6151.0773383992755, 6164.445199931938, 6168.784092556739, 1529.0122840307556, 1570.6309017381134, 1576.9605759856095, 1581.4781122229638, 1587.3015873015836, 1053.773438710681, 1055.7993981143559, 1072.265498133781, 1081.7782354617284, 1089.7740042452242, 5824.767009319579, 5833.8997960734105, 5834.0, 5835.503982161564, 5855.448306198776, 6227.007357645601, 6247.812565623039, 6253.4053816277465, 6283.96858015707, 6306.148669929593, 5615.9157612635845, 5834.854128646818, 5874.676892770854, 5876.6180198286975, 5877.500412464989, 3332.750043746729, 3354.865805367807, 3359.6640335966695, 3366.81157247786, 3371.190914180628, 6384.425401713868, 6562.80311590653, 6602.0, 6604.405603495708, 6622.735090596322, 6501.252355979028, 6519.217693876765, 6525.64108974011, 6526.575772574767, 6537.28089910119, 6319.936800632118, 6474.190726159136, 6566.4418524425, 6569.441597464173, 6570.0, 6323.36766323364, 6341.334159913143, 6355.52333574993, 6358.1734374531015, 6361.173047503795, 6226.25284965807, 6226.968865155645, 6277.71750271248, 6278.4035516625845, 6282.052563705266, 6069.635821850703, 6131.724072416841, 6421.871562568629, 6507.349265073455, 6520.608763474207, 4452.888677783081, 4476.768888555674, 4482.663800215062, 4486.7083639563325, 4487.147441986142, 6136.23297087864, 6274.01984057569, 6287.905681414874, 6288.748450061947, 6334.493240540838, 6127.969360153171, 6133.417325354084, 6162.7534898605345, 6172.4136207060255, 6174.722137503913, 5950.732217050157, 6138.508919286535, 6149.84625384369, 6159.1069294951085, 6171.7531298749345, 6034.909476357946, 6100.359462256577, 6101.206843110367, 6116.29662588808, 6120.785772497941, 6118.0, 6158.768246350837, 6161.291451483136, 6190.380961903954, 6197.628142311476, 5874.412558744091, 5994.220751302477, 6011.7595296187665, 6024.216851809237, 6041.033434650435], "value": 4701.141652234215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.6071349029197, 726.5422783646309, 726.7674344209853, 734.313416955147, 754.8905408715733, 15352.692946141071, 15982.520524384257, 16043.83956160438, 16048.83951160491, 16057.437989670363, 14527.055741376804, 14671.752901003427, 14672.26638668065, 14726.48457303992, 15343.769843452357, 795.3915254830059, 803.9115697273324, 814.6130587970725, 814.6863457568849, 815.4210510537525, 819.4018366592361, 819.4837252530926, 820.9261166495014, 821.4003777242586, 821.5317269156583, 855.8801767752519, 856.1652388920814, 856.3577317012241, 856.5503110866782, 857.3055824781916, 827.9213474719892, 828.6312590897053, 828.8673812190051, 828.983420331593, 829.8174401631632, 613.8680183760513, 614.8616561273677, 615.3446579392977, 615.7721642992077, 615.9106929495243, 14824.184669843155, 14939.0, 15139.848601514064, 15375.077495350315, 15490.302936367778, 12485.313307768069, 12675.619731408073, 13430.93284533571, 13442.327883605878, 13513.0, 12504.874951250462, 12954.417051232624, 12988.675283117904, 13059.347032648424, 13099.148555343967, 4567.474740404863, 4573.725576465389, 4585.656075794329, 4657.4411070671395, 4735.431748190239, 46280.30579541309, 52096.21855672168, 52931.735341323045, 54620.45379546194, 61213.693931530506, 11646.24299420535, 12220.5946316173, 12242.510299588095, 12247.632571022883, 12309.876901231051, 22658.886705566365, 22863.657045144337, 22929.41411717663, 23133.190338338078, 23158.42103947415, 8306.335493160532, 8355.415120941476, 8381.916180838236, 8550.828983420297, 8577.356698247531, 4349.325854492558, 4382.282533813727, 4440.0, 4502.4371953505815, 4509.503954564995, 4151.169766046803, 4254.404383386328, 4261.083866968616, 4265.936010959838, 4268.893277668021, 801.3949468151508, 807.8263173417744, 819.5615345789973, 822.7943014246439, 835.8829763833069, 622.0033691849214, 632.3992207402986, 656.1535619051466, 661.4377778888003, 662.6156829039146, 10636.46817659114, 10770.192235582363, 10955.83566246507, 10983.231173817916, 11000.504977276041, 637.9904301435482, 664.5613894829389, 665.4011389749264, 666.167290886392, 680.5304340005381, 4287.164003019381, 4663.358827815603, 4683.859484215479, 4720.645951553647, 4743.0988112258565, 76604.61697691475, 78657.60671196607, 79178.0, 80351.59824200842, 81807.59096204481, 51260.74369628128, 52802.20796688053, 55387.0, 66684.33315666878, 70999.62560838637, 60883.39116608866, 60906.0, 61930.0, 61976.0, 63908.360916390244, 9331.860022099538, 9573.377730447499, 9636.807263854544, 9686.951565242129, 9689.418634881647, 33415.66584334125, 33583.16042098967, 33933.66066339305, 34480.65519344775, 35490.11274718153, 39379.0, 40306.0, 41030.79484602558, 41332.58667413287, 41717.37423938583, 11872.347020913932, 11918.463669135082, 12118.757624847278, 12276.386180690752, 12389.75220495603, 13160.868391315964, 13394.397252123483, 13676.452941882213, 14086.0, 14610.342534586182, 12606.06421694951, 13043.739125217633, 13063.738725225252, 13143.34283285851, 13686.45254189861, 12322.445489952794, 12485.438155283216, 12544.749105017667, 12659.177153484994, 12698.492060317853, 12243.816342754688, 12285.631431057083, 12330.0, 12447.875521244672, 12907.225566465666, 8132.593370331578, 8157.918420815716, 8344.707935222237, 8486.787830304293, 8877.245434137913, 10429.58281668755, 10619.36283822973, 10731.248812583357, 10768.353898765785, 10771.730706732395], "value": 7021.705678860361, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 109.0, 109.0, 108.0, 109.0, 110.0, 109.0, 109.0, 109.0, 110.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 122.0, 115.0, 148.0, 120.0, 117.0, 117.0, 120.0, 138.0, 117.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 160.0, 162.0, 162.0, 161.0, 162.0, 162.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 111.0, 75.0, 77.0, 75.0, 76.0, 94.0, 74.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 103.0, 82.0, 82.0, 83.0, 97.0, 83.0, 83.0, 99.0], "value": 87.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 57.0, 57.0, 57.0, 67.0, 57.0, 57.0, 59.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 55.0, 54.0, 55.0, 55.0, 55.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 80.0, 85.0, 80.0, 79.0, 80.0, 78.0, 80.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 124.0, 133.0, 128.0, 125.0, 125.0, 126.0, 177.0, 128.0], "value": 133.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 177.0, 180.0, 183.0, 179.0, 178.0, 179.0, 182.0, 176.0, 180.0], "value": 180.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 64.0, 64.0, 63.0, 62.0, 65.0, 67.0, 64.0, 63.0, 66.0], "value": 64.9, "unit": "score"}]}]} 04:35:53 INFO - Return code: 0 04:35:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:35:53 ERROR - # TBPL SUCCESS # 04:35:53 INFO - Running post-action listener: _resource_record_post_action 04:35:53 INFO - [mozharness: 2017-03-24 11:35:53.562560Z] Finished run-tests step (success) 04:35:53 INFO - Running post-run listener: _resource_record_post_run 04:35:53 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 369876992; Read time: 0; Write time: 214304 04:35:53 INFO - TinderboxPrint: CPU usage
12.9% 04:35:53 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:35:53 INFO - TinderboxPrint: I/O write bytes / time
369,876,992 / 214,304 04:35:53 INFO - TinderboxPrint: CPU idle
8,312.6 (87.1%) 04:35:53 INFO - TinderboxPrint: CPU user
1,186.9 (12.4%) 04:35:53 INFO - TinderboxPrint: Swap in / out
0 / 0 04:35:53 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112836608; Read time: 0; Write time: 76904 04:35:53 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 255963136; Read time: 0; Write time: 137360 04:35:54 INFO - Running post-run listener: _upload_blobber_files 04:35:54 INFO - Blob upload gear active. 04:35:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:35:54 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 04:35:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:35:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:35:54 INFO - (blobuploader) - INFO - Open directory for files ... 04:35:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:35:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:35:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:35:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:35:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:35:55 INFO - (blobuploader) - INFO - Done attempting. 04:35:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:35:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:35:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:35:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:35:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:35:56 INFO - (blobuploader) - INFO - Done attempting. 04:35:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:35:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:35:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:35:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:35:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:35:57 INFO - (blobuploader) - INFO - Done attempting. 04:35:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:35:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:35:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:36:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:00 INFO - (blobuploader) - INFO - Done attempting. 04:36:00 INFO - (blobuploader) - INFO - Iteration through files over. 04:36:00 INFO - Return code: 0 04:36:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:36:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:36:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b0e2229110d2050c3eecb26d5b08ad2edb5dee3da8d86e480c98be6a32a380d3463caa514005ca654ff49e23a75443c0b31b84ba50069192a2b9e641c876b86", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d330a18127a1851f8858381be9b6d8835fb261788112f7d6076e8f0e1b36a399c60624dcdc60a3cd03d6eb6ff933472d14a8e686eb44ad3b57e57d0d0481affe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/435b3aaef9492ef8a3bb6871143fd02973e18dc21cc6c30ee0cc3be536486e465b170fb1274298c628c4413b75f28aa612957d77103db9956fb9ce996e7ab810", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561c9880855e6e430d8056743fdac65e6624ae390a37d5e31ac78271a4e8c92bdb98c0ba8b0b1a8e3cc507a146a157b9c185bd5e37bee193709f1562c1fdb05e"} 04:36:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:36:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:36:00 INFO - Contents: 04:36:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b0e2229110d2050c3eecb26d5b08ad2edb5dee3da8d86e480c98be6a32a380d3463caa514005ca654ff49e23a75443c0b31b84ba50069192a2b9e641c876b86", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d330a18127a1851f8858381be9b6d8835fb261788112f7d6076e8f0e1b36a399c60624dcdc60a3cd03d6eb6ff933472d14a8e686eb44ad3b57e57d0d0481affe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/435b3aaef9492ef8a3bb6871143fd02973e18dc21cc6c30ee0cc3be536486e465b170fb1274298c628c4413b75f28aa612957d77103db9956fb9ce996e7ab810", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561c9880855e6e430d8056743fdac65e6624ae390a37d5e31ac78271a4e8c92bdb98c0ba8b0b1a8e3cc507a146a157b9c185bd5e37bee193709f1562c1fdb05e"} 04:36:00 INFO - Running post-run listener: copy_logs_to_upload_dir 04:36:00 INFO - Copying logs to upload dir... 04:36:00 INFO - mkdir: /builds/slave/test/build/upload/logs 04:36:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.478211 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-03-24 04:35:59.899047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 04:35:59.902948) ========= 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=7de08804244eb36560b44971000001c3-1490354100.757830-2095725649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b0e2229110d2050c3eecb26d5b08ad2edb5dee3da8d86e480c98be6a32a380d3463caa514005ca654ff49e23a75443c0b31b84ba50069192a2b9e641c876b86", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d330a18127a1851f8858381be9b6d8835fb261788112f7d6076e8f0e1b36a399c60624dcdc60a3cd03d6eb6ff933472d14a8e686eb44ad3b57e57d0d0481affe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/435b3aaef9492ef8a3bb6871143fd02973e18dc21cc6c30ee0cc3be536486e465b170fb1274298c628c4413b75f28aa612957d77103db9956fb9ce996e7ab810", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561c9880855e6e430d8056743fdac65e6624ae390a37d5e31ac78271a4e8c92bdb98c0ba8b0b1a8e3cc507a146a157b9c185bd5e37bee193709f1562c1fdb05e"} build_url:https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011124 build_url: 'https://queue.taskcluster.net/v1/task/J7lBUM6WQoa1qEKoGqAQWQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b0e2229110d2050c3eecb26d5b08ad2edb5dee3da8d86e480c98be6a32a380d3463caa514005ca654ff49e23a75443c0b31b84ba50069192a2b9e641c876b86", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d330a18127a1851f8858381be9b6d8835fb261788112f7d6076e8f0e1b36a399c60624dcdc60a3cd03d6eb6ff933472d14a8e686eb44ad3b57e57d0d0481affe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/435b3aaef9492ef8a3bb6871143fd02973e18dc21cc6c30ee0cc3be536486e465b170fb1274298c628c4413b75f28aa612957d77103db9956fb9ce996e7ab810", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561c9880855e6e430d8056743fdac65e6624ae390a37d5e31ac78271a4e8c92bdb98c0ba8b0b1a8e3cc507a146a157b9c185bd5e37bee193709f1562c1fdb05e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 04:35:59.928543) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 04:35:59.928901) ========= 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=7de08804244eb36560b44971000001c3-1490354100.757830-2095725649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 04:36:00.011570) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 04:36:00.011901) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 04:36:00.012234) ========= ========= Total master_lag: 0.17 =========